builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0150 starttime: 1472261439.98 results: success (0) buildid: 20160826160813 builduid: cdc985df0e484e5ca3c672cb8ae19dc2 revision: b12ec65ba1a9a8005e473cfb03fc2246769425f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:39.981977) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:39.982542) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-08-26 18:30:39.982835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-08-26 18:30:44.970465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:44.970874) ========= 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.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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-26 18:30:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-08-26 18:30:45 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.176857 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:45.589111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:45.589419) ========= 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.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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.020369 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:45.631090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 18:30:45.631480) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b12ec65ba1a9a8005e473cfb03fc2246769425f5 --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 b12ec65ba1a9a8005e473cfb03fc2246769425f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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-26 18:30:45,710 truncating revision to first 12 chars 2016-08-26 18:30:45,710 Setting DEBUG logging. 2016-08-26 18:30:45,710 attempt 1/10 2016-08-26 18:30:45,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b12ec65ba1a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 18:30:46,528 unpacking tar archive at: mozilla-central-b12ec65ba1a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.070045 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 18:30:46.723802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:46.724124) ========= script_repo_revision: b12ec65ba1a9a8005e473cfb03fc2246769425f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:46.724535) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:46.724843) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 18:30:46.743802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-26 18:30:46.744195) ========= /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.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:30:46 INFO - MultiFileLogger online at 20160826 18:30:46 in /builds/slave/test 18:30:46 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 18:30:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:30:46 INFO - {'append_to_log': False, 18:30:46 INFO - 'base_work_dir': '/builds/slave/test', 18:30:46 INFO - 'blob_upload_branch': 'Firefox', 18:30:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:30:46 INFO - 'branch': 'Firefox', 18:30:46 INFO - 'buildbot_json_path': 'buildprops.json', 18:30:46 INFO - 'config_files': ('talos/mac_config.py',), 18:30:46 INFO - 'default_actions': ('clobber', 18:30:46 INFO - 'read-buildbot-config', 18:30:46 INFO - 'download-and-extract', 18:30:46 INFO - 'populate-webroot', 18:30:46 INFO - 'create-virtualenv', 18:30:46 INFO - 'install', 18:30:46 INFO - 'run-tests'), 18:30:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:30:46 INFO - 'download_minidump_stackwalk': True, 18:30:46 INFO - 'download_symbols': 'ondemand', 18:30:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:30:46 INFO - 'tooltool.py': '/tools/tooltool.py', 18:30:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:30:46 INFO - '/tools/misc-python/virtualenv.py')}, 18:30:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:30:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:30:46 INFO - 'installer_path': 'installer.exe', 18:30:46 INFO - 'log_level': 'info', 18:30:46 INFO - 'log_name': 'talos', 18:30:46 INFO - 'log_to_console': True, 18:30:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:30:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:30:46 INFO - 'opt_config_files': (), 18:30:46 INFO - 'pip_index': False, 18:30:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:46 INFO - 'cmd': ('bash', 18:30:46 INFO - '-c', 18:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:46 INFO - 'enabled': True, 18:30:46 INFO - 'halt_on_failure': False, 18:30:46 INFO - 'name': 'check_screen_resolution'},), 18:30:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:30:46 INFO - 'cmd': ('bash', 18:30:46 INFO - '-c', 18:30:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:30:46 INFO - 'enabled': True, 18:30:46 INFO - 'halt_on_failure': False, 18:30:46 INFO - 'name': 'check_screen_resolution'},), 18:30:46 INFO - 'run_cmd_checks_enabled': True, 18:30:46 INFO - 'sps_profile': False, 18:30:46 INFO - 'sps_profile_interval': 0, 18:30:46 INFO - 'suite': 'dromaeojs-e10s', 18:30:46 INFO - 'system_bits': '32', 18:30:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:30:46 INFO - 'title': 't-yosemite-r7-0150', 18:30:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:30:46 INFO - 'use_talos_json': True, 18:30:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:30:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:30:46 INFO - 'work_dir': 'build'} 18:30:46 INFO - [mozharness: 2016-08-27 01:30:46.939839Z] Running clobber step. 18:30:46 INFO - Running pre-action listener: _resource_record_pre_action 18:30:46 INFO - Running main action method: clobber 18:30:46 INFO - rmtree: /builds/slave/test/build 18:30:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:30:47 INFO - Running post-action listener: _resource_record_post_action 18:30:47 INFO - [mozharness: 2016-08-27 01:30:47.883213Z] Finished clobber step (success) 18:30:47 INFO - [mozharness: 2016-08-27 01:30:47.883447Z] Running read-buildbot-config step. 18:30:47 INFO - Running pre-action listener: _resource_record_pre_action 18:30:47 INFO - Running main action method: read_buildbot_config 18:30:47 INFO - Using buildbot properties: 18:30:47 INFO - { 18:30:47 INFO - "project": "", 18:30:47 INFO - "product": "firefox", 18:30:47 INFO - "script_repo_revision": "production", 18:30:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:30:47 INFO - "repository": "", 18:30:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:30:47 INFO - "buildid": "20160826160813", 18:30:47 INFO - "pgo_build": "False", 18:30:47 INFO - "basedir": "/builds/slave/test", 18:30:47 INFO - "buildnumber": 41, 18:30:47 INFO - "slavename": "t-yosemite-r7-0150", 18:30:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:30:47 INFO - "platform": "yosemite_r7", 18:30:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:30:47 INFO - "branch": "mozilla-central", 18:30:47 INFO - "repo_path": "mozilla-central", 18:30:47 INFO - "revision": "b12ec65ba1a9a8005e473cfb03fc2246769425f5", 18:30:47 INFO - "stage_platform": "macosx64", 18:30:47 INFO - "builduid": "cdc985df0e484e5ca3c672cb8ae19dc2", 18:30:47 INFO - "slavebuilddir": "test" 18:30:47 INFO - } 18:30:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:30:47 INFO - Running post-action listener: _resource_record_post_action 18:30:47 INFO - [mozharness: 2016-08-27 01:30:47.889276Z] Finished read-buildbot-config step (success) 18:30:47 INFO - [mozharness: 2016-08-27 01:30:47.889486Z] Running download-and-extract step. 18:30:47 INFO - Running pre-action listener: _resource_record_pre_action 18:30:47 INFO - Running main action method: download_and_extract 18:30:47 INFO - mkdir: /builds/slave/test/build/tests 18:30:47 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')]} 18:30:47 INFO - trying https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:30:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:48 INFO - Downloaded 1199 bytes. 18:30:48 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:30:48 INFO - Using the following test package requirements: 18:30:48 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:30:48 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:30:48 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:30:48 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'jsshell-mac.zip'], 18:30:48 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:30:48 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:30:48 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:30:48 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:30:48 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:30:48 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:30:48 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:30:48 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:30:48 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')]} 18:30:48 INFO - trying https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:30:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:49 INFO - Downloaded 16368925 bytes. 18:30:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:30:56 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 18:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:56 INFO - trying https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:30:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:30:57 INFO - Downloaded 16368925 bytes. 18:30:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:31:04 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')]} 18:31:04 INFO - trying https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:31:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:31:05 INFO - Downloaded 14413998 bytes. 18:31:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:31:05 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')]} 18:31:05 INFO - trying https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:31:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:31:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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 18:31:08 INFO - Downloaded 111601067 bytes. 18:31:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:31:08 INFO - mkdir: /builds/slave/test/properties 18:31:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:31:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:31:08 INFO - Contents: 18:31:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:31:09 INFO - Running post-action listener: _resource_record_post_action 18:31:09 INFO - Running post-action listener: set_extra_try_arguments 18:31:09 INFO - [mozharness: 2016-08-27 01:31:09.378707Z] Finished download-and-extract step (success) 18:31:09 INFO - [mozharness: 2016-08-27 01:31:09.378840Z] Running populate-webroot step. 18:31:09 INFO - Running pre-action listener: _resource_record_pre_action 18:31:09 INFO - Running main action method: populate_webroot 18:31:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:31:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:31:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:31:09 INFO - '--tppagecycles', 18:31:09 INFO - '7'], 18:31:09 INFO - 'tests': ['tsvgm']}}, 18:31:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:31:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:31:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:31:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:31:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:31:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['damp', 'tps']}, 18:31:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'tests': ['damp', 'tps']}, 18:31:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['dromaeo_dom']}, 18:31:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:31:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['basic_compositor_video']}, 18:31:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:31:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'other-e10s': {'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['a11yr', 18:31:09 INFO - 'ts_paint', 18:31:09 INFO - 'tpaint', 18:31:09 INFO - 'sessionrestore', 18:31:09 INFO - 'sessionrestore_no_auto_restore', 18:31:09 INFO - 'tabpaint']}, 18:31:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['tsvgx', 18:31:09 INFO - 'tsvgr_opacity', 18:31:09 INFO - 'tart', 18:31:09 INFO - 'tscrollx', 18:31:09 INFO - 'cart']}, 18:31:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:31:09 INFO - 'tsvgr_opacity', 18:31:09 INFO - 'tart', 18:31:09 INFO - 'tscrollx', 18:31:09 INFO - 'cart']}, 18:31:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'talos_options': ['--disable-e10s'], 18:31:09 INFO - 'tests': ['tp5o']}, 18:31:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'tests': ['tp5o']}, 18:31:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'talos_options': ['--disable-e10s', 18:31:09 INFO - '--xperf_path', 18:31:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:09 INFO - 'tests': ['tp5n']}, 18:31:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:31:09 INFO - 'talos_options': ['--xperf_path', 18:31:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:31:09 INFO - 'tests': ['tp5n']}}, 18:31:09 INFO - 'talos.zip': {'path': '', 18:31:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:31:09 INFO - Running post-action listener: _resource_record_post_action 18:31:09 INFO - [mozharness: 2016-08-27 01:31:09.397471Z] Finished populate-webroot step (success) 18:31:09 INFO - [mozharness: 2016-08-27 01:31:09.397579Z] Running create-virtualenv step. 18:31:09 INFO - Running pre-action listener: _resource_record_pre_action 18:31:09 INFO - Running main action method: create_virtualenv 18:31:09 INFO - Creating virtualenv /builds/slave/test/build/venv 18:31:09 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 18:31:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:31:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:31:09 INFO - Using real prefix '/tools/python27' 18:31:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:31:10 INFO - Installing distribute.............................................................................................................................................................................................done. 18:31:13 INFO - Installing pip.................done. 18:31:13 INFO - Return code: 0 18:31:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:13 INFO - 'HOME': '/Users/cltbld', 18:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:13 INFO - 'LOGNAME': 'cltbld', 18:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:13 INFO - 'MOZ_NO_REMOTE': '1', 18:31:13 INFO - 'NO_EM_RESTART': '1', 18:31:13 INFO - 'PAGER': '/bin/cat', 18:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:13 INFO - 'PWD': '/builds/slave/test', 18:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:13 INFO - 'SHELL': '/bin/bash', 18:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:13 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:13 INFO - 'USER': 'cltbld', 18:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:13 INFO - 'XPC_FLAGS': '0x0', 18:31:13 INFO - 'XPC_SERVICE_NAME': '0', 18:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:13 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 18:31:13 INFO - Cleaning up... 18:31:13 INFO - Return code: 0 18:31:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:13 INFO - 'HOME': '/Users/cltbld', 18:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:13 INFO - 'LOGNAME': 'cltbld', 18:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:13 INFO - 'MOZ_NO_REMOTE': '1', 18:31:13 INFO - 'NO_EM_RESTART': '1', 18:31:13 INFO - 'PAGER': '/bin/cat', 18:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:13 INFO - 'PWD': '/builds/slave/test', 18:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:13 INFO - 'SHELL': '/bin/bash', 18:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:13 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:13 INFO - 'USER': 'cltbld', 18:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:13 INFO - 'XPC_FLAGS': '0x0', 18:31:13 INFO - 'XPC_SERVICE_NAME': '0', 18:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:13 INFO - Downloading/unpacking psutil>=3.1.1 18:31:13 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 18:31:13 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 18:31:13 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 18:31:13 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 18:31:13 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 18:31:13 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 18:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:31:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:16 INFO - Installing collected packages: psutil 18:31:16 INFO - Running setup.py install for psutil 18:31:16 INFO - building 'psutil._psutil_osx' extension 18:31:16 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 18:31:16 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 18:31:17 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 18:31:17 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 18:31:17 INFO - building 'psutil._psutil_posix' extension 18:31:17 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 18:31:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:31:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:31:17 INFO - ^ 18:31:17 INFO - 1 warning generated. 18:31:17 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 18:31:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:31:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:31:17 INFO - Successfully installed psutil 18:31:17 INFO - Cleaning up... 18:31:17 INFO - Return code: 0 18:31:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:17 INFO - 'HOME': '/Users/cltbld', 18:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:17 INFO - 'LOGNAME': 'cltbld', 18:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:17 INFO - 'MOZ_NO_REMOTE': '1', 18:31:17 INFO - 'NO_EM_RESTART': '1', 18:31:17 INFO - 'PAGER': '/bin/cat', 18:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:17 INFO - 'PWD': '/builds/slave/test', 18:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:17 INFO - 'SHELL': '/bin/bash', 18:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:17 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:17 INFO - 'USER': 'cltbld', 18:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:17 INFO - 'XPC_FLAGS': '0x0', 18:31:17 INFO - 'XPC_SERVICE_NAME': '0', 18:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:31:20 INFO - Installing collected packages: mozsystemmonitor 18:31:20 INFO - Running setup.py install for mozsystemmonitor 18:31:20 INFO - Successfully installed mozsystemmonitor 18:31:20 INFO - Cleaning up... 18:31:20 INFO - Return code: 0 18:31:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:31:20 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')]} 18:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:20 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')]} 18:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:20 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 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:20 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 18:31:20 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 18:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:20 INFO - 'HOME': '/Users/cltbld', 18:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:20 INFO - 'LOGNAME': 'cltbld', 18:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:20 INFO - 'MOZ_NO_REMOTE': '1', 18:31:20 INFO - 'NO_EM_RESTART': '1', 18:31:20 INFO - 'PAGER': '/bin/cat', 18:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:20 INFO - 'PWD': '/builds/slave/test', 18:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:20 INFO - 'SHELL': '/bin/bash', 18:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:20 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:20 INFO - 'USER': 'cltbld', 18:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:20 INFO - 'XPC_FLAGS': '0x0', 18:31:20 INFO - 'XPC_SERVICE_NAME': '0', 18:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:21 INFO - Downloading/unpacking jsonschema==2.5.1 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:31:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:24 INFO - Downloading functools32-3.2.3-2.tar.gz 18:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:31:24 INFO - warning: no files found matching '*.txt' 18:31:24 INFO - no previously-included directories found matching 'build' 18:31:24 INFO - no previously-included directories found matching 'dist' 18:31:24 INFO - no previously-included directories found matching '.git*' 18:31:24 INFO - Installing collected packages: jsonschema, functools32 18:31:24 INFO - Running setup.py install for functools32 18:31:24 INFO - warning: no files found matching '*.txt' 18:31:24 INFO - no previously-included directories found matching 'build' 18:31:24 INFO - no previously-included directories found matching 'dist' 18:31:24 INFO - no previously-included directories found matching '.git*' 18:31:24 INFO - Successfully installed jsonschema functools32 18:31:24 INFO - Cleaning up... 18:31:24 INFO - Return code: 0 18:31:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:31:24 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')]} 18:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:24 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')]} 18:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:24 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 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:24 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 18:31:24 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 18:31:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:24 INFO - 'HOME': '/Users/cltbld', 18:31:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:24 INFO - 'LOGNAME': 'cltbld', 18:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:24 INFO - 'MOZ_NO_REMOTE': '1', 18:31:24 INFO - 'NO_EM_RESTART': '1', 18:31:24 INFO - 'PAGER': '/bin/cat', 18:31:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:24 INFO - 'PWD': '/builds/slave/test', 18:31:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:24 INFO - 'SHELL': '/bin/bash', 18:31:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:24 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:24 INFO - 'USER': 'cltbld', 18:31:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:24 INFO - 'XPC_FLAGS': '0x0', 18:31:24 INFO - 'XPC_SERVICE_NAME': '0', 18:31:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:31:25 INFO - Cleaning up... 18:31:25 INFO - Return code: 0 18:31:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:25 INFO - 'HOME': '/Users/cltbld', 18:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:25 INFO - 'LOGNAME': 'cltbld', 18:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:25 INFO - 'MOZ_NO_REMOTE': '1', 18:31:25 INFO - 'NO_EM_RESTART': '1', 18:31:25 INFO - 'PAGER': '/bin/cat', 18:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:25 INFO - 'PWD': '/builds/slave/test', 18:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:25 INFO - 'SHELL': '/bin/bash', 18:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:25 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:25 INFO - 'USER': 'cltbld', 18:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:25 INFO - 'XPC_FLAGS': '0x0', 18:31:25 INFO - 'XPC_SERVICE_NAME': '0', 18:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:25 INFO - Downloading/unpacking blobuploader==1.2.4 18:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:27 INFO - Downloading blobuploader-1.2.4.tar.gz 18:31:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:31:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:31:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:28 INFO - Downloading docopt-0.6.1.tar.gz 18:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:31:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:31:29 INFO - Running setup.py install for blobuploader 18:31:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:31:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:31:29 INFO - Running setup.py install for requests 18:31:29 INFO - Running setup.py install for docopt 18:31:29 INFO - Successfully installed blobuploader requests docopt 18:31:29 INFO - Cleaning up... 18:31:29 INFO - Return code: 0 18:31:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:31:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:31:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:29 INFO - 'HOME': '/Users/cltbld', 18:31:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:29 INFO - 'LOGNAME': 'cltbld', 18:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:29 INFO - 'MOZ_NO_REMOTE': '1', 18:31:29 INFO - 'NO_EM_RESTART': '1', 18:31:29 INFO - 'PAGER': '/bin/cat', 18:31:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:29 INFO - 'PWD': '/builds/slave/test', 18:31:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:29 INFO - 'SHELL': '/bin/bash', 18:31:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:29 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:29 INFO - 'USER': 'cltbld', 18:31:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:29 INFO - 'XPC_FLAGS': '0x0', 18:31:29 INFO - 'XPC_SERVICE_NAME': '0', 18:31:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-O5ukSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-VfRLqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-wtTmQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-Px7ICx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-I7q5j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-EpTCYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-PwmHsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:30 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-56u7DS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-JFh5J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-_rPEJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-kzr3Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-mjDpuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-yHaeWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-0DCXNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-SCeCWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-tgZFPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:31 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-DzZpYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:31:32 INFO - Running setup.py install for manifestparser 18:31:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:31:32 INFO - Running setup.py install for mozcrash 18:31:32 INFO - Running setup.py install for mozdebug 18:31:32 INFO - Running setup.py install for mozdevice 18:31:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:31:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:31:32 INFO - Running setup.py install for mozfile 18:31:32 INFO - Running setup.py install for mozhttpd 18:31:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:31:32 INFO - Running setup.py install for mozinfo 18:31:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Running setup.py install for mozInstall 18:31:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Running setup.py install for mozleak 18:31:33 INFO - Running setup.py install for mozlog 18:31:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Running setup.py install for moznetwork 18:31:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Running setup.py install for mozprocess 18:31:33 INFO - Running setup.py install for mozprofile 18:31:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:31:33 INFO - Running setup.py install for mozrunner 18:31:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:31:34 INFO - Running setup.py install for mozscreenshot 18:31:34 INFO - Running setup.py install for moztest 18:31:34 INFO - Running setup.py install for mozversion 18:31:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:31:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:31:34 INFO - Cleaning up... 18:31:34 INFO - Return code: 0 18:31:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:34 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')]} 18:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:34 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')]} 18:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:34 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 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:34 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 18:31:34 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 18:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:34 INFO - 'HOME': '/Users/cltbld', 18:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:34 INFO - 'LOGNAME': 'cltbld', 18:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:34 INFO - 'MOZ_NO_REMOTE': '1', 18:31:34 INFO - 'NO_EM_RESTART': '1', 18:31:34 INFO - 'PAGER': '/bin/cat', 18:31:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:34 INFO - 'PWD': '/builds/slave/test', 18:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:34 INFO - 'SHELL': '/bin/bash', 18:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:34 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:34 INFO - 'USER': 'cltbld', 18:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:34 INFO - 'XPC_FLAGS': '0x0', 18:31:34 INFO - 'XPC_SERVICE_NAME': '0', 18:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:31:34 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-cFGPXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:31:34 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-HWq8QU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-PMOXNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-hjw8jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-By5FZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-YZm46X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-gTbG9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-TobiW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-LQ0Nw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-5KQa3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:31:35 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)) 18:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:31:35 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-cXsCzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-NgAqiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-sHy3wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-O6Xxll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-k7Hh6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-NwPFj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:31:36 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)) 18:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:31:36 INFO - Running setup.py (path:/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/pip-CndEMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:31:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:31:39 INFO - Downloading blessings-1.6.tar.gz 18:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:31:39 INFO - Installing collected packages: blessings 18:31:39 INFO - Running setup.py install for blessings 18:31:39 INFO - Successfully installed blessings 18:31:39 INFO - Cleaning up... 18:31:39 INFO - Return code: 0 18:31:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:31:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:39 INFO - Reading from file tmpfile_stdout 18:31:39 INFO - Current package versions: 18:31:39 INFO - blessings == 1.6 18:31:39 INFO - blobuploader == 1.2.4 18:31:39 INFO - docopt == 0.6.1 18:31:39 INFO - functools32 == 3.2.3-2 18:31:39 INFO - jsonschema == 2.5.1 18:31:39 INFO - manifestparser == 1.1 18:31:39 INFO - mozInstall == 1.12 18:31:39 INFO - mozcrash == 0.17 18:31:39 INFO - mozdebug == 0.1 18:31:39 INFO - mozdevice == 0.48 18:31:39 INFO - mozfile == 1.2 18:31:39 INFO - mozhttpd == 0.7 18:31:39 INFO - mozinfo == 0.9 18:31:39 INFO - mozleak == 0.1 18:31:39 INFO - mozlog == 3.3 18:31:39 INFO - moznetwork == 0.27 18:31:39 INFO - mozprocess == 0.23 18:31:39 INFO - mozprofile == 0.28 18:31:39 INFO - mozrunner == 6.12 18:31:39 INFO - mozscreenshot == 0.1 18:31:39 INFO - mozsystemmonitor == 0.3 18:31:39 INFO - moztest == 0.7 18:31:39 INFO - mozversion == 1.4 18:31:39 INFO - psutil == 3.1.1 18:31:39 INFO - requests == 1.2.3 18:31:39 INFO - wsgiref == 0.1.2 18:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31:39 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 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31:39 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 18:31:39 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 18:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:39 INFO - 'HOME': '/Users/cltbld', 18:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:39 INFO - 'LOGNAME': 'cltbld', 18:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:39 INFO - 'MOZ_NO_REMOTE': '1', 18:31:39 INFO - 'NO_EM_RESTART': '1', 18:31:39 INFO - 'PAGER': '/bin/cat', 18:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:39 INFO - 'PWD': '/builds/slave/test', 18:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:39 INFO - 'SHELL': '/bin/bash', 18:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:39 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:39 INFO - 'USER': 'cltbld', 18:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:39 INFO - 'XPC_FLAGS': '0x0', 18:31:39 INFO - 'XPC_SERVICE_NAME': '0', 18:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 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)) 18:31:40 INFO - Cleaning up... 18:31:40 INFO - Return code: 0 18:31:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:31: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')]} 18:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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')]} 18:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:31: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ef618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8799d544b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, '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.0qnDbOWneN/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.btqpBsOp2g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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 18:31: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'] in /builds/slave/test/build 18:31: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 18:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:40 INFO - 'HOME': '/Users/cltbld', 18:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:40 INFO - 'LOGNAME': 'cltbld', 18:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:40 INFO - 'MOZ_NO_REMOTE': '1', 18:31:40 INFO - 'NO_EM_RESTART': '1', 18:31:40 INFO - 'PAGER': '/bin/cat', 18:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:40 INFO - 'PWD': '/builds/slave/test', 18:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:40 INFO - 'SHELL': '/bin/bash', 18:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:31:40 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:40 INFO - 'USER': 'cltbld', 18:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:40 INFO - 'XPC_FLAGS': '0x0', 18:31:40 INFO - 'XPC_SERVICE_NAME': '0', 18:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:31:40 INFO - Cleaning up... 18:31:40 INFO - Return code: 0 18:31:40 INFO - Running post-action listener: _resource_record_post_action 18:31:40 INFO - Running post-action listener: _start_resource_monitoring 18:31:40 INFO - Starting resource monitoring. 18:31:40 INFO - [mozharness: 2016-08-27 01:31:40.312067Z] Finished create-virtualenv step (success) 18:31:40 INFO - [mozharness: 2016-08-27 01:31:40.312891Z] Running install step. 18:31:40 INFO - Running pre-action listener: _resource_record_pre_action 18:31:40 INFO - Running main action method: install 18:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:31:40 INFO - Reading from file tmpfile_stdout 18:31:40 INFO - Detecting whether we're running mozinstall >=1.0... 18:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:31:40 INFO - Reading from file tmpfile_stdout 18:31:40 INFO - Output received: 18:31:40 INFO - Usage: mozinstall [options] installer 18:31:40 INFO - Options: 18:31:40 INFO - -h, --help show this help message and exit 18:31:40 INFO - -d DEST, --destination=DEST 18:31:40 INFO - Directory to install application into. [default: 18:31:40 INFO - "/builds/slave/test"] 18:31:40 INFO - --app=APP Application being installed. [default: firefox] 18:31:40 INFO - mkdir: /builds/slave/test/build/application 18:31:40 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'] 18:31:40 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 18:32:14 INFO - Reading from file tmpfile_stdout 18:32:14 INFO - Output received: 18:32:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:32:14 INFO - Running post-action listener: _resource_record_post_action 18:32:14 INFO - [mozharness: 2016-08-27 01:32:14.990920Z] Finished install step (success) 18:32:14 INFO - [mozharness: 2016-08-27 01:32:14.991087Z] Running run-tests step. 18:32:14 INFO - Running pre-action listener: _resource_record_pre_action 18:32:14 INFO - Running main action method: run_tests 18:32:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:32:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:32:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:32:15 INFO - 2016-08-26 18:32:15.005 screenresolution[1961:13923] starting screenresolution argv=screenresolution get 18:32:15 INFO - 2016-08-26 18:32:15.008 screenresolution[1961:13923] Display 0: 1600x1200x32@60 18:32:15 INFO - 2016-08-26 18:32:15.014 screenresolution[1962:13925] starting screenresolution argv=screenresolution list 18:32:15 INFO - Available Modes on Display 0 18:32:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:32:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:32:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:32:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:32:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:32:15 INFO - Intel Iris: 18:32:15 INFO - Chipset Model: Intel Iris 18:32:15 INFO - Type: GPU 18:32:15 INFO - Bus: Built-In 18:32:15 INFO - VRAM (Dynamic, Max): 1536 MB 18:32:15 INFO - Vendor: Intel (0x8086) 18:32:15 INFO - Device ID: 0x0a2e 18:32:15 INFO - Revision ID: 0x0009 18:32:15 INFO - Displays: 18:32:15 INFO - Display: 18:32:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:32:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:32:15 INFO - Display Serial Number: 1600x1200 60 18:32:15 INFO - Main Display: Yes 18:32:15 INFO - Mirror: Off 18:32:15 INFO - Online: Yes 18:32:15 INFO - Rotation: Supported 18:32:15 INFO - Return code: 0 18:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:32:15 INFO - Python 2.7.3 18:32:15 INFO - Return code: 0 18:32:15 INFO - grabbing minidump binary from tooltool 18:32:15 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')]} 18:32:15 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 0x1083bed30>, '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 0x108123db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083b6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:32:15 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 18:32:15 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 18:32:15 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 18:32:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:32:15 INFO - Return code: 0 18:32:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:32:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:32:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:32:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:32:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:32:15 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/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 18:32:15 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/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0150 --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 18:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0qnDbOWneN/Render', 18:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:32:15 INFO - 'HOME': '/Users/cltbld', 18:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:32:15 INFO - 'LOGNAME': 'cltbld', 18:32:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:32:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:32:15 INFO - 'MOZ_NO_REMOTE': '1', 18:32:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:32:15 INFO - 'NO_EM_RESTART': '1', 18:32:15 INFO - 'PAGER': '/bin/cat', 18:32:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:32:15 INFO - 'PWD': '/builds/slave/test', 18:32:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:32:15 INFO - 'SHELL': '/bin/bash', 18:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.btqpBsOp2g/Listeners', 18:32:15 INFO - 'TMPDIR': '/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/', 18:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:32:15 INFO - 'USER': 'cltbld', 18:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:32:15 INFO - 'XPC_FLAGS': '0x0', 18:32:15 INFO - 'XPC_SERVICE_NAME': '0', 18:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:32:15 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/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 18:32:15 INFO - mozversion application_buildid: 20160826160813 18:32:15 INFO - mozversion application_changeset: b12ec65ba1a9a8005e473cfb03fc2246769425f5 18:32:15 INFO - mozversion application_display_name: Nightly 18:32:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:32:15 INFO - mozversion application_name: Firefox 18:32:15 INFO - mozversion application_remotingname: firefox 18:32:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:32:15 INFO - mozversion application_vendor: Mozilla 18:32:15 INFO - mozversion application_version: 51.0a1 18:32:15 INFO - mozversion platform_buildid: 20160826160813 18:32:15 INFO - mozversion platform_changeset: b12ec65ba1a9a8005e473cfb03fc2246769425f5 18:32:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:32:15 INFO - mozversion platform_version: 51.0a1 18:32:15 INFO - using testdate: 1472261535 18:32:15 INFO - actual date: 1472261535 18:32:15 INFO - starting webserver on 'localhost:51367' 18:32:15 INFO - SUITE-START | Running 2 tests 18:32:15 INFO - TEST-START | dromaeo_css 18:32:15 INFO - operating with platform_type : mac_ 18:32:15 INFO - Initialising browser for dromaeo_css test... 18:32:15 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpr0T93G/profile http://localhost:51367/getInfo.html) 18:32:18 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 18:32:18 INFO - PROCESS | 1972 | colorDepth:24 18:32:18 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 18:32:18 INFO - PROCESS | 1972 | __metrics 18:32:18 ERROR - PROCESS | 1972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 18:32:19 INFO - TEST-INFO | 1972: exit 0 18:32:19 INFO - Browser initialized. 18:32:19 INFO - Running cycle 1/1 for dromaeo_css test... 18:32:19 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpr0T93G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:31 INFO - PROCESS | 1975 | RSS: Main: 243863552 18:32:31 INFO - PROCESS | 1975 | 18:33:20 INFO - PROCESS | 1975 | 1472261600920 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] 18:35:33 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51367/tests/dromaeo/cssquery-ext.html) 18:35:34 INFO - PROCESS | 1975 | RSS: Main: 266653696 18:35:34 INFO - PROCESS | 1975 | 18:38:36 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-ext.html (next: http://localhost:51367/tests/dromaeo/cssquery-jquery.html) 18:38:37 INFO - PROCESS | 1975 | RSS: Main: 275374080 18:38:37 INFO - PROCESS | 1975 | 18:41:39 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51367/tests/dromaeo/cssquery-mootools.html) 18:41:39 INFO - PROCESS | 1975 | RSS: Main: 277008384 18:41:39 INFO - PROCESS | 1975 | 18:44:41 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51367/tests/dromaeo/cssquery-prototype.html) 18:44:42 INFO - PROCESS | 1975 | RSS: Main: 273219584 18:44:42 INFO - PROCESS | 1975 | 18:47:45 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51367/tests/dromaeo/cssquery-yui.html) 18:47:45 INFO - PROCESS | 1975 | RSS: Main: 278802432 18:47:45 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51367/tests/dromaeo/cssquery-yui.html (next: http://localhost:51367/tests/dromaeo/cssquery-dojo.html) 18:50:48 INFO - PROCESS | 1975 | RSS: Main: 281567232 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | __start_tp_report 18:50:48 INFO - PROCESS | 1975 | _x_x_mozilla_page_load 18:50:48 INFO - PROCESS | 1975 | _x_x_mozilla_page_load_details 18:50:48 INFO - PROCESS | 1975 | |i|pagename|runs| 18:50:48 INFO - PROCESS | 1975 | |0;dojo.html;3378.3918894598964;3388.8983330500087;3390.1524618845287;3403.6766507181815;3415.8292085395724;5202.699325168708;5260.973695131529;5614.522765564921;5678.772849086036;5693.943060569398;5656.123300888358;5718.971405142968;5736.254286942692;5777.364489906107;5810.099434587635;5123.2827404163445;5191.9616076784605;5194.92207616886;5248.6063545234065;5303.416624171357;5273.525382715555;5291.867703307429;5326.627136100475;5338.973305133488;5348.277982472356;5080.1617733074;5126.6923984561045;5158.664686795384;5199.012187684328;5218.895622087537;5201.08980928336;5219.973900130513;5234.214867769827;5235.607329450307;5236.764345604419;5139.049275883974;5162.199859021857;5182.896342073175;5197.246399272122;5209.270702101709;4636.86089417318;5562.249096371978;5597.272354593917;5671.773129074873;5712.200291959129;6128.625347140861;6163.599366041238;6203.720832562545;6205.81382558524;6223.315435302113;6183.381661833826;6190.5976111553055;6206.193194884681;6220;6229.0967809667345;5684.431556844365;5784.363719990798;5793;5794.362620111784;5861.853453663693;6244.718987645567;6245.156903817927;6248.4064013918605;6260.092286618415;6264.780732674334;6073.423024812636;6119.082137679295;6168.25980882297;6191;6203.286622038433;6096.1033937223965;6110.3765049759795;6149.985252433298;6186.690665466709;6236.469900058455;5673.716314184274;5720.313562372542;6143.542582966849;6221.129041934133;6231.937680623227;6174.012158054689;6198.907016394758;6203.16257305267;6252.155958945484;6254.906176407358;4837.32277481153;4873.26900964851;4894.559489645949;4913.140200464904;4930.5069493050405;4378.9928316487385;4400.515943246241;4415.337699345124;4463.531329210451;4488.461384633865;5589.720513974285;5740.454656807597;5746.994276001683;5751.1085781703905;5772.047612144033;6099.939000610026;6150.323464418911;6157.230346206724;6223.0354295084335;6246.875562398812;5740.225069615549;5769.509591684669;5779.913301300484;5793.159991801165;5813.389594092644;5376.946230537723;5417.566594672417;5425.593080518976;5439.211314359396;5439.891202175934;6152.953997820471;6235.501159907106;6271.404216599416;6305.148804911279;6332.176817013851;6185.567010309235;6186.566940314135;6205.007198848162;6206;6223.097650840693;5358.33020872391;6324.30432652417;6333.240011198502;6353.650549219652;6362.045693146065;5828.766849326149;6095.207623008981;6106.931287024664;6191.25704915413;6194.163787888758;6043.244594425697;6193.194884664965;6222.315545290105;6249;6265.467435268053;6125.142480052886;6150.692465376803;6152.230971128609;6152.969235153795;6157.722902469489;5369.543588794996;5976.073708575178;6084.421979911901;6142.9078563820685;6148.538859585638;4527.569880861313;4898.771059167999;5024.899502010013;5743.798967036134;6001.4298641628975;6123.755049797958;6131.662758548323;6143.784967526115;6148.44663980244;6155.722992465439;5866.560007999503;5929.940700593112;5938.346781854079;6007.789727359522;6017.849553761191 18:50:48 INFO - PROCESS | 1975 | |1;ext.html;462.88427893026744;508.1792904459295;513.0431744795955;542.2841848759637;578.1385735254472;37848.24303513928;40331.79834100829;40413.59586404135;40428.79785601075;40447.393289100684;40892.38661420081;41091.38362924558;41203.9699007525;41209.96975075617;41353.75938721836;9461.432314061161;9699.466529340883;9728.464934428604;9730.416175029486;9745.220382369393;9516.571754271074;9532.571034303473;9549.092836180587;9635.132838044574;9639.180669643089;9621.37461065032;9657.372270802376;9661.130498255156;9688.951555242249;9702;9597.904020959772;9606.27952903528;9645.662401815938;9655.855162172502;9694.03059694032;9598.568064437048;9666.178374838148;9702.757431064209;9718.757031074208;9726.465044422554;31912.840435797672;39182.02044948871;39290.41064384037;39325;39437.60562394368;3329.267561136546;3330.633630300665;3415.777974431679;3931.9017024574327;4012.9398059028845;30329.393412131856;30379.544306835414;30762;31008.379832403225;31520;3221.5167724841463;3222.661620529834;3254.0725893120584;3324.7672662913606;3362.0922350965348;468201;472619;475367;475559.6222018902;475779;32629.879057861162;56559.43440565624;56841.29476115723;57106.7144664274;57460;33246;34415.96752097441;34557.13607159841;34627.65372346294;34715;12164.635060948185;14539.3457294422;14563.271836408137;14724;14735;12524.874751252553;12574.245545267313;12596.937015314865;12673.809892851614;12714.936425318;7610.9238907610215;7653.8569200623915;7692.538447693157;7707.922920770833;7724.806879827936;2887.985560072186;3182.188541921795;3196.536502207167;3246.0910944935454;3251.138448311199;768.873135932576;971.3589031239349;997.7428440165298;1018.8181709217272;1108.7228192951761;26896.59655105175;29653.110406687832;30024.24939376533;30186.396272074875;30464.54303185454;1008.8032833597379;1067.0183431243274;1089.0307626212518;1112.3214838948236;1114.6878873915316;14309.78535321971;15529.45646902353;16102.43641472543;16360.509184724477;16570.83429165693;626157;630325;630592;631459;634624;52528.73735631374;53726.19410708843;53760;53918.03450810243;54035.459645403826;1334.3328335832084;1356.525216174331;1380.7997840313299;1388.7430825297554;1391.248725688117;27302.590461142703;29611.259718507208;29942.70057299486;30210.77631729063;30710.692893070784;41383.75848724543;45035.54964450314;49218;49854.501454986435;50084;588665;591437;594596;597138;598403;21992.45018874541;25419.237422877344;26834.463310734067;27139.185824425298;27758.306042348297;45889.311660324456;46182;46421.30368044414;46483;47556;33511.16222094467;37570.8121459391;37693.81153094217;38083.619163808005;38258.42612360761;32468;38336.233275333776;38579.80710096544;38943;39362;29890.70109298879;30522.542161868034;37317.25365492729;38321.808390958984;38397.808010959765;25179.74820251774;26493.602595960692;34253;34272.143196420286;34353.828230858686;25017.499650007263;25253.747462525873;25275.74724252734;25556.744432555435;25703.357416064748 18:50:48 INFO - PROCESS | 1975 | |2;jquery.html;308.62964442668795;366.1487042625894;371.47992810065915;380.38567713143283;400.7077176107055;18496.16767245474;18937.526561835963;19342;19505.51236219095;20588.588228235425;16081.356745730156;19768.70346944797;19890.40328790135;20329;20408.285710000153;5846.181534585151;5897.045992770784;6092.177556029946;6101.145839582462;6115.143879856823;5784.508316793078;5957.255343082114;5983.611065280743;5988.640681559121;6005.429484199016;4637.675362724611;5872.500837428824;5920.763169473216;5923.200367950336;5973.193618861464;5912.260967379078;6036.879262414727;6048.455638992467;6049.637021778707;6068.908966365508;5392.973035134838;5410.269613602152;5625.128105143709;5864.237649105632;5924.4667979882015;13062.608121756362;13309;13353.931685465139;13491.055626106176;13508.93245533776;2048.109072553444;2133.2107120365563;2184.7924447177493;2285.337252196861;2404.701461210944;10031.347962382495;10258.230632702502;10259.897401025968;10508.842367364496;10564.841527377019;1776.7740259221096;1806.683830329687;1850.620622772342;1856.118343786704;1892.8769629974008;105046.47476762567;125075;125540.37229813793;125944.37027814894;128431.07353389707;10997.780044399067;11324.37715925632;11349.319040857576;11363.659090227306;11367.261128026652;14107.858921410654;14186.645333866736;14211.928940355232;14245.430182792572;14255.572332830032;6859.485538584626;8141.453123906439;8234.341252699769;8588.22705956467;8599.914000859912;7181.820454488575;7351.779446616503;7394;7969.003874515685;8614.526201058876;3697.556293244828;4384.430024096847;4434.290513517822;4453.821847126079;4529.909401811945;1628.8778341624188;1904.561950751335;1939.224310275901;1952.8730632508841;1977.317825350252;820.819419727659;826.8883700700328;870.8737233101165;930.8231436027133;953.713885834247;19309.324173653855;20838.479038024172;20990.47523811917;21104.894475527522;21212.893935530225;700.9789706308819;874.6763697431991;941.110650435332;959.788846453779;1019.403648865422;10372.740681482872;10758.354498730101;10793.625402411457;10806.787585731565;10837.620683276047;523597;564277;564908;568375.158124215;592014;134160.32919835337;135522.32238838743;138846;139348;140384;1009.1119814563284;1015.3603229965074;1020.4030642074321;1033.0547548992588;1035.2597892506894;18062.187201575696;18157.818421815613;18214.817851821314;18257;18333.26666933361;49268.75365623149;54300.45699543112;55359.169612455036;55388.72305638446;55994;80528.79206811785;82502.58748706218;82547.76178357209;83280.58359708163;85944;6937.167539895245;7206;7276.745313913988;7483.401327893865;7535.962320188364;11359.829602555803;12620.747585048432;12774;13107.67230819199;13204.207747535178;10614.575416983234;10756.623518176826;11071.5571377144;11086.064447121478;11318.830217546909;9378.718638440858;10518.947405262925;11009.2843965142;11257.268277561932;11442.771144577227;11247.268927519684;11377.374244416636;11628.709282268012;11710.414479276173;11783.528658853893;6026.548008899263;8076.87884681742;8233.46482478637;8264.256216940505;8448.577571121297;10675.46622668879;10747;11018.228723989243;11243.325400475998;11575.826362604736 18:50:48 INFO - PROCESS | 1975 | |3;mootools.html;1467.4497063601148;1516.681496885654;1742.1724680776633;1817.4184261036473;1879.4643526594914;6540.803775886731;8361.247487726103;8510.957445212765;8937.419067760591;8971.73084807456;8530.360222983285;8716.477011379322;8748.037715851267;8748.081451447602;8798.252148567379;2314.6990891184123;2359.9292021239303;2362.763723627632;2362.9054837806552;2365.4086478380405;2333.288347054148;2359.327591636384;2368.4078980254935;2376.1683410806254;2377.3343463830156;2349.459624286407;2368.14746691191;2369.8933547990296;2381.6546600742968;2382.9642555361684;1995.7904420035832;2335.556244313592;2344.460774021976;2348.7064116985375;2356.222446592629;1947.3087054095733;2107.1782005018053;2211.1045026764186;2351.9647205291935;2354.2701762453694;8721.912780872173;8785.736427907173;8803.77990550235;8853.911460885372;8857.20285174331;5179.19722443022;5267.341582302212;5327.680339179623;5339.305890234284;5581.162825576155;2525.419153594665;2974.375381169957;3386.255023894739;3417.624061353249;3480.7737497062863;1095.6329629574022;1098.0556721219746;1101.3502033800016;1107.9501422435928;1118.848955391028;11524;11576.826347604709;11657.591984280509;11748.118891083117;11773.352465614387;4042.130941847517;4080.224757296104;4101.46680931477;4548.317752337176;4680.297955306732;8951.910480895238;9758.219342452587;9893.406395616286;9920.950395247977;9923.255755818342;5307.150855863043;5311.49540793624;5319.840404787863;5330.600204984642;5335.146376579729;4327.76197309151;4683.555062269079;4804.543568361;4848.151573474628;4854.004928989549;3811.294910441577;3879.010852232719;3930.6265904739003;3953.940690889639;3977.463042489285;1092.4592326798286;1284.4329917500634;1290.664427248922;1294.4304506017322;1298.7142828577698;786.5045021636332;842.2419822159983;873.3581164807931;898.1333013641867;899.9415038022638;5571.777128914798;5638.577106717013;5854.648721076749;5903.793367232126;5915.674637894871;985.3644399362514;1013.2350075692952;1035.9792804143874;1043.749500119981;1082.2694681090234;3751.662350388478;3898.8440462381755;3950.071733142709;4048.473698419187;4052.412400202013;9769.267304952156;10113.898861011443;10532.21008424356;10805.081568066646;10853.782924341469;9830.262730295257;10724.463776811128;10764.623238186627;10765.83851242232;10778.892211077946;7581.848363032819;8006.759797206093;8100.351971842357;8137.3490120791375;8153.714619988272;5693.715314234355;5886.823395298148;5939.406059394026;6024.698765061817;6130.754769809336;9769.120779129913;9818.65634702782;9862.211023118276;9912.454814984956;10016.499175041074;9513.429194248367;9891.752706182115;9919.504024798876;10083.495825208856;10115.241356898408;7476.70093196266;7846.33306168982;8012.959935200287;8078.111407745261;8259.587020648823;8352.916470835215;8400.453970491899;8411.45325553837;8412.158784121539;8419.663213471393;7011.859762804817;7020;7149.14210294768;7221.302993796348;7359.632018399166;7051.541649792747;7067.646617668993;7106.324899134574;7121.786346409616;7220.89168662481;6701.966490167518;6891.3797758202;6978.581285122909;7014.0531028310525;7019.824504387431;4859.854204373874;4916.188828843201;4920;4937.333459982857;5062.114130027304;4609.1703493371515;4894.535019173173;4981.42713587942;4999.600031997359;5022 18:50:48 INFO - PROCESS | 1975 | |4;prototype.html;279.9669220576074;291.82927987127516;296.88815386378013;304.8110171693549;321.3556818578749;4675.8363457279;5290.550303224221;5354.875476150003;5434.2935418395655;5487.917681234775;5581.972090139554;5673.744681489333;5709.628874123179;5720.599558030939;5724.942750572503;4282.6145646891855;4358.97820510897;4366.956330436687;4373.431453910987;4378.496472905625;3638.781673099609;3984.342583473723;4235.301175306071;4305.569443055685;4384.802683879233;3597.9640203598024;3682.981585092071;3874.670652994499;4212.08350200658;4221.00308780766;3897.9025524361978;4234.9364759528635;4238.724482908632;4287.506936702288;4288.249556327662;3535.540379750616;3651.7991510466913;3655.6161603031833;3668.5047518584915;3679.1354031802507;4552.726836389827;5076.390833100015;5453.481919217668;5498.697571633558;5522.447755224485;4175.54069052404;4677.321788340706;4803.495632958559;4865.245886887503;4915.778789954461;4690.249560070372;5660.518855897254;5991.910121348184;5993.550483713695;6038.57729958903;3171.2230503526553;3502.9474557881654;3524.4537096750046;3861.9806900965314;3981.562028176927;5545.972270138624;5741.425857414267;6323.747050118036;6382.776602818925;6450.838729031765;3391.5251864738957;4057.634812866856;4069.7965101744794;4079.8164082616354;4082.101937573729;3466.5493485847182;3821.293060783764;3879.9806000969816;4105.261053010487;4107.137501124737;4821.614270858323;4877.122118018719;4892.706437613754;4911.778969946361;4938.765308672831;5005.82479613219;5435.972820135874;5779.2775903012125;6137.5703700741415;6160.691965401712;1243.3969524780475;1375.2985977151525;1410.0411720030477;1411.9929400353137;1414.5826981040618;825.9793505162419;833.3458235285271;847.3687103108224;854.1373213054859;860.4407135362064;4196.601322874383;4345.97827010863;4392.407025051641;4405.889852753707;4853.975730121398;4253.808578613908;4653.184823181994;5200.5297483695695;5279.673805502509;5291.947080529146;4194.454720886265;4548.180727708943;4586.082783443325;4754.881127971828;4796.218193447792;2633.1837130489607;2791.4696207720467;2890.7253810887933;3303.3888730584918;3310.278655099335;6532.477401807844;6534.066779581386;6536.013032050446;6539.2479864815205;6575.243846957565;6118.082287656887;6414.711337989802;6444.065610486453;6455.4835613150835;6479.546431749733;6483.384078512534;6501.781618098977;6515.967420162963;6532.444742196966;6537.52905596237;5389.541888939327;5680.801171958961;6219.844503887439;6252.217763095379;6303.243610766737;5576.665400076008;5704.719786122913;5992.880142397041;6185.597936134137;6193.213054125751;5477.726113694379;6278.843028924313;6542.967285163544;6544.869102618016;6560.376764207393;3682.15892053973;3731.936398126521;4084.244414783275;4093.3859921012086;4107.876763697094;6007.519398448027;6301.369863013662;6315.273743519554;6333.290011696909;6344.651044192429;5956.642601443928;6084.756609735561;6097.50002498991;6111.602745821508;6118.877622447615;5115.104856649997;5296.417394086724;5336.359636843604;6091.111755355854;6116.094854943866;4992.478798206882;5187.714675692873;5788.27646544182;5947.04847224442;6044.15381846533;5101.285819985279;6078.7264573093435;6117.3270940197435;6117.938820611915;6138.439704971195;4514.961558841419;5861.706914654165;5981.581289309706;6009.248843894513;6020.608660437058 18:50:48 INFO - PROCESS | 1975 | |5;yui.html;690.7858563845206;702.3257672634271;719.910011248594;761.6229966166745;838.9874151887723;15104.169270690109;16281;16297.83702162978;16302.347906083742;16366.59083522911;14217.573472795806;14757.557273281793;14895.106293622363;14917.32872020759;15001.174935378549;775.2944820213606;784.6900474312644;790.2374208888422;863.7840539865034;864.9740507784777;683.4361651637395;841.5623875584693;868.2055918834291;870.7213691618684;872.6029656506274;913.1963871792847;914.6844338703137;914.7804526913559;916.9266458683289;917.9036201198878;887.8668199770021;910.544727636182;912.0241341764315;912.5528491039377;913.9946059334778;667.0760996020498;669.8961660942562;671.5299290496623;672.1295921781305;672.4754691340762;14936.103833770008;14947.103173809497;14957.252137393087;14996.100233985995;15008.849911500854;14350.64123396913;14497.782533262009;14525.201113938729;14596.416143354252;14743.262836858114;11744.647660570197;11884.346360950145;12100.213486123463;12155.331456769874;12416.503339866395;3750.643688849555;3777.8111094445444;3941.6452519273403;3999.120193557442;4002.2795896738867;114451;115327;116019.83980160175;116830;119668.40165799203;10942.835857462145;11130.387828669502;11193;11237.775244495064;11430.028447582048;19210.423687289403;22812.4296892579;22972.19597314086;22981.885090574775;23483.647745283834;7985.920140798634;7999.200079991954;8047.195280472023;8057.274845263954;8109.107998120202;4401.823927042947;4432.29083346663;4449.087936972913;4455.309427038814;4494.977525112354;3569.607343192247;3624.637536246354;3663.0476076220375;3674.375356189454;3689;649.9057636642755;652.6247407740568;654.6563054396385;654.8755736410067;659.5515049766205;545.0187474514399;551.7296524702947;588.2764200033982;606.9006484827466;612.5161122713007;8856.778580535538;9383.234068785896;9511.762205944768;9512.90487095134;9828.606855725691;572.2732130194629;614.9108379284978;646.5215740352199;658.2167221006987;694.2814187316104;4416.977915110404;4614.676972611952;4672.182368085571;4680.836170734008;4797.904041919142;147390.789138163;155294;163921;164964;173302.13348933426;77665;104052;138065.309673453;145253.27373363066;147409;109760.90239097667;110649;112055.43972280086;113455;113476;8851.822963540822;8870.42342247752;8929.419587726778;9030.683926062557;9251.76870578241;40804.79597601993;56479.71760141173;57498;63249.051264231995;65832.39173257825;66122;87147;91376.08623913942;93253;95017.524912375;11076;13022.2837743925;13298.601041968757;13386.531471398454;13796.965227607769;14938.77591836145;14993.700125997637;15000.549983500512;15110.244487775788;15210.923945380646;13477.797833032317;13660.93169534146;13740.312984350943;14056.297185140498;14676.633084172981;11182.720431989266;11977.820332695192;13445.193288402726;13972.371243293874;13991.860081399056;13586.32068396596;13608.251546165056;13978.510752123628;14390.92068094918;14512.709745805236;11001.394923279297;11304.886951130384;11328.150388720715;11361.659150225507;11653.88346116528;9749.317547771872;11411.486483108447;11508.446703543064;11616.767664646492;11659.766804664028 18:50:48 INFO - PROCESS | 1975 | __end_tp_report 18:50:48 INFO - PROCESS | 1975 | __start_cc_report 18:50:48 INFO - PROCESS | 1975 | _x_x_mozilla_cycle_collect,378 18:50:48 INFO - PROCESS | 1975 | __end_cc_report 18:50:48 INFO - PROCESS | 1975 | __startTimestamp1472262648760__endTimestamp 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | ------- Summary: start ------- 18:50:48 INFO - PROCESS | 1975 | Number of tests: 6 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#0] dojo.html Cycles:165 Average:5691.56 Median:5828.77 stddev:641.94 (11.0%) stddev-sans-first:617.73 18:50:48 INFO - PROCESS | 1975 | Values: 3378.4 3388.9 3390.2 3403.7 3415.8 5202.7 5261.0 5614.5 5678.8 5693.9 5656.1 5719.0 5736.3 5777.4 5810.1 5123.3 5192.0 5194.9 5248.6 5303.4 5273.5 5291.9 5326.6 5339.0 5348.3 5080.2 5126.7 5158.7 5199.0 5218.9 5201.1 5220.0 5234.2 5235.6 5236.8 5139.0 5162.2 5182.9 5197.2 5209.3 4636.9 5562.2 5597.3 5671.8 5712.2 6128.6 6163.6 6203.7 6205.8 6223.3 6183.4 6190.6 6206.2 6220.0 6229.1 5684.4 5784.4 5793.0 5794.4 5861.9 6244.7 6245.2 6248.4 6260.1 6264.8 6073.4 6119.1 6168.3 6191.0 6203.3 6096.1 6110.4 6150.0 6186.7 6236.5 5673.7 5720.3 6143.5 6221.1 6231.9 6174.0 6198.9 6203.2 6252.2 6254.9 4837.3 4873.3 4894.6 4913.1 4930.5 4379.0 4400.5 4415.3 4463.5 4488.5 5589.7 5740.5 5747.0 5751.1 5772.0 6099.9 6150.3 6157.2 6223.0 6246.9 5740.2 5769.5 5779.9 5793.2 5813.4 5376.9 5417.6 5425.6 5439.2 5439.9 6153.0 6235.5 6271.4 6305.1 6332.2 6185.6 6186.6 6205.0 6206.0 6223.1 5358.3 6324.3 6333.2 6353.7 6362.0 5828.8 6095.2 6106.9 6191.3 6194.2 6043.2 6193.2 6222.3 6249.0 6265.5 6125.1 6150.7 6152.2 6153.0 6157.7 5369.5 5976.1 6084.4 6142.9 6148.5 4527.6 4898.8 5024.9 5743.8 6001.4 6123.8 6131.7 6143.8 6148.4 6155.7 5866.6 5929.9 5938.3 6007.8 6017.8 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#1] ext.html Cycles:180 Average:67902.37 Median:38761.40 stddev:152721.48 (394.0%) stddev-sans-first:153065.97 18:50:48 INFO - PROCESS | 1975 | Values: 462.9 508.2 513.0 542.3 578.1 37848.2 40331.8 40413.6 40428.8 40447.4 40892.4 41091.4 41204.0 41210.0 41353.8 9461.4 9699.5 9728.5 9730.4 9745.2 9516.6 9532.6 9549.1 9635.1 9639.2 9621.4 9657.4 9661.1 9689.0 9702.0 9597.9 9606.3 9645.7 9655.9 9694.0 9598.6 9666.2 9702.8 9718.8 9726.5 31912.8 39182.0 39290.4 39325.0 39437.6 3329.3 3330.6 3415.8 3931.9 4012.9 30329.4 30379.5 30762.0 31008.4 31520.0 3221.5 3222.7 3254.1 3324.8 3362.1 468201.0 472619.0 475367.0 475559.6 475779.0 32629.9 56559.4 56841.3 57106.7 57460.0 33246.0 34416.0 34557.1 34627.7 34715.0 12164.6 14539.3 14563.3 14724.0 14735.0 12524.9 12574.2 12596.9 12673.8 12714.9 7610.9 7653.9 7692.5 7707.9 7724.8 2888.0 3182.2 3196.5 3246.1 3251.1 768.9 971.4 997.7 1018.8 1108.7 26896.6 29653.1 30024.2 30186.4 30464.5 1008.8 1067.0 1089.0 1112.3 1114.7 14309.8 15529.5 16102.4 16360.5 16570.8 626157.0 630325.0 630592.0 631459.0 634624.0 52528.7 53726.2 53760.0 53918.0 54035.5 1334.3 1356.5 1380.8 1388.7 1391.2 27302.6 29611.3 29942.7 30210.8 30710.7 41383.8 45035.5 49218.0 49854.5 50084.0 588665.0 591437.0 594596.0 597138.0 598403.0 21992.5 25419.2 26834.5 27139.2 27758.3 45889.3 46182.0 46421.3 46483.0 47556.0 33511.2 37570.8 37693.8 38083.6 38258.4 32468.0 38336.2 38579.8 38943.0 39362.0 29890.7 30522.5 37317.3 38321.8 38397.8 25179.7 26493.6 34253.0 34272.1 34353.8 25017.5 25253.7 25275.7 25556.7 25703.4 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#2] jquery.html Cycles:180 Average:34089.47 Median:11318.35 stddev:94878.75 (838.3%) stddev-sans-first:95111.00 18:50:48 INFO - PROCESS | 1975 | Values: 308.6 366.1 371.5 380.4 400.7 18496.2 18937.5 19342.0 19505.5 20588.6 16081.4 19768.7 19890.4 20329.0 20408.3 5846.2 5897.0 6092.2 6101.1 6115.1 5784.5 5957.3 5983.6 5988.6 6005.4 4637.7 5872.5 5920.8 5923.2 5973.2 5912.3 6036.9 6048.5 6049.6 6068.9 5393.0 5410.3 5625.1 5864.2 5924.5 13062.6 13309.0 13353.9 13491.1 13508.9 2048.1 2133.2 2184.8 2285.3 2404.7 10031.3 10258.2 10259.9 10508.8 10564.8 1776.8 1806.7 1850.6 1856.1 1892.9 105046.5 125075.0 125540.4 125944.4 128431.1 10997.8 11324.4 11349.3 11363.7 11367.3 14107.9 14186.6 14211.9 14245.4 14255.6 6859.5 8141.5 8234.3 8588.2 8599.9 7181.8 7351.8 7394.0 7969.0 8614.5 3697.6 4384.4 4434.3 4453.8 4529.9 1628.9 1904.6 1939.2 1952.9 1977.3 820.8 826.9 870.9 930.8 953.7 19309.3 20838.5 20990.5 21104.9 21212.9 701.0 874.7 941.1 959.8 1019.4 10372.7 10758.4 10793.6 10806.8 10837.6 523597.0 564277.0 564908.0 568375.2 592014.0 134160.3 135522.3 138846.0 139348.0 140384.0 1009.1 1015.4 1020.4 1033.1 1035.3 18062.2 18157.8 18214.8 18257.0 18333.3 49268.8 54300.5 55359.2 55388.7 55994.0 80528.8 82502.6 82547.8 83280.6 85944.0 6937.2 7206.0 7276.7 7483.4 7536.0 11359.8 12620.7 12774.0 13107.7 13204.2 10614.6 10756.6 11071.6 11086.1 11318.8 9378.7 10518.9 11009.3 11257.3 11442.8 11247.3 11377.4 11628.7 11710.4 11783.5 6026.5 8076.9 8233.5 8264.3 8448.6 10675.5 10747.0 11018.2 11243.3 11575.8 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#3] mootools.html Cycles:180 Average:5642.01 Median:4959.38 stddev:3169.56 (63.9%) stddev-sans-first:3162.92 18:50:48 INFO - PROCESS | 1975 | Values: 1467.4 1516.7 1742.2 1817.4 1879.5 6540.8 8361.2 8511.0 8937.4 8971.7 8530.4 8716.5 8748.0 8748.1 8798.3 2314.7 2359.9 2362.8 2362.9 2365.4 2333.3 2359.3 2368.4 2376.2 2377.3 2349.5 2368.1 2369.9 2381.7 2383.0 1995.8 2335.6 2344.5 2348.7 2356.2 1947.3 2107.2 2211.1 2352.0 2354.3 8721.9 8785.7 8803.8 8853.9 8857.2 5179.2 5267.3 5327.7 5339.3 5581.2 2525.4 2974.4 3386.3 3417.6 3480.8 1095.6 1098.1 1101.4 1108.0 1118.8 11524.0 11576.8 11657.6 11748.1 11773.4 4042.1 4080.2 4101.5 4548.3 4680.3 8951.9 9758.2 9893.4 9921.0 9923.3 5307.2 5311.5 5319.8 5330.6 5335.1 4327.8 4683.6 4804.5 4848.2 4854.0 3811.3 3879.0 3930.6 3953.9 3977.5 1092.5 1284.4 1290.7 1294.4 1298.7 786.5 842.2 873.4 898.1 899.9 5571.8 5638.6 5854.6 5903.8 5915.7 985.4 1013.2 1036.0 1043.7 1082.3 3751.7 3898.8 3950.1 4048.5 4052.4 9769.3 10113.9 10532.2 10805.1 10853.8 9830.3 10724.5 10764.6 10765.8 10778.9 7581.8 8006.8 8100.4 8137.3 8153.7 5693.7 5886.8 5939.4 6024.7 6130.8 9769.1 9818.7 9862.2 9912.5 10016.5 9513.4 9891.8 9919.5 10083.5 10115.2 7476.7 7846.3 8013.0 8078.1 8259.6 8352.9 8400.5 8411.5 8412.2 8419.7 7011.9 7020.0 7149.1 7221.3 7359.6 7051.5 7067.6 7106.3 7121.8 7220.9 6702.0 6891.4 6978.6 7014.1 7019.8 4859.9 4916.2 4920.0 4937.3 5062.1 4609.2 4894.5 4981.4 4999.6 5022.0 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#4] prototype.html Cycles:180 Average:4748.34 Median:5151.41 stddev:1546.99 (30.0%) stddev-sans-first:1514.54 18:50:48 INFO - PROCESS | 1975 | Values: 280.0 291.8 296.9 304.8 321.4 4675.8 5290.6 5354.9 5434.3 5487.9 5582.0 5673.7 5709.6 5720.6 5724.9 4282.6 4359.0 4367.0 4373.4 4378.5 3638.8 3984.3 4235.3 4305.6 4384.8 3598.0 3683.0 3874.7 4212.1 4221.0 3897.9 4234.9 4238.7 4287.5 4288.2 3535.5 3651.8 3655.6 3668.5 3679.1 4552.7 5076.4 5453.5 5498.7 5522.4 4175.5 4677.3 4803.5 4865.2 4915.8 4690.2 5660.5 5991.9 5993.6 6038.6 3171.2 3502.9 3524.5 3862.0 3981.6 5546.0 5741.4 6323.7 6382.8 6450.8 3391.5 4057.6 4069.8 4079.8 4082.1 3466.5 3821.3 3880.0 4105.3 4107.1 4821.6 4877.1 4892.7 4911.8 4938.8 5005.8 5436.0 5779.3 6137.6 6160.7 1243.4 1375.3 1410.0 1412.0 1414.6 826.0 833.3 847.4 854.1 860.4 4196.6 4346.0 4392.4 4405.9 4854.0 4253.8 4653.2 5200.5 5279.7 5291.9 4194.5 4548.2 4586.1 4754.9 4796.2 2633.2 2791.5 2890.7 3303.4 3310.3 6532.5 6534.1 6536.0 6539.2 6575.2 6118.1 6414.7 6444.1 6455.5 6479.5 6483.4 6501.8 6516.0 6532.4 6537.5 5389.5 5680.8 6219.8 6252.2 6303.2 5576.7 5704.7 5992.9 6185.6 6193.2 5477.7 6278.8 6543.0 6544.9 6560.4 3682.2 3731.9 4084.2 4093.4 4107.9 6007.5 6301.4 6315.3 6333.3 6344.7 5956.6 6084.8 6097.5 6111.6 6118.9 5115.1 5296.4 5336.4 6091.1 6116.1 4992.5 5187.7 5788.3 5947.0 6044.2 5101.3 6078.7 6117.3 6117.9 6138.4 4515.0 5861.7 5981.6 6009.2 6020.6 18:50:48 INFO - PROCESS | 1975 | 18:50:48 INFO - PROCESS | 1975 | [#5] yui.html Cycles:180 Average:25060.92 Median:3668.71 stddev:40706.72 (1109.6%) stddev-sans-first:40779.78 18:50:48 INFO - PROCESS | 1975 | Values: 690.8 702.3 719.9 761.6 839.0 15104.2 16281.0 16297.8 16302.3 16366.6 14217.6 14757.6 14895.1 14917.3 15001.2 775.3 784.7 790.2 863.8 865.0 683.4 841.6 868.2 870.7 872.6 913.2 914.7 914.8 916.9 917.9 887.9 910.5 912.0 912.6 914.0 667.1 669.9 671.5 672.1 672.5 14936.1 14947.1 14957.3 14996.1 15008.8 14350.6 14497.8 14525.2 14596.4 14743.3 11744.6 11884.3 12100.2 12155.3 12416.5 3750.6 3777.8 3941.6 3999.1 4002.3 114451.0 115327.0 116019.8 116830.0 119668.4 10942.8 11130.4 11193.0 11237.8 11430.0 19210.4 22812.4 22972.2 22981.9 23483.6 7985.9 7999.2 8047.2 8057.3 8109.1 4401.8 4432.3 4449.1 4455.3 4495.0 3569.6 3624.6 3663.0 3674.4 3689.0 649.9 652.6 654.7 654.9 659.6 545.0 551.7 588.3 606.9 612.5 8856.8 9383.2 9511.8 9512.9 9828.6 572.3 614.9 646.5 658.2 694.3 4417.0 4614.7 4672.2 4680.8 4797.9 147390.8 155294.0 163921.0 164964.0 173302.1 77665.0 104052.0 138065.3 145253.3 147409.0 109760.9 110649.0 112055.4 113455.0 113476.0 8851.8 8870.4 8929.4 9030.7 9251.8 40804.8 56479.7 57498.0 63249.1 65832.4 66122.0 87147.0 91376.1 93253.0 95017.5 11076.0 13022.3 13298.6 13386.5 13797.0 14938.8 14993.7 15000.5 15110.2 15210.9 13477.8 13660.9 13740.3 14056.3 14676.6 11182.7 11977.8 13445.2 13972.4 13991.9 13586.3 13608.3 13978.5 14390.9 14512.7 11001.4 11304.9 11328.2 11361.7 11653.9 9749.3 11411.5 11508.4 11616.8 11659.8 18:50:48 INFO - PROCESS | 1975 | -------- Summary: end -------- 18:50:48 INFO - PROCESS | 1975 | 18:50:49 INFO - TEST-INFO | 1975: exit 0 18:50:49 INFO - TEST-OK | dromaeo_css | took 1113814ms 18:50:49 INFO - TEST-START | kraken 18:50:49 INFO - operating with platform_type : mac_ 18:50:49 INFO - Initialising browser for kraken test... 18:50:49 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpoUF779/profile http://localhost:51367/getInfo.html) 18:50:52 INFO - PROCESS | 1981 | __metrics Screen width/height:1600/1200 18:50:52 INFO - PROCESS | 1981 | colorDepth:24 18:50:52 INFO - PROCESS | 1981 | Browser inner width/height: 1024/689 18:50:52 INFO - PROCESS | 1981 | __metrics 18:50:52 ERROR - PROCESS | 1981 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 18:50:52 INFO - TEST-INFO | 1981: exit 0 18:50:52 INFO - Browser initialized. 18:50:52 INFO - Running cycle 1/1 for kraken test... 18:50:52 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/76/zc2kqpz548q1by6kbrt7871800000w/T/tmpoUF779/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:51:04 INFO - PROCESS | 1983 | RSS: Main: 244039680 18:51:04 INFO - PROCESS | 1983 | 18:51:07 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-beat-detection) 18:51:08 INFO - PROCESS | 1983 | RSS: Main: 257470464 18:51:08 INFO - PROCESS | 1983 | 18:51:11 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-dft) 18:51:11 INFO - PROCESS | 1983 | RSS: Main: 265478144 18:51:11 INFO - PROCESS | 1983 | 18:51:14 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-fft) 18:51:15 INFO - PROCESS | 1983 | RSS: Main: 266043392 18:51:15 INFO - PROCESS | 1983 | 18:51:17 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51367/tests/kraken/driver.html?testName=audio-oscillator) 18:51:18 INFO - PROCESS | 1983 | RSS: Main: 268492800 18:51:18 INFO - PROCESS | 1983 | 18:51:20 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:51:20 INFO - PROCESS | 1983 | RSS: Main: 268988416 18:51:20 INFO - PROCESS | 1983 | 18:51:23 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-darkroom) 18:51:24 INFO - PROCESS | 1983 | RSS: Main: 269262848 18:51:24 INFO - PROCESS | 1983 | 18:51:27 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51367/tests/kraken/driver.html?testName=imaging-desaturate) 18:51:27 INFO - PROCESS | 1983 | RSS: Main: 269393920 18:51:27 INFO - PROCESS | 1983 | 18:51:31 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51367/tests/kraken/driver.html?testName=json-parse-financial) 18:51:31 INFO - PROCESS | 1983 | RSS: Main: 269860864 18:51:31 INFO - PROCESS | 1983 | 18:51:33 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:51:33 INFO - PROCESS | 1983 | RSS: Main: 269414400 18:51:33 INFO - PROCESS | 1983 | 18:51:35 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:51:36 INFO - PROCESS | 1983 | RSS: Main: 269598720 18:51:36 INFO - PROCESS | 1983 | 18:51:38 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:51:39 INFO - PROCESS | 1983 | RSS: Main: 269819904 18:51:39 INFO - PROCESS | 1983 | 18:51:42 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:51:42 INFO - PROCESS | 1983 | RSS: Main: 269864960 18:51:42 INFO - PROCESS | 1983 | 18:51:45 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:51:46 INFO - PROCESS | 1983 | RSS: Main: 270118912 18:51:46 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:51367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51367/tests/kraken/driver.html?testName=ai-astar) 18:51:48 INFO - PROCESS | 1983 | RSS: Main: 272330752 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | __start_tp_report 18:51:48 INFO - PROCESS | 1983 | _x_x_mozilla_page_load 18:51:48 INFO - PROCESS | 1983 | _x_x_mozilla_page_load_details 18:51:48 INFO - PROCESS | 1983 | |i|pagename|runs| 18:51:48 INFO - PROCESS | 1983 | |0;ai-astar;109;117;118;115;117;113;118;118;120;148 18:51:48 INFO - PROCESS | 1983 | |1;audio-beat-detection;147;134;170;161;109;135;132;112;162;112 18:51:48 INFO - PROCESS | 1983 | |2;audio-dft;197;197;203;237;197;196;200;192;200;191 18:51:48 INFO - PROCESS | 1983 | |3;audio-fft;64;81;74;148;60;59;57;61;140;68 18:51:48 INFO - PROCESS | 1983 | |4;audio-oscillator;68;72;112;69;70;78;98;57;59;115 18:51:48 INFO - PROCESS | 1983 | |5;imaging-gaussian-blur;89;87;101;86;88;87;88;87;77;132 18:51:48 INFO - PROCESS | 1983 | |6;imaging-darkroom;96;96;118;152;164;99;98;100;98;99 18:51:48 INFO - PROCESS | 1983 | |7;imaging-desaturate;93;91;145;141;91;129;152;91;125;152 18:51:48 INFO - PROCESS | 1983 | |8;json-parse-financial;52;55;56;56;54;58;58;56;57;55 18:51:48 INFO - PROCESS | 1983 | |9;json-stringify-tinderbox;63;70;68;75;73;81;73;68;69;71 18:51:48 INFO - PROCESS | 1983 | |10;stanford-crypto-aes;93;80;94;110;109;84;88;87;81;76 18:51:48 INFO - PROCESS | 1983 | |11;stanford-crypto-ccm;136;142;154;158;157;144;139;140;174;145 18:51:48 INFO - PROCESS | 1983 | |12;stanford-crypto-pbkdf2;171;172;173;173;174;171;172;173;169;175 18:51:48 INFO - PROCESS | 1983 | |13;stanford-crypto-sha256-iterative;68;70;66;69;69;68;68;67;70;68 18:51:48 INFO - PROCESS | 1983 | __end_tp_report 18:51:48 INFO - PROCESS | 1983 | __start_cc_report 18:51:48 INFO - PROCESS | 1983 | _x_x_mozilla_cycle_collect,956 18:51:48 INFO - PROCESS | 1983 | __end_cc_report 18:51:48 INFO - PROCESS | 1983 | __startTimestamp1472262708263__endTimestamp 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | ------- Summary: start ------- 18:51:48 INFO - PROCESS | 1983 | Number of tests: 14 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#0] ai-astar Cycles:10 Average:119.30 Median:118.00 stddev:10.56 (9.0%) stddev-sans-first:10.53 18:51:48 INFO - PROCESS | 1983 | Values: 109.0 117.0 118.0 115.0 117.0 113.0 118.0 118.0 120.0 148.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:141.00 stddev:22.26 (15.8%) stddev-sans-first:23.34 18:51:48 INFO - PROCESS | 1983 | Values: 147.0 134.0 170.0 161.0 109.0 135.0 132.0 112.0 162.0 112.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#2] audio-dft Cycles:10 Average:201.00 Median:198.50 stddev:13.15 (6.6%) stddev-sans-first:13.87 18:51:48 INFO - PROCESS | 1983 | Values: 197.0 197.0 203.0 237.0 197.0 196.0 200.0 192.0 200.0 191.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#3] audio-fft Cycles:10 Average:81.20 Median:62.50 stddev:33.96 (54.3%) stddev-sans-first:35.44 18:51:48 INFO - PROCESS | 1983 | Values: 64.0 81.0 74.0 148.0 60.0 59.0 57.0 61.0 140.0 68.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:69.50 stddev:21.02 (30.2%) stddev-sans-first:21.85 18:51:48 INFO - PROCESS | 1983 | Values: 68.0 72.0 112.0 69.0 70.0 78.0 98.0 57.0 59.0 115.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:87.00 stddev:15.12 (17.4%) stddev-sans-first:15.99 18:51:48 INFO - PROCESS | 1983 | Values: 89.0 87.0 101.0 86.0 88.0 87.0 88.0 87.0 77.0 132.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:97.00 stddev:25.22 (26.0%) stddev-sans-first:26.08 18:51:48 INFO - PROCESS | 1983 | Values: 96.0 96.0 118.0 152.0 164.0 99.0 98.0 100.0 98.0 99.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#7] imaging-desaturate Cycles:10 Average:121.00 Median:121.50 stddev:26.80 (22.1%) stddev-sans-first:26.44 18:51:48 INFO - PROCESS | 1983 | Values: 93.0 91.0 145.0 141.0 91.0 129.0 152.0 91.0 125.0 152.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.83 (3.3%) stddev-sans-first:1.36 18:51:48 INFO - PROCESS | 1983 | Values: 52.0 55.0 56.0 56.0 54.0 58.0 58.0 56.0 57.0 55.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:4.84 (6.7%) stddev-sans-first:4.15 18:51:48 INFO - PROCESS | 1983 | Values: 63.0 70.0 68.0 75.0 73.0 81.0 73.0 68.0 69.0 71.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:85.50 stddev:11.60 (13.6%) stddev-sans-first:12.26 18:51:48 INFO - PROCESS | 1983 | Values: 93.0 80.0 94.0 110.0 109.0 84.0 88.0 87.0 81.0 76.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:149.50 stddev:11.71 (7.8%) stddev-sans-first:11.46 18:51:48 INFO - PROCESS | 1983 | Values: 136.0 142.0 154.0 158.0 157.0 144.0 139.0 140.0 174.0 145.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.00 stddev:1.70 (1.0%) stddev-sans-first:1.74 18:51:48 INFO - PROCESS | 1983 | Values: 171.0 172.0 173.0 173.0 174.0 171.0 172.0 173.0 169.0 175.0 18:51:48 INFO - PROCESS | 1983 | 18:51:48 INFO - PROCESS | 1983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.50 stddev:1.25 (1.8%) stddev-sans-first:1.32 18:51:48 INFO - PROCESS | 1983 | Values: 68.0 70.0 66.0 69.0 69.0 68.0 68.0 67.0 70.0 68.0 18:51:48 INFO - PROCESS | 1983 | -------- Summary: end -------- 18:51:48 INFO - PROCESS | 1983 | 18:51:49 INFO - TEST-INFO | 1983: exit 0 18:51:49 INFO - TEST-OK | kraken | took 59668ms 18:51:49 INFO - SUITE-END | took 1173s 18:51:49 INFO - Completed test suite (00:19:33) 18:51:49 INFO - javascript benchmark 18:51:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7252.4273249858825, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.3918894598964, 3388.8983330500087, 3390.1524618845287, 3403.6766507181815, 3415.8292085395724, 5202.699325168708, 5260.973695131529, 5614.522765564921, 5678.772849086036, 5693.943060569398, 5656.123300888358, 5718.971405142968, 5736.254286942692, 5777.364489906107, 5810.099434587635, 5123.2827404163445, 5191.9616076784605, 5194.92207616886, 5248.6063545234065, 5303.416624171357, 5273.525382715555, 5291.867703307429, 5326.627136100475, 5338.973305133488, 5348.277982472356, 5080.1617733074, 5126.6923984561045, 5158.664686795384, 5199.012187684328, 5218.895622087537, 5201.08980928336, 5219.973900130513, 5234.214867769827, 5235.607329450307, 5236.764345604419, 5139.049275883974, 5162.199859021857, 5182.896342073175, 5197.246399272122, 5209.270702101709, 4636.86089417318, 5562.249096371978, 5597.272354593917, 5671.773129074873, 5712.200291959129, 6128.625347140861, 6163.599366041238, 6203.720832562545, 6205.81382558524, 6223.315435302113, 6183.381661833826, 6190.5976111553055, 6206.193194884681, 6220.0, 6229.0967809667345, 5684.431556844365, 5784.363719990798, 5793.0, 5794.362620111784, 5861.853453663693, 6244.718987645567, 6245.156903817927, 6248.4064013918605, 6260.092286618415, 6264.780732674334, 6073.423024812636, 6119.082137679295, 6168.25980882297, 6191.0, 6203.286622038433, 6096.1033937223965, 6110.3765049759795, 6149.985252433298, 6186.690665466709, 6236.469900058455, 5673.716314184274, 5720.313562372542, 6143.542582966849, 6221.129041934133, 6231.937680623227, 6174.012158054689, 6198.907016394758, 6203.16257305267, 6252.155958945484, 6254.906176407358, 4837.32277481153, 4873.26900964851, 4894.559489645949, 4913.140200464904, 4930.5069493050405, 4378.9928316487385, 4400.515943246241, 4415.337699345124, 4463.531329210451, 4488.461384633865, 5589.720513974285, 5740.454656807597, 5746.994276001683, 5751.1085781703905, 5772.047612144033, 6099.939000610026, 6150.323464418911, 6157.230346206724, 6223.0354295084335, 6246.875562398812, 5740.225069615549, 5769.509591684669, 5779.913301300484, 5793.159991801165, 5813.389594092644, 5376.946230537723, 5417.566594672417, 5425.593080518976, 5439.211314359396, 5439.891202175934, 6152.953997820471, 6235.501159907106, 6271.404216599416, 6305.148804911279, 6332.176817013851, 6185.567010309235, 6186.566940314135, 6205.007198848162, 6206.0, 6223.097650840693, 5358.33020872391, 6324.30432652417, 6333.240011198502, 6353.650549219652, 6362.045693146065, 5828.766849326149, 6095.207623008981, 6106.931287024664, 6191.25704915413, 6194.163787888758, 6043.244594425697, 6193.194884664965, 6222.315545290105, 6249.0, 6265.467435268053, 6125.142480052886, 6150.692465376803, 6152.230971128609, 6152.969235153795, 6157.722902469489, 5369.543588794996, 5976.073708575178, 6084.421979911901, 6142.9078563820685, 6148.538859585638, 4527.569880861313, 4898.771059167999, 5024.899502010013, 5743.798967036134, 6001.4298641628975, 6123.755049797958, 6131.662758548323, 6143.784967526115, 6148.44663980244, 6155.722992465439, 5866.560007999503, 5929.940700593112, 5938.346781854079, 6007.789727359522, 6017.849553761191], "value": 5651.93066485431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.88427893026744, 508.1792904459295, 513.0431744795955, 542.2841848759637, 578.1385735254472, 37848.24303513928, 40331.79834100829, 40413.59586404135, 40428.79785601075, 40447.393289100684, 40892.38661420081, 41091.38362924558, 41203.9699007525, 41209.96975075617, 41353.75938721836, 9461.432314061161, 9699.466529340883, 9728.464934428604, 9730.416175029486, 9745.220382369393, 9516.571754271074, 9532.571034303473, 9549.092836180587, 9635.132838044574, 9639.180669643089, 9621.37461065032, 9657.372270802376, 9661.130498255156, 9688.951555242249, 9702.0, 9597.904020959772, 9606.27952903528, 9645.662401815938, 9655.855162172502, 9694.03059694032, 9598.568064437048, 9666.178374838148, 9702.757431064209, 9718.757031074208, 9726.465044422554, 31912.840435797672, 39182.02044948871, 39290.41064384037, 39325.0, 39437.60562394368, 3329.267561136546, 3330.633630300665, 3415.777974431679, 3931.9017024574327, 4012.9398059028845, 30329.393412131856, 30379.544306835414, 30762.0, 31008.379832403225, 31520.0, 3221.5167724841463, 3222.661620529834, 3254.0725893120584, 3324.7672662913606, 3362.0922350965348, 468201.0, 472619.0, 475367.0, 475559.6222018902, 475779.0, 32629.879057861162, 56559.43440565624, 56841.29476115723, 57106.7144664274, 57460.0, 33246.0, 34415.96752097441, 34557.13607159841, 34627.65372346294, 34715.0, 12164.635060948185, 14539.3457294422, 14563.271836408137, 14724.0, 14735.0, 12524.874751252553, 12574.245545267313, 12596.937015314865, 12673.809892851614, 12714.936425318, 7610.9238907610215, 7653.8569200623915, 7692.538447693157, 7707.922920770833, 7724.806879827936, 2887.985560072186, 3182.188541921795, 3196.536502207167, 3246.0910944935454, 3251.138448311199, 768.873135932576, 971.3589031239349, 997.7428440165298, 1018.8181709217272, 1108.7228192951761, 26896.59655105175, 29653.110406687832, 30024.24939376533, 30186.396272074875, 30464.54303185454, 1008.8032833597379, 1067.0183431243274, 1089.0307626212518, 1112.3214838948236, 1114.6878873915316, 14309.78535321971, 15529.45646902353, 16102.43641472543, 16360.509184724477, 16570.83429165693, 626157.0, 630325.0, 630592.0, 631459.0, 634624.0, 52528.73735631374, 53726.19410708843, 53760.0, 53918.03450810243, 54035.459645403826, 1334.3328335832084, 1356.525216174331, 1380.7997840313299, 1388.7430825297554, 1391.248725688117, 27302.590461142703, 29611.259718507208, 29942.70057299486, 30210.77631729063, 30710.692893070784, 41383.75848724543, 45035.54964450314, 49218.0, 49854.501454986435, 50084.0, 588665.0, 591437.0, 594596.0, 597138.0, 598403.0, 21992.45018874541, 25419.237422877344, 26834.463310734067, 27139.185824425298, 27758.306042348297, 45889.311660324456, 46182.0, 46421.30368044414, 46483.0, 47556.0, 33511.16222094467, 37570.8121459391, 37693.81153094217, 38083.619163808005, 38258.42612360761, 32468.0, 38336.233275333776, 38579.80710096544, 38943.0, 39362.0, 29890.70109298879, 30522.542161868034, 37317.25365492729, 38321.808390958984, 38397.808010959765, 25179.74820251774, 26493.602595960692, 34253.0, 34272.143196420286, 34353.828230858686, 25017.499650007263, 25253.747462525873, 25275.74724252734, 25556.744432555435, 25703.357416064748], "value": 18347.624142235283, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.62964442668795, 366.1487042625894, 371.47992810065915, 380.38567713143283, 400.7077176107055, 18496.16767245474, 18937.526561835963, 19342.0, 19505.51236219095, 20588.588228235425, 16081.356745730156, 19768.70346944797, 19890.40328790135, 20329.0, 20408.285710000153, 5846.181534585151, 5897.045992770784, 6092.177556029946, 6101.145839582462, 6115.143879856823, 5784.508316793078, 5957.255343082114, 5983.611065280743, 5988.640681559121, 6005.429484199016, 4637.675362724611, 5872.500837428824, 5920.763169473216, 5923.200367950336, 5973.193618861464, 5912.260967379078, 6036.879262414727, 6048.455638992467, 6049.637021778707, 6068.908966365508, 5392.973035134838, 5410.269613602152, 5625.128105143709, 5864.237649105632, 5924.4667979882015, 13062.608121756362, 13309.0, 13353.931685465139, 13491.055626106176, 13508.93245533776, 2048.109072553444, 2133.2107120365563, 2184.7924447177493, 2285.337252196861, 2404.701461210944, 10031.347962382495, 10258.230632702502, 10259.897401025968, 10508.842367364496, 10564.841527377019, 1776.7740259221096, 1806.683830329687, 1850.620622772342, 1856.118343786704, 1892.8769629974008, 105046.47476762567, 125075.0, 125540.37229813793, 125944.37027814894, 128431.07353389707, 10997.780044399067, 11324.37715925632, 11349.319040857576, 11363.659090227306, 11367.261128026652, 14107.858921410654, 14186.645333866736, 14211.928940355232, 14245.430182792572, 14255.572332830032, 6859.485538584626, 8141.453123906439, 8234.341252699769, 8588.22705956467, 8599.914000859912, 7181.820454488575, 7351.779446616503, 7394.0, 7969.003874515685, 8614.526201058876, 3697.556293244828, 4384.430024096847, 4434.290513517822, 4453.821847126079, 4529.909401811945, 1628.8778341624188, 1904.561950751335, 1939.224310275901, 1952.8730632508841, 1977.317825350252, 820.819419727659, 826.8883700700328, 870.8737233101165, 930.8231436027133, 953.713885834247, 19309.324173653855, 20838.479038024172, 20990.47523811917, 21104.894475527522, 21212.893935530225, 700.9789706308819, 874.6763697431991, 941.110650435332, 959.788846453779, 1019.403648865422, 10372.740681482872, 10758.354498730101, 10793.625402411457, 10806.787585731565, 10837.620683276047, 523597.0, 564277.0, 564908.0, 568375.158124215, 592014.0, 134160.32919835337, 135522.32238838743, 138846.0, 139348.0, 140384.0, 1009.1119814563284, 1015.3603229965074, 1020.4030642074321, 1033.0547548992588, 1035.2597892506894, 18062.187201575696, 18157.818421815613, 18214.817851821314, 18257.0, 18333.26666933361, 49268.75365623149, 54300.45699543112, 55359.169612455036, 55388.72305638446, 55994.0, 80528.79206811785, 82502.58748706218, 82547.76178357209, 83280.58359708163, 85944.0, 6937.167539895245, 7206.0, 7276.745313913988, 7483.401327893865, 7535.962320188364, 11359.829602555803, 12620.747585048432, 12774.0, 13107.67230819199, 13204.207747535178, 10614.575416983234, 10756.623518176826, 11071.5571377144, 11086.064447121478, 11318.830217546909, 9378.718638440858, 10518.947405262925, 11009.2843965142, 11257.268277561932, 11442.771144577227, 11247.268927519684, 11377.374244416636, 11628.709282268012, 11710.414479276173, 11783.528658853893, 6026.548008899263, 8076.87884681742, 8233.46482478637, 8264.256216940505, 8448.577571121297, 10675.46622668879, 10747.0, 11018.228723989243, 11243.325400475998, 11575.826362604736], "value": 9263.772284466795, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.4497063601148, 1516.681496885654, 1742.1724680776633, 1817.4184261036473, 1879.4643526594914, 6540.803775886731, 8361.247487726103, 8510.957445212765, 8937.419067760591, 8971.73084807456, 8530.360222983285, 8716.477011379322, 8748.037715851267, 8748.081451447602, 8798.252148567379, 2314.6990891184123, 2359.9292021239303, 2362.763723627632, 2362.9054837806552, 2365.4086478380405, 2333.288347054148, 2359.327591636384, 2368.4078980254935, 2376.1683410806254, 2377.3343463830156, 2349.459624286407, 2368.14746691191, 2369.8933547990296, 2381.6546600742968, 2382.9642555361684, 1995.7904420035832, 2335.556244313592, 2344.460774021976, 2348.7064116985375, 2356.222446592629, 1947.3087054095733, 2107.1782005018053, 2211.1045026764186, 2351.9647205291935, 2354.2701762453694, 8721.912780872173, 8785.736427907173, 8803.77990550235, 8853.911460885372, 8857.20285174331, 5179.19722443022, 5267.341582302212, 5327.680339179623, 5339.305890234284, 5581.162825576155, 2525.419153594665, 2974.375381169957, 3386.255023894739, 3417.624061353249, 3480.7737497062863, 1095.6329629574022, 1098.0556721219746, 1101.3502033800016, 1107.9501422435928, 1118.848955391028, 11524.0, 11576.826347604709, 11657.591984280509, 11748.118891083117, 11773.352465614387, 4042.130941847517, 4080.224757296104, 4101.46680931477, 4548.317752337176, 4680.297955306732, 8951.910480895238, 9758.219342452587, 9893.406395616286, 9920.950395247977, 9923.255755818342, 5307.150855863043, 5311.49540793624, 5319.840404787863, 5330.600204984642, 5335.146376579729, 4327.76197309151, 4683.555062269079, 4804.543568361, 4848.151573474628, 4854.004928989549, 3811.294910441577, 3879.010852232719, 3930.6265904739003, 3953.940690889639, 3977.463042489285, 1092.4592326798286, 1284.4329917500634, 1290.664427248922, 1294.4304506017322, 1298.7142828577698, 786.5045021636332, 842.2419822159983, 873.3581164807931, 898.1333013641867, 899.9415038022638, 5571.777128914798, 5638.577106717013, 5854.648721076749, 5903.793367232126, 5915.674637894871, 985.3644399362514, 1013.2350075692952, 1035.9792804143874, 1043.749500119981, 1082.2694681090234, 3751.662350388478, 3898.8440462381755, 3950.071733142709, 4048.473698419187, 4052.412400202013, 9769.267304952156, 10113.898861011443, 10532.21008424356, 10805.081568066646, 10853.782924341469, 9830.262730295257, 10724.463776811128, 10764.623238186627, 10765.83851242232, 10778.892211077946, 7581.848363032819, 8006.759797206093, 8100.351971842357, 8137.3490120791375, 8153.714619988272, 5693.715314234355, 5886.823395298148, 5939.406059394026, 6024.698765061817, 6130.754769809336, 9769.120779129913, 9818.65634702782, 9862.211023118276, 9912.454814984956, 10016.499175041074, 9513.429194248367, 9891.752706182115, 9919.504024798876, 10083.495825208856, 10115.241356898408, 7476.70093196266, 7846.33306168982, 8012.959935200287, 8078.111407745261, 8259.587020648823, 8352.916470835215, 8400.453970491899, 8411.45325553837, 8412.158784121539, 8419.663213471393, 7011.859762804817, 7020.0, 7149.14210294768, 7221.302993796348, 7359.632018399166, 7051.541649792747, 7067.646617668993, 7106.324899134574, 7121.786346409616, 7220.89168662481, 6701.966490167518, 6891.3797758202, 6978.581285122909, 7014.0531028310525, 7019.824504387431, 4859.854204373874, 4916.188828843201, 4920.0, 4937.333459982857, 5062.114130027304, 4609.1703493371515, 4894.535019173173, 4981.42713587942, 4999.600031997359, 5022.0], "value": 4541.728535793141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.9669220576074, 291.82927987127516, 296.88815386378013, 304.8110171693549, 321.3556818578749, 4675.8363457279, 5290.550303224221, 5354.875476150003, 5434.2935418395655, 5487.917681234775, 5581.972090139554, 5673.744681489333, 5709.628874123179, 5720.599558030939, 5724.942750572503, 4282.6145646891855, 4358.97820510897, 4366.956330436687, 4373.431453910987, 4378.496472905625, 3638.781673099609, 3984.342583473723, 4235.301175306071, 4305.569443055685, 4384.802683879233, 3597.9640203598024, 3682.981585092071, 3874.670652994499, 4212.08350200658, 4221.00308780766, 3897.9025524361978, 4234.9364759528635, 4238.724482908632, 4287.506936702288, 4288.249556327662, 3535.540379750616, 3651.7991510466913, 3655.6161603031833, 3668.5047518584915, 3679.1354031802507, 4552.726836389827, 5076.390833100015, 5453.481919217668, 5498.697571633558, 5522.447755224485, 4175.54069052404, 4677.321788340706, 4803.495632958559, 4865.245886887503, 4915.778789954461, 4690.249560070372, 5660.518855897254, 5991.910121348184, 5993.550483713695, 6038.57729958903, 3171.2230503526553, 3502.9474557881654, 3524.4537096750046, 3861.9806900965314, 3981.562028176927, 5545.972270138624, 5741.425857414267, 6323.747050118036, 6382.776602818925, 6450.838729031765, 3391.5251864738957, 4057.634812866856, 4069.7965101744794, 4079.8164082616354, 4082.101937573729, 3466.5493485847182, 3821.293060783764, 3879.9806000969816, 4105.261053010487, 4107.137501124737, 4821.614270858323, 4877.122118018719, 4892.706437613754, 4911.778969946361, 4938.765308672831, 5005.82479613219, 5435.972820135874, 5779.2775903012125, 6137.5703700741415, 6160.691965401712, 1243.3969524780475, 1375.2985977151525, 1410.0411720030477, 1411.9929400353137, 1414.5826981040618, 825.9793505162419, 833.3458235285271, 847.3687103108224, 854.1373213054859, 860.4407135362064, 4196.601322874383, 4345.97827010863, 4392.407025051641, 4405.889852753707, 4853.975730121398, 4253.808578613908, 4653.184823181994, 5200.5297483695695, 5279.673805502509, 5291.947080529146, 4194.454720886265, 4548.180727708943, 4586.082783443325, 4754.881127971828, 4796.218193447792, 2633.1837130489607, 2791.4696207720467, 2890.7253810887933, 3303.3888730584918, 3310.278655099335, 6532.477401807844, 6534.066779581386, 6536.013032050446, 6539.2479864815205, 6575.243846957565, 6118.082287656887, 6414.711337989802, 6444.065610486453, 6455.4835613150835, 6479.546431749733, 6483.384078512534, 6501.781618098977, 6515.967420162963, 6532.444742196966, 6537.52905596237, 5389.541888939327, 5680.801171958961, 6219.844503887439, 6252.217763095379, 6303.243610766737, 5576.665400076008, 5704.719786122913, 5992.880142397041, 6185.597936134137, 6193.213054125751, 5477.726113694379, 6278.843028924313, 6542.967285163544, 6544.869102618016, 6560.376764207393, 3682.15892053973, 3731.936398126521, 4084.244414783275, 4093.3859921012086, 4107.876763697094, 6007.519398448027, 6301.369863013662, 6315.273743519554, 6333.290011696909, 6344.651044192429, 5956.642601443928, 6084.756609735561, 6097.50002498991, 6111.602745821508, 6118.877622447615, 5115.104856649997, 5296.417394086724, 5336.359636843604, 6091.111755355854, 6116.094854943866, 4992.478798206882, 5187.714675692873, 5788.27646544182, 5947.04847224442, 6044.15381846533, 5101.285819985279, 6078.7264573093435, 6117.3270940197435, 6117.938820611915, 6138.439704971195, 4514.961558841419, 5861.706914654165, 5981.581289309706, 6009.248843894513, 6020.608660437058], "value": 4247.126008299035, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.7858563845206, 702.3257672634271, 719.910011248594, 761.6229966166745, 838.9874151887723, 15104.169270690109, 16281.0, 16297.83702162978, 16302.347906083742, 16366.59083522911, 14217.573472795806, 14757.557273281793, 14895.106293622363, 14917.32872020759, 15001.174935378549, 775.2944820213606, 784.6900474312644, 790.2374208888422, 863.7840539865034, 864.9740507784777, 683.4361651637395, 841.5623875584693, 868.2055918834291, 870.7213691618684, 872.6029656506274, 913.1963871792847, 914.6844338703137, 914.7804526913559, 916.9266458683289, 917.9036201198878, 887.8668199770021, 910.544727636182, 912.0241341764315, 912.5528491039377, 913.9946059334778, 667.0760996020498, 669.8961660942562, 671.5299290496623, 672.1295921781305, 672.4754691340762, 14936.103833770008, 14947.103173809497, 14957.252137393087, 14996.100233985995, 15008.849911500854, 14350.64123396913, 14497.782533262009, 14525.201113938729, 14596.416143354252, 14743.262836858114, 11744.647660570197, 11884.346360950145, 12100.213486123463, 12155.331456769874, 12416.503339866395, 3750.643688849555, 3777.8111094445444, 3941.6452519273403, 3999.120193557442, 4002.2795896738867, 114451.0, 115327.0, 116019.83980160175, 116830.0, 119668.40165799203, 10942.835857462145, 11130.387828669502, 11193.0, 11237.775244495064, 11430.028447582048, 19210.423687289403, 22812.4296892579, 22972.19597314086, 22981.885090574775, 23483.647745283834, 7985.920140798634, 7999.200079991954, 8047.195280472023, 8057.274845263954, 8109.107998120202, 4401.823927042947, 4432.29083346663, 4449.087936972913, 4455.309427038814, 4494.977525112354, 3569.607343192247, 3624.637536246354, 3663.0476076220375, 3674.375356189454, 3689.0, 649.9057636642755, 652.6247407740568, 654.6563054396385, 654.8755736410067, 659.5515049766205, 545.0187474514399, 551.7296524702947, 588.2764200033982, 606.9006484827466, 612.5161122713007, 8856.778580535538, 9383.234068785896, 9511.762205944768, 9512.90487095134, 9828.606855725691, 572.2732130194629, 614.9108379284978, 646.5215740352199, 658.2167221006987, 694.2814187316104, 4416.977915110404, 4614.676972611952, 4672.182368085571, 4680.836170734008, 4797.904041919142, 147390.789138163, 155294.0, 163921.0, 164964.0, 173302.13348933426, 77665.0, 104052.0, 138065.309673453, 145253.27373363066, 147409.0, 109760.90239097667, 110649.0, 112055.43972280086, 113455.0, 113476.0, 8851.822963540822, 8870.42342247752, 8929.419587726778, 9030.683926062557, 9251.76870578241, 40804.79597601993, 56479.71760141173, 57498.0, 63249.051264231995, 65832.39173257825, 66122.0, 87147.0, 91376.08623913942, 93253.0, 95017.524912375, 11076.0, 13022.2837743925, 13298.601041968757, 13386.531471398454, 13796.965227607769, 14938.77591836145, 14993.700125997637, 15000.549983500512, 15110.244487775788, 15210.923945380646, 13477.797833032317, 13660.93169534146, 13740.312984350943, 14056.297185140498, 14676.633084172981, 11182.720431989266, 11977.820332695192, 13445.193288402726, 13972.371243293874, 13991.860081399056, 13586.32068396596, 13608.251546165056, 13978.510752123628, 14390.92068094918, 14512.709745805236, 11001.394923279297, 11304.886951130384, 11328.150388720715, 11361.659150225507, 11653.88346116528, 9749.317547771872, 11411.486483108447, 11508.446703543064, 11616.767664646492, 11659.766804664028], "value": 7851.9620034755635, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 118.0, 115.0, 117.0, 113.0, 118.0, 118.0, 120.0, 148.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 134.0, 170.0, 161.0, 109.0, 135.0, 132.0, 112.0, 162.0, 112.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 197.0, 203.0, 237.0, 197.0, 196.0, 200.0, 192.0, 200.0, 191.0], "value": 201.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 81.0, 74.0, 148.0, 60.0, 59.0, 57.0, 61.0, 140.0, 68.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 112.0, 69.0, 70.0, 78.0, 98.0, 57.0, 59.0, 115.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 101.0, 86.0, 88.0, 87.0, 88.0, 87.0, 77.0, 132.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 118.0, 152.0, 164.0, 99.0, 98.0, 100.0, 98.0, 99.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 145.0, 141.0, 91.0, 129.0, 152.0, 91.0, 125.0, 152.0], "value": 121.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 56.0, 56.0, 54.0, 58.0, 58.0, 56.0, 57.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 68.0, 75.0, 73.0, 81.0, 73.0, 68.0, 69.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 80.0, 94.0, 110.0, 109.0, 84.0, 88.0, 87.0, 81.0, 76.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 154.0, 158.0, 157.0, 144.0, 139.0, 140.0, 174.0, 145.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 173.0, 173.0, 174.0, 171.0, 172.0, 173.0, 169.0, 175.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 66.0, 69.0, 69.0, 68.0, 68.0, 67.0, 70.0, 68.0], "value": 68.3, "unit": "score"}]}]} 18:51:49 INFO - Return code: 0 18:51:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:51:49 ERROR - # TBPL SUCCESS # 18:51:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:49 INFO - 2016-08-26 18:51:49.577 screenresolution[1988:16798] starting screenresolution argv=screenresolution get 18:51:49 INFO - 2016-08-26 18:51:49.580 screenresolution[1988:16798] Display 0: 1600x1200x32@60 18:51:49 INFO - 2016-08-26 18:51:49.586 screenresolution[1989:16802] starting screenresolution argv=screenresolution list 18:51:49 INFO - Available Modes on Display 0 18:51:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:49 INFO - Intel Iris: 18:51:49 INFO - Chipset Model: Intel Iris 18:51:49 INFO - Type: GPU 18:51:49 INFO - Bus: Built-In 18:51:49 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:49 INFO - Vendor: Intel (0x8086) 18:51:49 INFO - Device ID: 0x0a2e 18:51:49 INFO - Revision ID: 0x0009 18:51:49 INFO - Displays: 18:51:49 INFO - Display: 18:51:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:49 INFO - Display Serial Number: 1600x1200 60 18:51:49 INFO - Main Display: Yes 18:51:49 INFO - Mirror: Off 18:51:49 INFO - Online: Yes 18:51:49 INFO - Rotation: Supported 18:51:49 INFO - Return code: 0 18:51:49 INFO - Running post-action listener: _resource_record_post_action 18:51:49 INFO - [mozharness: 2016-08-27 01:51:49.818874Z] Finished run-tests step (success) 18:51:49 INFO - Running post-run listener: _resource_record_post_run 18:51:49 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 790528; Write bytes: 707463168; Read time: 10; Write time: 2123 18:51:49 INFO - TinderboxPrint: CPU usage
54.4% 18:51:49 INFO - TinderboxPrint: I/O read bytes / time
790,528 / 10 18:51:49 INFO - TinderboxPrint: I/O write bytes / time
707,463,168 / 2,123 18:51:49 INFO - TinderboxPrint: CPU idle
2,204.1 (45.6%) 18:51:49 INFO - TinderboxPrint: CPU system
978.0 (20.2%) 18:51:49 INFO - TinderboxPrint: CPU user
1,655.6 (34.2%) 18:51:49 INFO - TinderboxPrint: Swap in / out
407,068,672 / 0 18:51:49 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392151552; Write bytes: 387585536; Read time: 29544; Write time: 719 18:51:49 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 786432; Write bytes: 297017856; Read time: 10; Write time: 1356 18:51:50 INFO - Running post-run listener: _upload_blobber_files 18:51:50 INFO - Blob upload gear active. 18:51:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:51:50 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 18:51:50 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'] 18:51:50 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 18:51:50 INFO - (blobuploader) - INFO - Open directory for files ... 18:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:51 INFO - (blobuploader) - INFO - Done attempting. 18:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:51:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:52 INFO - (blobuploader) - INFO - Done attempting. 18:51:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:51:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:51:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:51:54 INFO - (blobuploader) - INFO - Done attempting. 18:51:54 INFO - (blobuploader) - INFO - Iteration through files over. 18:51:54 INFO - Return code: 0 18:51:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:51:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:51:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f1445ba41d006cd32a440c4f36f3c32a051abcd6c67f3f232ead01b017e040384985d2a29c8cacf4f6e189b80db78aa3e545010dd372d735a9d03c6ea312c75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b532ae268bb63fcd85d830868ccb8f8cb3c316f775e0ead0586814b100dc39eb2bc39e4142f97cf5c5660d7113e13d706b81be15fa4d709d7e2d62b334203d22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1c33f909e6ec70adbd5943c6e3af748c7c10f2f56cb92f308acff084ce5c5b3d66f58201630314eb90ff0025d2b9250aea235fd0a49952822516870a526bb81"} 18:51:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:51:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:51:54 INFO - Contents: 18:51:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f1445ba41d006cd32a440c4f36f3c32a051abcd6c67f3f232ead01b017e040384985d2a29c8cacf4f6e189b80db78aa3e545010dd372d735a9d03c6ea312c75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b532ae268bb63fcd85d830868ccb8f8cb3c316f775e0ead0586814b100dc39eb2bc39e4142f97cf5c5660d7113e13d706b81be15fa4d709d7e2d62b334203d22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1c33f909e6ec70adbd5943c6e3af748c7c10f2f56cb92f308acff084ce5c5b3d66f58201630314eb90ff0025d2b9250aea235fd0a49952822516870a526bb81"} 18:51:54 INFO - Running post-run listener: copy_logs_to_upload_dir 18:51:54 INFO - Copying logs to upload dir... 18:51:54 INFO - mkdir: /builds/slave/test/build/upload/logs 18:51:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.252186 ========= master_lag: 2.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-26 18:51:57.225637) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-08-26 18:51:57.233022) ========= 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.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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/9f1445ba41d006cd32a440c4f36f3c32a051abcd6c67f3f232ead01b017e040384985d2a29c8cacf4f6e189b80db78aa3e545010dd372d735a9d03c6ea312c75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b532ae268bb63fcd85d830868ccb8f8cb3c316f775e0ead0586814b100dc39eb2bc39e4142f97cf5c5660d7113e13d706b81be15fa4d709d7e2d62b334203d22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1c33f909e6ec70adbd5943c6e3af748c7c10f2f56cb92f308acff084ce5c5b3d66f58201630314eb90ff0025d2b9250aea235fd0a49952822516870a526bb81"} build_url:https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/Kj32OjXdSfmiPTBbeGA2jQ/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/9f1445ba41d006cd32a440c4f36f3c32a051abcd6c67f3f232ead01b017e040384985d2a29c8cacf4f6e189b80db78aa3e545010dd372d735a9d03c6ea312c75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b532ae268bb63fcd85d830868ccb8f8cb3c316f775e0ead0586814b100dc39eb2bc39e4142f97cf5c5660d7113e13d706b81be15fa4d709d7e2d62b334203d22", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1c33f909e6ec70adbd5943c6e3af748c7c10f2f56cb92f308acff084ce5c5b3d66f58201630314eb90ff0025d2b9250aea235fd0a49952822516870a526bb81"}' ========= master_lag: 4.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-08-26 18:52:01.310079) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:52:01.310452) ========= 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.0qnDbOWneN/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.btqpBsOp2g/Listeners TMPDIR=/var/folders/76/zc2kqpz548q1by6kbrt7871800000w/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.005321 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 18:52:01.356149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:52:01.356487) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 18:52:01.361774) ========= ========= Total master_lag: 6.82 =========