builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0288 starttime: 1495488787.29 results: success (0) buildid: 20170522133621 builduid: eb23d765494845fda0e59bf890121674 revision: 64096bfc5a89e6c92911a3257fdca0f169418022 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:07.293570) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:07.294073) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-05-22 14:33:07.294357) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-05-22 14:33:09.207064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:09.207411) ========= 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.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-05-22 14:33:09-- 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% 18.2M=0.001s 2017-05-22 14:33:09 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147632 ========= master_lag: 0.46 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:09.810145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:09.810470) ========= 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.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.026300 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:09.858929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-22 14:33:09.859350) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 64096bfc5a89e6c92911a3257fdca0f169418022 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 64096bfc5a89e6c92911a3257fdca0f169418022 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-05-22 14:33:09,959 truncating revision to first 12 chars 2017-05-22 14:33:09,959 Setting DEBUG logging. 2017-05-22 14:33:09,959 attempt 1/10 2017-05-22 14:33:09,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/64096bfc5a89?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 14:33:11,749 unpacking tar archive at: mozilla-beta-64096bfc5a89/testing/mozharness/ program finished with exit code 0 elapsedTime=2.060567 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-22 14:33:11.941813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:11.942161) ========= script_repo_revision: 64096bfc5a89e6c92911a3257fdca0f169418022 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:11.942548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:11.942823) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:11.960881) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-22 14:33:11.961201) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:33:12 INFO - MultiFileLogger online at 20170522 14:33:12 in /builds/slave/test 14:33:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:33:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:33:12 INFO - {'append_to_log': False, 14:33:12 INFO - 'base_work_dir': '/builds/slave/test', 14:33:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:33:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:33:12 INFO - 'branch': 'Mozilla-Beta', 14:33:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:33:12 INFO - 'config_files': ('talos/mac_config.py',), 14:33:12 INFO - 'default_actions': ('clobber', 14:33:12 INFO - 'read-buildbot-config', 14:33:12 INFO - 'download-and-extract', 14:33:12 INFO - 'populate-webroot', 14:33:12 INFO - 'create-virtualenv', 14:33:12 INFO - 'install', 14:33:12 INFO - 'run-tests'), 14:33:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:33:12 INFO - 'download_minidump_stackwalk': True, 14:33:12 INFO - 'download_symbols': 'ondemand', 14:33:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:33:12 INFO - 'tooltool.py': '/tools/tooltool.py', 14:33:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:33:12 INFO - '/tools/misc-python/virtualenv.py')}, 14:33:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:33:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:33:12 INFO - 'gecko_profile': False, 14:33:12 INFO - 'gecko_profile_interval': 0, 14:33:12 INFO - 'installer_path': 'installer.exe', 14:33:12 INFO - 'log_level': 'info', 14:33:12 INFO - 'log_name': 'talos', 14:33:12 INFO - 'log_to_console': True, 14:33:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:33:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:33:12 INFO - 'opt_config_files': (), 14:33:12 INFO - 'pip_index': False, 14:33:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:12 INFO - 'cmd': ('bash', 14:33:12 INFO - '-c', 14:33:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:12 INFO - 'enabled': True, 14:33:12 INFO - 'halt_on_failure': False, 14:33:12 INFO - 'name': 'check_screen_resolution'},), 14:33:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:12 INFO - 'cmd': ('bash', 14:33:12 INFO - '-c', 14:33:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:12 INFO - 'enabled': True, 14:33:12 INFO - 'halt_on_failure': False, 14:33:12 INFO - 'name': 'check_screen_resolution'},), 14:33:12 INFO - 'run_cmd_checks_enabled': True, 14:33:12 INFO - 'suite': 'dromaeojs', 14:33:12 INFO - 'system_bits': '32', 14:33:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:33:12 INFO - 'title': 't-yosemite-r7-0288', 14:33:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:33:12 INFO - 'use_talos_json': True, 14:33:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:33:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:33:12 INFO - 'work_dir': 'build'} 14:33:12 INFO - [mozharness: 2017-05-22 21:33:12.170898Z] Running clobber step. 14:33:12 INFO - Running pre-action listener: _resource_record_pre_action 14:33:12 INFO - Running main action method: clobber 14:33:12 INFO - rmtree: /builds/slave/test/build 14:33:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:33:14 INFO - Running post-action listener: _resource_record_post_action 14:33:14 INFO - [mozharness: 2017-05-22 21:33:14.497979Z] Finished clobber step (success) 14:33:14 INFO - [mozharness: 2017-05-22 21:33:14.498110Z] Running read-buildbot-config step. 14:33:14 INFO - Running pre-action listener: _resource_record_pre_action 14:33:14 INFO - Running main action method: read_buildbot_config 14:33:14 INFO - Using buildbot properties: 14:33:14 INFO - { 14:33:14 INFO - "project": "", 14:33:14 INFO - "product": "firefox", 14:33:14 INFO - "script_repo_revision": "production", 14:33:14 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 14:33:14 INFO - "repository": "", 14:33:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 14:33:14 INFO - "buildid": "20170522133621", 14:33:14 INFO - "pgo_build": "False", 14:33:14 INFO - "basedir": "/builds/slave/test", 14:33:14 INFO - "buildnumber": 10, 14:33:14 INFO - "slavename": "t-yosemite-r7-0288", 14:33:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:33:14 INFO - "platform": "yosemite_r7", 14:33:14 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:33:14 INFO - "branch": "mozilla-beta", 14:33:14 INFO - "repo_path": "releases/mozilla-beta", 14:33:14 INFO - "revision": "64096bfc5a89e6c92911a3257fdca0f169418022", 14:33:14 INFO - "stage_platform": "macosx64", 14:33:14 INFO - "builduid": "eb23d765494845fda0e59bf890121674", 14:33:14 INFO - "slavebuilddir": "test" 14:33:14 INFO - } 14:33:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:33:14 INFO - Running post-action listener: _resource_record_post_action 14:33:14 INFO - [mozharness: 2017-05-22 21:33:14.501583Z] Finished read-buildbot-config step (success) 14:33:14 INFO - [mozharness: 2017-05-22 21:33:14.501722Z] Running download-and-extract step. 14:33:14 INFO - Running pre-action listener: _resource_record_pre_action 14:33:14 INFO - Running main action method: download_and_extract 14:33:14 INFO - mkdir: /builds/slave/test/build/tests 14:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:14 INFO - trying https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:33:14 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:33:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 14:33:15 INFO - Downloaded 1287 bytes. 14:33:15 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:33:15 INFO - Using the following test package requirements: 14:33:15 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:33:15 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:33:15 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:33:15 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:33:15 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:33:15 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:33:15 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:33:15 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:33:15 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:33:15 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:33:15 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:15 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:33:15 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:33:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:33:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:33:15 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:33:17 INFO - Expected file size: 51393220 14:33:17 INFO - Obtained file size: 51393220 14:33:33 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:33:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:33:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:33:33 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:33:34 INFO - Expected file size: 51393220 14:33:35 INFO - Obtained file size: 51393220 14:33:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 14:33:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 14:33:49 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 14:33:50 INFO - Expected file size: 14404449 14:33:50 INFO - Obtained file size: 14404449 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:51 INFO - trying https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:51 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 14:33:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 14:33:53 INFO - Downloaded 59649299 bytes. 14:33:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:53 INFO - mkdir: /builds/slave/test/properties 14:33:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:53 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:53 INFO - Contents: 14:33:53 INFO - build_url:https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:53 INFO - Running post-action listener: _resource_record_post_action 14:33:53 INFO - Running post-action listener: set_extra_try_arguments 14:33:53 INFO - [mozharness: 2017-05-22 21:33:53.840456Z] Finished download-and-extract step (success) 14:33:53 INFO - [mozharness: 2017-05-22 21:33:53.840561Z] Running populate-webroot step. 14:33:53 INFO - Running pre-action listener: _resource_record_pre_action 14:33:53 INFO - Running main action method: populate_webroot 14:33:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:53 INFO - '--tppagecycles', 14:33:53 INFO - '7'], 14:33:53 INFO - 'tests': ['tsvgm']}}, 14:33:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['damp', 'tps']}, 14:33:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'tests': ['damp', 'tps']}, 14:33:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['dromaeo_dom']}, 14:33:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['basic_compositor_video']}, 14:33:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['a11yr', 14:33:53 INFO - 'ts_paint', 14:33:53 INFO - 'tpaint', 14:33:53 INFO - 'sessionrestore', 14:33:53 INFO - 'sessionrestore_no_auto_restore', 14:33:53 INFO - 'tabpaint']}, 14:33:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['tsvgx', 14:33:53 INFO - 'tsvgr_opacity', 14:33:53 INFO - 'tart', 14:33:53 INFO - 'tscrollx', 14:33:53 INFO - 'cart', 14:33:53 INFO - 'tsvg_static']}, 14:33:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:53 INFO - 'tsvgr_opacity', 14:33:53 INFO - 'tart', 14:33:53 INFO - 'tscrollx', 14:33:53 INFO - 'cart', 14:33:53 INFO - 'tsvg_static']}, 14:33:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'talos_options': ['--disable-e10s'], 14:33:53 INFO - 'tests': ['tp5o']}, 14:33:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:33:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'talos_options': ['--disable-e10s', 14:33:53 INFO - '--xperf_path', 14:33:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:53 INFO - 'tests': ['tp5n']}, 14:33:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:33:53 INFO - 'talos_options': ['--xperf_path', 14:33:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:53 INFO - 'tests': ['tp5n']}}, 14:33:53 INFO - 'talos.zip': {'path': '', 14:33:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:53 INFO - Running post-action listener: _resource_record_post_action 14:33:53 INFO - [mozharness: 2017-05-22 21:33:53.859260Z] Finished populate-webroot step (success) 14:33:53 INFO - [mozharness: 2017-05-22 21:33:53.859395Z] Running create-virtualenv step. 14:33:53 INFO - Running pre-action listener: _resource_record_pre_action 14:33:53 INFO - Running main action method: create_virtualenv 14:33:53 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:53 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:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:53 INFO - Using real prefix '/tools/python27' 14:33:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:54 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:57 INFO - Installing pip.................done. 14:33:57 INFO - Return code: 0 14:33:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:33:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:33:57 INFO - Reading from file tmpfile_stdout 14:33:57 INFO - Output received: 14:33:57 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:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:57 INFO - 'HOME': '/Users/cltbld', 14:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:57 INFO - 'LOGNAME': 'cltbld', 14:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:57 INFO - 'MOZ_NO_REMOTE': '1', 14:33:57 INFO - 'NO_EM_RESTART': '1', 14:33:57 INFO - 'PAGER': '/bin/cat', 14:33:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:57 INFO - 'PWD': '/builds/slave/test', 14:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:57 INFO - 'SHELL': '/bin/bash', 14:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:33:57 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:57 INFO - 'USER': 'cltbld', 14:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:57 INFO - 'XPC_FLAGS': '0x0', 14:33:57 INFO - 'XPC_SERVICE_NAME': '0', 14:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:58 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:58 INFO - Cleaning up... 14:33:58 INFO - Return code: 0 14:33:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:58 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:33:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:33:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:58 INFO - 'HOME': '/Users/cltbld', 14:33:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:58 INFO - 'LOGNAME': 'cltbld', 14:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:58 INFO - 'MOZ_NO_REMOTE': '1', 14:33:58 INFO - 'NO_EM_RESTART': '1', 14:33:58 INFO - 'PAGER': '/bin/cat', 14:33:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:58 INFO - 'PWD': '/builds/slave/test', 14:33:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:58 INFO - 'SHELL': '/bin/bash', 14:33:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:33:58 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:33:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:58 INFO - 'USER': 'cltbld', 14:33:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:58 INFO - 'XPC_FLAGS': '0x0', 14:33:58 INFO - 'XPC_SERVICE_NAME': '0', 14:33:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:58 INFO - Downloading/unpacking psutil>=3.1.1 14:33:58 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:58 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:58 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:58 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:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:34:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:34:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:34:00 INFO - Installing collected packages: psutil 14:34:00 INFO - Running setup.py install for psutil 14:34:00 INFO - building 'psutil._psutil_osx' extension 14:34:00 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:34:00 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:34:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:34:01 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:34:01 INFO - building 'psutil._psutil_posix' extension 14:34:01 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:34:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:34:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:34:01 INFO - ^ 14:34:01 INFO - 1 warning generated. 14:34:01 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:34:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:34:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:34:01 INFO - Successfully installed psutil 14:34:01 INFO - Cleaning up... 14:34:01 INFO - Return code: 0 14:34:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:01 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:01 INFO - 'HOME': '/Users/cltbld', 14:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:01 INFO - 'LOGNAME': 'cltbld', 14:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:01 INFO - 'MOZ_NO_REMOTE': '1', 14:34:01 INFO - 'NO_EM_RESTART': '1', 14:34:01 INFO - 'PAGER': '/bin/cat', 14:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:01 INFO - 'PWD': '/builds/slave/test', 14:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:01 INFO - 'SHELL': '/bin/bash', 14:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:01 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:01 INFO - 'USER': 'cltbld', 14:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:01 INFO - 'XPC_FLAGS': '0x0', 14:34:01 INFO - 'XPC_SERVICE_NAME': '0', 14:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:34:01 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:01 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:01 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:01 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:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:34:03 INFO - Installing collected packages: mozsystemmonitor 14:34:03 INFO - Running setup.py install for mozsystemmonitor 14:34:03 INFO - Successfully installed mozsystemmonitor 14:34:03 INFO - Cleaning up... 14:34:03 INFO - Return code: 0 14:34:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:04 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:04 INFO - 'HOME': '/Users/cltbld', 14:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:04 INFO - 'LOGNAME': 'cltbld', 14:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:04 INFO - 'MOZ_NO_REMOTE': '1', 14:34:04 INFO - 'NO_EM_RESTART': '1', 14:34:04 INFO - 'PAGER': '/bin/cat', 14:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:04 INFO - 'PWD': '/builds/slave/test', 14:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:04 INFO - 'SHELL': '/bin/bash', 14:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:04 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:04 INFO - 'USER': 'cltbld', 14:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:04 INFO - 'XPC_FLAGS': '0x0', 14:34:04 INFO - 'XPC_SERVICE_NAME': '0', 14:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:04 INFO - Downloading/unpacking jsonschema==2.5.1 14:34:04 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:04 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 - 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:04 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:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:34:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:34:06 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:06 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:06 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:06 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:06 INFO - Downloading functools32-3.2.3-2.tar.gz 14:34:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:34:06 INFO - warning: no files found matching '*.txt' 14:34:06 INFO - no previously-included directories found matching 'build' 14:34:06 INFO - no previously-included directories found matching 'dist' 14:34:06 INFO - no previously-included directories found matching '.git*' 14:34:06 INFO - Installing collected packages: jsonschema, functools32 14:34:06 INFO - Running setup.py install for functools32 14:34:06 INFO - warning: no files found matching '*.txt' 14:34:06 INFO - no previously-included directories found matching 'build' 14:34:06 INFO - no previously-included directories found matching 'dist' 14:34:06 INFO - no previously-included directories found matching '.git*' 14:34:06 INFO - Successfully installed jsonschema functools32 14:34:06 INFO - Cleaning up... 14:34:06 INFO - Return code: 0 14:34:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:07 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:07 INFO - 'HOME': '/Users/cltbld', 14:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:07 INFO - 'LOGNAME': 'cltbld', 14:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:07 INFO - 'MOZ_NO_REMOTE': '1', 14:34:07 INFO - 'NO_EM_RESTART': '1', 14:34:07 INFO - 'PAGER': '/bin/cat', 14:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:07 INFO - 'PWD': '/builds/slave/test', 14:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:07 INFO - 'SHELL': '/bin/bash', 14:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:07 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:07 INFO - 'USER': 'cltbld', 14:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:07 INFO - 'XPC_FLAGS': '0x0', 14:34:07 INFO - 'XPC_SERVICE_NAME': '0', 14:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:34:07 INFO - Cleaning up... 14:34:07 INFO - Return code: 0 14:34:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:07 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:07 INFO - 'HOME': '/Users/cltbld', 14:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:07 INFO - 'LOGNAME': 'cltbld', 14:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:07 INFO - 'MOZ_NO_REMOTE': '1', 14:34:07 INFO - 'NO_EM_RESTART': '1', 14:34:07 INFO - 'PAGER': '/bin/cat', 14:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:07 INFO - 'PWD': '/builds/slave/test', 14:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:07 INFO - 'SHELL': '/bin/bash', 14:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:07 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:07 INFO - 'USER': 'cltbld', 14:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:07 INFO - 'XPC_FLAGS': '0x0', 14:34:07 INFO - 'XPC_SERVICE_NAME': '0', 14:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:34:07 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:07 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:07 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:07 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:09 INFO - Downloading blobuploader-1.2.4.tar.gz 14:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:34:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:34:09 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:09 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:09 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:09 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:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:34:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:34:09 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:09 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:09 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:09 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:09 INFO - Downloading docopt-0.6.1.tar.gz 14:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:34:10 INFO - Installing collected packages: blobuploader, requests, docopt 14:34:10 INFO - Running setup.py install for blobuploader 14:34:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:34:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:34:10 INFO - Running setup.py install for requests 14:34:10 INFO - Running setup.py install for docopt 14:34:10 INFO - Successfully installed blobuploader requests docopt 14:34:10 INFO - Cleaning up... 14:34:10 INFO - Return code: 0 14:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:10 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:10 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:10 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:10 INFO - 'HOME': '/Users/cltbld', 14:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:10 INFO - 'LOGNAME': 'cltbld', 14:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:10 INFO - 'MOZ_NO_REMOTE': '1', 14:34:10 INFO - 'NO_EM_RESTART': '1', 14:34:10 INFO - 'PAGER': '/bin/cat', 14:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:10 INFO - 'PWD': '/builds/slave/test', 14:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:10 INFO - 'SHELL': '/bin/bash', 14:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:10 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:10 INFO - 'USER': 'cltbld', 14:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:10 INFO - 'XPC_FLAGS': '0x0', 14:34:10 INFO - 'XPC_SERVICE_NAME': '0', 14:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-oOW14p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-NPA2HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-UpRGD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ISqNhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-32ZiAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-_ENkUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-9uAhai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-1c3LHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:11 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-HB_sxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-edhN5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-EfUBdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-CFLLjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-1135_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-njqVMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-3lNZcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-oWGZl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:12 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-ULeGXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:34:12 INFO - Running setup.py install for manifestparser 14:34:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozcrash 14:34:13 INFO - Running setup.py install for mozdebug 14:34:13 INFO - Running setup.py install for mozdevice 14:34:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozfile 14:34:13 INFO - Running setup.py install for mozhttpd 14:34:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozinfo 14:34:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozInstall 14:34:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for mozleak 14:34:14 INFO - Running setup.py install for mozlog 14:34:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for moznetwork 14:34:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for mozprocess 14:34:14 INFO - Running setup.py install for mozprofile 14:34:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for mozrunner 14:34:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:34:15 INFO - Running setup.py install for mozscreenshot 14:34:15 INFO - Running setup.py install for moztest 14:34:15 INFO - Running setup.py install for mozversion 14:34:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:15 INFO - Cleaning up... 14:34:15 INFO - Return code: 0 14:34:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:15 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:15 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:15 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:15 INFO - 'HOME': '/Users/cltbld', 14:34:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:15 INFO - 'LOGNAME': 'cltbld', 14:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:15 INFO - 'MOZ_NO_REMOTE': '1', 14:34:15 INFO - 'NO_EM_RESTART': '1', 14:34:15 INFO - 'PAGER': '/bin/cat', 14:34:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:15 INFO - 'PWD': '/builds/slave/test', 14:34:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:15 INFO - 'SHELL': '/bin/bash', 14:34:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:15 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:15 INFO - 'USER': 'cltbld', 14:34:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:15 INFO - 'XPC_FLAGS': '0x0', 14:34:15 INFO - 'XPC_SERVICE_NAME': '0', 14:34:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-nTUb4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:15 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:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:15 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-HwvsPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-CHqrcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-bxNYtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-fOs9xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-5KSuGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-05nOHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-QtCG6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-t3JQIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-qIkXgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-K3ZPdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:16 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:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:16 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-WZuYqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-lF_WIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-enpqm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-MF3ual-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-CdlRYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:17 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-kfIjCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:17 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:17 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:17 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:17 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:17 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:17 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:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:19 INFO - Downloading blessings-1.6.tar.gz 14:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:19 INFO - Installing collected packages: blessings 14:34:19 INFO - Running setup.py install for blessings 14:34:19 INFO - Successfully installed blessings 14:34:19 INFO - Cleaning up... 14:34:19 INFO - Return code: 0 14:34:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:19 INFO - Reading from file tmpfile_stdout 14:34:19 INFO - Current package versions: 14:34:19 INFO - blessings == 1.6 14:34:19 INFO - blobuploader == 1.2.4 14:34:19 INFO - docopt == 0.6.1 14:34:19 INFO - functools32 == 3.2.3-2 14:34:19 INFO - jsonschema == 2.5.1 14:34:19 INFO - manifestparser == 1.1 14:34:19 INFO - mozInstall == 1.12 14:34:19 INFO - mozcrash == 1.0 14:34:19 INFO - mozdebug == 0.1 14:34:19 INFO - mozdevice == 0.49 14:34:19 INFO - mozfile == 1.2 14:34:19 INFO - mozhttpd == 0.7 14:34:19 INFO - mozinfo == 0.9 14:34:19 INFO - mozleak == 0.1 14:34:19 INFO - mozlog == 3.4 14:34:19 INFO - moznetwork == 0.27 14:34:19 INFO - mozprocess == 0.25 14:34:19 INFO - mozprofile == 0.28 14:34:19 INFO - mozrunner == 6.13 14:34:19 INFO - mozscreenshot == 0.1 14:34:19 INFO - mozsystemmonitor == 0.3 14:34:19 INFO - moztest == 0.8 14:34:19 INFO - mozversion == 1.4 14:34:19 INFO - psutil == 3.1.1 14:34:19 INFO - requests == 1.2.3 14:34:19 INFO - wsgiref == 0.1.2 14:34:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:19 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:19 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:34:19 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:19 INFO - 'HOME': '/Users/cltbld', 14:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:19 INFO - 'LOGNAME': 'cltbld', 14:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:19 INFO - 'MOZ_NO_REMOTE': '1', 14:34:19 INFO - 'NO_EM_RESTART': '1', 14:34:19 INFO - 'PAGER': '/bin/cat', 14:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:19 INFO - 'PWD': '/builds/slave/test', 14:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:19 INFO - 'SHELL': '/bin/bash', 14:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:19 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:19 INFO - 'USER': 'cltbld', 14:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:19 INFO - 'XPC_FLAGS': '0x0', 14:34:19 INFO - 'XPC_SERVICE_NAME': '0', 14:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 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:19 INFO - Cleaning up... 14:34:19 INFO - Return code: 0 14:34:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:19 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/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 0x10bea8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd063d63480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, '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.BNr901E9s8/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.eJl9uNzcVp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:19 INFO - 'HOME': '/Users/cltbld', 14:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:19 INFO - 'LOGNAME': 'cltbld', 14:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:19 INFO - 'MOZ_NO_REMOTE': '1', 14:34:19 INFO - 'NO_EM_RESTART': '1', 14:34:19 INFO - 'PAGER': '/bin/cat', 14:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:19 INFO - 'PWD': '/builds/slave/test', 14:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:19 INFO - 'SHELL': '/bin/bash', 14:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:19 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:19 INFO - 'USER': 'cltbld', 14:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:19 INFO - 'XPC_FLAGS': '0x0', 14:34:19 INFO - 'XPC_SERVICE_NAME': '0', 14:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:34:20 INFO - Cleaning up... 14:34:20 INFO - Return code: 0 14:34:20 INFO - Running post-action listener: _resource_record_post_action 14:34:20 INFO - Running post-action listener: _start_resource_monitoring 14:34:20 INFO - Starting resource monitoring. 14:34:20 INFO - [mozharness: 2017-05-22 21:34:20.272782Z] Finished create-virtualenv step (success) 14:34:20 INFO - [mozharness: 2017-05-22 21:34:20.273743Z] Running install step. 14:34:20 INFO - Running pre-action listener: _resource_record_pre_action 14:34:20 INFO - Running main action method: install 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Output received: 14:34:20 INFO - Usage: mozinstall [options] installer 14:34:20 INFO - Options: 14:34:20 INFO - -h, --help show this help message and exit 14:34:20 INFO - -d DEST, --destination=DEST 14:34:20 INFO - Directory to install application into. [default: 14:34:20 INFO - "/builds/slave/test"] 14:34:20 INFO - --app=APP Application being installed. [default: firefox] 14:34:20 INFO - mkdir: /builds/slave/test/build/application 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:34:35 INFO - Reading from file tmpfile_stdout 14:34:35 INFO - Output received: 14:34:35 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:34:35 INFO - Running post-action listener: _resource_record_post_action 14:34:35 INFO - [mozharness: 2017-05-22 21:34:35.319895Z] Finished install step (success) 14:34:35 INFO - [mozharness: 2017-05-22 21:34:35.320028Z] Running run-tests step. 14:34:35 INFO - Running pre-action listener: _resource_record_pre_action 14:34:35 INFO - Running main action method: run_tests 14:34:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:34:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:34:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:34:35 INFO - 2017-05-22 14:34:35.334 screenresolution[1626:7290] starting screenresolution argv=screenresolution get 14:34:35 INFO - 2017-05-22 14:34:35.337 screenresolution[1626:7290] Display 0: 1600x1200x32@60 14:34:35 INFO - 2017-05-22 14:34:35.343 screenresolution[1627:7292] starting screenresolution argv=screenresolution list 14:34:35 INFO - Available Modes on Display 0 14:34:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:34:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:34:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:34:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:34:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:34:35 INFO - Intel Iris: 14:34:35 INFO - Chipset Model: Intel Iris 14:34:35 INFO - Type: GPU 14:34:35 INFO - Bus: Built-In 14:34:35 INFO - VRAM (Dynamic, Max): 1536 MB 14:34:35 INFO - Vendor: Intel (0x8086) 14:34:35 INFO - Device ID: 0x0a2e 14:34:35 INFO - Revision ID: 0x0009 14:34:35 INFO - Displays: 14:34:35 INFO - Display: 14:34:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:34:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:34:35 INFO - Display Serial Number: 1600x1200 60 14:34:35 INFO - Main Display: Yes 14:34:35 INFO - Mirror: Off 14:34:35 INFO - Online: Yes 14:34:35 INFO - Rotation: Supported 14:34:35 INFO - Return code: 0 14:34:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:35 INFO - Python 2.7.3 14:34:35 INFO - Return code: 0 14:34:35 INFO - grabbing minidump binary from tooltool 14:34:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc86d30>, '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 0x10bbaddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc7bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:34:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:34:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:34:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:35 INFO - Return code: 0 14:34:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:34:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:35 INFO - ENV: RUST_BACKTRACE is now 1 14:34:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:34:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--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:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0288 --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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BNr901E9s8/Render', 14:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:35 INFO - 'HOME': '/Users/cltbld', 14:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:35 INFO - 'LOGNAME': 'cltbld', 14:34:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:35 INFO - 'MOZ_NO_REMOTE': '1', 14:34:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:35 INFO - 'NO_EM_RESTART': '1', 14:34:35 INFO - 'PAGER': '/bin/cat', 14:34:35 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:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:35 INFO - 'PWD': '/builds/slave/test', 14:34:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:35 INFO - 'RUST_BACKTRACE': '1', 14:34:35 INFO - 'SHELL': '/bin/bash', 14:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJl9uNzcVp/Listeners', 14:34:35 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 14:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:35 INFO - 'USER': 'cltbld', 14:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:35 INFO - 'XPC_FLAGS': '0x0', 14:34:35 INFO - 'XPC_SERVICE_NAME': '0', 14:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--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:35 INFO - mozversion application_buildid: 20170522133621 14:34:35 INFO - mozversion application_changeset: 64096bfc5a89e6c92911a3257fdca0f169418022 14:34:35 INFO - mozversion application_display_name: Firefox 14:34:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:35 INFO - mozversion application_name: Firefox 14:34:35 INFO - mozversion application_remotingname: firefox 14:34:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:34:35 INFO - mozversion application_vendor: Mozilla 14:34:35 INFO - mozversion application_version: 54.0 14:34:35 INFO - mozversion platform_buildid: 20170522133621 14:34:35 INFO - mozversion platform_changeset: 64096bfc5a89e6c92911a3257fdca0f169418022 14:34:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:34:35 INFO - mozversion platform_version: 54.0 14:34:35 INFO - using testdate: 1495488875 14:34:35 INFO - actual date: 1495488875 14:34:35 INFO - starting webserver on 'localhost:49219' 14:34:35 INFO - SUITE-START | Running 2 tests 14:34:35 INFO - TEST-START | dromaeo_css 14:34:35 INFO - operating with platform_type : mac_ 14:34:35 INFO - Initialising browser for dromaeo_css test... 14:34:35 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpNFNcON/profile http://localhost:49219/getInfo.html) 14:34:38 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 14:34:38 INFO - PROCESS | 1637 | colorDepth:24 14:34:38 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 14:34:38 INFO - PROCESS | 1637 | __metrics 14:34:38 INFO - PROCESS | 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:34:38 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:34:39 INFO - TEST-INFO | 1637: exit 0 14:34:39 INFO - Browser initialized. 14:34:39 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:39 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpNFNcON/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:51 INFO - PROCESS | 1640 | RSS: Main: 165109760 14:34:51 INFO - PROCESS | 1640 | 14:37:54 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 14:37:56 INFO - PROCESS | 1640 | RSS: Main: 323239936 14:37:56 INFO - PROCESS | 1640 | 14:40:59 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 14:41:00 INFO - PROCESS | 1640 | RSS: Main: 306544640 14:41:00 INFO - PROCESS | 1640 | 14:44:02 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 14:44:03 INFO - PROCESS | 1640 | RSS: Main: 296763392 14:44:03 INFO - PROCESS | 1640 | 14:47:05 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 14:47:06 INFO - PROCESS | 1640 | RSS: Main: 296583168 14:47:06 INFO - PROCESS | 1640 | 14:50:09 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 14:50:10 INFO - PROCESS | 1640 | RSS: Main: 261959680 14:50:10 INFO - PROCESS | 1640 | 14:53:13 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 14:53:14 INFO - PROCESS | 1640 | RSS: Main: 409812992 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | __start_tp_report 14:53:14 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 14:53:14 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 14:53:14 INFO - PROCESS | 1640 | |i|pagename|runs| 14:53:14 INFO - PROCESS | 1640 | |0;dojo.html;2938.2213713365954;2973.4499117663227;3587.5336206293177;3591.120175556989;3611.458281257809;6189.00975843865;6190.53570982177;6198.6900654967185;6215.906761398577;6255.937440625592;6155.01519756839;6159.137720719103;6159.5996260243055;6160.1683772690785;6167.845803854906;5553.333599968009;5574.219609254707;5631.18347839563;5681.431856814306;5735.483806457418;5462.945370546284;5491.862703432406;5725.427457254283;5744.368119506851;5745.368009518949;5513.476219759116;5596.244506991545;5599.552035837143;5600.327960644729;5603.3836278009385;5571.387147413781;5583.106702927533;5585.413531579166;5596.552275817966;5596.720163991784;5448.863778405532;5526.47498487643;5540.63985840919;5544.7227638618315;5550.639208451437;5569.579757161939;6158.860610787019;6168.352323006065;6183.783567575113;6187.535934804908;6054.545909056838;6366.777162799326;6380.936190638081;6426.2931077581425;6445.065465507522;6628.536002479828;6632.502562307846;6634.635095069769;6641.833954151137;6650.667466626649;6182.84542886427;6210.627362358227;6212.968935155341;6218.595791273553;6239.750409983595;6489;6528.934710652928;6605.339466053356;6784.864302713918;6795.082663840319;5700.486956173964;6355.904661430082;6430.871382572323;6493.058506516529;6494.512911531654;6612.07430959666;6625.469962403093;6628.403443690091;6637.1703537057865;6662.367075127952;6234.470070044097;6375.808725738235;6536.411722944958;6536.542442029107;6556.114924485229;6321.494280457552;6357.936420635734;6399.904001439889;6423.582467139621;6448.903266451007;5319.6808191508635;5323.121684922022;5325.3875804282225;5337.653052551572;5359.705216213146;4274.230638485103;4623.214053610895;4656.045510670305;4658.883527911829;4685.765711714469;6060.757569697251;6086.969565152146;6131.896637733196;6155.538334624832;6244.875102498016;6627.237867645283;6628.370304821035;6629;6638.900416493757;6639.336066393322;6244.438000579969;6244.781432649836;6251.28110267316;6262.843428914222;6273.65494897785;5599.832005039855;5618;5679.432056794288;5742.6554406734895;5778.104393818965;5742.856428589319;5761.596688231783;6635.938249879996;6643.966780166068;6751.561148525527;6472.385123413268;6491.383318584727;6499.1876015498065;6512.05575191604;6528.249251336157;6708.463322934056;6836.38472537474;6858.759943401957;6867.416269617139;6876.312368763284;6340.904886426615;6383.425491705769;6398.968005159944;6423.807285781434;6516.380943810331;5425.403205647454;6466.482681385572;6577.6053436793945;6594.505412094208;6616;6290.402411770874;6400.807975760735;6444.581102228341;6449.161608990801;6451.258105317948;6268.43584077417;6321.146645203023;6337.588056776295;6348.555601107878;6439.710213040518;6326.905096423649;6330.968345158245;6331.303556608677;6338;6348.174737284124;6218.844528886814;6261.7182226799;6424.839379015562;6441.903371449525;6450.096986421999;6185.257769067741;6204.844878878064;6282.811515654537;6299.905501417391;6322.33615470369 14:53:14 INFO - PROCESS | 1640 | |1;ext.html;636.5925807483212;806.1213277527496;825.4964471672279;888.9822203555929;898.2544488074899;30120.84939575302;30579.694203057963;41552.96117597058;42205.366919496235;42275;44153.67538973836;44231.336529952074;44264.77867610665;44377.7781111094;44424.88937776557;9773.706788796348;9932.35439696421;9947.005299470067;9952.651657192;9972.351797133164;8094.3119834814115;8256.917430825704;11841.644750657451;11999.520019199264;12234.571789987353;11979.161458697894;12030.218035827644;12040.337781422018;12044.457999390004;12054.879451205506;11964.64106076819;11990.280583164951;12010.919017288443;12014.699632509257;12041.93979030108;11618.593349232737;11695.415229238504;11703.590374336942;11710.590129345517;11711.53153873844;41483.7925810372;41525.792371037955;41539.1692166158;41560.79219603883;41669.1666166675;4029.700943259604;4084.8774536763945;4095.406166105898;4115.238680844053;4120.196561670476;23955.520889582283;28765.280867978257;30763.538546921813;31046.223844403845;31367.529487057713;3156.8737250509953;3275.9189467475912;3306.6528014558608;3392.932141357184;3411.522386865841;342648;343000.9999999975;344016;345803;346161;54168.45831541634;54177.45822541803;54184;54230;54394.72802635962;45016.099678006256;55368.61578460571;55425;55466.16800747992;56670.43329566652;15641.296141673653;15753.44862929792;15782.210889455482;15791.052536847827;15795.526134215992;13370.39833207508;13376.598702038955;13385.598432047054;13389.531366402129;13397.799033014513;11085.83371249432;11287.830682539769;11533.538658453735;14248.643783905485;14272.857271427152;4506.610918560104;6134.2332208473945;6148.956266648038;6150.661713605705;6185.783497577563;1082.2802836114054;1110.650145204258;1115.5593540551438;1121.4673030310537;1161.3114007514341;29101.981430649827;29496.852515737282;29670.851645741634;29847.701522984928;29907.700922990927;1161.647663481457;1172.783035138502;1184.2657552317446;1204.241327963395;1223.3332833605707;14454.988150829551;14625.634359141106;14638.268086595628;14908.70182596342;14925.402983880524;520883;525144;525680;526012;526170;49991.50008499941;50885.23672144921;53539.39381818552;59734.70132649396;60523.394766052654;1485.1534625263712;1491.8209814822292;1496.4014394242172;1507.0053764515367;1507.2765072764914;29086.56370154407;30539.2365190872;30926.762929482567;31977.84011080023;32476.837615812714;50510.49489505058;50832.74583627058;50836.237456438925;50907.49092509028;50991.72520687013;520490;529422;531427;612608;625977;36674.8166259167;37168.44247336346;37359.626403735616;37573.43639845459;37668.05829854275;46660.766696166305;47101.52898471108;47498.05003899972;47797.522024779304;47904.28143577919;31555.526667100472;31885.68114318827;32014.35971280608;32042.19894502656;33101.33797323992;25943;31699.683003170594;31724.365512689154;32447.513287301183;32716.67283327136;37243.882683519536;38531.42202867015;38756.40363766368;39163.41254881122;39410.80294598509;31987;32502.51246231261;32630.83684581562;32651.34697306088;33452.66547334593;23936.52126957416;24639;25513.48973020492;25524.744752552237;25887.482250355264 14:53:14 INFO - PROCESS | 1640 | |2;jquery.html;404.538825738658;410.35574148586693;417.4990011985618;426.74182119817516;447.24192493723154;19706.802931970695;21209.939503024845;21942;22137.889310553466;22190.55618887623;22954.426139346524;23531.647025294635;24035.399115022134;24053.398665033383;24383.65889876056;6702.899456508144;6736.2590115087305;6987.301269872997;6992.79021629352;6993.195782485029;6764.9323506764795;6828.48786341024;6881.036654868322;6899.482538809584;6902.240753517134;6558.80323590293;6785.185777706657;6866.896996545056;6874;6907.412869906039;6526.771562995273;6820.488463365243;6868.62222577758;6888.138982627146;6894.172699276069;6600.504962127811;6622.8344291393105;6668.499862510283;6669.733210671568;6671.332866713338;26153.476930461475;27028.729712703014;27245.86377068122;27262.454750905068;27679.446411071665;3425.7667239793655;3446.9655303446893;3486.0761898097026;3586.3903136466865;3696.2792255510185;16440.177991100398;16633.916830415772;16642.83357166425;16690.749638755427;16693.499195024167;2102.096098677568;2165.4802847316687;2187.6827859960376;2233.4304752288226;2242.9775702242932;131590;133534;135635;136294;137439;20386.796132038788;20418.795812041688;20760.68858967117;20965.89517052405;21020.15919363239;33790.32419351599;34395;34793.9561813146;34840.65159348425;34869.476957845654;10996;11108.055815255793;11307.378094204732;11352.48913798881;11509.53961841517;9149.359544831797;9221.631134754669;9230.907690923139;9421;9429.62281508732;9996.850047249298;10309.123724483354;10412.32319899204;10417.479126043667;10428.791424171473;3552.467129930531;3648.9635103648625;4289.828406863753;4353.36876152956;4368.934465983013;906.8685040669066;916.7708072981754;920.2132176988712;942.1332374215738;942.8397172480695;21612.675809862863;23892.402689932544;24021.919013644427;24174.39564010914;24182.516349672907;808.567416432206;942.9198518125899;953.3326671330096;982.9852552211722;987.8419452887647;9152.405093668891;12471.376431178587;13090.738185236241;13352.866471335357;13366;733582;738135;741586;759788;768499;83104.58447707843;107164;117993.41003294928;121198;126946;1262.3309645887693;1311.8097875807955;1336.5322137252078;1338.0432990411903;1338.3174580963778;21084.68372974376;21110.894445527672;21537.353879383645;21820.781792181875;21831.454213644785;54760.17859732187;55136.44863551474;55812.72093639506;56483.15275270792;56774.7161264191;73509;74110.62944685241;74589;75351.62324188344;75732.24267757252;11505.48225329879;11603.593874214363;11760.823587646364;11866.644000679993;12144.271343719405;13002.739945201232;13113.934430327787;13245.536406225736;13449.731005380034;13469.259190744604;11242.269252498561;11339.659810205707;11377.601783937522;11705.297682139098;11710;10024.799504010025;10129.848052279074;10587.258891877802;10704.785904281714;10910.345379277269;11386.316820990767;11525.366104864313;11849.703757406134;11850.407479625812;11901.132132056942;11265.155113366694;11414.600488982845;11704.648860534198;11885.46515406826;11913.761724765629;11128.777424451628;11152.49813758399;11337;11363.420522535374;11447.484863181344 14:53:14 INFO - PROCESS | 1640 | |3;mootools.html;1556.1052394872947;1580.4468436047378;1595.712771701094;1634.3544300001506;1647.2340361731792;11128.721781955455;11692.532298708042;11695.883041169585;11715.121365897548;11721.120915931308;11116.388598627072;11373.886261137386;11391.373474458902;11638.359890206035;11649.475773590188;2146.302451703195;2444.865532395722;2472.3942634054683;2482.7765501104896;2485.962710559343;2373.4541055557233;2448.534778392109;2451.9877400613063;2454.116518053508;2454.349597356702;2388.6178211486167;2443.609022556391;2466.839655422401;2468.7531246875346;2475.232677869857;2342.6603142544404;2413.1071503543712;2414.396400899775;2418.0327868852423;2421.2615152378517;2380.321608341614;2402.039184326266;2413.2157048958984;2413.915512957038;2414.7585241475886;11185.72035699114;11432.314061156358;11437.771244575062;11437.828432573519;11448.942755286254;5635.943640563582;5787.189793428924;5796.449337312949;5803.7968671096305;5838.5329173666005;3427.5715535558056;3447.5518182636347;3530.5586801649793;3551.058969373118;3568.6252943441086;1213.696575856036;1219.3476490077767;1257.937103144839;1265.4305562496913;1267.6704056945262;14138.222397768119;14418.711625767532;14768.335424905905;15779.605509862346;15997.92001039999;4495.617872480876;4671.672982891165;4683.7892294846815;4712.976435117803;4714.717116972993;12015.978641815369;12030.39848007596;12044.036477081812;12049.698757530956;12049.819252711217;4980.078685443204;5135.537801597799;5600.327960644749;5716.314042314865;5726.828195154152;5175.508326708957;5279.313689220454;5312.3890752563175;5362.034833729888;5362.678239305654;3826.617338266207;3852.9807350963624;3868.1490072184074;3902.673091149023;3928.253631809947;906.3202598051462;1010.0455069958965;1015.5937624949931;1023.5138309303021;1032.5043978890023;955.7562821480478;965.8261512927741;976.7118699983522;992.3500107429645;998.605550807427;6559.180102487189;6575;6637.070810086591;6805.93194068053;6830.726770929107;963.5712108111823;971.8542218667116;985.4875464758286;1005.8139244239838;1017.3387298256192;3370.898873033813;3982.7012974027066;4228.873133805991;4262.786860656955;4351.282038463682;13898.957578181475;14147.575572732834;14322.85677143236;14556.417743290362;14556.417743290362;14476;14477.85522144786;14481.05873118244;14517.854821451861;14520.709585808225;10633.574657013633;10702.03681668638;10711.303765255234;10718.142548596094;10725.83911241332;6480.611163330215;6587.341265873566;6700.162479690039;6717.764878229238;6777.525573209828;12471;12491.437885295365;12838.807417888926;12853.971682265583;12872.806907896202;12694.555690550786;12712.936435317764;12769.361531923552;12813.167144135601;12888.162269452456;8633.827323453621;8780.956095219482;8847;8862.158094980967;8874.733757987271;8979.955100224457;10481.528331224961;10491.895081049397;10597.099246564128;10602.992715691997;8931.464112153291;9109.77225569366;9192.448453092837;9203.815923681623;9209.677661281823;8909.821803564022;8949.910500894908;8974.641014359353;8985.910140898508;9011.2790976723;7099.716011359487;7207.207207207098;7214.6392680364725;7244.311790379906;7248.818779530554;5496.835094947158;5582.906459907046;5589.80435684749;5741.3971532988435;5754.769809207585;5626.381098079126;5742.28221472316;5900.055991041413;5955;5999.160117583455 14:53:14 INFO - PROCESS | 1640 | |4;prototype.html;380.5376467591875;390.09497964721845;395.8278149005181;399.41884557968143;401.54424727933787;5196.792128314873;5327.307450031495;5349.438308977559;5411;5433.701146436949;4221.514525829522;4469.821207151718;4786.401699787527;4937.888974980628;5126.538611524962;3467.7919324840464;3909.0032041829436;3920.0395903003805;4064.6748260139193;4384.4654370970065;3684.465752465891;3726.4037753959374;4084.65280451162;4239.7668128252935;4377.584129507692;3922.5096862892137;4008.719389642726;4031.5162180538377;4098.569650186732;4282.8715138545895;3718.5723641781137;3835.232953409329;4339.414179085815;4340.52254252032;4365.628921541673;4037.37420699792;4045.3527435610454;4089.6912987843903;4096.05790668148;4110.677467231005;4548.589937119504;4996.625253106032;5138.177891537373;5205.401378841444;5252.737363131828;4400.537943515948;4477.141986075775;4641.837535686269;4937.308776771255;5026.2711906773675;5195.714235717034;5448.536874365684;5605;5648.8870222595315;5650.350209725893;4189.664826813848;4239.788010599488;4476.798544065525;4533.161365147425;4562.292844609091;5079.873003174913;5609.298837645294;5665.263515742927;5665.470323012763;5924.97037514814;3816.4847745553993;3859.9035024124623;4497.572730590588;4531.954680453153;4539.818407263739;4361.694681372273;4373.343998400202;4495.393121928498;4637.907241855144;4670.392848929618;4433.623142032899;4529.977350113229;4664.906701866012;4889.875328674354;5018.272350509155;4540.568645978693;4692.389989301437;5488.835334939959;5623.184638227434;5627.6904770237215;1402.425005747659;1498.4455751371845;1561.3520389038422;1571;1598.5603958911393;975.6487664440938;1155.3074745497886;1175.18324764288;1179.262960649594;1179.351356753782;4433.583696885205;4985.839655956068;5116.130257856174;5164.586833053347;5224.242484839677;4983.750812459435;5675.8864822704145;5715.142728590745;5749.88500229993;5768.826935191951;4442.644588432918;4645.767711614406;4826.420829500483;5045.369328833895;5068.695878247317;2839.772818174541;2873.4971380008833;2874.8131371460786;2955.807872488281;2964.2293003819163;5306.310179676694;5393.9730301348245;5626.156076588544;5725.791981498791;5882.911756323659;5446.673199608036;5603;5724.341700704389;5782.824372186869;5793.420657934173;5124.359455068116;5314.548263397654;5950.69736296351;5959.851003724942;5963.224780778471;5342.866428339323;5486.316203977224;5556.9719601873785;5642.266505354278;5667.526443124735;4607.6313894889;4999.575036121971;5094.108531007133;5529.364123125731;5535.204721615559;5026.472220416804;5523.066926575666;5750.6837123958585;5764.702941838221;5874.471297583238;3885.825137868859;3914.8629797956933;4411.617726427693;4548.977255113836;4558.954410455853;4814.197470340257;5318.989392015505;5426.945730542802;5540.864122854886;5765.481106700249;4727.57451829337;4860.513948605111;5460.31746031746;5502.284702988586;5635.0702134147405;5061.266116413026;5369.570434365163;5518.806841760519;5522.5858060644805;5575.637583557056;4121.928298642315;4864.197407427735;5213.295666521489;5275.391005743285;5545.445455454423;4745.667803253739;4889.755512224446;5516.779328826962;5524.696141712084;5564.91652625219;5295.967286379119;5423.050966080999;5431.755570999236;5443.863903402446;5448.673079615236 14:53:14 INFO - PROCESS | 1640 | |5;yui.html;743.6430513353594;791.8574656561821;853.7310747114655;904.2223687628638;913.1553313185304;15851.603709907258;17561.648767024653;17572.209250583706;17590.384336548224;17592.03243821591;14194.36125374358;16119.677606447864;16235.675286494263;16283.023018618891;16373.42693005745;849.6091797773032;1003.523326419952;1004.6383302011307;1005.8440941654055;1007.5969612155124;969.3505351414534;971.3394891473795;971.4996776660025;974.3958745577752;974.6783561424749;1066.9839952400682;1067.1782727299974;1068.0547715272;1069.315637991686;1070.3738313086853;1058.439027315524;1060.512164404375;1060.8779990301136;1061.5594528270758;1063.2663462211087;787.8188016756121;788;788.491423032141;788.8225149341409;789.5223389849174;14511.927440362837;14879.18164500952;15296.08223506582;15517.612059698486;15587.92206038974;11275.154363422695;12328;12465.189762665397;12590.307533085677;12604;11299.435028248554;11391.316521008683;11514.366709830956;11554.76890462186;11709.473073711704;3266.379387916312;3781.621837816224;3799.6580307772433;3967.4247234151176;4020.859269925568;104178;111828;112439.99999999919;112987.43506282498;113464;9421.424936894435;9466.242700583936;9868.555914983699;9926.751831204278;10009.64966226193;21051.68422473664;21271;21335.93320333977;21627.567448651254;21657.891710541346;8213.876791848126;8241.725592726798;8278.213569710868;8343.290820280223;8423.536705481258;4483.32750087483;4510.571495707903;4609.216433206363;4626.444826620827;4821.975890120527;3511.0520159557286;3638.799866007342;3639.1811842335264;3639.9636003640153;3644.3440180767716;618.9721462534196;627.4290395739946;649.7660842096841;663.2140913018089;664.9368310010541;578.467809615155;584.7982446056104;625.4746013348808;665.9933400665931;669.0466085827676;10364.896351036545;10675.57297708083;10842.024217820433;10976.176786741027;10999.945000274947;556.0436049994004;562.5359078759958;613.6469085666096;669.8425869920565;721.8448033672786;5044.445111037783;5101.701724539844;5130.692158470503;5179.7309659133;5206.869828254324;98737;99644;99834.50248246282;100120;101999;72726.27273727302;73422;75782;79534;80299;70875.64562177156;72163.6391818048;72404.55190896257;72789.27210727824;73332.63333683298;8996.235319997611;10902.40036797952;11031.503582338653;11380;11465.484053217791;44359.33460998023;45348;46124;46943.530564693916;47004.294935576676;44206.77896610496;57038.42961570331;58343;59666.40333596782;59789.40210598012;12639.431225594686;13101.279429631462;13191.406386712813;13495.932520337336;13577.1853688779;12501.499940002299;14696.41214351414;14737.631559211106;14857.851421485646;15028.849711502746;10941.234113612289;10946.452677366258;11140.610078647209;11148.219624626197;11449.484773185393;11822.290662560274;12096.334701591497;12295.69260768488;12307.384630768605;12632.2420654761;11765.11761617865;11857.762844743229;12418.75162496763;12448.564300249447;12590.559330423757;8936.24041956441;9066.863997039918;9291.535423228675;9418.717438476857;9662.661806836726;8002.07976082758;9985.700428987142;10218.536110821487;10435.582576696846;10459.766673652497 14:53:14 INFO - PROCESS | 1640 | __end_tp_report 14:53:14 INFO - PROCESS | 1640 | __start_cc_report 14:53:14 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1581 14:53:14 INFO - PROCESS | 1640 | __end_cc_report 14:53:14 INFO - PROCESS | 1640 | __startTimestamp1495489994027__endTimestamp 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | ------- Summary: start ------- 14:53:14 INFO - PROCESS | 1640 | Number of tests: 6 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:6061.44 Median:6261.72 stddev:686.03 (11.0%) stddev-sans-first:642.90 14:53:14 INFO - PROCESS | 1640 | Values: 2938.2 2973.4 3587.5 3591.1 3611.5 6189.0 6190.5 6198.7 6215.9 6255.9 6155.0 6159.1 6159.6 6160.2 6167.8 5553.3 5574.2 5631.2 5681.4 5735.5 5462.9 5491.9 5725.4 5744.4 5745.4 5513.5 5596.2 5599.6 5600.3 5603.4 5571.4 5583.1 5585.4 5596.6 5596.7 5448.9 5526.5 5540.6 5544.7 5550.6 5569.6 6158.9 6168.4 6183.8 6187.5 6054.5 6366.8 6380.9 6426.3 6445.1 6628.5 6632.5 6634.6 6641.8 6650.7 6182.8 6210.6 6213.0 6218.6 6239.8 6489.0 6528.9 6605.3 6784.9 6795.1 5700.5 6355.9 6430.9 6493.1 6494.5 6612.1 6625.5 6628.4 6637.2 6662.4 6234.5 6375.8 6536.4 6536.5 6556.1 6321.5 6357.9 6399.9 6423.6 6448.9 5319.7 5323.1 5325.4 5337.7 5359.7 4274.2 4623.2 4656.0 4658.9 4685.8 6060.8 6087.0 6131.9 6155.5 6244.9 6627.2 6628.4 6629.0 6638.9 6639.3 6244.4 6244.8 6251.3 6262.8 6273.7 5599.8 5618.0 5679.4 5742.7 5778.1 5742.9 5761.6 6635.9 6644.0 6751.6 6472.4 6491.4 6499.2 6512.1 6528.2 6708.5 6836.4 6858.8 6867.4 6876.3 6340.9 6383.4 6399.0 6423.8 6516.4 5425.4 6466.5 6577.6 6594.5 6616.0 6290.4 6400.8 6444.6 6449.2 6451.3 6268.4 6321.1 6337.6 6348.6 6439.7 6326.9 6331.0 6331.3 6338.0 6348.2 6218.8 6261.7 6424.8 6441.9 6450.1 6185.3 6204.8 6282.8 6299.9 6322.3 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:62055.76 Median:18204.00 stddev:130004.85 (714.2%) stddev-sans-first:130287.76 14:53:14 INFO - PROCESS | 1640 | Values: 636.6 806.1 825.5 889.0 898.3 30120.8 30579.7 41553.0 42205.4 42275.0 44153.7 44231.3 44264.8 44377.8 44424.9 9773.7 9932.4 9947.0 9952.7 9972.4 8094.3 8256.9 11841.6 11999.5 12234.6 11979.2 12030.2 12040.3 12044.5 12054.9 11964.6 11990.3 12010.9 12014.7 12041.9 11618.6 11695.4 11703.6 11710.6 11711.5 41483.8 41525.8 41539.2 41560.8 41669.2 4029.7 4084.9 4095.4 4115.2 4120.2 23955.5 28765.3 30763.5 31046.2 31367.5 3156.9 3275.9 3306.7 3392.9 3411.5 342648.0 343001.0 344016.0 345803.0 346161.0 54168.5 54177.5 54184.0 54230.0 54394.7 45016.1 55368.6 55425.0 55466.2 56670.4 15641.3 15753.4 15782.2 15791.1 15795.5 13370.4 13376.6 13385.6 13389.5 13397.8 11085.8 11287.8 11533.5 14248.6 14272.9 4506.6 6134.2 6149.0 6150.7 6185.8 1082.3 1110.7 1115.6 1121.5 1161.3 29102.0 29496.9 29670.9 29847.7 29907.7 1161.6 1172.8 1184.3 1204.2 1223.3 14455.0 14625.6 14638.3 14908.7 14925.4 520883.0 525144.0 525680.0 526012.0 526170.0 49991.5 50885.2 53539.4 59734.7 60523.4 1485.2 1491.8 1496.4 1507.0 1507.3 29086.6 30539.2 30926.8 31977.8 32476.8 50510.5 50832.7 50836.2 50907.5 50991.7 520490.0 529422.0 531427.0 612608.0 625977.0 36674.8 37168.4 37359.6 37573.4 37668.1 46660.8 47101.5 47498.1 47797.5 47904.3 31555.5 31885.7 32014.4 32042.2 33101.3 25943.0 31699.7 31724.4 32447.5 32716.7 37243.9 38531.4 38756.4 39163.4 39410.8 31987.0 32502.5 32630.8 32651.3 33452.7 23936.5 24639.0 25513.5 25524.7 25887.5 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:41285.09 Median:23957.16 stddev:123348.01 (514.9%) stddev-sans-first:123655.84 14:53:14 INFO - PROCESS | 1640 | Values: 404.5 410.4 417.5 426.7 447.2 19706.8 21209.9 21942.0 22137.9 22190.6 22954.4 23531.6 24035.4 24053.4 24383.7 6702.9 6736.3 6987.3 6992.8 6993.2 6764.9 6828.5 6881.0 6899.5 6902.2 6558.8 6785.2 6866.9 6874.0 6907.4 6526.8 6820.5 6868.6 6888.1 6894.2 6600.5 6622.8 6668.5 6669.7 6671.3 26153.5 27028.7 27245.9 27262.5 27679.4 3425.8 3447.0 3486.1 3586.4 3696.3 16440.2 16633.9 16642.8 16690.7 16693.5 2102.1 2165.5 2187.7 2233.4 2243.0 131590.0 133534.0 135635.0 136294.0 137439.0 20386.8 20418.8 20760.7 20965.9 21020.2 33790.3 34395.0 34794.0 34840.7 34869.5 10996.0 11108.1 11307.4 11352.5 11509.5 9149.4 9221.6 9230.9 9421.0 9429.6 9996.9 10309.1 10412.3 10417.5 10428.8 3552.5 3649.0 4289.8 4353.4 4368.9 906.9 916.8 920.2 942.1 942.8 21612.7 23892.4 24021.9 24174.4 24182.5 808.6 942.9 953.3 983.0 987.8 9152.4 12471.4 13090.7 13352.9 13366.0 733582.0 738135.0 741586.0 759788.0 768499.0 83104.6 107164.0 117993.4 121198.0 126946.0 1262.3 1311.8 1336.5 1338.0 1338.3 21084.7 21110.9 21537.4 21820.8 21831.5 54760.2 55136.4 55812.7 56483.2 56774.7 73509.0 74110.6 74589.0 75351.6 75732.2 11505.5 11603.6 11760.8 11866.6 12144.3 13002.7 13113.9 13245.5 13449.7 13469.3 11242.3 11339.7 11377.6 11705.3 11710.0 10024.8 10129.8 10587.3 10704.8 10910.3 11386.3 11525.4 11849.7 11850.4 11901.1 11265.2 11414.6 11704.6 11885.5 11913.8 11128.8 11152.5 11337.0 11363.4 11447.5 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6835.33 Median:2470.57 stddev:4369.27 (176.9%) stddev-sans-first:4363.52 14:53:14 INFO - PROCESS | 1640 | Values: 1556.1 1580.4 1595.7 1634.4 1647.2 11128.7 11692.5 11695.9 11715.1 11721.1 11116.4 11373.9 11391.4 11638.4 11649.5 2146.3 2444.9 2472.4 2482.8 2486.0 2373.5 2448.5 2452.0 2454.1 2454.3 2388.6 2443.6 2466.8 2468.8 2475.2 2342.7 2413.1 2414.4 2418.0 2421.3 2380.3 2402.0 2413.2 2413.9 2414.8 11185.7 11432.3 11437.8 11437.8 11448.9 5635.9 5787.2 5796.4 5803.8 5838.5 3427.6 3447.6 3530.6 3551.1 3568.6 1213.7 1219.3 1257.9 1265.4 1267.7 14138.2 14418.7 14768.3 15779.6 15997.9 4495.6 4671.7 4683.8 4713.0 4714.7 12016.0 12030.4 12044.0 12049.7 12049.8 4980.1 5135.5 5600.3 5716.3 5726.8 5175.5 5279.3 5312.4 5362.0 5362.7 3826.6 3853.0 3868.1 3902.7 3928.3 906.3 1010.0 1015.6 1023.5 1032.5 955.8 965.8 976.7 992.4 998.6 6559.2 6575.0 6637.1 6805.9 6830.7 963.6 971.9 985.5 1005.8 1017.3 3370.9 3982.7 4228.9 4262.8 4351.3 13899.0 14147.6 14322.9 14556.4 14556.4 14476.0 14477.9 14481.1 14517.9 14520.7 10633.6 10702.0 10711.3 10718.1 10725.8 6480.6 6587.3 6700.2 6717.8 6777.5 12471.0 12491.4 12838.8 12854.0 12872.8 12694.6 12712.9 12769.4 12813.2 12888.2 8633.8 8781.0 8847.0 8862.2 8874.7 8980.0 10481.5 10491.9 10597.1 10603.0 8931.5 9109.8 9192.4 9203.8 9209.7 8909.8 8949.9 8974.6 8985.9 9011.3 7099.7 7207.2 7214.6 7244.3 7248.8 5496.8 5582.9 5589.8 5741.4 5754.8 5626.4 5742.3 5900.1 5955.0 5999.2 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4573.33 Median:4960.82 stddev:1280.15 (25.8%) stddev-sans-first:1244.45 14:53:14 INFO - PROCESS | 1640 | Values: 380.5 390.1 395.8 399.4 401.5 5196.8 5327.3 5349.4 5411.0 5433.7 4221.5 4469.8 4786.4 4937.9 5126.5 3467.8 3909.0 3920.0 4064.7 4384.5 3684.5 3726.4 4084.7 4239.8 4377.6 3922.5 4008.7 4031.5 4098.6 4282.9 3718.6 3835.2 4339.4 4340.5 4365.6 4037.4 4045.4 4089.7 4096.1 4110.7 4548.6 4996.6 5138.2 5205.4 5252.7 4400.5 4477.1 4641.8 4937.3 5026.3 5195.7 5448.5 5605.0 5648.9 5650.4 4189.7 4239.8 4476.8 4533.2 4562.3 5079.9 5609.3 5665.3 5665.5 5925.0 3816.5 3859.9 4497.6 4532.0 4539.8 4361.7 4373.3 4495.4 4637.9 4670.4 4433.6 4530.0 4664.9 4889.9 5018.3 4540.6 4692.4 5488.8 5623.2 5627.7 1402.4 1498.4 1561.4 1571.0 1598.6 975.6 1155.3 1175.2 1179.3 1179.4 4433.6 4985.8 5116.1 5164.6 5224.2 4983.8 5675.9 5715.1 5749.9 5768.8 4442.6 4645.8 4826.4 5045.4 5068.7 2839.8 2873.5 2874.8 2955.8 2964.2 5306.3 5394.0 5626.2 5725.8 5882.9 5446.7 5603.0 5724.3 5782.8 5793.4 5124.4 5314.5 5950.7 5959.9 5963.2 5342.9 5486.3 5557.0 5642.3 5667.5 4607.6 4999.6 5094.1 5529.4 5535.2 5026.5 5523.1 5750.7 5764.7 5874.5 3885.8 3914.9 4411.6 4549.0 4559.0 4814.2 5319.0 5426.9 5540.9 5765.5 4727.6 4860.5 5460.3 5502.3 5635.1 5061.3 5369.6 5518.8 5522.6 5575.6 4121.9 4864.2 5213.3 5275.4 5545.4 4745.7 4889.8 5516.8 5524.7 5564.9 5296.0 5423.1 5431.8 5443.9 5448.7 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:19482.47 Median:3574.93 stddev:27963.82 (782.2%) stddev-sans-first:28006.87 14:53:14 INFO - PROCESS | 1640 | Values: 743.6 791.9 853.7 904.2 913.2 15851.6 17561.6 17572.2 17590.4 17592.0 14194.4 16119.7 16235.7 16283.0 16373.4 849.6 1003.5 1004.6 1005.8 1007.6 969.4 971.3 971.5 974.4 974.7 1067.0 1067.2 1068.1 1069.3 1070.4 1058.4 1060.5 1060.9 1061.6 1063.3 787.8 788.0 788.5 788.8 789.5 14511.9 14879.2 15296.1 15517.6 15587.9 11275.2 12328.0 12465.2 12590.3 12604.0 11299.4 11391.3 11514.4 11554.8 11709.5 3266.4 3781.6 3799.7 3967.4 4020.9 104178.0 111828.0 112440.0 112987.4 113464.0 9421.4 9466.2 9868.6 9926.8 10009.6 21051.7 21271.0 21335.9 21627.6 21657.9 8213.9 8241.7 8278.2 8343.3 8423.5 4483.3 4510.6 4609.2 4626.4 4822.0 3511.1 3638.8 3639.2 3640.0 3644.3 619.0 627.4 649.8 663.2 664.9 578.5 584.8 625.5 666.0 669.0 10364.9 10675.6 10842.0 10976.2 10999.9 556.0 562.5 613.6 669.8 721.8 5044.4 5101.7 5130.7 5179.7 5206.9 98737.0 99644.0 99834.5 100120.0 101999.0 72726.3 73422.0 75782.0 79534.0 80299.0 70875.6 72163.6 72404.6 72789.3 73332.6 8996.2 10902.4 11031.5 11380.0 11465.5 44359.3 45348.0 46124.0 46943.5 47004.3 44206.8 57038.4 58343.0 59666.4 59789.4 12639.4 13101.3 13191.4 13495.9 13577.2 12501.5 14696.4 14737.6 14857.9 15028.8 10941.2 10946.5 11140.6 11148.2 11449.5 11822.3 12096.3 12295.7 12307.4 12632.2 11765.1 11857.8 12418.8 12448.6 12590.6 8936.2 9066.9 9291.5 9418.7 9662.7 8002.1 9985.7 10218.5 10435.6 10459.8 14:53:14 INFO - PROCESS | 1640 | -------- Summary: end -------- 14:53:14 INFO - PROCESS | 1640 | 14:53:14 INFO - TEST-INFO | 1640: exit 0 14:53:14 INFO - TEST-OK | dromaeo_css | took 1118721ms 14:53:14 INFO - TEST-START | kraken 14:53:14 INFO - operating with platform_type : mac_ 14:53:14 INFO - Initialising browser for kraken test... 14:53:14 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpb2Jhgq/profile http://localhost:49219/getInfo.html) 14:53:16 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 14:53:16 INFO - PROCESS | 1649 | colorDepth:24 14:53:16 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 14:53:16 INFO - PROCESS | 1649 | __metrics 14:53:16 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:53:16 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:17 INFO - TEST-INFO | 1649: exit 0 14:53:17 INFO - Browser initialized. 14:53:17 INFO - Running cycle 1/1 for kraken test... 14:53:17 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpb2Jhgq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:29 INFO - PROCESS | 1651 | RSS: Main: 165228544 14:53:29 INFO - PROCESS | 1651 | 14:53:32 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:33 INFO - PROCESS | 1651 | RSS: Main: 215912448 14:53:33 INFO - PROCESS | 1651 | 14:53:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 14:53:36 INFO - PROCESS | 1651 | RSS: Main: 231440384 14:53:36 INFO - PROCESS | 1651 | 14:53:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 14:53:39 INFO - PROCESS | 1651 | RSS: Main: 328994816 14:53:39 INFO - PROCESS | 1651 | 14:53:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 14:53:42 INFO - PROCESS | 1651 | RSS: Main: 230064128 14:53:42 INFO - PROCESS | 1651 | 14:53:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:53:45 INFO - PROCESS | 1651 | RSS: Main: 231518208 14:53:45 INFO - PROCESS | 1651 | 14:53:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 14:53:48 INFO - PROCESS | 1651 | RSS: Main: 272482304 14:53:48 INFO - PROCESS | 1651 | 14:53:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 14:53:52 INFO - PROCESS | 1651 | RSS: Main: 275050496 14:53:52 INFO - PROCESS | 1651 | 14:53:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 14:53:55 INFO - PROCESS | 1651 | RSS: Main: 272629760 14:53:55 INFO - PROCESS | 1651 | 14:53:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:53:57 INFO - PROCESS | 1651 | RSS: Main: 248123392 14:53:57 INFO - PROCESS | 1651 | 14:53:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:54:00 INFO - PROCESS | 1651 | RSS: Main: 252059648 14:54:00 INFO - PROCESS | 1651 | 14:54:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:54:03 INFO - PROCESS | 1651 | RSS: Main: 257290240 14:54:03 INFO - PROCESS | 1651 | 14:54:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:06 INFO - PROCESS | 1651 | RSS: Main: 261185536 14:54:06 INFO - PROCESS | 1651 | 14:54:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:10 INFO - PROCESS | 1651 | RSS: Main: 262320128 14:54:10 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 14:54:12 INFO - PROCESS | 1651 | RSS: Main: 306606080 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | __start_tp_report 14:54:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 14:54:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 14:54:12 INFO - PROCESS | 1651 | |i|pagename|runs| 14:54:12 INFO - PROCESS | 1651 | |0;ai-astar;104;115;114;114;115;115;115;115;116;115 14:54:12 INFO - PROCESS | 1651 | |1;audio-beat-detection;135;107;104;140;107;107;107;107;124;107 14:54:12 INFO - PROCESS | 1651 | |2;audio-dft;183;186;180;185;184;183;182;186;186;183 14:54:12 INFO - PROCESS | 1651 | |3;audio-fft;82;62;59;89;70;59;58;58;74;59 14:54:12 INFO - PROCESS | 1651 | |4;audio-oscillator;88;68;86;70;69;67;81;67;69;82 14:54:12 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;92;88;88;88;93;92;87;88;89;87 14:54:12 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;93;95;93;94;119;150;93;94;94 14:54:12 INFO - PROCESS | 1651 | |7;imaging-desaturate;83;84;85;85;83;83;82;88;85;83 14:54:12 INFO - PROCESS | 1651 | |8;json-parse-financial;64;60;60;60;61;64;64;63;63;64 14:54:12 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;56;67;64;67;60;59;63;59;66;86 14:54:12 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;78;85;83;85;82;83;80;87;87;118 14:54:12 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;138;132;143;119;143;139;146;124;150;134 14:54:12 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;163;158;157;161;160;163;162;162;161;161 14:54:12 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;64;64;64;63;63;64;66;74;79;80 14:54:12 INFO - PROCESS | 1651 | __end_tp_report 14:54:12 INFO - PROCESS | 1651 | __start_cc_report 14:54:12 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2025 14:54:12 INFO - PROCESS | 1651 | __end_cc_report 14:54:12 INFO - PROCESS | 1651 | __startTimestamp1495490052269__endTimestamp 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | ------- Summary: start ------- 14:54:12 INFO - PROCESS | 1651 | Number of tests: 14 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.49 (3.0%) stddev-sans-first:0.60 14:54:12 INFO - PROCESS | 1651 | Values: 104.0 115.0 114.0 114.0 115.0 115.0 115.0 115.0 116.0 115.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:114.50 Median:107.00 stddev:13.37 (12.5%) stddev-sans-first:11.95 14:54:12 INFO - PROCESS | 1651 | Values: 135.0 107.0 104.0 140.0 107.0 107.0 107.0 107.0 124.0 107.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.99 (1.1%) stddev-sans-first:2.09 14:54:12 INFO - PROCESS | 1651 | Values: 183.0 186.0 180.0 185.0 184.0 183.0 182.0 186.0 186.0 183.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.00 Median:66.00 stddev:11.28 (17.1%) stddev-sans-first:10.58 14:54:12 INFO - PROCESS | 1651 | Values: 82.0 62.0 59.0 89.0 70.0 59.0 58.0 58.0 74.0 59.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.50 stddev:8.49 (11.2%) stddev-sans-first:7.51 14:54:12 INFO - PROCESS | 1651 | Values: 88.0 68.0 86.0 70.0 69.0 67.0 81.0 67.0 69.0 82.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:88.50 stddev:2.25 (2.5%) stddev-sans-first:2.15 14:54:12 INFO - PROCESS | 1651 | Values: 92.0 88.0 88.0 88.0 93.0 92.0 87.0 88.0 89.0 87.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:93.50 stddev:18.73 (20.0%) stddev-sans-first:19.59 14:54:12 INFO - PROCESS | 1651 | Values: 93.0 93.0 95.0 93.0 94.0 119.0 150.0 93.0 94.0 94.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:1.73 (2.0%) stddev-sans-first:1.79 14:54:12 INFO - PROCESS | 1651 | Values: 83.0 84.0 85.0 85.0 83.0 83.0 82.0 88.0 85.0 83.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:63.50 stddev:1.83 (2.9%) stddev-sans-first:1.83 14:54:12 INFO - PROCESS | 1651 | Values: 64.0 60.0 60.0 60.0 61.0 64.0 64.0 63.0 63.0 64.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:8.38 (12.9%) stddev-sans-first:8.28 14:54:12 INFO - PROCESS | 1651 | Values: 56.0 67.0 64.0 67.0 60.0 59.0 63.0 59.0 66.0 86.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:11.33 (13.5%) stddev-sans-first:11.56 14:54:12 INFO - PROCESS | 1651 | Values: 78.0 85.0 83.0 85.0 82.0 83.0 80.0 87.0 87.0 118.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:141.00 stddev:9.74 (6.9%) stddev-sans-first:10.32 14:54:12 INFO - PROCESS | 1651 | Values: 138.0 132.0 143.0 119.0 143.0 139.0 146.0 124.0 150.0 134.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:161.50 stddev:1.99 (1.2%) stddev-sans-first:1.94 14:54:12 INFO - PROCESS | 1651 | Values: 163.0 158.0 157.0 161.0 160.0 163.0 162.0 162.0 161.0 161.0 14:54:12 INFO - PROCESS | 1651 | 14:54:12 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:65.00 stddev:6.82 (10.5%) stddev-sans-first:7.07 14:54:12 INFO - PROCESS | 1651 | Values: 64.0 64.0 64.0 63.0 63.0 64.0 66.0 74.0 79.0 80.0 14:54:12 INFO - PROCESS | 1651 | -------- Summary: end -------- 14:54:12 INFO - PROCESS | 1651 | 14:54:13 INFO - TEST-INFO | 1651: exit 0 14:54:13 INFO - TEST-OK | kraken | took 58434ms 14:54:13 INFO - SUITE-END | took 1177s 14:54:13 INFO - Completed test suite (00:19:37) 14:54:13 INFO - javascript benchmark 14:54:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7833.130330769095, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2938.2213713365954, 2973.4499117663227, 3587.5336206293177, 3591.120175556989, 3611.458281257809, 6189.00975843865, 6190.53570982177, 6198.6900654967185, 6215.906761398577, 6255.937440625592, 6155.01519756839, 6159.137720719103, 6159.5996260243055, 6160.1683772690785, 6167.845803854906, 5553.333599968009, 5574.219609254707, 5631.18347839563, 5681.431856814306, 5735.483806457418, 5462.945370546284, 5491.862703432406, 5725.427457254283, 5744.368119506851, 5745.368009518949, 5513.476219759116, 5596.244506991545, 5599.552035837143, 5600.327960644729, 5603.3836278009385, 5571.387147413781, 5583.106702927533, 5585.413531579166, 5596.552275817966, 5596.720163991784, 5448.863778405532, 5526.47498487643, 5540.63985840919, 5544.7227638618315, 5550.639208451437, 5569.579757161939, 6158.860610787019, 6168.352323006065, 6183.783567575113, 6187.535934804908, 6054.545909056838, 6366.777162799326, 6380.936190638081, 6426.2931077581425, 6445.065465507522, 6628.536002479828, 6632.502562307846, 6634.635095069769, 6641.833954151137, 6650.667466626649, 6182.84542886427, 6210.627362358227, 6212.968935155341, 6218.595791273553, 6239.750409983595, 6489.0, 6528.934710652928, 6605.339466053356, 6784.864302713918, 6795.082663840319, 5700.486956173964, 6355.904661430082, 6430.871382572323, 6493.058506516529, 6494.512911531654, 6612.07430959666, 6625.469962403093, 6628.403443690091, 6637.1703537057865, 6662.367075127952, 6234.470070044097, 6375.808725738235, 6536.411722944958, 6536.542442029107, 6556.114924485229, 6321.494280457552, 6357.936420635734, 6399.904001439889, 6423.582467139621, 6448.903266451007, 5319.6808191508635, 5323.121684922022, 5325.3875804282225, 5337.653052551572, 5359.705216213146, 4274.230638485103, 4623.214053610895, 4656.045510670305, 4658.883527911829, 4685.765711714469, 6060.757569697251, 6086.969565152146, 6131.896637733196, 6155.538334624832, 6244.875102498016, 6627.237867645283, 6628.370304821035, 6629.0, 6638.900416493757, 6639.336066393322, 6244.438000579969, 6244.781432649836, 6251.28110267316, 6262.843428914222, 6273.65494897785, 5599.832005039855, 5618.0, 5679.432056794288, 5742.6554406734895, 5778.104393818965, 5742.856428589319, 5761.596688231783, 6635.938249879996, 6643.966780166068, 6751.561148525527, 6472.385123413268, 6491.383318584727, 6499.1876015498065, 6512.05575191604, 6528.249251336157, 6708.463322934056, 6836.38472537474, 6858.759943401957, 6867.416269617139, 6876.312368763284, 6340.904886426615, 6383.425491705769, 6398.968005159944, 6423.807285781434, 6516.380943810331, 5425.403205647454, 6466.482681385572, 6577.6053436793945, 6594.505412094208, 6616.0, 6290.402411770874, 6400.807975760735, 6444.581102228341, 6449.161608990801, 6451.258105317948, 6268.43584077417, 6321.146645203023, 6337.588056776295, 6348.555601107878, 6439.710213040518, 6326.905096423649, 6330.968345158245, 6331.303556608677, 6338.0, 6348.174737284124, 6218.844528886814, 6261.7182226799, 6424.839379015562, 6441.903371449525, 6450.096986421999, 6185.257769067741, 6204.844878878064, 6282.811515654537, 6299.905501417391, 6322.33615470369], "value": 6015.597857574182, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.5925807483212, 806.1213277527496, 825.4964471672279, 888.9822203555929, 898.2544488074899, 30120.84939575302, 30579.694203057963, 41552.96117597058, 42205.366919496235, 42275.0, 44153.67538973836, 44231.336529952074, 44264.77867610665, 44377.7781111094, 44424.88937776557, 9773.706788796348, 9932.35439696421, 9947.005299470067, 9952.651657192, 9972.351797133164, 8094.3119834814115, 8256.917430825704, 11841.644750657451, 11999.520019199264, 12234.571789987353, 11979.161458697894, 12030.218035827644, 12040.337781422018, 12044.457999390004, 12054.879451205506, 11964.64106076819, 11990.280583164951, 12010.919017288443, 12014.699632509257, 12041.93979030108, 11618.593349232737, 11695.415229238504, 11703.590374336942, 11710.590129345517, 11711.53153873844, 41483.7925810372, 41525.792371037955, 41539.1692166158, 41560.79219603883, 41669.1666166675, 4029.700943259604, 4084.8774536763945, 4095.406166105898, 4115.238680844053, 4120.196561670476, 23955.520889582283, 28765.280867978257, 30763.538546921813, 31046.223844403845, 31367.529487057713, 3156.8737250509953, 3275.9189467475912, 3306.6528014558608, 3392.932141357184, 3411.522386865841, 342648.0, 343000.9999999975, 344016.0, 345803.0, 346161.0, 54168.45831541634, 54177.45822541803, 54184.0, 54230.0, 54394.72802635962, 45016.099678006256, 55368.61578460571, 55425.0, 55466.16800747992, 56670.43329566652, 15641.296141673653, 15753.44862929792, 15782.210889455482, 15791.052536847827, 15795.526134215992, 13370.39833207508, 13376.598702038955, 13385.598432047054, 13389.531366402129, 13397.799033014513, 11085.83371249432, 11287.830682539769, 11533.538658453735, 14248.643783905485, 14272.857271427152, 4506.610918560104, 6134.2332208473945, 6148.956266648038, 6150.661713605705, 6185.783497577563, 1082.2802836114054, 1110.650145204258, 1115.5593540551438, 1121.4673030310537, 1161.3114007514341, 29101.981430649827, 29496.852515737282, 29670.851645741634, 29847.701522984928, 29907.700922990927, 1161.647663481457, 1172.783035138502, 1184.2657552317446, 1204.241327963395, 1223.3332833605707, 14454.988150829551, 14625.634359141106, 14638.268086595628, 14908.70182596342, 14925.402983880524, 520883.0, 525144.0, 525680.0, 526012.0, 526170.0, 49991.50008499941, 50885.23672144921, 53539.39381818552, 59734.70132649396, 60523.394766052654, 1485.1534625263712, 1491.8209814822292, 1496.4014394242172, 1507.0053764515367, 1507.2765072764914, 29086.56370154407, 30539.2365190872, 30926.762929482567, 31977.84011080023, 32476.837615812714, 50510.49489505058, 50832.74583627058, 50836.237456438925, 50907.49092509028, 50991.72520687013, 520490.0, 529422.0, 531427.0, 612608.0, 625977.0, 36674.8166259167, 37168.44247336346, 37359.626403735616, 37573.43639845459, 37668.05829854275, 46660.766696166305, 47101.52898471108, 47498.05003899972, 47797.522024779304, 47904.28143577919, 31555.526667100472, 31885.68114318827, 32014.35971280608, 32042.19894502656, 33101.33797323992, 25943.0, 31699.683003170594, 31724.365512689154, 32447.513287301183, 32716.67283327136, 37243.882683519536, 38531.42202867015, 38756.40363766368, 39163.41254881122, 39410.80294598509, 31987.0, 32502.51246231261, 32630.83684581562, 32651.34697306088, 33452.66547334593, 23936.52126957416, 24639.0, 25513.48973020492, 25524.744752552237, 25887.482250355264], "value": 20036.4652625097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.538825738658, 410.35574148586693, 417.4990011985618, 426.74182119817516, 447.24192493723154, 19706.802931970695, 21209.939503024845, 21942.0, 22137.889310553466, 22190.55618887623, 22954.426139346524, 23531.647025294635, 24035.399115022134, 24053.398665033383, 24383.65889876056, 6702.899456508144, 6736.2590115087305, 6987.301269872997, 6992.79021629352, 6993.195782485029, 6764.9323506764795, 6828.48786341024, 6881.036654868322, 6899.482538809584, 6902.240753517134, 6558.80323590293, 6785.185777706657, 6866.896996545056, 6874.0, 6907.412869906039, 6526.771562995273, 6820.488463365243, 6868.62222577758, 6888.138982627146, 6894.172699276069, 6600.504962127811, 6622.8344291393105, 6668.499862510283, 6669.733210671568, 6671.332866713338, 26153.476930461475, 27028.729712703014, 27245.86377068122, 27262.454750905068, 27679.446411071665, 3425.7667239793655, 3446.9655303446893, 3486.0761898097026, 3586.3903136466865, 3696.2792255510185, 16440.177991100398, 16633.916830415772, 16642.83357166425, 16690.749638755427, 16693.499195024167, 2102.096098677568, 2165.4802847316687, 2187.6827859960376, 2233.4304752288226, 2242.9775702242932, 131590.0, 133534.0, 135635.0, 136294.0, 137439.0, 20386.796132038788, 20418.795812041688, 20760.68858967117, 20965.89517052405, 21020.15919363239, 33790.32419351599, 34395.0, 34793.9561813146, 34840.65159348425, 34869.476957845654, 10996.0, 11108.055815255793, 11307.378094204732, 11352.48913798881, 11509.53961841517, 9149.359544831797, 9221.631134754669, 9230.907690923139, 9421.0, 9429.62281508732, 9996.850047249298, 10309.123724483354, 10412.32319899204, 10417.479126043667, 10428.791424171473, 3552.467129930531, 3648.9635103648625, 4289.828406863753, 4353.36876152956, 4368.934465983013, 906.8685040669066, 916.7708072981754, 920.2132176988712, 942.1332374215738, 942.8397172480695, 21612.675809862863, 23892.402689932544, 24021.919013644427, 24174.39564010914, 24182.516349672907, 808.567416432206, 942.9198518125899, 953.3326671330096, 982.9852552211722, 987.8419452887647, 9152.405093668891, 12471.376431178587, 13090.738185236241, 13352.866471335357, 13366.0, 733582.0, 738135.0, 741586.0, 759788.0, 768499.0, 83104.58447707843, 107164.0, 117993.41003294928, 121198.0, 126946.0, 1262.3309645887693, 1311.8097875807955, 1336.5322137252078, 1338.0432990411903, 1338.3174580963778, 21084.68372974376, 21110.894445527672, 21537.353879383645, 21820.781792181875, 21831.454213644785, 54760.17859732187, 55136.44863551474, 55812.72093639506, 56483.15275270792, 56774.7161264191, 73509.0, 74110.62944685241, 74589.0, 75351.62324188344, 75732.24267757252, 11505.48225329879, 11603.593874214363, 11760.823587646364, 11866.644000679993, 12144.271343719405, 13002.739945201232, 13113.934430327787, 13245.536406225736, 13449.731005380034, 13469.259190744604, 11242.269252498561, 11339.659810205707, 11377.601783937522, 11705.297682139098, 11710.0, 10024.799504010025, 10129.848052279074, 10587.258891877802, 10704.785904281714, 10910.345379277269, 11386.316820990767, 11525.366104864313, 11849.703757406134, 11850.407479625812, 11901.132132056942, 11265.155113366694, 11414.600488982845, 11704.648860534198, 11885.46515406826, 11913.761724765629, 11128.777424451628, 11152.49813758399, 11337.0, 11363.420522535374, 11447.484863181344], "value": 11742.296110527865, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.1052394872947, 1580.4468436047378, 1595.712771701094, 1634.3544300001506, 1647.2340361731792, 11128.721781955455, 11692.532298708042, 11695.883041169585, 11715.121365897548, 11721.120915931308, 11116.388598627072, 11373.886261137386, 11391.373474458902, 11638.359890206035, 11649.475773590188, 2146.302451703195, 2444.865532395722, 2472.3942634054683, 2482.7765501104896, 2485.962710559343, 2373.4541055557233, 2448.534778392109, 2451.9877400613063, 2454.116518053508, 2454.349597356702, 2388.6178211486167, 2443.609022556391, 2466.839655422401, 2468.7531246875346, 2475.232677869857, 2342.6603142544404, 2413.1071503543712, 2414.396400899775, 2418.0327868852423, 2421.2615152378517, 2380.321608341614, 2402.039184326266, 2413.2157048958984, 2413.915512957038, 2414.7585241475886, 11185.72035699114, 11432.314061156358, 11437.771244575062, 11437.828432573519, 11448.942755286254, 5635.943640563582, 5787.189793428924, 5796.449337312949, 5803.7968671096305, 5838.5329173666005, 3427.5715535558056, 3447.5518182636347, 3530.5586801649793, 3551.058969373118, 3568.6252943441086, 1213.696575856036, 1219.3476490077767, 1257.937103144839, 1265.4305562496913, 1267.6704056945262, 14138.222397768119, 14418.711625767532, 14768.335424905905, 15779.605509862346, 15997.92001039999, 4495.617872480876, 4671.672982891165, 4683.7892294846815, 4712.976435117803, 4714.717116972993, 12015.978641815369, 12030.39848007596, 12044.036477081812, 12049.698757530956, 12049.819252711217, 4980.078685443204, 5135.537801597799, 5600.327960644749, 5716.314042314865, 5726.828195154152, 5175.508326708957, 5279.313689220454, 5312.3890752563175, 5362.034833729888, 5362.678239305654, 3826.617338266207, 3852.9807350963624, 3868.1490072184074, 3902.673091149023, 3928.253631809947, 906.3202598051462, 1010.0455069958965, 1015.5937624949931, 1023.5138309303021, 1032.5043978890023, 955.7562821480478, 965.8261512927741, 976.7118699983522, 992.3500107429645, 998.605550807427, 6559.180102487189, 6575.0, 6637.070810086591, 6805.93194068053, 6830.726770929107, 963.5712108111823, 971.8542218667116, 985.4875464758286, 1005.8139244239838, 1017.3387298256192, 3370.898873033813, 3982.7012974027066, 4228.873133805991, 4262.786860656955, 4351.282038463682, 13898.957578181475, 14147.575572732834, 14322.85677143236, 14556.417743290362, 14556.417743290362, 14476.0, 14477.85522144786, 14481.05873118244, 14517.854821451861, 14520.709585808225, 10633.574657013633, 10702.03681668638, 10711.303765255234, 10718.142548596094, 10725.83911241332, 6480.611163330215, 6587.341265873566, 6700.162479690039, 6717.764878229238, 6777.525573209828, 12471.0, 12491.437885295365, 12838.807417888926, 12853.971682265583, 12872.806907896202, 12694.555690550786, 12712.936435317764, 12769.361531923552, 12813.167144135601, 12888.162269452456, 8633.827323453621, 8780.956095219482, 8847.0, 8862.158094980967, 8874.733757987271, 8979.955100224457, 10481.528331224961, 10491.895081049397, 10597.099246564128, 10602.992715691997, 8931.464112153291, 9109.77225569366, 9192.448453092837, 9203.815923681623, 9209.677661281823, 8909.821803564022, 8949.910500894908, 8974.641014359353, 8985.910140898508, 9011.2790976723, 7099.716011359487, 7207.207207207098, 7214.6392680364725, 7244.311790379906, 7248.818779530554, 5496.835094947158, 5582.906459907046, 5589.80435684749, 5741.3971532988435, 5754.769809207585, 5626.381098079126, 5742.28221472316, 5900.055991041413, 5955.0, 5999.160117583455], "value": 5179.591664319978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5376467591875, 390.09497964721845, 395.8278149005181, 399.41884557968143, 401.54424727933787, 5196.792128314873, 5327.307450031495, 5349.438308977559, 5411.0, 5433.701146436949, 4221.514525829522, 4469.821207151718, 4786.401699787527, 4937.888974980628, 5126.538611524962, 3467.7919324840464, 3909.0032041829436, 3920.0395903003805, 4064.6748260139193, 4384.4654370970065, 3684.465752465891, 3726.4037753959374, 4084.65280451162, 4239.7668128252935, 4377.584129507692, 3922.5096862892137, 4008.719389642726, 4031.5162180538377, 4098.569650186732, 4282.8715138545895, 3718.5723641781137, 3835.232953409329, 4339.414179085815, 4340.52254252032, 4365.628921541673, 4037.37420699792, 4045.3527435610454, 4089.6912987843903, 4096.05790668148, 4110.677467231005, 4548.589937119504, 4996.625253106032, 5138.177891537373, 5205.401378841444, 5252.737363131828, 4400.537943515948, 4477.141986075775, 4641.837535686269, 4937.308776771255, 5026.2711906773675, 5195.714235717034, 5448.536874365684, 5605.0, 5648.8870222595315, 5650.350209725893, 4189.664826813848, 4239.788010599488, 4476.798544065525, 4533.161365147425, 4562.292844609091, 5079.873003174913, 5609.298837645294, 5665.263515742927, 5665.470323012763, 5924.97037514814, 3816.4847745553993, 3859.9035024124623, 4497.572730590588, 4531.954680453153, 4539.818407263739, 4361.694681372273, 4373.343998400202, 4495.393121928498, 4637.907241855144, 4670.392848929618, 4433.623142032899, 4529.977350113229, 4664.906701866012, 4889.875328674354, 5018.272350509155, 4540.568645978693, 4692.389989301437, 5488.835334939959, 5623.184638227434, 5627.6904770237215, 1402.425005747659, 1498.4455751371845, 1561.3520389038422, 1571.0, 1598.5603958911393, 975.6487664440938, 1155.3074745497886, 1175.18324764288, 1179.262960649594, 1179.351356753782, 4433.583696885205, 4985.839655956068, 5116.130257856174, 5164.586833053347, 5224.242484839677, 4983.750812459435, 5675.8864822704145, 5715.142728590745, 5749.88500229993, 5768.826935191951, 4442.644588432918, 4645.767711614406, 4826.420829500483, 5045.369328833895, 5068.695878247317, 2839.772818174541, 2873.4971380008833, 2874.8131371460786, 2955.807872488281, 2964.2293003819163, 5306.310179676694, 5393.9730301348245, 5626.156076588544, 5725.791981498791, 5882.911756323659, 5446.673199608036, 5603.0, 5724.341700704389, 5782.824372186869, 5793.420657934173, 5124.359455068116, 5314.548263397654, 5950.69736296351, 5959.851003724942, 5963.224780778471, 5342.866428339323, 5486.316203977224, 5556.9719601873785, 5642.266505354278, 5667.526443124735, 4607.6313894889, 4999.575036121971, 5094.108531007133, 5529.364123125731, 5535.204721615559, 5026.472220416804, 5523.066926575666, 5750.6837123958585, 5764.702941838221, 5874.471297583238, 3885.825137868859, 3914.8629797956933, 4411.617726427693, 4548.977255113836, 4558.954410455853, 4814.197470340257, 5318.989392015505, 5426.945730542802, 5540.864122854886, 5765.481106700249, 4727.57451829337, 4860.513948605111, 5460.31746031746, 5502.284702988586, 5635.0702134147405, 5061.266116413026, 5369.570434365163, 5518.806841760519, 5522.5858060644805, 5575.637583557056, 4121.928298642315, 4864.197407427735, 5213.295666521489, 5275.391005743285, 5545.445455454423, 4745.667803253739, 4889.755512224446, 5516.779328826962, 5524.696141712084, 5564.91652625219, 5295.967286379119, 5423.050966080999, 5431.755570999236, 5443.863903402446, 5448.673079615236], "value": 4205.051343651377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.6430513353594, 791.8574656561821, 853.7310747114655, 904.2223687628638, 913.1553313185304, 15851.603709907258, 17561.648767024653, 17572.209250583706, 17590.384336548224, 17592.03243821591, 14194.36125374358, 16119.677606447864, 16235.675286494263, 16283.023018618891, 16373.42693005745, 849.6091797773032, 1003.523326419952, 1004.6383302011307, 1005.8440941654055, 1007.5969612155124, 969.3505351414534, 971.3394891473795, 971.4996776660025, 974.3958745577752, 974.6783561424749, 1066.9839952400682, 1067.1782727299974, 1068.0547715272, 1069.315637991686, 1070.3738313086853, 1058.439027315524, 1060.512164404375, 1060.8779990301136, 1061.5594528270758, 1063.2663462211087, 787.8188016756121, 788.0, 788.491423032141, 788.8225149341409, 789.5223389849174, 14511.927440362837, 14879.18164500952, 15296.08223506582, 15517.612059698486, 15587.92206038974, 11275.154363422695, 12328.0, 12465.189762665397, 12590.307533085677, 12604.0, 11299.435028248554, 11391.316521008683, 11514.366709830956, 11554.76890462186, 11709.473073711704, 3266.379387916312, 3781.621837816224, 3799.6580307772433, 3967.4247234151176, 4020.859269925568, 104178.0, 111828.0, 112439.99999999919, 112987.43506282498, 113464.0, 9421.424936894435, 9466.242700583936, 9868.555914983699, 9926.751831204278, 10009.64966226193, 21051.68422473664, 21271.0, 21335.93320333977, 21627.567448651254, 21657.891710541346, 8213.876791848126, 8241.725592726798, 8278.213569710868, 8343.290820280223, 8423.536705481258, 4483.32750087483, 4510.571495707903, 4609.216433206363, 4626.444826620827, 4821.975890120527, 3511.0520159557286, 3638.799866007342, 3639.1811842335264, 3639.9636003640153, 3644.3440180767716, 618.9721462534196, 627.4290395739946, 649.7660842096841, 663.2140913018089, 664.9368310010541, 578.467809615155, 584.7982446056104, 625.4746013348808, 665.9933400665931, 669.0466085827676, 10364.896351036545, 10675.57297708083, 10842.024217820433, 10976.176786741027, 10999.945000274947, 556.0436049994004, 562.5359078759958, 613.6469085666096, 669.8425869920565, 721.8448033672786, 5044.445111037783, 5101.701724539844, 5130.692158470503, 5179.7309659133, 5206.869828254324, 98737.0, 99644.0, 99834.50248246282, 100120.0, 101999.0, 72726.27273727302, 73422.0, 75782.0, 79534.0, 80299.0, 70875.64562177156, 72163.6391818048, 72404.55190896257, 72789.27210727824, 73332.63333683298, 8996.235319997611, 10902.40036797952, 11031.503582338653, 11380.0, 11465.484053217791, 44359.33460998023, 45348.0, 46124.0, 46943.530564693916, 47004.294935576676, 44206.77896610496, 57038.42961570331, 58343.0, 59666.40333596782, 59789.40210598012, 12639.431225594686, 13101.279429631462, 13191.406386712813, 13495.932520337336, 13577.1853688779, 12501.499940002299, 14696.41214351414, 14737.631559211106, 14857.851421485646, 15028.849711502746, 10941.234113612289, 10946.452677366258, 11140.610078647209, 11148.219624626197, 11449.484773185393, 11822.290662560274, 12096.334701591497, 12295.69260768488, 12307.384630768605, 12632.2420654761, 11765.11761617865, 11857.762844743229, 12418.75162496763, 12448.564300249447, 12590.559330423757, 8936.24041956441, 9066.863997039918, 9291.535423228675, 9418.717438476857, 9662.661806836726, 8002.07976082758, 9985.700428987142, 10218.536110821487, 10435.582576696846, 10459.766673652497], "value": 7492.885316879154, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1408.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 115.0, 116.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 107.0, 104.0, 140.0, 107.0, 107.0, 107.0, 107.0, 124.0, 107.0], "value": 114.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 180.0, 185.0, 184.0, 183.0, 182.0, 186.0, 186.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 59.0, 89.0, 70.0, 59.0, 58.0, 58.0, 74.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 86.0, 70.0, 69.0, 67.0, 81.0, 67.0, 69.0, 82.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 88.0, 88.0, 93.0, 92.0, 87.0, 88.0, 89.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 93.0, 94.0, 119.0, 150.0, 93.0, 94.0, 94.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 85.0, 83.0, 83.0, 82.0, 88.0, 85.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 60.0, 60.0, 61.0, 64.0, 64.0, 63.0, 63.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 67.0, 64.0, 67.0, 60.0, 59.0, 63.0, 59.0, 66.0, 86.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 85.0, 83.0, 85.0, 82.0, 83.0, 80.0, 87.0, 87.0, 118.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 143.0, 119.0, 143.0, 139.0, 146.0, 124.0, 150.0, 134.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 158.0, 157.0, 161.0, 160.0, 163.0, 162.0, 162.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 63.0, 64.0, 66.0, 74.0, 79.0, 80.0], "value": 68.1, "unit": "score"}]}]} 14:54:13 INFO - Return code: 0 14:54:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:54:13 INFO - # TBPL SUCCESS # 14:54:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:13 INFO - 2017-05-22 14:54:13.591 screenresolution[1655:9608] starting screenresolution argv=screenresolution get 14:54:13 INFO - 2017-05-22 14:54:13.594 screenresolution[1655:9608] Display 0: 1600x1200x32@60 14:54:13 INFO - 2017-05-22 14:54:13.600 screenresolution[1656:9611] starting screenresolution argv=screenresolution list 14:54:13 INFO - Available Modes on Display 0 14:54:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:13 INFO - Intel Iris: 14:54:13 INFO - Chipset Model: Intel Iris 14:54:13 INFO - Type: GPU 14:54:13 INFO - Bus: Built-In 14:54:13 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:13 INFO - Vendor: Intel (0x8086) 14:54:13 INFO - Device ID: 0x0a2e 14:54:13 INFO - Revision ID: 0x0009 14:54:13 INFO - Displays: 14:54:13 INFO - Display: 14:54:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:13 INFO - Display Serial Number: 1600x1200 60 14:54:13 INFO - Main Display: Yes 14:54:13 INFO - Mirror: Off 14:54:13 INFO - Online: Yes 14:54:13 INFO - Rotation: Supported 14:54:13 INFO - Return code: 0 14:54:13 INFO - Running post-action listener: _resource_record_post_action 14:54:13 INFO - [mozharness: 2017-05-22 21:54:13.836136Z] Finished run-tests step (success) 14:54:13 INFO - Running post-run listener: _resource_record_post_run 14:54:13 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 39052288; Write bytes: 425238016; Read time: 249; Write time: 1381 14:54:13 INFO - TinderboxPrint: CPU usage
53.8% 14:54:13 INFO - TinderboxPrint: I/O read bytes / time
39,052,288 / 249 14:54:13 INFO - TinderboxPrint: I/O write bytes / time
425,238,016 / 1,381 14:54:13 INFO - TinderboxPrint: CPU idle
2,204.6 (46.2%) 14:54:13 INFO - TinderboxPrint: CPU system
949.7 (19.9%) 14:54:13 INFO - TinderboxPrint: CPU user
1,619.4 (33.9%) 14:54:13 INFO - TinderboxPrint: Swap in / out
160,837,632 / 0 14:54:13 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123382272; Write bytes: 127715328; Read time: 11086; Write time: 298 14:54:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34817024; Write bytes: 296121856; Read time: 223; Write time: 1077 14:54:14 INFO - Running post-run listener: _upload_blobber_files 14:54:14 INFO - Blob upload gear active. 14:54:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:14 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:14 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:54:14 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:54:14 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:54:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:54:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:16 INFO - (blobuploader) - INFO - Done attempting. 14:54:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:54:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:16 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:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:20 INFO - (blobuploader) - INFO - Done attempting. 14:54:20 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:20 INFO - Return code: 0 14:54:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/385f2b6e60671698126967fa511251a7f436695e82b515b8812b5c92b7cc9efe03d0d64760404351153ebf78422082fe7371d0679a4ee349e5cd6da1d034ff3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35caaf7d5a5d2e4502f13283eb6d68cd37a2a6f026ff2c29673ff0010193ca7caa05699719d602139ef190a122f2ca748ab22586c3150a57726fa7d534971a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d35aa562c2b7ebcdc8a757de55471fcff901bce56e0822d6517c56a92c328c48b1fcf01ae12df885605a996656165241da8f0f8b15595094166c1a1721e2659d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27ea03ac6d2d31c3ac0228a271705c2eaa4241fec0f8b0caeedfacace0e1bad6d898fa1f020f43b3bc0ee2fd20ae98b5a49f36fddde3166b37ee4631cdd1a5a8"} 14:54:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:20 INFO - Contents: 14:54:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/385f2b6e60671698126967fa511251a7f436695e82b515b8812b5c92b7cc9efe03d0d64760404351153ebf78422082fe7371d0679a4ee349e5cd6da1d034ff3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35caaf7d5a5d2e4502f13283eb6d68cd37a2a6f026ff2c29673ff0010193ca7caa05699719d602139ef190a122f2ca748ab22586c3150a57726fa7d534971a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d35aa562c2b7ebcdc8a757de55471fcff901bce56e0822d6517c56a92c328c48b1fcf01ae12df885605a996656165241da8f0f8b15595094166c1a1721e2659d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27ea03ac6d2d31c3ac0228a271705c2eaa4241fec0f8b0caeedfacace0e1bad6d898fa1f020f43b3bc0ee2fd20ae98b5a49f36fddde3166b37ee4631cdd1a5a8"} 14:54:20 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:20 INFO - Copying logs to upload dir... 14:54:20 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.933630 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-22 14:54:20.931249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:20.934450) ========= 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.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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/Mozilla-Beta/sha512/385f2b6e60671698126967fa511251a7f436695e82b515b8812b5c92b7cc9efe03d0d64760404351153ebf78422082fe7371d0679a4ee349e5cd6da1d034ff3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35caaf7d5a5d2e4502f13283eb6d68cd37a2a6f026ff2c29673ff0010193ca7caa05699719d602139ef190a122f2ca748ab22586c3150a57726fa7d534971a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d35aa562c2b7ebcdc8a757de55471fcff901bce56e0822d6517c56a92c328c48b1fcf01ae12df885605a996656165241da8f0f8b15595094166c1a1721e2659d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27ea03ac6d2d31c3ac0228a271705c2eaa4241fec0f8b0caeedfacace0e1bad6d898fa1f020f43b3bc0ee2fd20ae98b5a49f36fddde3166b37ee4631cdd1a5a8"} build_url:https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010661 build_url: 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/385f2b6e60671698126967fa511251a7f436695e82b515b8812b5c92b7cc9efe03d0d64760404351153ebf78422082fe7371d0679a4ee349e5cd6da1d034ff3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/35caaf7d5a5d2e4502f13283eb6d68cd37a2a6f026ff2c29673ff0010193ca7caa05699719d602139ef190a122f2ca748ab22586c3150a57726fa7d534971a13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d35aa562c2b7ebcdc8a757de55471fcff901bce56e0822d6517c56a92c328c48b1fcf01ae12df885605a996656165241da8f0f8b15595094166c1a1721e2659d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27ea03ac6d2d31c3ac0228a271705c2eaa4241fec0f8b0caeedfacace0e1bad6d898fa1f020f43b3bc0ee2fd20ae98b5a49f36fddde3166b37ee4631cdd1a5a8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:20.966537) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:20.966874) ========= 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.BNr901E9s8/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.eJl9uNzcVp/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.005291 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:21.016191) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 14:54:21.016514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 14:54:21.016878) ========= ========= Total master_lag: 0.60 =========