builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0142 starttime: 1490736767.39 results: success (0) buildid: 20170328131621 builduid: 361be3a54f3f475ab307b50abd3073dc revision: e23cf1b38ad4b55416318d205864195d3666b4f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.388989) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.389454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.389778) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.416762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.417153) ========= 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.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-28 14:32:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 194M=0s 2017-03-28 14:32:47 (194 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.175255 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.617985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.618507) ========= 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.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.039938 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:47.680809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 14:32:47.681187) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e23cf1b38ad4b55416318d205864195d3666b4f3 --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 e23cf1b38ad4b55416318d205864195d3666b4f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-28 14:32:47,835 truncating revision to first 12 chars 2017-03-28 14:32:47,835 Setting DEBUG logging. 2017-03-28 14:32:47,835 attempt 1/10 2017-03-28 14:32:47,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e23cf1b38ad4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 14:32:48,674 unpacking tar archive at: mozilla-central-e23cf1b38ad4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 14:32:48.824870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:48.825196) ========= script_repo_revision: e23cf1b38ad4b55416318d205864195d3666b4f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:48.825571) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:48.825831) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:32:48.845293) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2017-03-28 14:32:48.845572) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:32:49 INFO - MultiFileLogger online at 20170328 14:32:49 in /builds/slave/test 14:32:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:32:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:32:49 INFO - {'append_to_log': False, 14:32:49 INFO - 'base_work_dir': '/builds/slave/test', 14:32:49 INFO - 'blob_upload_branch': 'Firefox', 14:32:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:32:49 INFO - 'branch': 'Firefox', 14:32:49 INFO - 'buildbot_json_path': 'buildprops.json', 14:32:49 INFO - 'config_files': ('talos/mac_config.py',), 14:32:49 INFO - 'default_actions': ('clobber', 14:32:49 INFO - 'read-buildbot-config', 14:32:49 INFO - 'download-and-extract', 14:32:49 INFO - 'populate-webroot', 14:32:49 INFO - 'create-virtualenv', 14:32:49 INFO - 'install', 14:32:49 INFO - 'run-tests'), 14:32:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:32:49 INFO - 'download_minidump_stackwalk': True, 14:32:49 INFO - 'download_symbols': 'ondemand', 14:32:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:32:49 INFO - 'tooltool.py': '/tools/tooltool.py', 14:32:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:32:49 INFO - '/tools/misc-python/virtualenv.py')}, 14:32:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:32:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:32:49 INFO - 'gecko_profile': False, 14:32:49 INFO - 'gecko_profile_interval': 0, 14:32:49 INFO - 'installer_path': 'installer.exe', 14:32:49 INFO - 'log_level': 'info', 14:32:49 INFO - 'log_name': 'talos', 14:32:49 INFO - 'log_to_console': True, 14:32:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:32:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:32:49 INFO - 'opt_config_files': (), 14:32:49 INFO - 'pip_index': False, 14:32:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:32:49 INFO - 'cmd': ('bash', 14:32:49 INFO - '-c', 14:32:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:32:49 INFO - 'enabled': True, 14:32:49 INFO - 'halt_on_failure': False, 14:32:49 INFO - 'name': 'check_screen_resolution'},), 14:32:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:32:49 INFO - 'cmd': ('bash', 14:32:49 INFO - '-c', 14:32:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:32:49 INFO - 'enabled': True, 14:32:49 INFO - 'halt_on_failure': False, 14:32:49 INFO - 'name': 'check_screen_resolution'},), 14:32:49 INFO - 'run_cmd_checks_enabled': True, 14:32:49 INFO - 'suite': 'dromaeojs', 14:32:49 INFO - 'system_bits': '32', 14:32:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:32:49 INFO - 'title': 't-yosemite-r7-0142', 14:32:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:32:49 INFO - 'use_talos_json': True, 14:32:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:32:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:32:49 INFO - 'work_dir': 'build'} 14:32:49 INFO - [mozharness: 2017-03-28 21:32:49.115098Z] Running clobber step. 14:32:49 INFO - Running pre-action listener: _resource_record_pre_action 14:32:49 INFO - Running main action method: clobber 14:32:49 INFO - rmtree: /builds/slave/test/build 14:32:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:32:51 INFO - Running post-action listener: _resource_record_post_action 14:32:51 INFO - [mozharness: 2017-03-28 21:32:51.726078Z] Finished clobber step (success) 14:32:51 INFO - [mozharness: 2017-03-28 21:32:51.726311Z] Running read-buildbot-config step. 14:32:51 INFO - Running pre-action listener: _resource_record_pre_action 14:32:51 INFO - Running main action method: read_buildbot_config 14:32:51 INFO - Using buildbot properties: 14:32:51 INFO - { 14:32:51 INFO - "project": "", 14:32:51 INFO - "product": "firefox", 14:32:51 INFO - "script_repo_revision": "production", 14:32:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:32:51 INFO - "repository": "", 14:32:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:32:51 INFO - "buildid": "20170328131621", 14:32:51 INFO - "pgo_build": "False", 14:32:51 INFO - "basedir": "/builds/slave/test", 14:32:51 INFO - "buildnumber": 2, 14:32:51 INFO - "slavename": "t-yosemite-r7-0142", 14:32:51 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:32:51 INFO - "platform": "yosemite_r7", 14:32:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:32:51 INFO - "branch": "mozilla-central", 14:32:51 INFO - "repo_path": "mozilla-central", 14:32:51 INFO - "revision": "e23cf1b38ad4b55416318d205864195d3666b4f3", 14:32:51 INFO - "stage_platform": "macosx64", 14:32:51 INFO - "builduid": "361be3a54f3f475ab307b50abd3073dc", 14:32:51 INFO - "slavebuilddir": "test" 14:32:51 INFO - } 14:32:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:32:51 INFO - Running post-action listener: _resource_record_post_action 14:32:51 INFO - [mozharness: 2017-03-28 21:32:51.732077Z] Finished read-buildbot-config step (success) 14:32:51 INFO - [mozharness: 2017-03-28 21:32:51.732330Z] Running download-and-extract step. 14:32:51 INFO - Running pre-action listener: _resource_record_pre_action 14:32:51 INFO - Running main action method: download_and_extract 14:32:51 INFO - mkdir: /builds/slave/test/build/tests 14:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:51 INFO - trying https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:32:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:32:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:32:52 INFO - Downloaded 1325 bytes. 14:32:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:32:52 INFO - Using the following test package requirements: 14:32:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:32:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:32:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:32:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:32:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'jsshell-mac.zip'], 14:32:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:32:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:32:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:32:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:32:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:32:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:32:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:32:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:32:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:32:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:32:52 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:32:54 INFO - Expected file size: 52503642 14:32:54 INFO - Obtained file size: 52503642 14:33:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:33:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:33:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:33:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:33:12 INFO - Expected file size: 52503642 14:33:12 INFO - Obtained file size: 52503642 14:33:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:33:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:33:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:33:29 INFO - Expected file size: 14404171 14:33:29 INFO - Obtained file size: 14404171 14:33: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')]} 14:33:29 INFO - trying https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:33:32 INFO - Downloaded 75588452 bytes. 14:33:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:32 INFO - mkdir: /builds/slave/test/properties 14:33:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:32 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:32 INFO - Contents: 14:33:32 INFO - build_url:https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:32 INFO - Running post-action listener: _resource_record_post_action 14:33:32 INFO - Running post-action listener: set_extra_try_arguments 14:33:32 INFO - [mozharness: 2017-03-28 21:33:32.729241Z] Finished download-and-extract step (success) 14:33:32 INFO - [mozharness: 2017-03-28 21:33:32.729371Z] Running populate-webroot step. 14:33:32 INFO - Running pre-action listener: _resource_record_pre_action 14:33:32 INFO - Running main action method: populate_webroot 14:33:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:32 INFO - '--tppagecycles', 14:33:32 INFO - '7'], 14:33:32 INFO - 'tests': ['tsvgm']}}, 14:33:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['damp', 'tps']}, 14:33:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'tests': ['damp', 'tps']}, 14:33:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['dromaeo_dom']}, 14:33:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['basic_compositor_video']}, 14:33:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['a11yr', 14:33:32 INFO - 'ts_paint', 14:33:32 INFO - 'tpaint', 14:33:32 INFO - 'sessionrestore', 14:33:32 INFO - 'sessionrestore_no_auto_restore', 14:33:32 INFO - 'tabpaint']}, 14:33:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['tsvgx', 14:33:32 INFO - 'tsvgr_opacity', 14:33:32 INFO - 'tart', 14:33:32 INFO - 'tscrollx', 14:33:32 INFO - 'cart', 14:33:32 INFO - 'tsvg_static']}, 14:33:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:32 INFO - 'tsvgr_opacity', 14:33:32 INFO - 'tart', 14:33:32 INFO - 'tscrollx', 14:33:32 INFO - 'cart', 14:33:32 INFO - 'tsvg_static']}, 14:33:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'talos_options': ['--disable-e10s'], 14:33:32 INFO - 'tests': ['tp5o']}, 14:33:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:33:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'talos_options': ['--disable-e10s', 14:33:32 INFO - '--xperf_path', 14:33:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:32 INFO - 'tests': ['tp5n']}, 14:33:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:33:32 INFO - 'talos_options': ['--xperf_path', 14:33:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:32 INFO - 'tests': ['tp5n']}}, 14:33:32 INFO - 'talos.zip': {'path': '', 14:33:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:32 INFO - Running post-action listener: _resource_record_post_action 14:33:32 INFO - [mozharness: 2017-03-28 21:33:32.747808Z] Finished populate-webroot step (success) 14:33:32 INFO - [mozharness: 2017-03-28 21:33:32.747941Z] Running create-virtualenv step. 14:33:32 INFO - Running pre-action listener: _resource_record_pre_action 14:33:32 INFO - Running main action method: create_virtualenv 14:33:32 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:33:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:32 INFO - Using real prefix '/tools/python27' 14:33:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:33 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:36 INFO - Installing pip.................done. 14:33:36 INFO - Return code: 0 14:33:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:33:36 INFO - Reading from file tmpfile_stdout 14:33:36 INFO - Output received: 14:33:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:33:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:33:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:33:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:36 INFO - 'HOME': '/Users/cltbld', 14:33:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:36 INFO - 'LOGNAME': 'cltbld', 14:33:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:36 INFO - 'MOZ_NO_REMOTE': '1', 14:33:36 INFO - 'NO_EM_RESTART': '1', 14:33:36 INFO - 'PAGER': '/bin/cat', 14:33:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:36 INFO - 'PWD': '/builds/slave/test', 14:33:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:36 INFO - 'SHELL': '/bin/bash', 14:33:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:36 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:36 INFO - 'USER': 'cltbld', 14:33:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:36 INFO - 'XPC_FLAGS': '0x0', 14:33:36 INFO - 'XPC_SERVICE_NAME': '0', 14:33:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:33:37 INFO - Cleaning up... 14:33:37 INFO - Return code: 0 14:33:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:33:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:37 INFO - 'HOME': '/Users/cltbld', 14:33:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:37 INFO - 'LOGNAME': 'cltbld', 14:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:37 INFO - 'MOZ_NO_REMOTE': '1', 14:33:37 INFO - 'NO_EM_RESTART': '1', 14:33:37 INFO - 'PAGER': '/bin/cat', 14:33:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:37 INFO - 'PWD': '/builds/slave/test', 14:33:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:37 INFO - 'SHELL': '/bin/bash', 14:33:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:37 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:37 INFO - 'USER': 'cltbld', 14:33:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:37 INFO - 'XPC_FLAGS': '0x0', 14:33:37 INFO - 'XPC_SERVICE_NAME': '0', 14:33:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:37 INFO - Downloading/unpacking psutil>=3.1.1 14:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:33:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:40 INFO - Installing collected packages: psutil 14:33:40 INFO - Running setup.py install for psutil 14:33:40 INFO - building 'psutil._psutil_osx' extension 14:33:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:33:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:33:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:33:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:33:41 INFO - building 'psutil._psutil_posix' extension 14:33:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:33:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:33:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:33:41 INFO - ^ 14:33:41 INFO - 1 warning generated. 14:33:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:33:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:42 INFO - Successfully installed psutil 14:33:42 INFO - Cleaning up... 14:33:42 INFO - Return code: 0 14:33:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:42 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:33:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:42 INFO - 'HOME': '/Users/cltbld', 14:33:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:42 INFO - 'LOGNAME': 'cltbld', 14:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:42 INFO - 'MOZ_NO_REMOTE': '1', 14:33:42 INFO - 'NO_EM_RESTART': '1', 14:33:42 INFO - 'PAGER': '/bin/cat', 14:33:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:42 INFO - 'PWD': '/builds/slave/test', 14:33:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:42 INFO - 'SHELL': '/bin/bash', 14:33:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:42 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:42 INFO - 'USER': 'cltbld', 14:33:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:42 INFO - 'XPC_FLAGS': '0x0', 14:33:42 INFO - 'XPC_SERVICE_NAME': '0', 14:33:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:33:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:33:45 INFO - Installing collected packages: mozsystemmonitor 14:33:45 INFO - Running setup.py install for mozsystemmonitor 14:33:45 INFO - Successfully installed mozsystemmonitor 14:33:45 INFO - Cleaning up... 14:33:45 INFO - Return code: 0 14:33:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:46 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:33:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:46 INFO - 'HOME': '/Users/cltbld', 14:33:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:46 INFO - 'LOGNAME': 'cltbld', 14:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:46 INFO - 'MOZ_NO_REMOTE': '1', 14:33:46 INFO - 'NO_EM_RESTART': '1', 14:33:46 INFO - 'PAGER': '/bin/cat', 14:33:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:46 INFO - 'PWD': '/builds/slave/test', 14:33:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:46 INFO - 'SHELL': '/bin/bash', 14:33:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:46 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:46 INFO - 'USER': 'cltbld', 14:33:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:46 INFO - 'XPC_FLAGS': '0x0', 14:33:46 INFO - 'XPC_SERVICE_NAME': '0', 14:33:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:46 INFO - Downloading/unpacking jsonschema==2.5.1 14:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:33:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:49 INFO - Downloading functools32-3.2.3-2.tar.gz 14:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:33:49 INFO - warning: no files found matching '*.txt' 14:33:49 INFO - no previously-included directories found matching 'build' 14:33:49 INFO - no previously-included directories found matching 'dist' 14:33:49 INFO - no previously-included directories found matching '.git*' 14:33:49 INFO - Installing collected packages: jsonschema, functools32 14:33:49 INFO - Running setup.py install for functools32 14:33:50 INFO - warning: no files found matching '*.txt' 14:33:50 INFO - no previously-included directories found matching 'build' 14:33:50 INFO - no previously-included directories found matching 'dist' 14:33:50 INFO - no previously-included directories found matching '.git*' 14:33:50 INFO - Successfully installed jsonschema functools32 14:33:50 INFO - Cleaning up... 14:33:50 INFO - Return code: 0 14:33:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:50 INFO - 'HOME': '/Users/cltbld', 14:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:50 INFO - 'LOGNAME': 'cltbld', 14:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:50 INFO - 'MOZ_NO_REMOTE': '1', 14:33:50 INFO - 'NO_EM_RESTART': '1', 14:33:50 INFO - 'PAGER': '/bin/cat', 14:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:50 INFO - 'PWD': '/builds/slave/test', 14:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:50 INFO - 'SHELL': '/bin/bash', 14:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:50 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:50 INFO - 'USER': 'cltbld', 14:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:50 INFO - 'XPC_FLAGS': '0x0', 14:33:50 INFO - 'XPC_SERVICE_NAME': '0', 14:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:33:50 INFO - Cleaning up... 14:33:50 INFO - Return code: 0 14:33:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:50 INFO - 'HOME': '/Users/cltbld', 14:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:50 INFO - 'LOGNAME': 'cltbld', 14:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:50 INFO - 'MOZ_NO_REMOTE': '1', 14:33:50 INFO - 'NO_EM_RESTART': '1', 14:33:50 INFO - 'PAGER': '/bin/cat', 14:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:50 INFO - 'PWD': '/builds/slave/test', 14:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:50 INFO - 'SHELL': '/bin/bash', 14:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:50 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:50 INFO - 'USER': 'cltbld', 14:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:50 INFO - 'XPC_FLAGS': '0x0', 14:33:50 INFO - 'XPC_SERVICE_NAME': '0', 14:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:50 INFO - Downloading/unpacking blobuploader==1.2.4 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:33:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:33:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:54 INFO - Downloading docopt-0.6.1.tar.gz 14:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:33:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:33:54 INFO - Running setup.py install for blobuploader 14:33:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:33:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:33:54 INFO - Running setup.py install for requests 14:33:55 INFO - Running setup.py install for docopt 14:33:55 INFO - Successfully installed blobuploader requests docopt 14:33:55 INFO - Cleaning up... 14:33:55 INFO - Return code: 0 14:33:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:55 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:33:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:33:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:33:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:33:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:55 INFO - 'HOME': '/Users/cltbld', 14:33:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:55 INFO - 'LOGNAME': 'cltbld', 14:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:55 INFO - 'MOZ_NO_REMOTE': '1', 14:33:55 INFO - 'NO_EM_RESTART': '1', 14:33:55 INFO - 'PAGER': '/bin/cat', 14:33:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:55 INFO - 'PWD': '/builds/slave/test', 14:33:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:55 INFO - 'SHELL': '/bin/bash', 14:33:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:33:55 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:33:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:55 INFO - 'USER': 'cltbld', 14:33:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:55 INFO - 'XPC_FLAGS': '0x0', 14:33:55 INFO - 'XPC_SERVICE_NAME': '0', 14:33:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:33:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qwywf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:33:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:33:55 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-T2rLRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-TTeqiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-7b1qXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rZ6Q9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-0CFDl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-tGKhCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rA0b4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-LQZ197-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-wOS2LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zwV2nG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:33:56 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zjTJxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:33:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-UwMGS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:33:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-xIYmSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-9pQAhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:33:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_XST5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:33:57 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Q3t1R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:33:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:33:57 INFO - Running setup.py install for manifestparser 14:33:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:33:57 INFO - Running setup.py install for mozcrash 14:33:57 INFO - Running setup.py install for mozdebug 14:33:58 INFO - Running setup.py install for mozdevice 14:33:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Running setup.py install for mozfile 14:33:58 INFO - Running setup.py install for mozhttpd 14:33:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Running setup.py install for mozinfo 14:33:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Running setup.py install for mozInstall 14:33:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:33:58 INFO - Running setup.py install for mozleak 14:33:58 INFO - Running setup.py install for mozlog 14:33:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Running setup.py install for moznetwork 14:33:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Running setup.py install for mozprocess 14:33:59 INFO - Running setup.py install for mozprofile 14:33:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Running setup.py install for mozrunner 14:33:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:33:59 INFO - Running setup.py install for mozscreenshot 14:33:59 INFO - Running setup.py install for moztest 14:34:00 INFO - Running setup.py install for mozversion 14:34:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:00 INFO - Cleaning up... 14:34:00 INFO - Return code: 0 14:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:00 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:00 INFO - 'HOME': '/Users/cltbld', 14:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:00 INFO - 'LOGNAME': 'cltbld', 14:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:00 INFO - 'MOZ_NO_REMOTE': '1', 14:34:00 INFO - 'NO_EM_RESTART': '1', 14:34:00 INFO - 'PAGER': '/bin/cat', 14:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:00 INFO - 'PWD': '/builds/slave/test', 14:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:00 INFO - 'SHELL': '/bin/bash', 14:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:34:00 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:00 INFO - 'USER': 'cltbld', 14:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:00 INFO - 'XPC_FLAGS': '0x0', 14:34:00 INFO - 'XPC_SERVICE_NAME': '0', 14:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-WbSyFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-mngNjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Ua_Rw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-uvxXpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:00 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-AlQ6UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bgvns9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-pSzjoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-I4HE0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-NPVHVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-3T5FP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-FyyU1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-kSCy0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RBy4X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:01 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-mx2mxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:02 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Rs5bBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:02 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-AEQe6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:02 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-w19o61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - Downloading blessings-1.6.tar.gz 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:05 INFO - Installing collected packages: blessings 14:34:05 INFO - Running setup.py install for blessings 14:34:05 INFO - Successfully installed blessings 14:34:05 INFO - Cleaning up... 14:34:05 INFO - Return code: 0 14:34:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:05 INFO - Reading from file tmpfile_stdout 14:34:05 INFO - Current package versions: 14:34:05 INFO - blessings == 1.6 14:34:05 INFO - blobuploader == 1.2.4 14:34:05 INFO - docopt == 0.6.1 14:34:05 INFO - functools32 == 3.2.3-2 14:34:05 INFO - jsonschema == 2.5.1 14:34:05 INFO - manifestparser == 1.1 14:34:05 INFO - mozInstall == 1.12 14:34:05 INFO - mozcrash == 1.0 14:34:05 INFO - mozdebug == 0.1 14:34:05 INFO - mozdevice == 0.49 14:34:05 INFO - mozfile == 1.2 14:34:05 INFO - mozhttpd == 0.7 14:34:05 INFO - mozinfo == 0.9 14:34:05 INFO - mozleak == 0.1 14:34:05 INFO - mozlog == 3.4 14:34:05 INFO - moznetwork == 0.27 14:34:05 INFO - mozprocess == 0.25 14:34:05 INFO - mozprofile == 0.28 14:34:05 INFO - mozrunner == 6.13 14:34:05 INFO - mozscreenshot == 0.1 14:34:05 INFO - mozsystemmonitor == 0.3 14:34:05 INFO - moztest == 0.8 14:34:05 INFO - mozversion == 1.4 14:34:05 INFO - psutil == 3.1.1 14:34:05 INFO - requests == 1.2.3 14:34:05 INFO - wsgiref == 0.1.2 14:34:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34: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')]} 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:05 INFO - 'HOME': '/Users/cltbld', 14:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:05 INFO - 'LOGNAME': 'cltbld', 14:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:05 INFO - 'MOZ_NO_REMOTE': '1', 14:34:05 INFO - 'NO_EM_RESTART': '1', 14:34:05 INFO - 'PAGER': '/bin/cat', 14:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:05 INFO - 'PWD': '/builds/slave/test', 14:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:05 INFO - 'SHELL': '/bin/bash', 14:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:34:05 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:05 INFO - 'USER': 'cltbld', 14:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:05 INFO - 'XPC_FLAGS': '0x0', 14:34:05 INFO - 'XPC_SERVICE_NAME': '0', 14:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:34:05 INFO - Cleaning up... 14:34:05 INFO - Return code: 0 14:34:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34: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')]} 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 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 0x1094a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f893b4d0670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, '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.WDe6XGgerj/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.cmPBisOmba/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:05 INFO - 'HOME': '/Users/cltbld', 14:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:05 INFO - 'LOGNAME': 'cltbld', 14:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:05 INFO - 'MOZ_NO_REMOTE': '1', 14:34:05 INFO - 'NO_EM_RESTART': '1', 14:34:05 INFO - 'PAGER': '/bin/cat', 14:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:05 INFO - 'PWD': '/builds/slave/test', 14:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:05 INFO - 'SHELL': '/bin/bash', 14:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:34:05 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:05 INFO - 'USER': 'cltbld', 14:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:05 INFO - 'XPC_FLAGS': '0x0', 14:34:05 INFO - 'XPC_SERVICE_NAME': '0', 14:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:34:06 INFO - Cleaning up... 14:34:06 INFO - Return code: 0 14:34:06 INFO - Running post-action listener: _resource_record_post_action 14:34:06 INFO - Running post-action listener: _start_resource_monitoring 14:34:06 INFO - Starting resource monitoring. 14:34:06 INFO - [mozharness: 2017-03-28 21:34:06.186104Z] Finished create-virtualenv step (success) 14:34:06 INFO - [mozharness: 2017-03-28 21:34:06.187116Z] Running install step. 14:34:06 INFO - Running pre-action listener: _resource_record_pre_action 14:34:06 INFO - Running main action method: install 14:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:06 INFO - Reading from file tmpfile_stdout 14:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:06 INFO - Reading from file tmpfile_stdout 14:34:06 INFO - Output received: 14:34:06 INFO - Usage: mozinstall [options] installer 14:34:06 INFO - Options: 14:34:06 INFO - -h, --help show this help message and exit 14:34:06 INFO - -d DEST, --destination=DEST 14:34:06 INFO - Directory to install application into. [default: 14:34:06 INFO - "/builds/slave/test"] 14:34:06 INFO - --app=APP Application being installed. [default: firefox] 14:34:06 INFO - mkdir: /builds/slave/test/build/application 14:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:34:27 INFO - Reading from file tmpfile_stdout 14:34:27 INFO - Output received: 14:34:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:34:27 INFO - Running post-action listener: _resource_record_post_action 14:34:27 INFO - [mozharness: 2017-03-28 21:34:27.075991Z] Finished install step (success) 14:34:27 INFO - [mozharness: 2017-03-28 21:34:27.076121Z] Running run-tests step. 14:34:27 INFO - Running pre-action listener: _resource_record_pre_action 14:34:27 INFO - Running main action method: run_tests 14:34:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:34:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:34:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:34:27 INFO - 2017-03-28 14:34:27.090 screenresolution[1627:7416] starting screenresolution argv=screenresolution get 14:34:27 INFO - 2017-03-28 14:34:27.093 screenresolution[1627:7416] Display 0: 1600x1200x32@60 14:34:27 INFO - 2017-03-28 14:34:27.099 screenresolution[1628:7418] starting screenresolution argv=screenresolution list 14:34:27 INFO - Available Modes on Display 0 14:34:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:34:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:34:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:34:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:34:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:34:27 INFO - Intel Iris: 14:34:27 INFO - Chipset Model: Intel Iris 14:34:27 INFO - Type: GPU 14:34:27 INFO - Bus: Built-In 14:34:27 INFO - VRAM (Dynamic, Max): 1536 MB 14:34:27 INFO - Vendor: Intel (0x8086) 14:34:27 INFO - Device ID: 0x0a2e 14:34:27 INFO - Revision ID: 0x0009 14:34:27 INFO - Displays: 14:34:27 INFO - Display: 14:34:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:34:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:34:27 INFO - Display Serial Number: 1600x1200 60 14:34:27 INFO - Main Display: Yes 14:34:27 INFO - Mirror: Off 14:34:27 INFO - Online: Yes 14:34:27 INFO - Rotation: Supported 14:34:27 INFO - Return code: 0 14:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:27 INFO - Python 2.7.3 14:34:27 INFO - Return code: 0 14:34:27 INFO - grabbing minidump binary from tooltool 14:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:27 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 0x109283d30>, '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 0x1091aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109278be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:34:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:34:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:34:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:27 INFO - Return code: 0 14:34:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:34:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:27 INFO - ENV: RUST_BACKTRACE is now 1 14:34:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:34:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WDe6XGgerj/Render', 14:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:27 INFO - 'HOME': '/Users/cltbld', 14:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:27 INFO - 'LOGNAME': 'cltbld', 14:34:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:27 INFO - 'MOZ_NO_REMOTE': '1', 14:34:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:27 INFO - 'NO_EM_RESTART': '1', 14:34:27 INFO - 'PAGER': '/bin/cat', 14:34:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:27 INFO - 'PWD': '/builds/slave/test', 14:34:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:27 INFO - 'RUST_BACKTRACE': '1', 14:34:27 INFO - 'SHELL': '/bin/bash', 14:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cmPBisOmba/Listeners', 14:34:27 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 14:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:27 INFO - 'USER': 'cltbld', 14:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:27 INFO - 'XPC_FLAGS': '0x0', 14:34:27 INFO - 'XPC_SERVICE_NAME': '0', 14:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:34:27 INFO - mozversion application_buildid: 20170328131621 14:34:27 INFO - mozversion application_changeset: e23cf1b38ad4b55416318d205864195d3666b4f3 14:34:27 INFO - mozversion application_display_name: Nightly 14:34:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:27 INFO - mozversion application_name: Firefox 14:34:27 INFO - mozversion application_remotingname: firefox 14:34:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:34:27 INFO - mozversion application_vendor: Mozilla 14:34:27 INFO - mozversion application_version: 55.0a1 14:34:27 INFO - mozversion platform_buildid: 20170328131621 14:34:27 INFO - mozversion platform_changeset: e23cf1b38ad4b55416318d205864195d3666b4f3 14:34:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:34:27 INFO - mozversion platform_version: 55.0a1 14:34:27 INFO - using testdate: 1490736867 14:34:27 INFO - actual date: 1490736867 14:34:27 INFO - starting webserver on 'localhost:49241' 14:34:27 INFO - SUITE-START | Running 2 tests 14:34:27 INFO - TEST-START | dromaeo_css 14:34:27 INFO - operating with platform_type : mac_ 14:34:27 INFO - Initialising browser for dromaeo_css test... 14:34:27 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpb5G5Hv/profile http://localhost:49241/getInfo.html) 14:34:30 INFO - PID 1638 | __metrics Screen width/height:1600/1200 14:34:30 INFO - PID 1638 | colorDepth:24 14:34:30 INFO - PID 1638 | Browser inner width/height: 1024/689 14:34:30 INFO - PID 1638 | __metrics 14:34:30 ERROR - PID 1638 | 1490736870245 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:34:30 INFO - PID 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:34:30 INFO - PID 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:34:31 INFO - TEST-INFO | 1638: exit 0 14:34:31 INFO - Browser initialized. 14:34:31 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:31 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpb5G5Hv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:43 INFO - PID 1640 | RSS: Main: 234094592 14:34:43 INFO - PID 1640 | 14:37:49 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 14:37:51 INFO - PID 1640 | RSS: Main: 428937216 14:37:51 INFO - PID 1640 | 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 14:39:33 INFO - PID 1640 | 14:39:33 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:33 INFO - PID 1640 | Full stack: 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 14:39:33 INFO - PID 1640 | 14:39:33 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:33 INFO - PID 1640 | Full stack: 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 14:39:33 INFO - PID 1640 | 14:39:33 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:33 INFO - PID 1640 | Full stack: 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | ************************* 14:39:33 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 14:39:33 INFO - PID 1640 | 14:39:33 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:33 INFO - PID 1640 | Full stack: 14:39:33 INFO - PID 1640 | ************************* 14:40:54 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 14:40:55 INFO - PID 1640 | RSS: Main: 369766400 14:40:55 INFO - PID 1640 | 14:43:57 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 14:43:58 INFO - PID 1640 | RSS: Main: 327430144 14:43:58 INFO - PID 1640 | 14:47:00 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 14:47:01 INFO - PID 1640 | RSS: Main: 332853248 14:47:01 INFO - PID 1640 | 14:50:04 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 14:50:05 INFO - PID 1640 | RSS: Main: 333021184 14:50:05 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 14:53:09 INFO - PID 1640 | RSS: Main: 443256832 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | __start_tp_report 14:53:09 INFO - PID 1640 | _x_x_mozilla_page_load 14:53:09 INFO - PID 1640 | _x_x_mozilla_page_load_details 14:53:09 INFO - PID 1640 | |i|pagename|runs| 14:53:09 INFO - PID 1640 | |0;dojo.html;2765.907466550712;3425.62318145004;3428.228648554076;3444.414449543577;3483.7038851697603;5905.557083218754;5981;6108.938910610893;6126.693665316733;6138.324784273732;6008.309044459888;6106.005662907946;6115.449609535141;6137.897974673444;6145.832291864543;4986.32494695662;5299.60252981026;5388.676679399229;5411.702356370398;5440.129579267318;5572;5607.214989901417;5621.831345059655;5623.156526521014;5663.8584035399235;5290.127129023706;5342.759575819097;5368.3826359968325;5378.811741589026;5518.475744804257;5397.298351214356;5400.486953739389;5409.810656626999;5412.107002344608;5417.241586177938;4402.471703395613;5327.627066105374;5346.438623944468;5377.327834020747;5388.7305634718105;5334.146536554172;5644.125160600113;5982.312034116088;6013.6993150342305;6022.457978781887;6337.96831015842;6339.651319177444;6347.87304253917;6361.045843123568;6374.808755737336;6347.777827776005;6386;6427.389398007229;6441.935580644181;6490.221173459168;6190.535709821781;6190.845228869224;6193.318734939108;6196.349383314777;6197.070439434121;6634.402903738686;6643.269240383554;6652.135222421054;6655.168103987002;6656.134702488645;6259.436650701458;6303.149074874925;6324.367563243639;6353.904691429632;6376;6452.580582262138;6455.5158363122955;6469.126667899867;6477.417032467007;6487.837804054936;6363.077353783676;6372.044193371032;6376.362363763679;6391.456726178234;6402.551821372459;6263.31103578606;6280.183576135073;6347.39699728525;6372.0760489729655;6375.649339286384;4413.889652758642;5174.922376164361;5180.507851754077;5233.947660523422;5250.107481728115;4591.701539399928;4608.239640459354;4616.515265897099;4631;4652.069586082797;5932.940670593325;6075.787347442818;6233.781817636361;6238.344973777779;6239.875202495925;6291.307956124822;6316.2736285326855;6328.905066424008;6329.746810127635;6341.080543321193;6066.939330606638;6093.360197179321;6112.388761123852;6120.418560236771;6134.447899689049;5621.634593751393;5626.634268772516;5628.690422026828;5634.915476267859;5669.546436285087;6501.8699626008165;6505.0892874997535;6511.446527045254;6513.674316284072;6514.869702605826;6450.741970321323;6472;6476.31998640136;6485.675716214265;6493.772717954849;6428.710708018244;6446.355364463516;6455.741770329322;6588.736450541925;6692.263850976291;6365.8726825464155;6392.456641185551;6395.616263024233;6404.871902561829;6412.775552855627;5274.446183150714;6286.497080233479;6313.053042043546;6324.873502530016;6437.324080971618;6218.720157592828;6237.438630523275;6272.74909003653;6286.27707813589;6297.118403423616;6301.936980630135;6324.177856878577;6328.240611126695;6328.588641738273;6343.777967771106;6343.809685709251;6346.587471814318;6350.90473642905;6357.96821015892;6363.236411630634;6222.906656400067;6287.371262873676;6297.370262973666;6333.461655759312;6362.172917520693;5114.488551145005;6231.470325022424;6293.118963345044;6313.936860631335;6351.3331100235655 14:53:09 INFO - PID 1640 | |1;ext.html;693.9895901561478;796.2077732656008;1107.2581370481776;1132.631894634244;1134.7503549219175;26796.330091747717;30474.085777426684;36604.450933236025;36798.26403471929;36898.631013689854;31052.534211986782;37803.86588402352;38242.235155296876;38278.4258236126;38300;6076.392360763933;8482.999999999985;8636.784080397978;8655.523946182957;8668.739937801876;6162.322144564095;8390.37072219586;8594.09761974993;8614.827703445928;8656.567171641424;8648;8663.090375510576;8675.522846243453;8686.826263474726;8700.216980471756;8563.657453701844;8645.221930026328;8662.047174810767;8662.740117796477;8663.956680216621;8481.321494280442;8537.274331681814;8615.439996400277;8618.095100014534;8623.870641940375;37696.24607507862;37916.672916447795;37932.620673793186;37963.05092372685;37965.43051854224;3024.894128705507;3034.499307614241;3043.7564994800364;3047.481928072233;3048.7195377941316;27193.45613087747;27290.77191526386;27385.31536711578;27423;27642.585361219597;2738.2059202831356;2740.1916434651826;2742.80800343976;2802.4306388422547;2821.562657788046;288051;289675;289686;289753;289882.1011789876;37104;38594.42108368377;47502.76248618804;47513.287300690514;47725.56823295307;39607.00982475461;47871.04257914822;48069.519304806505;48448.03103937901;48460.757696211294;13963.301834908418;14105.435782568788;14138.929305353407;14142.080764750259;14162.866970642324;11473.254238474472;11479.885201148049;11485.770284594428;11490;11505.597304094485;13473.528426505025;13510.392032358568;13512.459501619825;13552;13668.794968075486;5350.79607088402;5859.121131830174;5900.1739756434135;5938.376470470538;5941.197938278277;854.7136709202487;911.4850109688138;919.1222382624711;1015.5328548867603;1024.882138554061;23013;23767.286981390585;24091.156809511584;24138;24385.512289754104;1057.4184198690689;1081.4592703648175;1095.3209010413595;1105.5412004018242;1117.7596816684452;11955.222910510964;12032.796720327897;12174.939125304316;12207.69480762988;12269.447874845653;378870;435882;475875;476637.2336276662;477090;49734.75132624314;55072.44927550673;55106.44893551093;55226.72386638041;55520;1409.4714482069223;1447.118949395659;1486.390579862273;1492.7760835874706;1497.1316636351107;23665.763342367045;23704.288871333887;23758.643620346054;23765.287041388787;23816.40458988539;38269.234615308094;38347.80826095852;38361.4245786319;38424.423633646074;38434.42348364833;428612;430566;430579;430875;431738;31937.20156996001;32902.670973290915;32919.50620740739;33036;33121.83439082886;34474.82762586171;41988;42648.78675606602;42695.786521067195;43536.91157721082;22988.850557472393;29363.85318073396;30417.695823041486;30577.388452231276;30802.997805142593;29560.852195738884;29725.702742973157;30310.545341820332;30750.077497675105;30817.845910770302;29875.402491949604;33636;34639.480407794406;36179.45730813987;36359;27775.888964442005;28380.00669976541;29332.97334593279;29368.853155734083;29608.703912961457;20786.792132079092;22225.666615000464;22946.54106917819;23383.41541461477;24281.75718242795 14:53:09 INFO - PID 1640 | |2;jquery.html;387.29705584364393;416.4024624663608;430.54577420821033;431.9805608747606;443.3394242578557;14803.259837008147;20469.795302046976;20797.48006299843;20974.685379719296;21038.737675739427;20180.899095504537;21350.46623834401;21450.678239826415;21704.457388565294;22572.322830315075;4646.558576935186;5142.460041695615;6389.808305750835;6405.5516113872045;6421.550491465599;6084.574079814415;6246.781362652286;6259.937400626004;6260.342664020281;6278.246610406758;6071.908921366183;6106.694665266719;6249.937500625004;6267.529935254851;6295.213098362705;6163.753449862;6209.285932117774;6219.222597175353;6220.595661282002;6234.0960560718895;4416.40378548895;5175.80956380031;5471.945280547223;5475.5072043516275;5489.58828087895;23254.883725581265;23640.40898977522;24444.511109777883;25483.617745733827;27214.591781123298;2022.7876073012417;2037.4295197344768;2037.5619241863071;2042.8672136311093;2051.8461115416253;15898.76151857723;16373.83626163735;16634.58413539659;16869.409570664968;17373.652526949518;1330.368075164299;1635.1006946179555;1682.9747553786704;1684.9494515164565;1729.195923895382;130531;132152.3392383032;132492.33753831265;133594;136742.632573674;10001.649942251986;10891.891081089088;11723.120765942418;11798.941005294919;11910.523579056913;14339.569812905629;14383.352749126105;14383.568492945229;14388;14397.712045759026;7068.434525237969;7414.777556673201;7618.085829700472;8548.700795472252;8757.69348072827;8245.804358368003;8258.504489730636;8261.297789687944;8419.70531031411;8434.915650843535;7458.29146231118;7585.734499292608;7606.467547271748;7607.16321204667;7617;1799.7840259168722;2137.4549489879983;2150.4301360139575;2160.816330611884;2171.457135716071;777.941654375924;905.2893478619252;915.8900931888217;953.6730377877956;959.9376040557341;19019.90490047541;20700.275490358068;20707.792922070887;20717.37847864536;20739.79260207409;950.4202436513721;964.3538828984597;986.8963758805365;992.4789485520159;1009.6112996496402;10012;10827.512761925733;10830.67507974762;10854.945725271324;10873.40196289196;557990;626714;633144;648997;658577;147121.26439367735;152247;166490;168160.31839681446;170136;1184.7512022475205;1189.8155785853035;1247.1457051919645;1251.7246205834701;1252.8496580410226;15227.619309517351;17763.20065597077;21184.470388240417;21612.89193554022;21720.45698857478;49431.50568494413;50414.747926260134;63134.68432657807;63764.68117659382;64403.30555374965;93231.53384233307;94959;95232.04767952232;95906;96910;6085.695924436889;8457.238848503419;8766.693165739169;8972;9018.909810901807;13453.46186152543;13599.940013496884;13747.587572372844;13945.651358716113;14198.290085495477;11687.88312116868;11812.055035596959;11839.34883581377;11977;12006.39968001579;10923;10969.83545246806;11089.667309980714;11185.384803835866;11411.372374519482;10828.18788590843;11382;11807.291562506278;11949.64151075469;12098.939505302416;6813.863722725617;8524.829503410021;8600.870986935326;8945.284377249935;8956.596953136994;11800;11801.17391782567;11854.110941679237;12163.695907602381;12181.634550963485 14:53:09 INFO - PID 1640 | |3;mootools.html;1364.467857535561;1424.4943045218947;1452.1142103316968;1453.5276035288534;1454.520008397229;10208.3874967502;10576.365418074922;10577.471126443685;10595.894041059588;10641.308314959531;8470.957645211765;8721.65113395465;10484.895151048488;10510.789784204331;10536.36781793093;2357.1985524921524;2421.842580232288;2422.152246713645;2430.8176886733477;2434.6591477193206;1955.9535648428093;2303.389601755536;2387.498625288683;2415.625578035407;2419.4072452249134;2052.6510493216115;2323.2913961241816;2414.843035202715;2418.262429958862;2419.322589674894;2388.6775285336516;2395.724491683444;2398.5083057973247;2405.290439320387;2407.217654262363;1939.679952807785;2346.3547524430746;2352;2354.105439932832;2357.0571771291447;10313.948430257875;10531.736706582396;10542.894571054268;10549.525271362807;10555.630552930686;5980.162777211194;6229.127922090911;6236.688165591748;6252.718627661766;6254.8123556293385;2869.7991140620165;3651.6713495785507;3811.4854494643146;3894.1432884765304;3912.374020156761;1120.618989543559;1133.286029801228;1142.0920368307216;1152.2913408253946;1198.6823491774687;12112.697182570419;14376.281185940661;14610.634734131625;14751.557453276419;14777.187254700988;5193.610479214074;5411.621186516984;5465.07093794048;5490.917636235459;5533.557315414757;12557.309347985949;12561.246325220516;12562.43469043877;12582.748345033047;12588.81116783249;6266.934621114421;6271.874562508724;6272.372762723691;6279.309275979638;6299.212598425197;5421.674699518042;5554.805581804618;5582.49757521825;5650;5651.745671444794;3517.613062563116;4143.668506519532;4324.567543245713;4357.411749413852;4376.124775045004;1107.5182295701395;1364.1678576068591;1365.2900491744238;1385.5358454917514;1386.9306534673226;557.0843680783983;673.2997682410337;750.572173860905;755.2409828122702;799.6561478564157;6203.348648391854;6259.530535209878;6464.25661048976;6469.514786390945;6551.148350714377;505.375860811897;550.8402563256691;563.1018525451898;578.9305283366023;679.0900193740412;4093.672506199497;4117.629413352813;4192.45498085247;4276.786160691953;4278.251306021434;12734.745305093846;13394.062415630813;13399.196048237138;13400.865991339962;13411.061225714302;13285;13329.266890321125;13333.333333333294;13344.933275333562;13369;9529.952350238205;9925.2059835213;9928.80142397148;9930.354526955725;9978.850317245247;6289.622622642656;6344.460720838596;6358.586691865014;6593.670316484253;6609.4712423005085;11877.406129693309;12042.63872083839;12218.327991960528;12249.510019599473;12307.75384492323;12100.273983561014;12106.75786484283;12207;12358.62924112242;12414.751704966031;7595.848083038419;8601.483910965362;8805.207531322212;8805.867911981453;8876.245519130687;9602.759931001501;9623.422594644064;9718.416894986323;9725.221982241263;9740.561674724513;8254.669813207405;8350.749477515685;8428.45215061019;8471.95764021176;8501.617427215913;8197.631106600336;8251.876221856797;8266.20732229433;8289.834203315779;8372.99524057117;7523;7683.769486915401;7708.460407771402;7722.845543089219;7727.768166955;5412.621116521806;5614;5809.2738407699035;5835.153902684172;5902.229331200709;4332.653387728911;5638.887222255614;5901.350851406428;5926.022206336079;6086.995645718407 14:53:09 INFO - PID 1640 | |4;prototype.html;388.6832231731139;395.0459639969472;399.3490610305202;404.4014858010145;404.8340180525985;6042.879142417154;6052.667103309322;6155.692215389224;6156.322804491502;6186.6906654667255;5161.896762064756;5607.691576963265;5800.767969281234;5860.443257890493;6033.306169790475;4098.692598055143;4607.953920460786;4714.9528504714945;4716.245400735883;4721;3881.844726210948;3992.6406623403886;4560.2019646561885;4701.224297990844;4748.620110391161;4018.894803928914;4476;4623.768811559426;4701.600363969067;4714.58562431271;3773.9056523586855;4021.416894550274;4176.644985176264;4473.753943533105;4665.580097791198;3504.2816222674546;4043.4137050127565;4128.543516607614;4138.151678905847;4140.896477588054;4705.952940470585;5114.974425127888;5619.662820230799;5876.2067120938555;5910.408959104098;4469.910601787946;5412.621116521845;5420.159875219308;5441.993231252231;5466.726663666801;6419.74321027163;6435.908329792836;6461.157090880045;6469.708863101172;6491.8052458425855;4445.5776701213335;4523.570260825217;4568.588827005552;4571.6114130298965;4603.447586289633;5833.445822646884;6496.382843629847;6634.208763633792;6717.798466046026;6725.2602213756445;3982.601739825994;4129.545749967501;4389;4519.05099929012;4570.565796249351;4003.3794761811964;4842.467328593852;4870.853874383774;4873.171560834667;4876.000839655724;4568.2690769477385;4697.694649847749;5000.67495612784;5216.713080780518;5479.917801232985;6410.67946602668;6484.773032943919;6522.249941256816;6531;6531;1511.168857128575;1601.2954300107915;1662.3018332300462;1662.5926647971326;1673.0212825496976;1122.4342042850178;1145.7250259937555;1150.1948635954698;1156.919015668895;1165.720227145479;5033;6093.9390606093375;6127.509799216052;6128.097521982396;6144.446999770043;6261.499080073674;6577.86844263112;6648.800535983928;6694.656870095525;6741.988701694785;5642.802501912413;5965.242362727104;6076.787312444043;6139.508839292846;6143.754249830046;3190.883190883172;3347.7154441872713;3479.7042251408407;3503.404421248394;3510.8946731598094;6637.0796849386115;6915.026538588455;6937.375636192666;6954.547954382949;7006.614636194956;6978.092847929736;6980.930190698028;6992.650367481606;6999.685014174374;7031.7890463286185;6381.680915954184;6545.476361891037;6927.480438966997;6950.513464057467;7002.684879180449;5869.4717475427415;6774.830629234309;6789.762358317435;6816.79610583045;6818.761343352959;5547.750351234124;5877.676727780014;6583.868322633425;6674.799756007327;6750.45996320303;6288.24541055058;6340.144080549251;6940.444764418733;6962.632704880365;6992.895106573507;3670.3943849264574;4386.320120381346;4429.911401771882;4432.82268709248;4437.711548749321;6510.674466276572;6607.2071351438135;6731.360520750521;6778.898316525355;6798.980152977093;5668.149777533403;6491.740330386731;6506.4469520091325;6511.47908167336;6529.9020514691365;6203.534734894991;6418.614883107029;6477.838054048687;6489;6531.412172904462;5506.265526359184;5937.406259374028;6124.846878828065;6314.463270622048;6352.618842869442;5761.8559536011935;6452.483801295791;6458.483321334188;6543.069794410565;6547.508936829852;5330.307060082165;6084.8783024340155;6153.353897840662;6306.540449224898;6321.735652869353 14:53:09 INFO - PID 1640 | |5;yui.html;704.1901812915147;713.9286071392858;724.6014691919446;732.114141888315;759.1535437986643;13126.934365328172;15255.0846949183;15858.541926001459;15871;15885.761713574291;14700.264986750646;14815.851841481583;14815.92592037041;14818.481353152642;14844.109353438775;909.9408538444997;920.1948295241664;950.9904900950971;954.5799848066863;955.2787645327802;941.0636416765328;949.8670186173941;958.4872093430016;958.58301638787;961.216608464103;1012.7215015870657;1012.8936461671492;1016.0753714120152;1017.3641474078701;1022.8056669232822;1008.3193844155165;1009.7122320138762;1010.5200029985734;1011.0900189829213;1011.4689787861358;741.5106030020215;742.4913933955258;744.6016381236012;744.9478536502447;745.7278093495889;16159;16522.917385413115;16564.75152872708;16767.329306827713;17000.234989425506;11568.421578921021;14651.267436628126;14785.70428591433;14909.552713418614;14921.030133041317;10373.89626103737;10891;11768.823467647906;11789.88210117905;11984.221025633393;2285.223024171773;2326.6044772388736;2349.7532759060396;2380.4167978845294;2447.155731272708;111187.44406277998;111327.33009004871;116530;117781.41109294399;118775.99999999914;11460.541578336773;11543.88456115445;11624.070074394029;11684.415779211005;11718.472668729928;25108.372290692514;25339.87330063338;25368.492630147295;25378.492430151662;25448.363790905376;7785.221477852169;7848.21517848222;7875.212478752079;7955.005624296963;8104.918950810535;3742.9064273393383;4120.896977575525;4217.768022758716;4248.63886569645;4299.97850010748;3950.1309711863346;4036.8788936331957;4055.3916912463364;4079.9796001019804;4081.2653722329674;750.4446709434978;757.4886951307845;772.0349563046192;779.5322806316278;779.7562887195028;524.3378490186119;561.3936948096047;566.4222493057059;577.9624324418984;595.2916029924463;7994.2405471480115;8881.37830351882;10102.292839501311;10200.404302577994;10291.53688084023;525.3826753564547;554.6162324999153;572.9541636669139;576.0581449330291;605.2404232687949;3730.0674831292176;4084.9183016339507;4396.142752163361;4612.7001744886475;4854.586911075723;94740.15748031496;97665.51167244118;97790;98110.5094474523;99038;63308.36691633117;81017.9745506367;83534.58232708798;86063.84840379041;86862;61713;67376;67681;69923;70259.56468164257;9306.255499559884;10524;10888.61889833852;10983.176261780558;11076.889231107585;34619.82690086534;36816.44775328426;38537.614623853406;39016;39185.216295674494;50701.23948140855;50816.13982112515;51394.74302628463;51483;52051.2192317108;13263.535776247785;13360.797249628264;13507.527236546674;13773.586792396243;14166.504172353918;14561.854381456049;14586.416543338148;14657.926710366806;14957.476488322856;15110.39558417651;13050.282234477196;13370.532031378854;13525.391357389139;13543.88490647137;14075.436982520585;12328;13337.19976801395;13450.65493450647;13604.727905441638;13922.373493192628;12769.61691149267;13186.142900711424;13420.932895335462;13620.93189534046;13890.652733681738;9335.719928401888;9579.185769209695;9606;9779.511024448606;10257.487125643836;10074.395536267848;10614.59457041014;10971.451427428756;11109.388983605979;11163.72090697739 14:53:09 INFO - PID 1640 | __end_tp_report 14:53:09 INFO - PID 1640 | __start_cc_report 14:53:09 INFO - PID 1640 | _x_x_mozilla_cycle_collect,2261 14:53:09 INFO - PID 1640 | __end_cc_report 14:53:09 INFO - PID 1640 | __startTimestamp1490737989881__endTimestamp 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | ------- Summary: start ------- 14:53:09 INFO - PID 1640 | Number of tests: 6 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:5953.47 Median:6263.31 stddev:696.20 (11.1%) stddev-sans-first:651.88 14:53:09 INFO - PID 1640 | Values: 2765.9 3425.6 3428.2 3444.4 3483.7 5905.6 5981.0 6108.9 6126.7 6138.3 6008.3 6106.0 6115.4 6137.9 6145.8 4986.3 5299.6 5388.7 5411.7 5440.1 5572.0 5607.2 5621.8 5623.2 5663.9 5290.1 5342.8 5368.4 5378.8 5518.5 5397.3 5400.5 5409.8 5412.1 5417.2 4402.5 5327.6 5346.4 5377.3 5388.7 5334.1 5644.1 5982.3 6013.7 6022.5 6338.0 6339.7 6347.9 6361.0 6374.8 6347.8 6386.0 6427.4 6441.9 6490.2 6190.5 6190.8 6193.3 6196.3 6197.1 6634.4 6643.3 6652.1 6655.2 6656.1 6259.4 6303.1 6324.4 6353.9 6376.0 6452.6 6455.5 6469.1 6477.4 6487.8 6363.1 6372.0 6376.4 6391.5 6402.6 6263.3 6280.2 6347.4 6372.1 6375.6 4413.9 5174.9 5180.5 5233.9 5250.1 4591.7 4608.2 4616.5 4631.0 4652.1 5932.9 6075.8 6233.8 6238.3 6239.9 6291.3 6316.3 6328.9 6329.7 6341.1 6066.9 6093.4 6112.4 6120.4 6134.4 5621.6 5626.6 5628.7 5634.9 5669.5 6501.9 6505.1 6511.4 6513.7 6514.9 6450.7 6472.0 6476.3 6485.7 6493.8 6428.7 6446.4 6455.7 6588.7 6692.3 6365.9 6392.5 6395.6 6404.9 6412.8 5274.4 6286.5 6313.1 6324.9 6437.3 6218.7 6237.4 6272.7 6286.3 6297.1 6301.9 6324.2 6328.2 6328.6 6343.8 6343.8 6346.6 6350.9 6358.0 6363.2 6222.9 6287.4 6297.4 6333.5 6362.2 5114.5 6231.5 6293.1 6313.9 6351.3 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:51741.06 Median:32911.09 stddev:105452.38 (320.4%) stddev-sans-first:105678.56 14:53:09 INFO - PID 1640 | Values: 694.0 796.2 1107.3 1132.6 1134.8 26796.3 30474.1 36604.5 36798.3 36898.6 31052.5 37803.9 38242.2 38278.4 38300.0 6076.4 8483.0 8636.8 8655.5 8668.7 6162.3 8390.4 8594.1 8614.8 8656.6 8648.0 8663.1 8675.5 8686.8 8700.2 8563.7 8645.2 8662.0 8662.7 8664.0 8481.3 8537.3 8615.4 8618.1 8623.9 37696.2 37916.7 37932.6 37963.1 37965.4 3024.9 3034.5 3043.8 3047.5 3048.7 27193.5 27290.8 27385.3 27423.0 27642.6 2738.2 2740.2 2742.8 2802.4 2821.6 288051.0 289675.0 289686.0 289753.0 289882.1 37104.0 38594.4 47502.8 47513.3 47725.6 39607.0 47871.0 48069.5 48448.0 48460.8 13963.3 14105.4 14138.9 14142.1 14162.9 11473.3 11479.9 11485.8 11490.0 11505.6 13473.5 13510.4 13512.5 13552.0 13668.8 5350.8 5859.1 5900.2 5938.4 5941.2 854.7 911.5 919.1 1015.5 1024.9 23013.0 23767.3 24091.2 24138.0 24385.5 1057.4 1081.5 1095.3 1105.5 1117.8 11955.2 12032.8 12174.9 12207.7 12269.4 378870.0 435882.0 475875.0 476637.2 477090.0 49734.8 55072.4 55106.4 55226.7 55520.0 1409.5 1447.1 1486.4 1492.8 1497.1 23665.8 23704.3 23758.6 23765.3 23816.4 38269.2 38347.8 38361.4 38424.4 38434.4 428612.0 430566.0 430579.0 430875.0 431738.0 31937.2 32902.7 32919.5 33036.0 33121.8 34474.8 41988.0 42648.8 42695.8 43536.9 22988.9 29363.9 30417.7 30577.4 30803.0 29560.9 29725.7 30310.5 30750.1 30817.8 29875.4 33636.0 34639.5 36179.5 36359.0 27775.9 28380.0 29333.0 29368.9 29608.7 20786.8 22225.7 22946.5 23383.4 24281.8 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:38079.48 Median:11886.85 stddev:105731.89 (889.5%) stddev-sans-first:105990.61 14:53:09 INFO - PID 1640 | Values: 387.3 416.4 430.5 432.0 443.3 14803.3 20469.8 20797.5 20974.7 21038.7 20180.9 21350.5 21450.7 21704.5 22572.3 4646.6 5142.5 6389.8 6405.6 6421.6 6084.6 6246.8 6259.9 6260.3 6278.2 6071.9 6106.7 6249.9 6267.5 6295.2 6163.8 6209.3 6219.2 6220.6 6234.1 4416.4 5175.8 5471.9 5475.5 5489.6 23254.9 23640.4 24444.5 25483.6 27214.6 2022.8 2037.4 2037.6 2042.9 2051.8 15898.8 16373.8 16634.6 16869.4 17373.7 1330.4 1635.1 1683.0 1684.9 1729.2 130531.0 132152.3 132492.3 133594.0 136742.6 10001.6 10891.9 11723.1 11798.9 11910.5 14339.6 14383.4 14383.6 14388.0 14397.7 7068.4 7414.8 7618.1 8548.7 8757.7 8245.8 8258.5 8261.3 8419.7 8434.9 7458.3 7585.7 7606.5 7607.2 7617.0 1799.8 2137.5 2150.4 2160.8 2171.5 777.9 905.3 915.9 953.7 959.9 19019.9 20700.3 20707.8 20717.4 20739.8 950.4 964.4 986.9 992.5 1009.6 10012.0 10827.5 10830.7 10854.9 10873.4 557990.0 626714.0 633144.0 648997.0 658577.0 147121.3 152247.0 166490.0 168160.3 170136.0 1184.8 1189.8 1247.1 1251.7 1252.8 15227.6 17763.2 21184.5 21612.9 21720.5 49431.5 50414.7 63134.7 63764.7 64403.3 93231.5 94959.0 95232.0 95906.0 96910.0 6085.7 8457.2 8766.7 8972.0 9018.9 13453.5 13599.9 13747.6 13945.7 14198.3 11687.9 11812.1 11839.3 11977.0 12006.4 10923.0 10969.8 11089.7 11185.4 11411.4 10828.2 11382.0 11807.3 11949.6 12098.9 6813.9 8524.8 8600.9 8945.3 8956.6 11800.0 11801.2 11854.1 12163.7 12181.6 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6591.57 Median:4130.65 stddev:4061.69 (98.3%) stddev-sans-first:4054.09 14:53:09 INFO - PID 1640 | Values: 1364.5 1424.5 1452.1 1453.5 1454.5 10208.4 10576.4 10577.5 10595.9 10641.3 8471.0 8721.7 10484.9 10510.8 10536.4 2357.2 2421.8 2422.2 2430.8 2434.7 1956.0 2303.4 2387.5 2415.6 2419.4 2052.7 2323.3 2414.8 2418.3 2419.3 2388.7 2395.7 2398.5 2405.3 2407.2 1939.7 2346.4 2352.0 2354.1 2357.1 10313.9 10531.7 10542.9 10549.5 10555.6 5980.2 6229.1 6236.7 6252.7 6254.8 2869.8 3651.7 3811.5 3894.1 3912.4 1120.6 1133.3 1142.1 1152.3 1198.7 12112.7 14376.3 14610.6 14751.6 14777.2 5193.6 5411.6 5465.1 5490.9 5533.6 12557.3 12561.2 12562.4 12582.7 12588.8 6266.9 6271.9 6272.4 6279.3 6299.2 5421.7 5554.8 5582.5 5650.0 5651.7 3517.6 4143.7 4324.6 4357.4 4376.1 1107.5 1364.2 1365.3 1385.5 1386.9 557.1 673.3 750.6 755.2 799.7 6203.3 6259.5 6464.3 6469.5 6551.1 505.4 550.8 563.1 578.9 679.1 4093.7 4117.6 4192.5 4276.8 4278.3 12734.7 13394.1 13399.2 13400.9 13411.1 13285.0 13329.3 13333.3 13344.9 13369.0 9530.0 9925.2 9928.8 9930.4 9978.9 6289.6 6344.5 6358.6 6593.7 6609.5 11877.4 12042.6 12218.3 12249.5 12307.8 12100.3 12106.8 12207.0 12358.6 12414.8 7595.8 8601.5 8805.2 8805.9 8876.2 9602.8 9623.4 9718.4 9725.2 9740.6 8254.7 8350.7 8428.5 8472.0 8501.6 8197.6 8251.9 8266.2 8289.8 8373.0 7523.0 7683.8 7708.5 7722.8 7727.8 5412.6 5614.0 5809.3 5835.2 5902.2 4332.7 5638.9 5901.4 5926.0 6087.0 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5267.30 Median:5872.84 stddev:1630.22 (27.8%) stddev-sans-first:1593.13 14:53:09 INFO - PID 1640 | Values: 388.7 395.0 399.3 404.4 404.8 6042.9 6052.7 6155.7 6156.3 6186.7 5161.9 5607.7 5800.8 5860.4 6033.3 4098.7 4608.0 4715.0 4716.2 4721.0 3881.8 3992.6 4560.2 4701.2 4748.6 4018.9 4476.0 4623.8 4701.6 4714.6 3773.9 4021.4 4176.6 4473.8 4665.6 3504.3 4043.4 4128.5 4138.2 4140.9 4706.0 5115.0 5619.7 5876.2 5910.4 4469.9 5412.6 5420.2 5442.0 5466.7 6419.7 6435.9 6461.2 6469.7 6491.8 4445.6 4523.6 4568.6 4571.6 4603.4 5833.4 6496.4 6634.2 6717.8 6725.3 3982.6 4129.5 4389.0 4519.1 4570.6 4003.4 4842.5 4870.9 4873.2 4876.0 4568.3 4697.7 5000.7 5216.7 5479.9 6410.7 6484.8 6522.2 6531.0 6531.0 1511.2 1601.3 1662.3 1662.6 1673.0 1122.4 1145.7 1150.2 1156.9 1165.7 5033.0 6093.9 6127.5 6128.1 6144.4 6261.5 6577.9 6648.8 6694.7 6742.0 5642.8 5965.2 6076.8 6139.5 6143.8 3190.9 3347.7 3479.7 3503.4 3510.9 6637.1 6915.0 6937.4 6954.5 7006.6 6978.1 6980.9 6992.7 6999.7 7031.8 6381.7 6545.5 6927.5 6950.5 7002.7 5869.5 6774.8 6789.8 6816.8 6818.8 5547.8 5877.7 6583.9 6674.8 6750.5 6288.2 6340.1 6940.4 6962.6 6992.9 3670.4 4386.3 4429.9 4432.8 4437.7 6510.7 6607.2 6731.4 6778.9 6799.0 5668.1 6491.7 6506.4 6511.5 6529.9 6203.5 6418.6 6477.8 6489.0 6531.4 5506.3 5937.4 6124.8 6314.5 6352.6 5761.9 6452.5 6458.5 6543.1 6547.5 5330.3 6084.9 6153.4 6306.5 6321.7 14:53:09 INFO - PID 1640 | 14:53:09 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:19370.72 Median:25373.49 stddev:27805.11 (109.6%) stddev-sans-first:27847.79 14:53:09 INFO - PID 1640 | Values: 704.2 713.9 724.6 732.1 759.2 13126.9 15255.1 15858.5 15871.0 15885.8 14700.3 14815.9 14815.9 14818.5 14844.1 909.9 920.2 951.0 954.6 955.3 941.1 949.9 958.5 958.6 961.2 1012.7 1012.9 1016.1 1017.4 1022.8 1008.3 1009.7 1010.5 1011.1 1011.5 741.5 742.5 744.6 744.9 745.7 16159.0 16522.9 16564.8 16767.3 17000.2 11568.4 14651.3 14785.7 14909.6 14921.0 10373.9 10891.0 11768.8 11789.9 11984.2 2285.2 2326.6 2349.8 2380.4 2447.2 111187.4 111327.3 116530.0 117781.4 118776.0 11460.5 11543.9 11624.1 11684.4 11718.5 25108.4 25339.9 25368.5 25378.5 25448.4 7785.2 7848.2 7875.2 7955.0 8104.9 3742.9 4120.9 4217.8 4248.6 4300.0 3950.1 4036.9 4055.4 4080.0 4081.3 750.4 757.5 772.0 779.5 779.8 524.3 561.4 566.4 578.0 595.3 7994.2 8881.4 10102.3 10200.4 10291.5 525.4 554.6 573.0 576.1 605.2 3730.1 4084.9 4396.1 4612.7 4854.6 94740.2 97665.5 97790.0 98110.5 99038.0 63308.4 81018.0 83534.6 86063.8 86862.0 61713.0 67376.0 67681.0 69923.0 70259.6 9306.3 10524.0 10888.6 10983.2 11076.9 34619.8 36816.4 38537.6 39016.0 39185.2 50701.2 50816.1 51394.7 51483.0 52051.2 13263.5 13360.8 13507.5 13773.6 14166.5 14561.9 14586.4 14657.9 14957.5 15110.4 13050.3 13370.5 13525.4 13543.9 14075.4 12328.0 13337.2 13450.7 13604.7 13922.4 12769.6 13186.1 13420.9 13620.9 13890.7 9335.7 9579.2 9606.0 9779.5 10257.5 10074.4 10614.6 10971.5 11109.4 11163.7 14:53:09 INFO - PID 1640 | -------- Summary: end -------- 14:53:09 INFO - PID 1640 | 14:53:10 INFO - TEST-INFO | 1640: exit 0 14:53:10 INFO - TEST-OK | dromaeo_css | took 1122914ms 14:53:10 INFO - TEST-START | kraken 14:53:10 INFO - operating with platform_type : mac_ 14:53:10 INFO - Initialising browser for kraken test... 14:53:10 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpYisBG4/profile http://localhost:49241/getInfo.html) 14:53:12 INFO - PID 1650 | __metrics Screen width/height:1600/1200 14:53:12 INFO - PID 1650 | colorDepth:24 14:53:12 INFO - PID 1650 | Browser inner width/height: 1024/689 14:53:12 INFO - PID 1650 | __metrics 14:53:13 ERROR - PID 1650 | 1490737993025 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:53:13 INFO - PID 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:53:13 INFO - PID 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:13 INFO - TEST-INFO | 1650: exit 0 14:53:13 INFO - Browser initialized. 14:53:13 INFO - Running cycle 1/1 for kraken test... 14:53:13 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpYisBG4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:26 INFO - PID 1651 | RSS: Main: 235241472 14:53:26 INFO - PID 1651 | 14:53:29 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:30 INFO - PID 1651 | RSS: Main: 286982144 14:53:30 INFO - PID 1651 | 14:53:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 14:53:33 INFO - PID 1651 | RSS: Main: 288882688 14:53:33 INFO - PID 1651 | 14:53:37 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 14:53:38 INFO - PID 1651 | RSS: Main: 378892288 14:53:38 INFO - PID 1651 | 14:53:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 14:53:40 INFO - PID 1651 | RSS: Main: 283975680 14:53:40 INFO - PID 1651 | 14:53:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:53:43 INFO - PID 1651 | RSS: Main: 286220288 14:53:43 INFO - PID 1651 | 14:53:46 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 14:53:47 INFO - PID 1651 | RSS: Main: 326139904 14:53:47 INFO - PID 1651 | 14:53:50 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 14:53:51 INFO - PID 1651 | RSS: Main: 337903616 14:53:51 INFO - PID 1651 | 14:53:54 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 14:53:55 INFO - PID 1651 | RSS: Main: 343101440 14:53:55 INFO - PID 1651 | 14:53:56 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:53:57 INFO - PID 1651 | RSS: Main: 304222208 14:53:57 INFO - PID 1651 | 14:53:59 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:54:00 INFO - PID 1651 | RSS: Main: 308625408 14:54:00 INFO - PID 1651 | 14:54:02 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:54:03 INFO - PID 1651 | RSS: Main: 310697984 14:54:03 INFO - PID 1651 | 14:54:06 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:07 INFO - PID 1651 | RSS: Main: 328110080 14:54:07 INFO - PID 1651 | 14:54:10 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:11 INFO - PID 1651 | RSS: Main: 311537664 14:54:11 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 14:54:13 INFO - PID 1651 | RSS: Main: 377552896 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | __start_tp_report 14:54:13 INFO - PID 1651 | _x_x_mozilla_page_load 14:54:13 INFO - PID 1651 | _x_x_mozilla_page_load_details 14:54:13 INFO - PID 1651 | |i|pagename|runs| 14:54:13 INFO - PID 1651 | |0;ai-astar;112;121;123;122;130;181;128;124;122;124 14:54:13 INFO - PID 1651 | |1;audio-beat-detection;166;146;160;192;123;117;129;159;186;132 14:54:13 INFO - PID 1651 | |2;audio-dft;215;212;213;212;221;219;216;218;218;221 14:54:13 INFO - PID 1651 | |3;audio-fft;85;62;64;99;66;63;61;62;81;65 14:54:13 INFO - PID 1651 | |4;audio-oscillator;100;75;96;75;75;75;90;75;74;92 14:54:13 INFO - PID 1651 | |5;imaging-gaussian-blur;98;97;98;97;97;97;97;96;97;95 14:54:13 INFO - PID 1651 | |6;imaging-darkroom;102;101;101;102;127;158;136;102;102;100 14:54:13 INFO - PID 1651 | |7;imaging-desaturate;104;102;101;103;103;101;103;139;159;102 14:54:13 INFO - PID 1651 | |8;json-parse-financial;67;63;63;64;65;65;65;64;64;83 14:54:13 INFO - PID 1651 | |9;json-stringify-tinderbox;58;68;60;62;70;71;64;65;65;66 14:54:13 INFO - PID 1651 | |10;stanford-crypto-aes;92;89;90;90;93;92;92;90;91;92 14:54:13 INFO - PID 1651 | |11;stanford-crypto-ccm;152;152;153;166;168;143;175;167;233;161 14:54:13 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;202;205;200;207;204;200;203;205;200;233 14:54:13 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;74;73;76;73;74;80;103;104;99;82 14:54:13 INFO - PID 1651 | __end_tp_report 14:54:13 INFO - PID 1651 | __start_cc_report 14:54:13 INFO - PID 1651 | _x_x_mozilla_cycle_collect,2222 14:54:13 INFO - PID 1651 | __end_cc_report 14:54:13 INFO - PID 1651 | __startTimestamp1490738053463__endTimestamp 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | ------- Summary: start ------- 14:54:13 INFO - PID 1651 | Number of tests: 14 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:128.70 Median:124.00 stddev:18.98 (15.3%) stddev-sans-first:19.14 14:54:13 INFO - PID 1651 | Values: 112.0 121.0 123.0 122.0 130.0 181.0 128.0 124.0 122.0 124.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:151.00 Median:159.50 stddev:26.00 (16.3%) stddev-sans-first:27.01 14:54:13 INFO - PID 1651 | Values: 166.0 146.0 160.0 192.0 123.0 117.0 129.0 159.0 186.0 132.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:216.50 Median:218.00 stddev:3.44 (1.6%) stddev-sans-first:3.61 14:54:13 INFO - PID 1651 | Values: 215.0 212.0 213.0 212.0 221.0 219.0 216.0 218.0 218.0 221.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:70.80 Median:65.50 stddev:12.98 (19.8%) stddev-sans-first:12.71 14:54:13 INFO - PID 1651 | Values: 85.0 62.0 64.0 99.0 66.0 63.0 61.0 62.0 81.0 65.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:82.70 Median:75.00 stddev:10.48 (14.0%) stddev-sans-first:9.05 14:54:13 INFO - PID 1651 | Values: 100.0 75.0 96.0 75.0 75.0 75.0 90.0 75.0 74.0 92.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:97.00 stddev:0.88 (0.9%) stddev-sans-first:0.83 14:54:13 INFO - PID 1651 | Values: 98.0 97.0 98.0 97.0 97.0 97.0 97.0 96.0 97.0 95.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:113.10 Median:102.00 stddev:20.25 (19.9%) stddev-sans-first:21.08 14:54:13 INFO - PID 1651 | Values: 102.0 101.0 101.0 102.0 127.0 158.0 136.0 102.0 102.0 100.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:111.70 Median:103.00 stddev:20.24 (19.6%) stddev-sans-first:21.27 14:54:13 INFO - PID 1651 | Values: 104.0 102.0 101.0 103.0 103.0 101.0 103.0 139.0 159.0 102.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:65.00 stddev:5.98 (9.2%) stddev-sans-first:6.34 14:54:13 INFO - PID 1651 | Values: 67.0 63.0 63.0 64.0 65.0 65.0 65.0 64.0 64.0 83.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.50 stddev:4.15 (6.3%) stddev-sans-first:3.57 14:54:13 INFO - PID 1651 | Values: 58.0 68.0 60.0 62.0 70.0 71.0 64.0 65.0 65.0 66.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:92.00 stddev:1.29 (1.4%) stddev-sans-first:1.32 14:54:13 INFO - PID 1651 | Values: 92.0 89.0 90.0 90.0 93.0 92.0 92.0 90.0 91.0 92.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:167.00 Median:166.50 stddev:25.12 (15.1%) stddev-sans-first:26.05 14:54:13 INFO - PID 1651 | Values: 152.0 152.0 153.0 166.0 168.0 143.0 175.0 167.0 233.0 161.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.90 Median:204.50 stddev:9.83 (4.8%) stddev-sans-first:10.32 14:54:13 INFO - PID 1651 | Values: 202.0 205.0 200.0 207.0 204.0 200.0 203.0 205.0 200.0 233.0 14:54:13 INFO - PID 1651 | 14:54:13 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.80 Median:75.00 stddev:12.96 (17.3%) stddev-sans-first:13.25 14:54:13 INFO - PID 1651 | Values: 74.0 73.0 76.0 73.0 74.0 80.0 103.0 104.0 99.0 82.0 14:54:13 INFO - PID 1651 | -------- Summary: end -------- 14:54:13 INFO - PID 1651 | 14:54:14 INFO - TEST-INFO | 1651: exit 0 14:54:14 INFO - TEST-OK | kraken | took 63800ms 14:54:14 INFO - SUITE-END | took 1186s 14:54:14 INFO - Completed test suite (00:19:46) 14:54:14 INFO - javascript benchmark 14:54:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7528.8407967014255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2765.907466550712, 3425.62318145004, 3428.228648554076, 3444.414449543577, 3483.7038851697603, 5905.557083218754, 5981.0, 6108.938910610893, 6126.693665316733, 6138.324784273732, 6008.309044459888, 6106.005662907946, 6115.449609535141, 6137.897974673444, 6145.832291864543, 4986.32494695662, 5299.60252981026, 5388.676679399229, 5411.702356370398, 5440.129579267318, 5572.0, 5607.214989901417, 5621.831345059655, 5623.156526521014, 5663.8584035399235, 5290.127129023706, 5342.759575819097, 5368.3826359968325, 5378.811741589026, 5518.475744804257, 5397.298351214356, 5400.486953739389, 5409.810656626999, 5412.107002344608, 5417.241586177938, 4402.471703395613, 5327.627066105374, 5346.438623944468, 5377.327834020747, 5388.7305634718105, 5334.146536554172, 5644.125160600113, 5982.312034116088, 6013.6993150342305, 6022.457978781887, 6337.96831015842, 6339.651319177444, 6347.87304253917, 6361.045843123568, 6374.808755737336, 6347.777827776005, 6386.0, 6427.389398007229, 6441.935580644181, 6490.221173459168, 6190.535709821781, 6190.845228869224, 6193.318734939108, 6196.349383314777, 6197.070439434121, 6634.402903738686, 6643.269240383554, 6652.135222421054, 6655.168103987002, 6656.134702488645, 6259.436650701458, 6303.149074874925, 6324.367563243639, 6353.904691429632, 6376.0, 6452.580582262138, 6455.5158363122955, 6469.126667899867, 6477.417032467007, 6487.837804054936, 6363.077353783676, 6372.044193371032, 6376.362363763679, 6391.456726178234, 6402.551821372459, 6263.31103578606, 6280.183576135073, 6347.39699728525, 6372.0760489729655, 6375.649339286384, 4413.889652758642, 5174.922376164361, 5180.507851754077, 5233.947660523422, 5250.107481728115, 4591.701539399928, 4608.239640459354, 4616.515265897099, 4631.0, 4652.069586082797, 5932.940670593325, 6075.787347442818, 6233.781817636361, 6238.344973777779, 6239.875202495925, 6291.307956124822, 6316.2736285326855, 6328.905066424008, 6329.746810127635, 6341.080543321193, 6066.939330606638, 6093.360197179321, 6112.388761123852, 6120.418560236771, 6134.447899689049, 5621.634593751393, 5626.634268772516, 5628.690422026828, 5634.915476267859, 5669.546436285087, 6501.8699626008165, 6505.0892874997535, 6511.446527045254, 6513.674316284072, 6514.869702605826, 6450.741970321323, 6472.0, 6476.31998640136, 6485.675716214265, 6493.772717954849, 6428.710708018244, 6446.355364463516, 6455.741770329322, 6588.736450541925, 6692.263850976291, 6365.8726825464155, 6392.456641185551, 6395.616263024233, 6404.871902561829, 6412.775552855627, 5274.446183150714, 6286.497080233479, 6313.053042043546, 6324.873502530016, 6437.324080971618, 6218.720157592828, 6237.438630523275, 6272.74909003653, 6286.27707813589, 6297.118403423616, 6301.936980630135, 6324.177856878577, 6328.240611126695, 6328.588641738273, 6343.777967771106, 6343.809685709251, 6346.587471814318, 6350.90473642905, 6357.96821015892, 6363.236411630634, 6222.906656400067, 6287.371262873676, 6297.370262973666, 6333.461655759312, 6362.172917520693, 5114.488551145005, 6231.470325022424, 6293.118963345044, 6313.936860631335, 6351.3331100235655], "value": 5906.4843015141005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9895901561478, 796.2077732656008, 1107.2581370481776, 1132.631894634244, 1134.7503549219175, 26796.330091747717, 30474.085777426684, 36604.450933236025, 36798.26403471929, 36898.631013689854, 31052.534211986782, 37803.86588402352, 38242.235155296876, 38278.4258236126, 38300.0, 6076.392360763933, 8482.999999999985, 8636.784080397978, 8655.523946182957, 8668.739937801876, 6162.322144564095, 8390.37072219586, 8594.09761974993, 8614.827703445928, 8656.567171641424, 8648.0, 8663.090375510576, 8675.522846243453, 8686.826263474726, 8700.216980471756, 8563.657453701844, 8645.221930026328, 8662.047174810767, 8662.740117796477, 8663.956680216621, 8481.321494280442, 8537.274331681814, 8615.439996400277, 8618.095100014534, 8623.870641940375, 37696.24607507862, 37916.672916447795, 37932.620673793186, 37963.05092372685, 37965.43051854224, 3024.894128705507, 3034.499307614241, 3043.7564994800364, 3047.481928072233, 3048.7195377941316, 27193.45613087747, 27290.77191526386, 27385.31536711578, 27423.0, 27642.585361219597, 2738.2059202831356, 2740.1916434651826, 2742.80800343976, 2802.4306388422547, 2821.562657788046, 288051.0, 289675.0, 289686.0, 289753.0, 289882.1011789876, 37104.0, 38594.42108368377, 47502.76248618804, 47513.287300690514, 47725.56823295307, 39607.00982475461, 47871.04257914822, 48069.519304806505, 48448.03103937901, 48460.757696211294, 13963.301834908418, 14105.435782568788, 14138.929305353407, 14142.080764750259, 14162.866970642324, 11473.254238474472, 11479.885201148049, 11485.770284594428, 11490.0, 11505.597304094485, 13473.528426505025, 13510.392032358568, 13512.459501619825, 13552.0, 13668.794968075486, 5350.79607088402, 5859.121131830174, 5900.1739756434135, 5938.376470470538, 5941.197938278277, 854.7136709202487, 911.4850109688138, 919.1222382624711, 1015.5328548867603, 1024.882138554061, 23013.0, 23767.286981390585, 24091.156809511584, 24138.0, 24385.512289754104, 1057.4184198690689, 1081.4592703648175, 1095.3209010413595, 1105.5412004018242, 1117.7596816684452, 11955.222910510964, 12032.796720327897, 12174.939125304316, 12207.69480762988, 12269.447874845653, 378870.0, 435882.0, 475875.0, 476637.2336276662, 477090.0, 49734.75132624314, 55072.44927550673, 55106.44893551093, 55226.72386638041, 55520.0, 1409.4714482069223, 1447.118949395659, 1486.390579862273, 1492.7760835874706, 1497.1316636351107, 23665.763342367045, 23704.288871333887, 23758.643620346054, 23765.287041388787, 23816.40458988539, 38269.234615308094, 38347.80826095852, 38361.4245786319, 38424.423633646074, 38434.42348364833, 428612.0, 430566.0, 430579.0, 430875.0, 431738.0, 31937.20156996001, 32902.670973290915, 32919.50620740739, 33036.0, 33121.83439082886, 34474.82762586171, 41988.0, 42648.78675606602, 42695.786521067195, 43536.91157721082, 22988.850557472393, 29363.85318073396, 30417.695823041486, 30577.388452231276, 30802.997805142593, 29560.852195738884, 29725.702742973157, 30310.545341820332, 30750.077497675105, 30817.845910770302, 29875.402491949604, 33636.0, 34639.480407794406, 36179.45730813987, 36359.0, 27775.888964442005, 28380.00669976541, 29332.97334593279, 29368.853155734083, 29608.703912961457, 20786.792132079092, 22225.666615000464, 22946.54106917819, 23383.41541461477, 24281.75718242795], "value": 17289.464533425966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.29705584364393, 416.4024624663608, 430.54577420821033, 431.9805608747606, 443.3394242578557, 14803.259837008147, 20469.795302046976, 20797.48006299843, 20974.685379719296, 21038.737675739427, 20180.899095504537, 21350.46623834401, 21450.678239826415, 21704.457388565294, 22572.322830315075, 4646.558576935186, 5142.460041695615, 6389.808305750835, 6405.5516113872045, 6421.550491465599, 6084.574079814415, 6246.781362652286, 6259.937400626004, 6260.342664020281, 6278.246610406758, 6071.908921366183, 6106.694665266719, 6249.937500625004, 6267.529935254851, 6295.213098362705, 6163.753449862, 6209.285932117774, 6219.222597175353, 6220.595661282002, 6234.0960560718895, 4416.40378548895, 5175.80956380031, 5471.945280547223, 5475.5072043516275, 5489.58828087895, 23254.883725581265, 23640.40898977522, 24444.511109777883, 25483.617745733827, 27214.591781123298, 2022.7876073012417, 2037.4295197344768, 2037.5619241863071, 2042.8672136311093, 2051.8461115416253, 15898.76151857723, 16373.83626163735, 16634.58413539659, 16869.409570664968, 17373.652526949518, 1330.368075164299, 1635.1006946179555, 1682.9747553786704, 1684.9494515164565, 1729.195923895382, 130531.0, 132152.3392383032, 132492.33753831265, 133594.0, 136742.632573674, 10001.649942251986, 10891.891081089088, 11723.120765942418, 11798.941005294919, 11910.523579056913, 14339.569812905629, 14383.352749126105, 14383.568492945229, 14388.0, 14397.712045759026, 7068.434525237969, 7414.777556673201, 7618.085829700472, 8548.700795472252, 8757.69348072827, 8245.804358368003, 8258.504489730636, 8261.297789687944, 8419.70531031411, 8434.915650843535, 7458.29146231118, 7585.734499292608, 7606.467547271748, 7607.16321204667, 7617.0, 1799.7840259168722, 2137.4549489879983, 2150.4301360139575, 2160.816330611884, 2171.457135716071, 777.941654375924, 905.2893478619252, 915.8900931888217, 953.6730377877956, 959.9376040557341, 19019.90490047541, 20700.275490358068, 20707.792922070887, 20717.37847864536, 20739.79260207409, 950.4202436513721, 964.3538828984597, 986.8963758805365, 992.4789485520159, 1009.6112996496402, 10012.0, 10827.512761925733, 10830.67507974762, 10854.945725271324, 10873.40196289196, 557990.0, 626714.0, 633144.0, 648997.0, 658577.0, 147121.26439367735, 152247.0, 166490.0, 168160.31839681446, 170136.0, 1184.7512022475205, 1189.8155785853035, 1247.1457051919645, 1251.7246205834701, 1252.8496580410226, 15227.619309517351, 17763.20065597077, 21184.470388240417, 21612.89193554022, 21720.45698857478, 49431.50568494413, 50414.747926260134, 63134.68432657807, 63764.68117659382, 64403.30555374965, 93231.53384233307, 94959.0, 95232.04767952232, 95906.0, 96910.0, 6085.695924436889, 8457.238848503419, 8766.693165739169, 8972.0, 9018.909810901807, 13453.46186152543, 13599.940013496884, 13747.587572372844, 13945.651358716113, 14198.290085495477, 11687.88312116868, 11812.055035596959, 11839.34883581377, 11977.0, 12006.39968001579, 10923.0, 10969.83545246806, 11089.667309980714, 11185.384803835866, 11411.372374519482, 10828.18788590843, 11382.0, 11807.291562506278, 11949.64151075469, 12098.939505302416, 6813.863722725617, 8524.829503410021, 8600.870986935326, 8945.284377249935, 8956.596953136994, 11800.0, 11801.17391782567, 11854.110941679237, 12163.695907602381, 12181.634550963485], "value": 10157.226905428493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.467857535561, 1424.4943045218947, 1452.1142103316968, 1453.5276035288534, 1454.520008397229, 10208.3874967502, 10576.365418074922, 10577.471126443685, 10595.894041059588, 10641.308314959531, 8470.957645211765, 8721.65113395465, 10484.895151048488, 10510.789784204331, 10536.36781793093, 2357.1985524921524, 2421.842580232288, 2422.152246713645, 2430.8176886733477, 2434.6591477193206, 1955.9535648428093, 2303.389601755536, 2387.498625288683, 2415.625578035407, 2419.4072452249134, 2052.6510493216115, 2323.2913961241816, 2414.843035202715, 2418.262429958862, 2419.322589674894, 2388.6775285336516, 2395.724491683444, 2398.5083057973247, 2405.290439320387, 2407.217654262363, 1939.679952807785, 2346.3547524430746, 2352.0, 2354.105439932832, 2357.0571771291447, 10313.948430257875, 10531.736706582396, 10542.894571054268, 10549.525271362807, 10555.630552930686, 5980.162777211194, 6229.127922090911, 6236.688165591748, 6252.718627661766, 6254.8123556293385, 2869.7991140620165, 3651.6713495785507, 3811.4854494643146, 3894.1432884765304, 3912.374020156761, 1120.618989543559, 1133.286029801228, 1142.0920368307216, 1152.2913408253946, 1198.6823491774687, 12112.697182570419, 14376.281185940661, 14610.634734131625, 14751.557453276419, 14777.187254700988, 5193.610479214074, 5411.621186516984, 5465.07093794048, 5490.917636235459, 5533.557315414757, 12557.309347985949, 12561.246325220516, 12562.43469043877, 12582.748345033047, 12588.81116783249, 6266.934621114421, 6271.874562508724, 6272.372762723691, 6279.309275979638, 6299.212598425197, 5421.674699518042, 5554.805581804618, 5582.49757521825, 5650.0, 5651.745671444794, 3517.613062563116, 4143.668506519532, 4324.567543245713, 4357.411749413852, 4376.124775045004, 1107.5182295701395, 1364.1678576068591, 1365.2900491744238, 1385.5358454917514, 1386.9306534673226, 557.0843680783983, 673.2997682410337, 750.572173860905, 755.2409828122702, 799.6561478564157, 6203.348648391854, 6259.530535209878, 6464.25661048976, 6469.514786390945, 6551.148350714377, 505.375860811897, 550.8402563256691, 563.1018525451898, 578.9305283366023, 679.0900193740412, 4093.672506199497, 4117.629413352813, 4192.45498085247, 4276.786160691953, 4278.251306021434, 12734.745305093846, 13394.062415630813, 13399.196048237138, 13400.865991339962, 13411.061225714302, 13285.0, 13329.266890321125, 13333.333333333294, 13344.933275333562, 13369.0, 9529.952350238205, 9925.2059835213, 9928.80142397148, 9930.354526955725, 9978.850317245247, 6289.622622642656, 6344.460720838596, 6358.586691865014, 6593.670316484253, 6609.4712423005085, 11877.406129693309, 12042.63872083839, 12218.327991960528, 12249.510019599473, 12307.75384492323, 12100.273983561014, 12106.75786484283, 12207.0, 12358.62924112242, 12414.751704966031, 7595.848083038419, 8601.483910965362, 8805.207531322212, 8805.867911981453, 8876.245519130687, 9602.759931001501, 9623.422594644064, 9718.416894986323, 9725.221982241263, 9740.561674724513, 8254.669813207405, 8350.749477515685, 8428.45215061019, 8471.95764021176, 8501.617427215913, 8197.631106600336, 8251.876221856797, 8266.20732229433, 8289.834203315779, 8372.99524057117, 7523.0, 7683.769486915401, 7708.460407771402, 7722.845543089219, 7727.768166955, 5412.621116521806, 5614.0, 5809.2738407699035, 5835.153902684172, 5902.229331200709, 4332.653387728911, 5638.887222255614, 5901.350851406428, 5926.022206336079, 6086.995645718407], "value": 4986.361863406344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.6832231731139, 395.0459639969472, 399.3490610305202, 404.4014858010145, 404.8340180525985, 6042.879142417154, 6052.667103309322, 6155.692215389224, 6156.322804491502, 6186.6906654667255, 5161.896762064756, 5607.691576963265, 5800.767969281234, 5860.443257890493, 6033.306169790475, 4098.692598055143, 4607.953920460786, 4714.9528504714945, 4716.245400735883, 4721.0, 3881.844726210948, 3992.6406623403886, 4560.2019646561885, 4701.224297990844, 4748.620110391161, 4018.894803928914, 4476.0, 4623.768811559426, 4701.600363969067, 4714.58562431271, 3773.9056523586855, 4021.416894550274, 4176.644985176264, 4473.753943533105, 4665.580097791198, 3504.2816222674546, 4043.4137050127565, 4128.543516607614, 4138.151678905847, 4140.896477588054, 4705.952940470585, 5114.974425127888, 5619.662820230799, 5876.2067120938555, 5910.408959104098, 4469.910601787946, 5412.621116521845, 5420.159875219308, 5441.993231252231, 5466.726663666801, 6419.74321027163, 6435.908329792836, 6461.157090880045, 6469.708863101172, 6491.8052458425855, 4445.5776701213335, 4523.570260825217, 4568.588827005552, 4571.6114130298965, 4603.447586289633, 5833.445822646884, 6496.382843629847, 6634.208763633792, 6717.798466046026, 6725.2602213756445, 3982.601739825994, 4129.545749967501, 4389.0, 4519.05099929012, 4570.565796249351, 4003.3794761811964, 4842.467328593852, 4870.853874383774, 4873.171560834667, 4876.000839655724, 4568.2690769477385, 4697.694649847749, 5000.67495612784, 5216.713080780518, 5479.917801232985, 6410.67946602668, 6484.773032943919, 6522.249941256816, 6531.0, 6531.0, 1511.168857128575, 1601.2954300107915, 1662.3018332300462, 1662.5926647971326, 1673.0212825496976, 1122.4342042850178, 1145.7250259937555, 1150.1948635954698, 1156.919015668895, 1165.720227145479, 5033.0, 6093.9390606093375, 6127.509799216052, 6128.097521982396, 6144.446999770043, 6261.499080073674, 6577.86844263112, 6648.800535983928, 6694.656870095525, 6741.988701694785, 5642.802501912413, 5965.242362727104, 6076.787312444043, 6139.508839292846, 6143.754249830046, 3190.883190883172, 3347.7154441872713, 3479.7042251408407, 3503.404421248394, 3510.8946731598094, 6637.0796849386115, 6915.026538588455, 6937.375636192666, 6954.547954382949, 7006.614636194956, 6978.092847929736, 6980.930190698028, 6992.650367481606, 6999.685014174374, 7031.7890463286185, 6381.680915954184, 6545.476361891037, 6927.480438966997, 6950.513464057467, 7002.684879180449, 5869.4717475427415, 6774.830629234309, 6789.762358317435, 6816.79610583045, 6818.761343352959, 5547.750351234124, 5877.676727780014, 6583.868322633425, 6674.799756007327, 6750.45996320303, 6288.24541055058, 6340.144080549251, 6940.444764418733, 6962.632704880365, 6992.895106573507, 3670.3943849264574, 4386.320120381346, 4429.911401771882, 4432.82268709248, 4437.711548749321, 6510.674466276572, 6607.2071351438135, 6731.360520750521, 6778.898316525355, 6798.980152977093, 5668.149777533403, 6491.740330386731, 6506.4469520091325, 6511.47908167336, 6529.9020514691365, 6203.534734894991, 6418.614883107029, 6477.838054048687, 6489.0, 6531.412172904462, 5506.265526359184, 5937.406259374028, 6124.846878828065, 6314.463270622048, 6352.618842869442, 5761.8559536011935, 6452.483801295791, 6458.483321334188, 6543.069794410565, 6547.508936829852, 5330.307060082165, 6084.8783024340155, 6153.353897840662, 6306.540449224898, 6321.735652869353], "value": 4773.153745920439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.1901812915147, 713.9286071392858, 724.6014691919446, 732.114141888315, 759.1535437986643, 13126.934365328172, 15255.0846949183, 15858.541926001459, 15871.0, 15885.761713574291, 14700.264986750646, 14815.851841481583, 14815.92592037041, 14818.481353152642, 14844.109353438775, 909.9408538444997, 920.1948295241664, 950.9904900950971, 954.5799848066863, 955.2787645327802, 941.0636416765328, 949.8670186173941, 958.4872093430016, 958.58301638787, 961.216608464103, 1012.7215015870657, 1012.8936461671492, 1016.0753714120152, 1017.3641474078701, 1022.8056669232822, 1008.3193844155165, 1009.7122320138762, 1010.5200029985734, 1011.0900189829213, 1011.4689787861358, 741.5106030020215, 742.4913933955258, 744.6016381236012, 744.9478536502447, 745.7278093495889, 16159.0, 16522.917385413115, 16564.75152872708, 16767.329306827713, 17000.234989425506, 11568.421578921021, 14651.267436628126, 14785.70428591433, 14909.552713418614, 14921.030133041317, 10373.89626103737, 10891.0, 11768.823467647906, 11789.88210117905, 11984.221025633393, 2285.223024171773, 2326.6044772388736, 2349.7532759060396, 2380.4167978845294, 2447.155731272708, 111187.44406277998, 111327.33009004871, 116530.0, 117781.41109294399, 118775.99999999914, 11460.541578336773, 11543.88456115445, 11624.070074394029, 11684.415779211005, 11718.472668729928, 25108.372290692514, 25339.87330063338, 25368.492630147295, 25378.492430151662, 25448.363790905376, 7785.221477852169, 7848.21517848222, 7875.212478752079, 7955.005624296963, 8104.918950810535, 3742.9064273393383, 4120.896977575525, 4217.768022758716, 4248.63886569645, 4299.97850010748, 3950.1309711863346, 4036.8788936331957, 4055.3916912463364, 4079.9796001019804, 4081.2653722329674, 750.4446709434978, 757.4886951307845, 772.0349563046192, 779.5322806316278, 779.7562887195028, 524.3378490186119, 561.3936948096047, 566.4222493057059, 577.9624324418984, 595.2916029924463, 7994.2405471480115, 8881.37830351882, 10102.292839501311, 10200.404302577994, 10291.53688084023, 525.3826753564547, 554.6162324999153, 572.9541636669139, 576.0581449330291, 605.2404232687949, 3730.0674831292176, 4084.9183016339507, 4396.142752163361, 4612.7001744886475, 4854.586911075723, 94740.15748031496, 97665.51167244118, 97790.0, 98110.5094474523, 99038.0, 63308.36691633117, 81017.9745506367, 83534.58232708798, 86063.84840379041, 86862.0, 61713.0, 67376.0, 67681.0, 69923.0, 70259.56468164257, 9306.255499559884, 10524.0, 10888.61889833852, 10983.176261780558, 11076.889231107585, 34619.82690086534, 36816.44775328426, 38537.614623853406, 39016.0, 39185.216295674494, 50701.23948140855, 50816.13982112515, 51394.74302628463, 51483.0, 52051.2192317108, 13263.535776247785, 13360.797249628264, 13507.527236546674, 13773.586792396243, 14166.504172353918, 14561.854381456049, 14586.416543338148, 14657.926710366806, 14957.476488322856, 15110.39558417651, 13050.282234477196, 13370.532031378854, 13525.391357389139, 13543.88490647137, 14075.436982520585, 12328.0, 13337.19976801395, 13450.65493450647, 13604.727905441638, 13922.373493192628, 12769.61691149267, 13186.142900711424, 13420.932895335462, 13620.93189534046, 13890.652733681738, 9335.719928401888, 9579.185769209695, 9606.0, 9779.511024448606, 10257.487125643836, 10074.395536267848, 10614.59457041014, 10971.451427428756, 11109.388983605979, 11163.72090697739], "value": 7376.688866598942, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1650.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 121.0, 123.0, 122.0, 130.0, 181.0, 128.0, 124.0, 122.0, 124.0], "value": 128.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 146.0, 160.0, 192.0, 123.0, 117.0, 129.0, 159.0, 186.0, 132.0], "value": 151.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 212.0, 213.0, 212.0, 221.0, 219.0, 216.0, 218.0, 218.0, 221.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 64.0, 99.0, 66.0, 63.0, 61.0, 62.0, 81.0, 65.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 75.0, 96.0, 75.0, 75.0, 75.0, 90.0, 75.0, 74.0, 92.0], "value": 82.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 97.0, 97.0, 97.0, 97.0, 96.0, 97.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 101.0, 102.0, 127.0, 158.0, 136.0, 102.0, 102.0, 100.0], "value": 113.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 101.0, 103.0, 103.0, 101.0, 103.0, 139.0, 159.0, 102.0], "value": 111.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 83.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 68.0, 60.0, 62.0, 70.0, 71.0, 64.0, 65.0, 65.0, 66.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 90.0, 90.0, 93.0, 92.0, 92.0, 90.0, 91.0, 92.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 153.0, 166.0, 168.0, 143.0, 175.0, 167.0, 233.0, 161.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 205.0, 200.0, 207.0, 204.0, 200.0, 203.0, 205.0, 200.0, 233.0], "value": 205.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 73.0, 74.0, 80.0, 103.0, 104.0, 99.0, 82.0], "value": 83.8, "unit": "score"}]}]} 14:54:14 INFO - Return code: 0 14:54:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:54:14 ERROR - # TBPL SUCCESS # 14:54:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:14 INFO - 2017-03-28 14:54:14.799 screenresolution[1655:9816] starting screenresolution argv=screenresolution get 14:54:14 INFO - 2017-03-28 14:54:14.801 screenresolution[1655:9816] Display 0: 1600x1200x32@60 14:54:14 INFO - 2017-03-28 14:54:14.808 screenresolution[1656:9819] starting screenresolution argv=screenresolution list 14:54:14 INFO - Available Modes on Display 0 14:54:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:15 INFO - Intel Iris: 14:54:15 INFO - Chipset Model: Intel Iris 14:54:15 INFO - Type: GPU 14:54:15 INFO - Bus: Built-In 14:54:15 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:15 INFO - Vendor: Intel (0x8086) 14:54:15 INFO - Device ID: 0x0a2e 14:54:15 INFO - Revision ID: 0x0009 14:54:15 INFO - Displays: 14:54:15 INFO - Display: 14:54:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:15 INFO - Display Serial Number: 1600x1200 60 14:54:15 INFO - Main Display: Yes 14:54:15 INFO - Mirror: Off 14:54:15 INFO - Online: Yes 14:54:15 INFO - Rotation: Supported 14:54:15 INFO - Return code: 0 14:54:15 INFO - Running post-action listener: _resource_record_post_action 14:54:15 INFO - [mozharness: 2017-03-28 21:54:15.058550Z] Finished run-tests step (success) 14:54:15 INFO - Running post-run listener: _resource_record_post_run 14:54:15 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 39420928; Write bytes: 514151424; Read time: 446; Write time: 1913 14:54:15 INFO - TinderboxPrint: CPU usage
53.5% 14:54:15 INFO - TinderboxPrint: I/O read bytes / time
39,420,928 / 446 14:54:15 INFO - TinderboxPrint: I/O write bytes / time
514,151,424 / 1,913 14:54:15 INFO - TinderboxPrint: CPU idle
2,247.5 (46.5%) 14:54:15 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 14:54:15 INFO - TinderboxPrint: CPU user
1,618.5 (33.5%) 14:54:15 INFO - TinderboxPrint: Swap in / out
250,404,864 / 0 14:54:15 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202844672; Write bytes: 200984576; Read time: 15723; Write time: 455 14:54:15 INFO - run-tests - Wall time: 1188s; CPU: 53.0%; Read bytes: 38458368; Write bytes: 299625472; Read time: 431; Write time: 1415 14:54:15 INFO - Running post-run listener: _upload_blobber_files 14:54:15 INFO - Blob upload gear active. 14:54:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:54:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:54:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:54:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:17 INFO - (blobuploader) - INFO - Done attempting. 14:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:54:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:17 INFO - (blobuploader) - INFO - Done attempting. 14:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:18 INFO - (blobuploader) - INFO - Done attempting. 14:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:21 INFO - (blobuploader) - INFO - Done attempting. 14:54:21 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:21 INFO - Return code: 0 14:54:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5601fba52b31892f17347aa64c0092fa2b7f7e2f8bc0794661e13b2bc960222968578d8f2abd52da88fbfbb5d6b567c42fee1bafdacfbcfa7426ac1e2981be38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b98463dc0b10d95863f70274ad074366cd87bec527bc00f09eaa3c5161768dac01e15724ddb65e1003b395ece1e43ab39d31e27e4e8f95b0d273a82653423eb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/199eda41605861a23b0159b2ccba25b79e07f033473db277f2048652a2984a51b045b40f1ce15a25bc62ea72ff0f1e649883f5e999ae0af7fdcc38e6a7e13a0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0270c55d9a65d0c56e5f494a2fbfac02ffc51cafb0622447ef69c0c280a7a7ed6e581023fb726900285133c74346f9e4b2a6919e4627c365fd45e078f64a8712"} 14:54:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:21 INFO - Contents: 14:54:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5601fba52b31892f17347aa64c0092fa2b7f7e2f8bc0794661e13b2bc960222968578d8f2abd52da88fbfbb5d6b567c42fee1bafdacfbcfa7426ac1e2981be38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b98463dc0b10d95863f70274ad074366cd87bec527bc00f09eaa3c5161768dac01e15724ddb65e1003b395ece1e43ab39d31e27e4e8f95b0d273a82653423eb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/199eda41605861a23b0159b2ccba25b79e07f033473db277f2048652a2984a51b045b40f1ce15a25bc62ea72ff0f1e649883f5e999ae0af7fdcc38e6a7e13a0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0270c55d9a65d0c56e5f494a2fbfac02ffc51cafb0622447ef69c0c280a7a7ed6e581023fb726900285133c74346f9e4b2a6919e4627c365fd45e078f64a8712"} 14:54:21 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:21 INFO - Copying logs to upload dir... 14:54:21 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.146620 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2017-03-28 14:54:21.103763) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:21.107340) ========= 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.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5601fba52b31892f17347aa64c0092fa2b7f7e2f8bc0794661e13b2bc960222968578d8f2abd52da88fbfbb5d6b567c42fee1bafdacfbcfa7426ac1e2981be38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b98463dc0b10d95863f70274ad074366cd87bec527bc00f09eaa3c5161768dac01e15724ddb65e1003b395ece1e43ab39d31e27e4e8f95b0d273a82653423eb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/199eda41605861a23b0159b2ccba25b79e07f033473db277f2048652a2984a51b045b40f1ce15a25bc62ea72ff0f1e649883f5e999ae0af7fdcc38e6a7e13a0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0270c55d9a65d0c56e5f494a2fbfac02ffc51cafb0622447ef69c0c280a7a7ed6e581023fb726900285133c74346f9e4b2a6919e4627c365fd45e078f64a8712"} build_url:https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5601fba52b31892f17347aa64c0092fa2b7f7e2f8bc0794661e13b2bc960222968578d8f2abd52da88fbfbb5d6b567c42fee1bafdacfbcfa7426ac1e2981be38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b98463dc0b10d95863f70274ad074366cd87bec527bc00f09eaa3c5161768dac01e15724ddb65e1003b395ece1e43ab39d31e27e4e8f95b0d273a82653423eb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/199eda41605861a23b0159b2ccba25b79e07f033473db277f2048652a2984a51b045b40f1ce15a25bc62ea72ff0f1e649883f5e999ae0af7fdcc38e6a7e13a0b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0270c55d9a65d0c56e5f494a2fbfac02ffc51cafb0622447ef69c0c280a7a7ed6e581023fb726900285133c74346f9e4b2a6919e4627c365fd45e078f64a8712"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:21.142523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:21.142921) ========= 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.WDe6XGgerj/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.cmPBisOmba/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:21.192291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:54:21.192603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:54:21.193029) ========= ========= Total master_lag: 0.25 =========