builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0375 starttime: 1495758326.62 results: success (0) buildid: 20170525155044 builduid: b03060bd613c4e08ae7de68fabecb80c revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.624909) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.625635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.626179) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.653152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.653619) ========= 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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-25 17:25:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-05-25 17:25:26 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149564 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.828417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.828823) ========= 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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.024881 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:26.879505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 17:25:26.880234) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --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 51c849c595a0b5428d1d56e4ae668653437283e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-25 17:25:26,980 truncating revision to first 12 chars 2017-05-25 17:25:26,980 Setting DEBUG logging. 2017-05-25 17:25:26,981 attempt 1/10 2017-05-25 17:25:26,981 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/51c849c595a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 17:25:29,383 unpacking tar archive at: mozilla-beta-51c849c595a0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.684347 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 17:25:29.591612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:29.592288) ========= script_repo_revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:29.593269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:29.593898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:25:29.615077) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 17:25:29.615724) ========= /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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:25:29 INFO - MultiFileLogger online at 20170525 17:25:29 in /builds/slave/test 17:25:29 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 17:25:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:25:29 INFO - {'append_to_log': False, 17:25:29 INFO - 'base_work_dir': '/builds/slave/test', 17:25:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:25:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:25:29 INFO - 'branch': 'Mozilla-Beta', 17:25:29 INFO - 'buildbot_json_path': 'buildprops.json', 17:25:29 INFO - 'config_files': ('talos/mac_config.py',), 17:25:29 INFO - 'default_actions': ('clobber', 17:25:29 INFO - 'read-buildbot-config', 17:25:29 INFO - 'download-and-extract', 17:25:29 INFO - 'populate-webroot', 17:25:29 INFO - 'create-virtualenv', 17:25:29 INFO - 'install', 17:25:29 INFO - 'run-tests'), 17:25:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:25:29 INFO - 'download_minidump_stackwalk': True, 17:25:29 INFO - 'download_symbols': 'ondemand', 17:25:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:25:29 INFO - 'tooltool.py': '/tools/tooltool.py', 17:25:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:25:29 INFO - '/tools/misc-python/virtualenv.py')}, 17:25:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:25:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:25:29 INFO - 'gecko_profile': False, 17:25:29 INFO - 'gecko_profile_interval': 0, 17:25:29 INFO - 'installer_path': 'installer.exe', 17:25:29 INFO - 'log_level': 'info', 17:25:29 INFO - 'log_name': 'talos', 17:25:29 INFO - 'log_to_console': True, 17:25:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:25:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:25:29 INFO - 'opt_config_files': (), 17:25:29 INFO - 'pip_index': False, 17:25:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:25:29 INFO - 'cmd': ('bash', 17:25:29 INFO - '-c', 17:25:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:25:29 INFO - 'enabled': True, 17:25:29 INFO - 'halt_on_failure': False, 17:25:29 INFO - 'name': 'check_screen_resolution'},), 17:25:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:25:29 INFO - 'cmd': ('bash', 17:25:29 INFO - '-c', 17:25:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:25:29 INFO - 'enabled': True, 17:25:29 INFO - 'halt_on_failure': False, 17:25:29 INFO - 'name': 'check_screen_resolution'},), 17:25:29 INFO - 'run_cmd_checks_enabled': True, 17:25:29 INFO - 'suite': 'dromaeojs', 17:25:29 INFO - 'system_bits': '32', 17:25:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:25:29 INFO - 'title': 't-yosemite-r7-0375', 17:25:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:25:29 INFO - 'use_talos_json': True, 17:25:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:25:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:25:29 INFO - 'work_dir': 'build'} 17:25:29 INFO - [mozharness: 2017-05-26 00:25:29.822526Z] Running clobber step. 17:25:29 INFO - Running pre-action listener: _resource_record_pre_action 17:25:29 INFO - Running main action method: clobber 17:25:29 INFO - rmtree: /builds/slave/test/build 17:25:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:25:32 INFO - Running post-action listener: _resource_record_post_action 17:25:32 INFO - [mozharness: 2017-05-26 00:25:32.250639Z] Finished clobber step (success) 17:25:32 INFO - [mozharness: 2017-05-26 00:25:32.250776Z] Running read-buildbot-config step. 17:25:32 INFO - Running pre-action listener: _resource_record_pre_action 17:25:32 INFO - Running main action method: read_buildbot_config 17:25:32 INFO - Using buildbot properties: 17:25:32 INFO - { 17:25:32 INFO - "project": "", 17:25:32 INFO - "product": "firefox", 17:25:32 INFO - "script_repo_revision": "production", 17:25:32 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 17:25:32 INFO - "repository": "", 17:25:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 17:25:32 INFO - "buildid": "20170525155044", 17:25:32 INFO - "pgo_build": "False", 17:25:32 INFO - "basedir": "/builds/slave/test", 17:25:32 INFO - "buildnumber": 7, 17:25:32 INFO - "slavename": "t-yosemite-r7-0375", 17:25:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:25:32 INFO - "platform": "yosemite_r7_devedition", 17:25:32 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 17:25:32 INFO - "branch": "mozilla-beta", 17:25:32 INFO - "repo_path": "releases/mozilla-beta", 17:25:32 INFO - "revision": "51c849c595a0b5428d1d56e4ae668653437283e2", 17:25:32 INFO - "stage_platform": "macosx64-devedition", 17:25:32 INFO - "builduid": "b03060bd613c4e08ae7de68fabecb80c", 17:25:32 INFO - "slavebuilddir": "test" 17:25:32 INFO - } 17:25:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:25:32 INFO - Running post-action listener: _resource_record_post_action 17:25:32 INFO - [mozharness: 2017-05-26 00:25:32.254067Z] Finished read-buildbot-config step (success) 17:25:32 INFO - [mozharness: 2017-05-26 00:25:32.254176Z] Running download-and-extract step. 17:25:32 INFO - Running pre-action listener: _resource_record_pre_action 17:25:32 INFO - Running main action method: download_and_extract 17:25:32 INFO - mkdir: /builds/slave/test/build/tests 17:25:32 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')]} 17:25:32 INFO - trying https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:25:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:25:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:25:33 INFO - Downloaded 1287 bytes. 17:25:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:25:33 INFO - Using the following test package requirements: 17:25:33 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:25:33 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:25:33 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:25:33 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:25:33 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:25:33 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:25:33 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:25:33 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:25:33 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:25:33 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:25:33 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:25:33 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:25:33 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:25:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:25:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:25:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:25:35 INFO - Expected file size: 51964031 17:25:35 INFO - Obtained file size: 51964031 17:25:50 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 17:25:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:25:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:25:50 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:25:52 INFO - Expected file size: 51964031 17:25:52 INFO - Obtained file size: 51964031 17:26:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:26:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:26:07 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:26:08 INFO - Expected file size: 14404449 17:26:08 INFO - Obtained file size: 14404449 17:26:08 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')]} 17:26:08 INFO - trying https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:26:08 INFO - Downloading https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:26:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:26:11 INFO - Downloaded 74891863 bytes. 17:26:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:26:11 INFO - mkdir: /builds/slave/test/properties 17:26:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:26:11 INFO - Writing to file /builds/slave/test/properties/build_url 17:26:11 INFO - Contents: 17:26:11 INFO - build_url:https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:26:11 INFO - Running post-action listener: _resource_record_post_action 17:26:11 INFO - Running post-action listener: set_extra_try_arguments 17:26:11 INFO - [mozharness: 2017-05-26 00:26:11.654425Z] Finished download-and-extract step (success) 17:26:11 INFO - [mozharness: 2017-05-26 00:26:11.654557Z] Running populate-webroot step. 17:26:11 INFO - Running pre-action listener: _resource_record_pre_action 17:26:11 INFO - Running main action method: populate_webroot 17:26:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:26:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:26:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:26:11 INFO - '--tppagecycles', 17:26:11 INFO - '7'], 17:26:11 INFO - 'tests': ['tsvgm']}}, 17:26:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:26:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:26:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:26:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:26:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:26:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:26:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['damp', 'tps']}, 17:26:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'tests': ['damp', 'tps']}, 17:26:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['dromaeo_dom']}, 17:26:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:26:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['basic_compositor_video']}, 17:26:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:26:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'other-e10s': {'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['a11yr', 17:26:11 INFO - 'ts_paint', 17:26:11 INFO - 'tpaint', 17:26:11 INFO - 'sessionrestore', 17:26:11 INFO - 'sessionrestore_no_auto_restore', 17:26:11 INFO - 'tabpaint']}, 17:26:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['tsvgx', 17:26:11 INFO - 'tsvgr_opacity', 17:26:11 INFO - 'tart', 17:26:11 INFO - 'tscrollx', 17:26:11 INFO - 'cart', 17:26:11 INFO - 'tsvg_static']}, 17:26:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:26:11 INFO - 'tsvgr_opacity', 17:26:11 INFO - 'tart', 17:26:11 INFO - 'tscrollx', 17:26:11 INFO - 'cart', 17:26:11 INFO - 'tsvg_static']}, 17:26:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'talos_options': ['--disable-e10s'], 17:26:11 INFO - 'tests': ['tp5o']}, 17:26:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:26:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'talos_options': ['--disable-e10s', 17:26:11 INFO - '--xperf_path', 17:26:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:26:11 INFO - 'tests': ['tp5n']}, 17:26:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:26:11 INFO - 'talos_options': ['--xperf_path', 17:26:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:26:11 INFO - 'tests': ['tp5n']}}, 17:26:11 INFO - 'talos.zip': {'path': '', 17:26:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:26:11 INFO - Running post-action listener: _resource_record_post_action 17:26:11 INFO - [mozharness: 2017-05-26 00:26:11.672556Z] Finished populate-webroot step (success) 17:26:11 INFO - [mozharness: 2017-05-26 00:26:11.672693Z] Running create-virtualenv step. 17:26:11 INFO - Running pre-action listener: _resource_record_pre_action 17:26:11 INFO - Running main action method: create_virtualenv 17:26:11 INFO - Creating virtualenv /builds/slave/test/build/venv 17:26:11 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 17:26:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:26:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:26:11 INFO - Using real prefix '/tools/python27' 17:26:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:26:12 INFO - Installing distribute.............................................................................................................................................................................................done. 17:26:15 INFO - Installing pip.................done. 17:26:15 INFO - Return code: 0 17:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:26:15 INFO - Reading from file tmpfile_stdout 17:26:15 INFO - Output received: 17:26:15 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) 17:26:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:26: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')]} 17:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26: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')]} 17:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:15 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:15 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 17:26:15 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 17:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:15 INFO - 'HOME': '/Users/cltbld', 17:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:15 INFO - 'LOGNAME': 'cltbld', 17:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:15 INFO - 'MOZ_NO_REMOTE': '1', 17:26:15 INFO - 'NO_EM_RESTART': '1', 17:26:15 INFO - 'PAGER': '/bin/cat', 17:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:15 INFO - 'PWD': '/builds/slave/test', 17:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:15 INFO - 'SHELL': '/bin/bash', 17:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:15 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:15 INFO - 'USER': 'cltbld', 17:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:15 INFO - 'XPC_FLAGS': '0x0', 17:26:15 INFO - 'XPC_SERVICE_NAME': '0', 17:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:15 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 17:26:15 INFO - Cleaning up... 17:26:15 INFO - Return code: 0 17:26:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:26: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')]} 17:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26: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')]} 17:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:15 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:15 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 17:26:15 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 17:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:15 INFO - 'HOME': '/Users/cltbld', 17:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:15 INFO - 'LOGNAME': 'cltbld', 17:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:15 INFO - 'MOZ_NO_REMOTE': '1', 17:26:15 INFO - 'NO_EM_RESTART': '1', 17:26:15 INFO - 'PAGER': '/bin/cat', 17:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:15 INFO - 'PWD': '/builds/slave/test', 17:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:15 INFO - 'SHELL': '/bin/bash', 17:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:15 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:15 INFO - 'USER': 'cltbld', 17:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:15 INFO - 'XPC_FLAGS': '0x0', 17:26:15 INFO - 'XPC_SERVICE_NAME': '0', 17:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:16 INFO - Downloading/unpacking psutil>=3.1.1 17:26:16 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 17:26:16 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 17:26:16 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 17:26:16 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 17:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:26:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:26:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:26:18 INFO - Installing collected packages: psutil 17:26:18 INFO - Running setup.py install for psutil 17:26:18 INFO - building 'psutil._psutil_osx' extension 17:26:18 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 17:26:18 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 17:26:18 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 17:26:18 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 17:26:18 INFO - building 'psutil._psutil_posix' extension 17:26:18 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 17:26:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:26:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:26:18 INFO - ^ 17:26:18 INFO - 1 warning generated. 17:26:19 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 17:26:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:26:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:26:19 INFO - Successfully installed psutil 17:26:19 INFO - Cleaning up... 17:26:19 INFO - Return code: 0 17:26:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:26: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')]} 17:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26: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')]} 17:26:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:26: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 mozsystemmonitor==0.3 17:26:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:19 INFO - 'HOME': '/Users/cltbld', 17:26:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:19 INFO - 'LOGNAME': 'cltbld', 17:26:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:19 INFO - 'MOZ_NO_REMOTE': '1', 17:26:19 INFO - 'NO_EM_RESTART': '1', 17:26:19 INFO - 'PAGER': '/bin/cat', 17:26:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:19 INFO - 'PWD': '/builds/slave/test', 17:26:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:19 INFO - 'SHELL': '/bin/bash', 17:26:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:19 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:19 INFO - 'USER': 'cltbld', 17:26:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:19 INFO - 'XPC_FLAGS': '0x0', 17:26:19 INFO - 'XPC_SERVICE_NAME': '0', 17:26:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:26:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:26:20 INFO - Installing collected packages: mozsystemmonitor 17:26:20 INFO - Running setup.py install for mozsystemmonitor 17:26:21 INFO - Successfully installed mozsystemmonitor 17:26:21 INFO - Cleaning up... 17:26:21 INFO - Return code: 0 17:26:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:26:21 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')]} 17:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:21 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')]} 17:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:21 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:21 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 17:26:21 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 17:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:21 INFO - 'HOME': '/Users/cltbld', 17:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:21 INFO - 'LOGNAME': 'cltbld', 17:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:21 INFO - 'MOZ_NO_REMOTE': '1', 17:26:21 INFO - 'NO_EM_RESTART': '1', 17:26:21 INFO - 'PAGER': '/bin/cat', 17:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:21 INFO - 'PWD': '/builds/slave/test', 17:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:21 INFO - 'SHELL': '/bin/bash', 17:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:21 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:21 INFO - 'USER': 'cltbld', 17:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:21 INFO - 'XPC_FLAGS': '0x0', 17:26:21 INFO - 'XPC_SERVICE_NAME': '0', 17:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:21 INFO - Downloading/unpacking jsonschema==2.5.1 17:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:26:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:26:22 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 17:26:22 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 17:26:22 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 17:26:22 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 17:26:23 INFO - Downloading functools32-3.2.3-2.tar.gz 17:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:26:23 INFO - warning: no files found matching '*.txt' 17:26:23 INFO - no previously-included directories found matching 'build' 17:26:23 INFO - no previously-included directories found matching 'dist' 17:26:23 INFO - no previously-included directories found matching '.git*' 17:26:23 INFO - Installing collected packages: jsonschema, functools32 17:26:23 INFO - Running setup.py install for functools32 17:26:23 INFO - warning: no files found matching '*.txt' 17:26:23 INFO - no previously-included directories found matching 'build' 17:26:23 INFO - no previously-included directories found matching 'dist' 17:26:23 INFO - no previously-included directories found matching '.git*' 17:26:23 INFO - Successfully installed jsonschema functools32 17:26:23 INFO - Cleaning up... 17:26:23 INFO - Return code: 0 17:26:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:26:23 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')]} 17:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:23 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')]} 17:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:24 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 17:26:24 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 17:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:24 INFO - 'HOME': '/Users/cltbld', 17:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:24 INFO - 'LOGNAME': 'cltbld', 17:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:24 INFO - 'MOZ_NO_REMOTE': '1', 17:26:24 INFO - 'NO_EM_RESTART': '1', 17:26:24 INFO - 'PAGER': '/bin/cat', 17:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:24 INFO - 'PWD': '/builds/slave/test', 17:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:24 INFO - 'SHELL': '/bin/bash', 17:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:24 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:24 INFO - 'USER': 'cltbld', 17:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:24 INFO - 'XPC_FLAGS': '0x0', 17:26:24 INFO - 'XPC_SERVICE_NAME': '0', 17:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:26:24 INFO - Cleaning up... 17:26:24 INFO - Return code: 0 17:26:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:26:24 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')]} 17:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:24 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')]} 17:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:24 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 17:26:24 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 17:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:24 INFO - 'HOME': '/Users/cltbld', 17:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:24 INFO - 'LOGNAME': 'cltbld', 17:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:24 INFO - 'MOZ_NO_REMOTE': '1', 17:26:24 INFO - 'NO_EM_RESTART': '1', 17:26:24 INFO - 'PAGER': '/bin/cat', 17:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:24 INFO - 'PWD': '/builds/slave/test', 17:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:24 INFO - 'SHELL': '/bin/bash', 17:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:24 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:24 INFO - 'USER': 'cltbld', 17:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:24 INFO - 'XPC_FLAGS': '0x0', 17:26:24 INFO - 'XPC_SERVICE_NAME': '0', 17:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:26 INFO - Downloading blobuploader-1.2.4.tar.gz 17:26:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:26:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:26:26 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 17:26:26 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 17:26:26 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 17:26:26 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 17:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:26:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:26:27 INFO - Downloading docopt-0.6.1.tar.gz 17:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:26:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:26:27 INFO - Running setup.py install for blobuploader 17:26:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:26:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:26:27 INFO - Running setup.py install for requests 17:26:28 INFO - Running setup.py install for docopt 17:26:28 INFO - Successfully installed blobuploader requests docopt 17:26:28 INFO - Cleaning up... 17:26:28 INFO - Return code: 0 17:26:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:28 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')]} 17:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:28 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')]} 17:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:28 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:28 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 17:26:28 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 17:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:28 INFO - 'HOME': '/Users/cltbld', 17:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:28 INFO - 'LOGNAME': 'cltbld', 17:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:28 INFO - 'MOZ_NO_REMOTE': '1', 17:26:28 INFO - 'NO_EM_RESTART': '1', 17:26:28 INFO - 'PAGER': '/bin/cat', 17:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:28 INFO - 'PWD': '/builds/slave/test', 17:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:28 INFO - 'SHELL': '/bin/bash', 17:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:28 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:28 INFO - 'USER': 'cltbld', 17:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:28 INFO - 'XPC_FLAGS': '0x0', 17:26:28 INFO - 'XPC_SERVICE_NAME': '0', 17:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:28 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-foatNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:28 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-MXiwQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-hwurmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-0ZQdeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-EGG1vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-50tB8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-yw36jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-SKFaod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Bt_Zjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-cizJg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NSEYny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-AF9tsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-kYl0go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-RwwHYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NwRgIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-jqpfCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-bnK367-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:26:30 INFO - Running setup.py install for manifestparser 17:26:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:26:30 INFO - Running setup.py install for mozcrash 17:26:30 INFO - Running setup.py install for mozdebug 17:26:31 INFO - Running setup.py install for mozdevice 17:26:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Running setup.py install for mozfile 17:26:31 INFO - Running setup.py install for mozhttpd 17:26:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Running setup.py install for mozinfo 17:26:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Running setup.py install for mozInstall 17:26:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:26:31 INFO - Running setup.py install for mozleak 17:26:31 INFO - Running setup.py install for mozlog 17:26:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Running setup.py install for moznetwork 17:26:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Running setup.py install for mozprocess 17:26:32 INFO - Running setup.py install for mozprofile 17:26:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Running setup.py install for mozrunner 17:26:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:26:32 INFO - Running setup.py install for mozscreenshot 17:26:32 INFO - Running setup.py install for moztest 17:26:33 INFO - Running setup.py install for mozversion 17:26:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:26:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:26:33 INFO - Cleaning up... 17:26:33 INFO - Return code: 0 17:26:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:33 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')]} 17:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:33 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')]} 17:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:33 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:33 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 17:26:33 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 17:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:33 INFO - 'HOME': '/Users/cltbld', 17:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:33 INFO - 'LOGNAME': 'cltbld', 17:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:33 INFO - 'MOZ_NO_REMOTE': '1', 17:26:33 INFO - 'NO_EM_RESTART': '1', 17:26:33 INFO - 'PAGER': '/bin/cat', 17:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:33 INFO - 'PWD': '/builds/slave/test', 17:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:33 INFO - 'SHELL': '/bin/bash', 17:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:33 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:33 INFO - 'USER': 'cltbld', 17:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:33 INFO - 'XPC_FLAGS': '0x0', 17:26:33 INFO - 'XPC_SERVICE_NAME': '0', 17:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:26:33 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-PpdK74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:26:33 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)) 17:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:26:33 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-qSPIaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:26:33 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)) 17:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:26:33 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-G2joBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:26:33 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)) 17:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:26:33 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-CN1wPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:26:33 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)) 17:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:26:33 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-OwkK9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-g8SaIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-pPVBq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-07yoMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-vFOv8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-hGkvdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-95o2a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-4PU5vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-aIUb3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ZV0hts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:26:34 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)) 17:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:34 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8DQf94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QdEgiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:26:35 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)) 17:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:26:35 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-pwXfgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:26:35 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)) 17:26:35 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)) 17:26:35 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)) 17:26:35 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)) 17:26:35 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)) 17:26:35 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)) 17:26:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:26:35 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 17:26:35 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 17:26:35 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 17:26:35 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 17:26:36 INFO - Downloading blessings-1.6.tar.gz 17:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:26:36 INFO - Installing collected packages: blessings 17:26:36 INFO - Running setup.py install for blessings 17:26:36 INFO - Successfully installed blessings 17:26:36 INFO - Cleaning up... 17:26:37 INFO - Return code: 0 17:26:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:26:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:37 INFO - Reading from file tmpfile_stdout 17:26:37 INFO - Current package versions: 17:26:37 INFO - blessings == 1.6 17:26:37 INFO - blobuploader == 1.2.4 17:26:37 INFO - docopt == 0.6.1 17:26:37 INFO - functools32 == 3.2.3-2 17:26:37 INFO - jsonschema == 2.5.1 17:26:37 INFO - manifestparser == 1.1 17:26:37 INFO - mozInstall == 1.12 17:26:37 INFO - mozcrash == 1.0 17:26:37 INFO - mozdebug == 0.1 17:26:37 INFO - mozdevice == 0.49 17:26:37 INFO - mozfile == 1.2 17:26:37 INFO - mozhttpd == 0.7 17:26:37 INFO - mozinfo == 0.9 17:26:37 INFO - mozleak == 0.1 17:26:37 INFO - mozlog == 3.4 17:26:37 INFO - moznetwork == 0.27 17:26:37 INFO - mozprocess == 0.25 17:26:37 INFO - mozprofile == 0.28 17:26:37 INFO - mozrunner == 6.13 17:26:37 INFO - mozscreenshot == 0.1 17:26:37 INFO - mozsystemmonitor == 0.3 17:26:37 INFO - moztest == 0.8 17:26:37 INFO - mozversion == 1.4 17:26:37 INFO - psutil == 3.1.1 17:26:37 INFO - requests == 1.2.3 17:26:37 INFO - wsgiref == 0.1.2 17:26:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:26:37 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')]} 17:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:37 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')]} 17:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:37 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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:37 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 17:26:37 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 17:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:37 INFO - 'HOME': '/Users/cltbld', 17:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:37 INFO - 'LOGNAME': 'cltbld', 17:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:37 INFO - 'MOZ_NO_REMOTE': '1', 17:26:37 INFO - 'NO_EM_RESTART': '1', 17:26:37 INFO - 'PAGER': '/bin/cat', 17:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:37 INFO - 'PWD': '/builds/slave/test', 17:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:37 INFO - 'SHELL': '/bin/bash', 17:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:37 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:37 INFO - 'USER': 'cltbld', 17:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:37 INFO - 'XPC_FLAGS': '0x0', 17:26:37 INFO - 'XPC_SERVICE_NAME': '0', 17:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 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)) 17:26:37 INFO - Cleaning up... 17:26:37 INFO - Return code: 0 17:26:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:26:37 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')]} 17:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:26:37 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')]} 17:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:26:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103c75b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c78768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a84ce300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, '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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 17:26:37 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 17:26:37 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 17:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:37 INFO - 'HOME': '/Users/cltbld', 17:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:37 INFO - 'LOGNAME': 'cltbld', 17:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:37 INFO - 'MOZ_NO_REMOTE': '1', 17:26:37 INFO - 'NO_EM_RESTART': '1', 17:26:37 INFO - 'PAGER': '/bin/cat', 17:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:37 INFO - 'PWD': '/builds/slave/test', 17:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:37 INFO - 'SHELL': '/bin/bash', 17:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:37 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:37 INFO - 'USER': 'cltbld', 17:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:37 INFO - 'XPC_FLAGS': '0x0', 17:26:37 INFO - 'XPC_SERVICE_NAME': '0', 17:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:26:37 INFO - Cleaning up... 17:26:37 INFO - Return code: 0 17:26:37 INFO - Running post-action listener: _resource_record_post_action 17:26:37 INFO - Running post-action listener: _start_resource_monitoring 17:26:37 INFO - Starting resource monitoring. 17:26:37 INFO - [mozharness: 2017-05-26 00:26:37.829214Z] Finished create-virtualenv step (success) 17:26:37 INFO - [mozharness: 2017-05-26 00:26:37.830183Z] Running install step. 17:26:37 INFO - Running pre-action listener: _resource_record_pre_action 17:26:37 INFO - Running main action method: install 17:26:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:26:38 INFO - Reading from file tmpfile_stdout 17:26:38 INFO - Detecting whether we're running mozinstall >=1.0... 17:26:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:26:38 INFO - Reading from file tmpfile_stdout 17:26:38 INFO - Output received: 17:26:38 INFO - Usage: mozinstall [options] installer 17:26:38 INFO - Options: 17:26:38 INFO - -h, --help show this help message and exit 17:26:38 INFO - -d DEST, --destination=DEST 17:26:38 INFO - Directory to install application into. [default: 17:26:38 INFO - "/builds/slave/test"] 17:26:38 INFO - --app=APP Application being installed. [default: firefox] 17:26:38 INFO - mkdir: /builds/slave/test/build/application 17:26:38 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'] 17:26:38 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 17:26:58 INFO - Reading from file tmpfile_stdout 17:26:58 INFO - Output received: 17:26:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:26:58 INFO - Running post-action listener: _resource_record_post_action 17:26:58 INFO - [mozharness: 2017-05-26 00:26:58.057083Z] Finished install step (success) 17:26:58 INFO - [mozharness: 2017-05-26 00:26:58.057230Z] Running run-tests step. 17:26:58 INFO - Running pre-action listener: _resource_record_pre_action 17:26:58 INFO - Running main action method: run_tests 17:26:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:58 INFO - 2017-05-25 17:26:58.072 screenresolution[1625:7270] starting screenresolution argv=screenresolution get 17:26:58 INFO - 2017-05-25 17:26:58.074 screenresolution[1625:7270] Display 0: 1600x1200x32@60 17:26:58 INFO - 2017-05-25 17:26:58.080 screenresolution[1626:7272] starting screenresolution argv=screenresolution list 17:26:58 INFO - Available Modes on Display 0 17:26:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:58 INFO - Intel Iris: 17:26:58 INFO - Chipset Model: Intel Iris 17:26:58 INFO - Type: GPU 17:26:58 INFO - Bus: Built-In 17:26:58 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:58 INFO - Vendor: Intel (0x8086) 17:26:58 INFO - Device ID: 0x0a2e 17:26:58 INFO - Revision ID: 0x0009 17:26:58 INFO - Displays: 17:26:58 INFO - Display: 17:26:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:58 INFO - Display Serial Number: 1600x1200 60 17:26:58 INFO - Main Display: Yes 17:26:58 INFO - Mirror: Off 17:26:58 INFO - Online: Yes 17:26:58 INFO - Rotation: Supported 17:26:58 INFO - Return code: 0 17:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:58 INFO - Python 2.7.3 17:26:58 INFO - Return code: 0 17:26:58 INFO - grabbing minidump binary from tooltool 17:26: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')]} 17:26:58 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 0x103a53d30>, '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 0x10397adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a48be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:58 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 17:26:58 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 17:26:58 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 17:26:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:58 INFO - Return code: 0 17:26:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:58 INFO - ENV: RUST_BACKTRACE is now 1 17:26:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:58 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 17:26:58 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 17:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yN1T9ywkwF/Render', 17:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:58 INFO - 'HOME': '/Users/cltbld', 17:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:58 INFO - 'LOGNAME': 'cltbld', 17:26:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:58 INFO - 'MOZ_NO_REMOTE': '1', 17:26:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:58 INFO - 'NO_EM_RESTART': '1', 17:26:58 INFO - 'PAGER': '/bin/cat', 17:26:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:58 INFO - 'PWD': '/builds/slave/test', 17:26:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:58 INFO - 'RUST_BACKTRACE': '1', 17:26:58 INFO - 'SHELL': '/bin/bash', 17:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IZHfoCxl7u/Listeners', 17:26:58 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 17:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:58 INFO - 'USER': 'cltbld', 17:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:58 INFO - 'XPC_FLAGS': '0x0', 17:26:58 INFO - 'XPC_SERVICE_NAME': '0', 17:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:58 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 17:26:58 INFO - mozversion application_buildid: 20170525155044 17:26:58 INFO - mozversion application_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:26:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:26:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:58 INFO - mozversion application_name: Firefox 17:26:58 INFO - mozversion application_remotingname: firefox-dev 17:26:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:26:58 INFO - mozversion application_vendor: Mozilla 17:26:58 INFO - mozversion application_version: 54.0 17:26:58 INFO - mozversion platform_buildid: 20170525155044 17:26:58 INFO - mozversion platform_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:26:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:26:58 INFO - mozversion platform_version: 54.0 17:26:58 INFO - using testdate: 1495758418 17:26:58 INFO - actual date: 1495758418 17:26:58 INFO - starting webserver on 'localhost:49218' 17:26:58 INFO - SUITE-START | Running 2 tests 17:26:58 INFO - TEST-START | dromaeo_css 17:26:58 INFO - operating with platform_type : mac_ 17:26:58 INFO - Initialising browser for dromaeo_css test... 17:26:58 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpq6X8kn/profile http://localhost:49218/getInfo.html) 17:27:01 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 17:27:01 INFO - PROCESS | 1636 | colorDepth:24 17:27:01 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 17:27:01 INFO - PROCESS | 1636 | __metrics 17:27:01 INFO - PROCESS | 1636 | 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" 17:27:01 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:27:01 INFO - TEST-INFO | 1636: exit 0 17:27:01 INFO - Browser initialized. 17:27:01 INFO - Running cycle 1/1 for dromaeo_css test... 17:27:01 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpq6X8kn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:27:13 INFO - PROCESS | 1639 | RSS: Main: 193994752 17:27:13 INFO - PROCESS | 1639 | 17:30:18 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49218/tests/dromaeo/cssquery-ext.html) 17:30:19 INFO - PROCESS | 1639 | RSS: Main: 319889408 17:30:19 INFO - PROCESS | 1639 | 17:33:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-ext.html (next: http://localhost:49218/tests/dromaeo/cssquery-jquery.html) 17:33:23 INFO - PROCESS | 1639 | RSS: Main: 332406784 17:33:23 INFO - PROCESS | 1639 | 17:36:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49218/tests/dromaeo/cssquery-mootools.html) 17:36:26 INFO - PROCESS | 1639 | RSS: Main: 302682112 17:36:26 INFO - PROCESS | 1639 | 17:39:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49218/tests/dromaeo/cssquery-prototype.html) 17:39:29 INFO - PROCESS | 1639 | RSS: Main: 295518208 17:39:29 INFO - PROCESS | 1639 | 17:42:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49218/tests/dromaeo/cssquery-yui.html) 17:42:33 INFO - PROCESS | 1639 | RSS: Main: 291307520 17:42:33 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-yui.html (next: http://localhost:49218/tests/dromaeo/cssquery-dojo.html) 17:45:36 INFO - PROCESS | 1639 | RSS: Main: 503255040 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | __start_tp_report 17:45:36 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 17:45:36 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 17:45:36 INFO - PROCESS | 1639 | |i|pagename|runs| 17:45:36 INFO - PROCESS | 1639 | |0;dojo.html;2567.4094958159612;3405.5402520659713;3431.7769344992575;3450.9999999999986;3493.283876805255;4443.311286750551;5917.615355001927;5926.733297001634;5935.792247271346;5938.198343223669;5005.599552035837;5853.8829223415505;5911.261092363455;5970.611910225833;5976.671283079429;5312.574994000481;5340.332458442695;5369.382521010095;5384.1923711443405;5429.647072940266;5260.447652996438;5285.735713214343;5292.285541451913;5440.809571664992;5462.0441422751055;5376.193570964367;5389.299391079174;5398.13629819229;5403.486668766461;5410.188471729232;5439.428859969706;5447.373552041527;5451.236826844205;5454.509094181542;5457.072297709399;5235.685858848481;5283.656562323436;5366.812161574326;5378.596605254583;5414.7834086636485;5774.740136693859;5785.27684039495;5802.042662960606;5873.765049398019;5947.316058653267;6354.7458101675875;6356.618602883796;6380.266269379034;6381.744730210786;6391.904121438182;6321.304656487782;6333.461655759266;6351;6361.968190159066;6398.7760428385245;5890.293164820163;5910;5914.792982245601;6115.327313995502;6151.784687535959;6311.084892690534;6422.839429014312;6488.805335839932;6507.544471887017;6528.804135875836;6365.968170159213;6392.648404337713;6403.007533832263;6409.903851442232;6410.262819775786;6262.843428914222;6263.3736626337;6390.105385246069;6392.872142557123;6410.8717825643225;6296.590721603173;6297.086922396227;6308.432241098323;6309.936900631027;6323.462505686976;6310;6355.269144048401;6356.2054743157105;6356.904646430307;6379.74481020754;4851.296561998491;4894.853154405374;4899.632527560448;4911.7298548580175;4935.988122434964;4299.247631664446;4353.412289340962;4376.737395756265;4441.222786012435;4463.308187230984;4945.851624451272;5773.134029895549;5866.941330586725;5905.704714764244;5941.524678025748;6349.936500635027;6394.23269207698;6404.007378856285;6407.647579383085;6409.230892292861;5821.417858214145;5869.20765696634;5875.912956103135;5901.321348044944;5901.380355062743;5371.006363822706;5436.510714035756;5458.1266997280245;5482.862928426821;5485.862853428696;6384.3934826191435;6500.089987401768;6506.056621789719;6598.37315455031;6620.040094186413;6409.3270206629495;6410.775622853178;6419.582727122722;6423.646699431576;6434.260060092962;6530.738770449128;6605.372489613479;6609.306022867722;6619.735210591522;6620.2386725527185;6225.875482490415;6278.434940855345;6280.717367718555;6352.841178970378;6407.455366293731;6296.307406185408;6341.397567231105;6362.6818659067785;6413.16628838248;6421.743130274736;5220.425753167073;6262.937370626236;6337.271213810286;6342.080398342307;6344.143540621957;6080.966235740023;6318.747250110128;6375.203099612549;6379.681015949277;6381.329960354096;6020.187274717857;6049.637021778707;6131.774283661862;6175.413335733098;6235.064740288993;6364.713587888648;6370.55406121567;6384.968075159594;6391.296957334596;6395.008773640093;6234.875302494015;6240.344763799917;6265.091561723433;6267.4045965633195;6282.340354262919 17:45:36 INFO - PROCESS | 1639 | |1;ext.html;695.4610177112737;715.8353578676904;745.4893398022355;857.5497863621594;876.6668665906955;28124.29689257767;40908.59091409085;41549.58450415495;41691.37462938058;41697.9575510612;42707.359389609184;42913.35629965553;42965.78517107418;43045.35431968523;43135;7546.471746977713;7730.458867879251;9513.38163019405;9640.180584650314;9646.517674116301;7454.25457454253;7531.774046778605;7542;11391.202615816856;11669.70825729355;11559.768804623902;11607.129465290094;11624.360660163687;11637.592684256053;11681;11480.196386252966;11542.94228528852;11547.826782598268;11570.363630000347;11587.478563464621;11201.495932683049;11277.548898044068;11281.266717663326;11301;11301.547938082467;40070;40330.99172520681;40341;40345;40403.797981010204;3866.729328946975;3931.783751893645;3949.4668219790246;3956.3867600521676;4016.0562267867026;27499.45001099967;27903.302417439525;29394.85302573495;30076.796928122847;30117.548236776467;3076.400101980115;3111.0511294055304;3204.6795320468;3302.240484688511;3320;368835;370693;370919.14540427394;371038;371065;52800;52879;52957.47042529602;53115.46884531183;53151.468485314654;54174;54231;54314;54337.72831135898;55756.44243557593;14934.178620175995;15050.849491505163;15052.548423547092;15059.870509711816;15064.472743453927;12975;12997.545085921947;13004.934975325063;13015.869841301466;13032.54386096482;10421.426821524738;10598.417087060132;11950.820737688942;12252.958498527725;12263.75472490563;4971.925421118686;5651.802186923438;5670.8015219467125;5671.773129074873;5695.373508914016;1037.866772402852;1149.4769879704882;1155.572438197872;1162.8313894485252;1182.7338848758961;26851.194464166107;27018.729812702015;27254.45491090167;27423.725762742117;27508.862455687595;871.7428358634218;1098.3025778630467;1142.4801715219555;1221.8411606491277;1304.315234501894;13704.972127090508;13945.233012184224;13990.440382384793;14001.22993235382;14027.298635068206;464677;465879;466953;467495;468954;46926.5307346929;47846;58119;58649;58900.70549647224;1425.2446203512154;1466.4207637982936;1470.5955862137785;1473.2486431920013;1480.1193289992436;28284.292892677848;30427.54358684577;30514;30966.22584435407;31149;48594.514054859;48847.267290989956;48981;49021.5097849017;49042.75478622727;416740.8325916702;435978;470006;482972;509954;31449.37101258008;31615;31633.5254971171;31638.367232655677;31771;36309.45535817018;43164.568354316056;43848.56151438445;44664.33003504885;45318.32022519599;28805;29315.560266596443;30645.846770766897;31175.688243118188;31179.68820311768;30024.399512010074;30073.548896767003;30927.381452371275;31016.844915775277;31043.534346985263;36319.63680363163;37028.81485592663;37121.886343409744;37163;37206.81396593;30349;30678.846605766827;30824;31216;31367.549569546874;20407.352013158194;23926.282211533682;24633.753662463863;25096.62355064712;25240.74759252384 17:45:36 INFO - PROCESS | 1639 | |2;jquery.html;427.77629244342893;431.2130362089188;431.79273948504715;436.65504251641204;443.63178561793717;16625.83374166258;19782.802171978277;20945;22339.77660223397;22450.214242501515;17347.39284125056;21852.890735546338;23258.18596349128;23692.407689807762;23986.640200396963;6688.431483323911;6895.655217239143;6941.861162776741;6964.616946067964;6978.092847929787;6409.679516024204;6841.281665425108;6917.342852429011;6927.3419025192525;6946.548474349176;6939.548929319603;6954.513184077116;6965.129358830146;7276.199618042011;7583.127940286882;6654.2014958204845;7713.961430192841;7801.180876008023;7805.41459390548;7808.960955195188;7419.258074192591;7506.024216851829;7532.246775322479;7533.886991695129;7554.508956917783;26718.33204169892;26756.732432675617;27526.17421477359;27784.58323125155;28170.43659126806;2920.780941429401;2932.135020169055;2952.4095180963677;2966.466036113499;2975.866086026134;16521.91739041297;16930;16999.74500382495;17055.48833534996;17200.741988870177;2071.564971356002;2101.2645574049116;2136.102836808544;2139.208492857637;2139.5293035532313;139261;153227;157176;160526;184850;18601.81398186028;20074.39776806669;20250.797492025187;20299.898500507403;20335.491612709506;30441;30705.69294307073;30762.384752304828;30921.381572368875;31069.844650776602;10225.693229203136;10301.74245643865;10467.790644187073;10490.21323400748;10596.523156457824;8258.917410825936;8427.915720842835;8786.956065219632;8818.603162857687;9006.549672516348;9199;9266.583003764847;9345.90654093464;9346.672866449777;9369.437833729999;4088.9591104089172;4129.69027322952;4157.04849556818;4159.875203743893;4162.4796900387455;681.7477533312706;841.8316336732555;846.3652260804397;851.595492141228;859.9054104048549;19419.51451213731;22033;23185.884070579537;23198.420039499146;23234.65148022781;881.5504092912661;892.906244844282;905.1129892705186;915.5879854065564;920.4983284110176;11479.196456247982;11750;11953.46209420578;11987.820182697267;12052.698682532831;596967;778747.106264465;780609;788925;808298;164575;166439.16780416263;170121;170437;176132;1253.3169422664669;1259.9874001260052;1317.0583033131356;1321.8678132186897;1330.294943679832;15285;18859.528511787314;19553.413397598095;20006.299779507648;20098.89950550238;59072.70463647654;59984.400155999625;60042.69978650079;60070.69964650149;60182.69908650429;77864;78653.60673196598;80385;80680.19319806961;80773.78839317533;11550.59572914944;11634.53461861516;11832.230904991147;11937.343446110548;12005.75988480243;11658.825117623073;13088.672783180496;13507.662308441975;13540.255285958972;13823;11614.5354185835;11652.475638596114;11672.358020308964;11899.405029748652;11986.820197696867;10619.141650211028;10646.095081918123;10995.230333876552;11012.063974562252;11017.008469237808;11668;11740.765184696087;12110.45502952387;12121;12176.695582610506;7795.454318197899;10202.948985255027;11494.080473562262;11632.302061876315;11954.342511161969;11191.104711623213;11242.83135752948;11426.143039271921;11569.710757231136;11626.069914406997 17:45:36 INFO - PROCESS | 1639 | |3;mootools.html;1371.0813754784294;1599.6720672262188;1623.6996155711188;1627.5605586491647;1640.6718656268747;9300.116488933558;9743.464109473976;9759.902400975998;9815;9817.7545561361;9584.993575674558;10721.035106840383;11041.282316649413;11121.610743623974;11166;2407.2657839358944;2456.557819592482;2459.754024597544;2467.2104926423553;2468.790152837011;2316.3051084674617;2438.743931887153;2447.755224477547;2448.853068815868;2450.742672019439;2047.488127968008;2126.829853611707;2140.1867290429614;2142.100317866492;2143.9571208575817;2112.6408510553165;2137.732783402075;2349.4478797482748;2422.345966589011;2430.5503481855735;2028.5942811437624;2428.441458464563;2432.7323994360604;2432.7567243275707;2443.706755189389;9529.237660987104;11154.553817847276;11229.382383968878;11329.376884271362;11330.490127944262;5840.678762668052;6044.395560443964;6054.182685337467;6055;6060.090986352083;3439.8280085995852;3549.5563054618174;3739.3082279778328;3740.869069582579;3744.6629803317833;1166.125405945541;1232.7287996640725;1233.3216730798115;1278.961631151067;1279.1941077121446;12974.221546707227;15707.607309817231;15773.290201940825;15776.290066947015;15796.763048554281;4689.554492323224;4969.676970996946;5023.422306434733;5034.144195486776;5066.746662666852;13006.414711338013;13020.739585208432;13021.674458138434;13027.413766380536;13076.54232101891;6024.337322894478;6025.728842202111;6026.0659597762415;6027.48766354856;6035.668038257938;5161.483851614884;5328.57371410294;5354.678719276856;5385.084535628953;5405.270288511079;3550.2721942001826;3884.6115388461494;3996.2407142642805;3998.2403343364667;4041.616046475639;1149.1266637355502;1152.706059954723;1158.2645020412099;1170.473287020827;1173.6244401791346;951.4529145741088;959.2805395953035;962.7256231973994;983.1102851919024;990.4700984973005;6158.815235542941;6691.665416729144;6733.091032710619;6776.864462710718;7005.0893383859775;908.7410088124852;936.3492372800838;958.0850287974968;975.7511834482303;985.7437066362654;3828.8468461261236;4693.601043911306;4700.482946875841;4718.2214934536105;4845.103655823684;13617.455301787817;13817.309134543233;13981.860181398055;14002.369893354824;14024.368903399372;13044.673883152996;13518.864811351958;13821.447142114203;13828.893688504895;13835;10122.898771012196;10409.375437473927;10411.895881041093;10412.791744165226;10427.322224055411;6816.693248803716;7057.61183134912;7194.460415468756;7230.060092187982;7237.131544214728;12280.078994075659;12370;12378.504859805507;12644.30456324911;12671.936640317108;12119.515219391125;12483.56307529232;12824.038197135065;12870.22778633285;12890.806637900627;8662.956685216534;8719.171678690514;8791.340649451444;8887.066857980077;8896.777580560538;9619.18236949867;9647.565859536478;9737.172340350866;9745.951270243604;9779;8283.04744954338;8307.83384332322;8384.371172162235;8422.32621390275;8440.071592124985;8144.75565733029;8157.714479993172;8187.79530511742;8213.95893020531;8387.874181887155;7456.888146677913;7615.847683046197;7630.694772209049;7631.503952243086;7639.732609358646;5064.670796398223;6058.787942421994;6088.726007329769;6214.627122372672;6250.06249062626;5993.370696077023;6101.60339577926;6106.267247930099;6155.384461553809;6214.9067763982675 17:45:36 INFO - PROCESS | 1639 | |4;prototype.html;377.418775086367;383.97504162229455;390.1514206600643;395.9445677605135;396.577644808279;4081.244969680604;4522.299043648236;4533.81864725411;4666.883327916804;4809.711417314955;4068.9389659155136;4443.5778601032935;4665.393498845146;4875.707457552549;5022.447530771612;4145.647619952292;4184.832606695729;4202.243596152706;4231.259529582326;4235.68232382571;4002.539707933581;4109.479492587731;4227.302495088322;4252.724792236154;4306.126834826845;3739.1026153723183;4234.06850492891;4260.744355338674;4280.229558679436;4283.785810709452;3640.4357324614725;3916.7649941003633;4136.338185890243;4147.688923330762;4226.91546169078;3878.4764056852528;3881.514810648669;3885.417187421881;3892.2799282132896;3893.824777885002;4103.876883693494;4546.795099298689;4891.559759621616;4903.215485522299;4928.7535623219055;4319.265724826787;4442.689011769177;4781.824269957161;4861.270809378586;4979.327790748239;5379.973100134474;5413.648112872651;5465.4261302563455;5486.595703232353;5502.9174562381595;3812.90467738306;4162.896832339433;4237.4491316129015;4401.581849724271;4465.508794032654;4008.9376315276477;4714.740689262124;4989.872678020472;5215.58275337972;5663.518600918886;3785.356489396809;3815.751976121543;4160.9375859362135;4184.937225941613;4410.845620403271;4470.374147619336;4495.505494395614;4501.122281155209;4501.459824821042;4520.988160268649;4830.6135509159185;4859.514048595183;4866.270059491034;4872.78072486739;4909.803607855717;5166.767495462713;5249;5309.867253318621;5342.893142137135;5350.545203657733;1318.5912367166209;1500.0898257380647;1506.9924650376897;1518.7873697682332;1543.3209387869301;983.9815790656636;1147.3230793831679;1150.2925700694013;1163.6858048326903;1173.061550759389;4359.80380882861;4444;4587.885302867388;4866.328324808153;4907.779149938261;4773.880652983703;5287.41838397776;5290.576753859682;5412.458754124556;5510.842723028209;3688.9302102390006;4278.935815962763;4314;4755.476897541268;4884.3650325457465;2653.761161495475;2682.2029240408974;2738.602902579115;2880.942381152407;2892.8119672221237;4873.31773551703;4876.414830220326;5053;5485.314335708036;5627.43725627434;5170.870728231825;5340.211029305148;5386.771384463178;5744.798932037359;5758.539316854725;5206.97396513015;5496.725163741797;5517.2275881377445;5537.723113844291;5663.943360566424;4650.930236046394;4769.61843052548;4974.701517908937;5109.897802044013;5541.032933308778;4453.354263631691;5306.0632869001765;5452.229299363057;5462.235287059894;5503.559715222853;5151.896962060663;5154.7938082476285;5804.332501762183;5879.20630714848;5915.881682366243;3835.9424608630334;4472.821087156477;4484.8430304939175;4513.300438432048;4551.522090180483;4733.431988161443;4744.861233304051;4886.169351210232;5059.089363914383;5716.42835716425;4667.719936803803;5083.796648134034;5477.356792962016;5486.7106230035115;5492.835214943558;4592.494825569252;4779.3070004848405;5389.973050134724;5441.83674489766;5498.615096943176;4931.334269873521;4992.475790042138;5259.789608415774;5316.069687804541;5380.009396523312;5063.924041139459;5267.78928842842;5272.499112584298;5397.568194544348;5509.173623956439;5060.367454068241;5157.6647517911215;5354.303940487867;5394.352677678547;5395.358133259955 17:45:36 INFO - PROCESS | 1639 | |5;yui.html;735.937445317148;767.3209209849282;778.1557010643448;841.4993079117926;848.2365870716354;12797.360131993399;15416.152111633855;16145;16215.02709837409;16443.58891027722;10931.726706832333;14921.626959325995;15161.469348572802;15168.848311516882;15234.705050070757;940.3417607674621;958.156821996642;964.5707660091263;965.1313817564178;967.2407160379104;977.2182254196614;989.787195752913;999.5851721535554;1002.7493126718284;1005.2812239248935;1037.8547003419528;1042.306865934153;1043.118564812732;1044.1959691037896;1044.310754901765;1034.7671773850861;1035.3322107240863;1035.5495359518634;1035.9741006474824;1037.9532921018535;772.7604442622805;774.1755030892082;775.2983549887362;775.3990657240628;775.8478659191097;16039.198040097948;16339.34642614294;16370.83629163705;16383.344666213337;16699.74950375745;11996.820047699292;14118;14238.288085595783;14271.00102992791;14623.853761462462;11935.343556104412;11989.820152697717;12017.93991030048;12025.398730063462;12200.816987745191;3491.284286721216;3545.3411722786254;3547;3563.1448452371505;3615.313090512794;105581;107918.46040769746;109245.45377273142;110536;111588;10051.648192313234;10802.081823044971;10911.781764364669;11205;11615.883841161649;18381.90809045973;22495.437614059778;22541.211057612905;22543.887280563493;22560.66159007616;7470.551766894004;7981.640826162837;8063;8148.714794982266;8236;3571.232185080168;4390.648748100144;4580.129775342674;4581.198290299184;4655.95344046562;3553.8400771964807;3658.1037645776955;3665.2303016366864;3669.743117981716;3670.3943849265106;646.6637348578806;647.6988200486796;650.2164891305965;664.3887623386496;670.4033410264868;662.9337066293429;664.9335066493409;695.7877847256635;696.2271878215178;732.5201992694793;10354.689359319233;10387.636432724817;10453.790924181474;10661.946690266499;10745.140388769036;595.3629616310593;658.7430901948244;682.9043933849265;706.1561434086218;714.9857002860018;4794.4486384065585;4990.850274491771;5010.173321401928;5131.358580177478;5142.768575414116;95318;96469;98623;99075.51386729204;99516;46649;60967.39032609706;71028.64485677642;72863.63568182125;83316;72636;73341.26658733345;73613;73965.2603473969;75582.62208688921;10931.289466184673;11086.326232216159;11190.440477976232;11354.432278386214;11360.943195283971;43456.69629911108;44186.779066104464;45004.549954500035;45054;45536.861578460805;48717.5128248713;48974.510254896995;54867.725661371434;55161.72419137879;55434.44565554293;12440.626781196217;12581.056420768296;12901.974436763241;13299.041150619103;13372.52314398244;14378.424863005363;14497.420103196177;14656.340464678902;14921.328540215933;15461.149636770086;13138.934305328412;13345.545461296784;13898.791518127438;13977.58067257984;14364.640883977567;13126.409311581192;13877.444902203799;13905;14037.947153963289;14385.928070359581;11851.881481185077;12291.385430728249;12803.231806091664;12804.487820487077;12834.807477888025;9450.952745236229;9731.951340243253;9920.950395247977;9953.452560109263;10038.949805250926;9797;9914.504274786375;10930.672079837617;11393.886061139614;11399.829002565135 17:45:36 INFO - PROCESS | 1639 | __end_tp_report 17:45:36 INFO - PROCESS | 1639 | __start_cc_report 17:45:36 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1394 17:45:36 INFO - PROCESS | 1639 | __end_cc_report 17:45:36 INFO - PROCESS | 1639 | __startTimestamp1495759536543__endTimestamp 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | ------- Summary: start ------- 17:45:36 INFO - PROCESS | 1639 | Number of tests: 6 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5879.50 Median:6234.88 stddev:717.60 (11.5%) stddev-sans-first:671.12 17:45:36 INFO - PROCESS | 1639 | Values: 2567.4 3405.5 3431.8 3451.0 3493.3 4443.3 5917.6 5926.7 5935.8 5938.2 5005.6 5853.9 5911.3 5970.6 5976.7 5312.6 5340.3 5369.4 5384.2 5429.6 5260.4 5285.7 5292.3 5440.8 5462.0 5376.2 5389.3 5398.1 5403.5 5410.2 5439.4 5447.4 5451.2 5454.5 5457.1 5235.7 5283.7 5366.8 5378.6 5414.8 5774.7 5785.3 5802.0 5873.8 5947.3 6354.7 6356.6 6380.3 6381.7 6391.9 6321.3 6333.5 6351.0 6362.0 6398.8 5890.3 5910.0 5914.8 6115.3 6151.8 6311.1 6422.8 6488.8 6507.5 6528.8 6366.0 6392.6 6403.0 6409.9 6410.3 6262.8 6263.4 6390.1 6392.9 6410.9 6296.6 6297.1 6308.4 6309.9 6323.5 6310.0 6355.3 6356.2 6356.9 6379.7 4851.3 4894.9 4899.6 4911.7 4936.0 4299.2 4353.4 4376.7 4441.2 4463.3 4945.9 5773.1 5866.9 5905.7 5941.5 6349.9 6394.2 6404.0 6407.6 6409.2 5821.4 5869.2 5875.9 5901.3 5901.4 5371.0 5436.5 5458.1 5482.9 5485.9 6384.4 6500.1 6506.1 6598.4 6620.0 6409.3 6410.8 6419.6 6423.6 6434.3 6530.7 6605.4 6609.3 6619.7 6620.2 6225.9 6278.4 6280.7 6352.8 6407.5 6296.3 6341.4 6362.7 6413.2 6421.7 5220.4 6262.9 6337.3 6342.1 6344.1 6081.0 6318.7 6375.2 6379.7 6381.3 6020.2 6049.6 6131.8 6175.4 6235.1 6364.7 6370.6 6385.0 6391.3 6395.0 6234.9 6240.3 6265.1 6267.4 6282.3 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:57482.03 Median:31624.26 stddev:115716.48 (365.9%) stddev-sans-first:115962.55 17:45:36 INFO - PROCESS | 1639 | Values: 695.5 715.8 745.5 857.5 876.7 28124.3 40908.6 41549.6 41691.4 41698.0 42707.4 42913.4 42965.8 43045.4 43135.0 7546.5 7730.5 9513.4 9640.2 9646.5 7454.3 7531.8 7542.0 11391.2 11669.7 11559.8 11607.1 11624.4 11637.6 11681.0 11480.2 11542.9 11547.8 11570.4 11587.5 11201.5 11277.5 11281.3 11301.0 11301.5 40070.0 40331.0 40341.0 40345.0 40403.8 3866.7 3931.8 3949.5 3956.4 4016.1 27499.5 27903.3 29394.9 30076.8 30117.5 3076.4 3111.1 3204.7 3302.2 3320.0 368835.0 370693.0 370919.1 371038.0 371065.0 52800.0 52879.0 52957.5 53115.5 53151.5 54174.0 54231.0 54314.0 54337.7 55756.4 14934.2 15050.8 15052.5 15059.9 15064.5 12975.0 12997.5 13004.9 13015.9 13032.5 10421.4 10598.4 11950.8 12253.0 12263.8 4971.9 5651.8 5670.8 5671.8 5695.4 1037.9 1149.5 1155.6 1162.8 1182.7 26851.2 27018.7 27254.5 27423.7 27508.9 871.7 1098.3 1142.5 1221.8 1304.3 13705.0 13945.2 13990.4 14001.2 14027.3 464677.0 465879.0 466953.0 467495.0 468954.0 46926.5 47846.0 58119.0 58649.0 58900.7 1425.2 1466.4 1470.6 1473.2 1480.1 28284.3 30427.5 30514.0 30966.2 31149.0 48594.5 48847.3 48981.0 49021.5 49042.8 416740.8 435978.0 470006.0 482972.0 509954.0 31449.4 31615.0 31633.5 31638.4 31771.0 36309.5 43164.6 43848.6 44664.3 45318.3 28805.0 29315.6 30645.8 31175.7 31179.7 30024.4 30073.5 30927.4 31016.8 31043.5 36319.6 37028.8 37121.9 37163.0 37206.8 30349.0 30678.8 30824.0 31216.0 31367.5 20407.4 23926.3 24633.8 25096.6 25240.7 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:43543.91 Median:23246.42 stddev:126328.31 (543.4%) stddev-sans-first:126641.21 17:45:36 INFO - PROCESS | 1639 | Values: 427.8 431.2 431.8 436.7 443.6 16625.8 19782.8 20945.0 22339.8 22450.2 17347.4 21852.9 23258.2 23692.4 23986.6 6688.4 6895.7 6941.9 6964.6 6978.1 6409.7 6841.3 6917.3 6927.3 6946.5 6939.5 6954.5 6965.1 7276.2 7583.1 6654.2 7714.0 7801.2 7805.4 7809.0 7419.3 7506.0 7532.2 7533.9 7554.5 26718.3 26756.7 27526.2 27784.6 28170.4 2920.8 2932.1 2952.4 2966.5 2975.9 16521.9 16930.0 16999.7 17055.5 17200.7 2071.6 2101.3 2136.1 2139.2 2139.5 139261.0 153227.0 157176.0 160526.0 184850.0 18601.8 20074.4 20250.8 20299.9 20335.5 30441.0 30705.7 30762.4 30921.4 31069.8 10225.7 10301.7 10467.8 10490.2 10596.5 8258.9 8427.9 8787.0 8818.6 9006.5 9199.0 9266.6 9345.9 9346.7 9369.4 4089.0 4129.7 4157.0 4159.9 4162.5 681.7 841.8 846.4 851.6 859.9 19419.5 22033.0 23185.9 23198.4 23234.7 881.6 892.9 905.1 915.6 920.5 11479.2 11750.0 11953.5 11987.8 12052.7 596967.0 778747.1 780609.0 788925.0 808298.0 164575.0 166439.2 170121.0 170437.0 176132.0 1253.3 1260.0 1317.1 1321.9 1330.3 15285.0 18859.5 19553.4 20006.3 20098.9 59072.7 59984.4 60042.7 60070.7 60182.7 77864.0 78653.6 80385.0 80680.2 80773.8 11550.6 11634.5 11832.2 11937.3 12005.8 11658.8 13088.7 13507.7 13540.3 13823.0 11614.5 11652.5 11672.4 11899.4 11986.8 10619.1 10646.1 10995.2 11012.1 11017.0 11668.0 11740.8 12110.5 12121.0 12176.7 7795.5 10202.9 11494.1 11632.3 11954.3 11191.1 11242.8 11426.1 11569.7 11626.1 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6749.50 Median:3940.43 stddev:4214.71 (107.0%) stddev-sans-first:4207.16 17:45:36 INFO - PROCESS | 1639 | Values: 1371.1 1599.7 1623.7 1627.6 1640.7 9300.1 9743.5 9759.9 9815.0 9817.8 9585.0 10721.0 11041.3 11121.6 11166.0 2407.3 2456.6 2459.8 2467.2 2468.8 2316.3 2438.7 2447.8 2448.9 2450.7 2047.5 2126.8 2140.2 2142.1 2144.0 2112.6 2137.7 2349.4 2422.3 2430.6 2028.6 2428.4 2432.7 2432.8 2443.7 9529.2 11154.6 11229.4 11329.4 11330.5 5840.7 6044.4 6054.2 6055.0 6060.1 3439.8 3549.6 3739.3 3740.9 3744.7 1166.1 1232.7 1233.3 1279.0 1279.2 12974.2 15707.6 15773.3 15776.3 15796.8 4689.6 4969.7 5023.4 5034.1 5066.7 13006.4 13020.7 13021.7 13027.4 13076.5 6024.3 6025.7 6026.1 6027.5 6035.7 5161.5 5328.6 5354.7 5385.1 5405.3 3550.3 3884.6 3996.2 3998.2 4041.6 1149.1 1152.7 1158.3 1170.5 1173.6 951.5 959.3 962.7 983.1 990.5 6158.8 6691.7 6733.1 6776.9 7005.1 908.7 936.3 958.1 975.8 985.7 3828.8 4693.6 4700.5 4718.2 4845.1 13617.5 13817.3 13981.9 14002.4 14024.4 13044.7 13518.9 13821.4 13828.9 13835.0 10122.9 10409.4 10411.9 10412.8 10427.3 6816.7 7057.6 7194.5 7230.1 7237.1 12280.1 12370.0 12378.5 12644.3 12671.9 12119.5 12483.6 12824.0 12870.2 12890.8 8663.0 8719.2 8791.3 8887.1 8896.8 9619.2 9647.6 9737.2 9746.0 9779.0 8283.0 8307.8 8384.4 8422.3 8440.1 8144.8 8157.7 8187.8 8214.0 8387.9 7456.9 7615.8 7630.7 7631.5 7639.7 5064.7 6058.8 6088.7 6214.6 6250.1 5993.4 6101.6 6106.3 6155.4 6214.9 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4438.13 Median:4771.75 stddev:1223.08 (25.6%) stddev-sans-first:1187.93 17:45:36 INFO - PROCESS | 1639 | Values: 377.4 384.0 390.2 395.9 396.6 4081.2 4522.3 4533.8 4666.9 4809.7 4068.9 4443.6 4665.4 4875.7 5022.4 4145.6 4184.8 4202.2 4231.3 4235.7 4002.5 4109.5 4227.3 4252.7 4306.1 3739.1 4234.1 4260.7 4280.2 4283.8 3640.4 3916.8 4136.3 4147.7 4226.9 3878.5 3881.5 3885.4 3892.3 3893.8 4103.9 4546.8 4891.6 4903.2 4928.8 4319.3 4442.7 4781.8 4861.3 4979.3 5380.0 5413.6 5465.4 5486.6 5502.9 3812.9 4162.9 4237.4 4401.6 4465.5 4008.9 4714.7 4989.9 5215.6 5663.5 3785.4 3815.8 4160.9 4184.9 4410.8 4470.4 4495.5 4501.1 4501.5 4521.0 4830.6 4859.5 4866.3 4872.8 4909.8 5166.8 5249.0 5309.9 5342.9 5350.5 1318.6 1500.1 1507.0 1518.8 1543.3 984.0 1147.3 1150.3 1163.7 1173.1 4359.8 4444.0 4587.9 4866.3 4907.8 4773.9 5287.4 5290.6 5412.5 5510.8 3688.9 4278.9 4314.0 4755.5 4884.4 2653.8 2682.2 2738.6 2880.9 2892.8 4873.3 4876.4 5053.0 5485.3 5627.4 5170.9 5340.2 5386.8 5744.8 5758.5 5207.0 5496.7 5517.2 5537.7 5663.9 4650.9 4769.6 4974.7 5109.9 5541.0 4453.4 5306.1 5452.2 5462.2 5503.6 5151.9 5154.8 5804.3 5879.2 5915.9 3835.9 4472.8 4484.8 4513.3 4551.5 4733.4 4744.9 4886.2 5059.1 5716.4 4667.7 5083.8 5477.4 5486.7 5492.8 4592.5 4779.3 5390.0 5441.8 5498.6 4931.3 4992.5 5259.8 5316.1 5380.0 5063.9 5267.8 5272.5 5397.6 5509.2 5060.4 5157.7 5354.3 5394.4 5395.4 17:45:36 INFO - PROCESS | 1639 | 17:45:36 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19211.44 Median:13025.97 stddev:27042.07 (207.6%) stddev-sans-first:27082.35 17:45:36 INFO - PROCESS | 1639 | Values: 735.9 767.3 778.2 841.5 848.2 12797.4 15416.2 16145.0 16215.0 16443.6 10931.7 14921.6 15161.5 15168.8 15234.7 940.3 958.2 964.6 965.1 967.2 977.2 989.8 999.6 1002.7 1005.3 1037.9 1042.3 1043.1 1044.2 1044.3 1034.8 1035.3 1035.5 1036.0 1038.0 772.8 774.2 775.3 775.4 775.8 16039.2 16339.3 16370.8 16383.3 16699.7 11996.8 14118.0 14238.3 14271.0 14623.9 11935.3 11989.8 12017.9 12025.4 12200.8 3491.3 3545.3 3547.0 3563.1 3615.3 105581.0 107918.5 109245.5 110536.0 111588.0 10051.6 10802.1 10911.8 11205.0 11615.9 18381.9 22495.4 22541.2 22543.9 22560.7 7470.6 7981.6 8063.0 8148.7 8236.0 3571.2 4390.6 4580.1 4581.2 4656.0 3553.8 3658.1 3665.2 3669.7 3670.4 646.7 647.7 650.2 664.4 670.4 662.9 664.9 695.8 696.2 732.5 10354.7 10387.6 10453.8 10661.9 10745.1 595.4 658.7 682.9 706.2 715.0 4794.4 4990.9 5010.2 5131.4 5142.8 95318.0 96469.0 98623.0 99075.5 99516.0 46649.0 60967.4 71028.6 72863.6 83316.0 72636.0 73341.3 73613.0 73965.3 75582.6 10931.3 11086.3 11190.4 11354.4 11360.9 43456.7 44186.8 45004.5 45054.0 45536.9 48717.5 48974.5 54867.7 55161.7 55434.4 12440.6 12581.1 12902.0 13299.0 13372.5 14378.4 14497.4 14656.3 14921.3 15461.1 13138.9 13345.5 13898.8 13977.6 14364.6 13126.4 13877.4 13905.0 14037.9 14385.9 11851.9 12291.4 12803.2 12804.5 12834.8 9451.0 9732.0 9921.0 9953.5 10038.9 9797.0 9914.5 10930.7 11393.9 11399.8 17:45:36 INFO - PROCESS | 1639 | -------- Summary: end -------- 17:45:36 INFO - PROCESS | 1639 | 17:45:37 INFO - TEST-INFO | 1639: exit 0 17:45:37 INFO - TEST-OK | dromaeo_css | took 1118407ms 17:45:37 INFO - TEST-START | kraken 17:45:37 INFO - operating with platform_type : mac_ 17:45:37 INFO - Initialising browser for kraken test... 17:45:37 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpE4oScE/profile http://localhost:49218/getInfo.html) 17:45:39 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 17:45:39 INFO - PROCESS | 1650 | colorDepth:24 17:45:39 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 17:45:39 INFO - PROCESS | 1650 | __metrics 17:45:39 INFO - PROCESS | 1650 | 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" 17:45:39 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:45:40 INFO - TEST-INFO | 1650: exit 0 17:45:40 INFO - Browser initialized. 17:45:40 INFO - Running cycle 1/1 for kraken test... 17:45:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpE4oScE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:45:52 INFO - PROCESS | 1652 | RSS: Main: 197509120 17:45:52 INFO - PROCESS | 1652 | 17:45:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:56 INFO - PROCESS | 1652 | RSS: Main: 248229888 17:45:56 INFO - PROCESS | 1652 | 17:45:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-dft) 17:45:59 INFO - PROCESS | 1652 | RSS: Main: 259858432 17:45:59 INFO - PROCESS | 1652 | 17:46:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-fft) 17:46:03 INFO - PROCESS | 1652 | RSS: Main: 357158912 17:46:03 INFO - PROCESS | 1652 | 17:46:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator) 17:46:06 INFO - PROCESS | 1652 | RSS: Main: 261001216 17:46:06 INFO - PROCESS | 1652 | 17:46:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:46:08 INFO - PROCESS | 1652 | RSS: Main: 262082560 17:46:08 INFO - PROCESS | 1652 | 17:46:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom) 17:46:12 INFO - PROCESS | 1652 | RSS: Main: 303841280 17:46:12 INFO - PROCESS | 1652 | 17:46:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate) 17:46:15 INFO - PROCESS | 1652 | RSS: Main: 302948352 17:46:15 INFO - PROCESS | 1652 | 17:46:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial) 17:46:19 INFO - PROCESS | 1652 | RSS: Main: 303411200 17:46:19 INFO - PROCESS | 1652 | 17:46:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:46:22 INFO - PROCESS | 1652 | RSS: Main: 275603456 17:46:22 INFO - PROCESS | 1652 | 17:46:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:46:24 INFO - PROCESS | 1652 | RSS: Main: 278835200 17:46:24 INFO - PROCESS | 1652 | 17:46:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:46:27 INFO - PROCESS | 1652 | RSS: Main: 288124928 17:46:27 INFO - PROCESS | 1652 | 17:46:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:46:30 INFO - PROCESS | 1652 | RSS: Main: 301232128 17:46:30 INFO - PROCESS | 1652 | 17:46:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:46:34 INFO - PROCESS | 1652 | RSS: Main: 294780928 17:46:34 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49218/tests/kraken/driver.html?testName=ai-astar) 17:46:36 INFO - PROCESS | 1652 | RSS: Main: 339550208 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | __start_tp_report 17:46:36 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 17:46:36 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 17:46:36 INFO - PROCESS | 1652 | |i|pagename|runs| 17:46:36 INFO - PROCESS | 1652 | |0;ai-astar;117;117;129;174;138;115;117;116;118;116 17:46:36 INFO - PROCESS | 1652 | |1;audio-beat-detection;143;111;110;145;110;111;109;109;131;109 17:46:36 INFO - PROCESS | 1652 | |2;audio-dft;186;182;184;260;225;186;184;186;184;184 17:46:36 INFO - PROCESS | 1652 | |3;audio-fft;79;60;62;93;65;61;61;61;77;59 17:46:36 INFO - PROCESS | 1652 | |4;audio-oscillator;88;70;88;70;78;95;116;94;69;84 17:46:36 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;86;85;86;85;85;86;86;85;89;86 17:46:36 INFO - PROCESS | 1652 | |6;imaging-darkroom;93;93;94;93;93;94;94;94;93;108 17:46:36 INFO - PROCESS | 1652 | |7;imaging-desaturate;124;129;83;83;83;83;102;129;69;131 17:46:36 INFO - PROCESS | 1652 | |8;json-parse-financial;62;59;60;60;63;64;64;64;63;63 17:46:36 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;55;56;70;65;61;59;58;58;57;58 17:46:36 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;82;82;86;84;82;84;84;83;87;83 17:46:36 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;145;142;136;134;136;153;151;183;147 17:46:36 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;166;167;163;164;165;164;163;167;161;208 17:46:36 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;66;63;64;64;66;75;78;79;81 17:46:36 INFO - PROCESS | 1652 | __end_tp_report 17:46:36 INFO - PROCESS | 1652 | __start_cc_report 17:46:36 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2166 17:46:36 INFO - PROCESS | 1652 | __end_cc_report 17:46:36 INFO - PROCESS | 1652 | __startTimestamp1495759596434__endTimestamp 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | ------- Summary: start ------- 17:46:36 INFO - PROCESS | 1652 | Number of tests: 14 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:125.70 Median:117.50 stddev:18.51 (15.8%) stddev-sans-first:19.36 17:46:36 INFO - PROCESS | 1652 | Values: 117.0 117.0 129.0 174.0 138.0 115.0 117.0 116.0 118.0 116.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:111.00 stddev:14.85 (13.4%) stddev-sans-first:12.92 17:46:36 INFO - PROCESS | 1652 | Values: 143.0 111.0 110.0 145.0 110.0 111.0 109.0 109.0 131.0 109.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:196.10 Median:186.00 stddev:25.84 (13.9%) stddev-sans-first:27.15 17:46:36 INFO - PROCESS | 1652 | Values: 186.0 182.0 184.0 260.0 225.0 186.0 184.0 186.0 184.0 184.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:67.80 Median:63.50 stddev:11.37 (17.9%) stddev-sans-first:11.31 17:46:36 INFO - PROCESS | 1652 | Values: 79.0 60.0 62.0 93.0 65.0 61.0 61.0 61.0 77.0 59.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:86.00 stddev:14.59 (17.0%) stddev-sans-first:15.44 17:46:36 INFO - PROCESS | 1652 | Values: 88.0 70.0 88.0 70.0 78.0 95.0 116.0 94.0 69.0 84.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.27 17:46:36 INFO - PROCESS | 1652 | Values: 86.0 85.0 86.0 85.0 85.0 86.0 86.0 85.0 89.0 86.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:93.50 stddev:4.63 (5.0%) stddev-sans-first:4.86 17:46:36 INFO - PROCESS | 1652 | Values: 93.0 93.0 94.0 93.0 93.0 94.0 94.0 94.0 93.0 108.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:76.00 stddev:24.30 (32.0%) stddev-sans-first:24.38 17:46:36 INFO - PROCESS | 1652 | Values: 124.0 129.0 83.0 83.0 83.0 83.0 102.0 129.0 69.0 131.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:63.00 stddev:1.87 (3.0%) stddev-sans-first:1.99 17:46:36 INFO - PROCESS | 1652 | Values: 62.0 59.0 60.0 60.0 63.0 64.0 64.0 64.0 63.0 63.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:58.50 stddev:4.57 (7.8%) stddev-sans-first:4.52 17:46:36 INFO - PROCESS | 1652 | Values: 55.0 56.0 70.0 65.0 61.0 59.0 58.0 58.0 57.0 58.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.70 (2.0%) stddev-sans-first:1.69 17:46:36 INFO - PROCESS | 1652 | Values: 82.0 82.0 86.0 84.0 82.0 84.0 84.0 83.0 87.0 83.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:146.00 stddev:14.28 (9.8%) stddev-sans-first:14.94 17:46:36 INFO - PROCESS | 1652 | Values: 140.0 145.0 142.0 136.0 134.0 136.0 153.0 151.0 183.0 147.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:165.50 stddev:13.90 (8.4%) stddev-sans-first:14.71 17:46:36 INFO - PROCESS | 1652 | Values: 166.0 167.0 163.0 164.0 165.0 164.0 163.0 167.0 161.0 208.0 17:46:36 INFO - PROCESS | 1652 | 17:46:36 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.50 stddev:7.15 (10.1%) stddev-sans-first:7.42 17:46:36 INFO - PROCESS | 1652 | Values: 66.0 66.0 63.0 64.0 64.0 66.0 75.0 78.0 79.0 81.0 17:46:36 INFO - PROCESS | 1652 | -------- Summary: end -------- 17:46:36 INFO - PROCESS | 1652 | 17:46:37 INFO - TEST-INFO | 1652: exit 0 17:46:37 INFO - TEST-OK | kraken | took 60056ms 17:46:37 INFO - SUITE-END | took 1178s 17:46:37 INFO - Completed test suite (00:19:38) 17:46:37 INFO - javascript benchmark 17:46:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7699.943529661095, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.4094958159612, 3405.5402520659713, 3431.7769344992575, 3450.9999999999986, 3493.283876805255, 4443.311286750551, 5917.615355001927, 5926.733297001634, 5935.792247271346, 5938.198343223669, 5005.599552035837, 5853.8829223415505, 5911.261092363455, 5970.611910225833, 5976.671283079429, 5312.574994000481, 5340.332458442695, 5369.382521010095, 5384.1923711443405, 5429.647072940266, 5260.447652996438, 5285.735713214343, 5292.285541451913, 5440.809571664992, 5462.0441422751055, 5376.193570964367, 5389.299391079174, 5398.13629819229, 5403.486668766461, 5410.188471729232, 5439.428859969706, 5447.373552041527, 5451.236826844205, 5454.509094181542, 5457.072297709399, 5235.685858848481, 5283.656562323436, 5366.812161574326, 5378.596605254583, 5414.7834086636485, 5774.740136693859, 5785.27684039495, 5802.042662960606, 5873.765049398019, 5947.316058653267, 6354.7458101675875, 6356.618602883796, 6380.266269379034, 6381.744730210786, 6391.904121438182, 6321.304656487782, 6333.461655759266, 6351.0, 6361.968190159066, 6398.7760428385245, 5890.293164820163, 5910.0, 5914.792982245601, 6115.327313995502, 6151.784687535959, 6311.084892690534, 6422.839429014312, 6488.805335839932, 6507.544471887017, 6528.804135875836, 6365.968170159213, 6392.648404337713, 6403.007533832263, 6409.903851442232, 6410.262819775786, 6262.843428914222, 6263.3736626337, 6390.105385246069, 6392.872142557123, 6410.8717825643225, 6296.590721603173, 6297.086922396227, 6308.432241098323, 6309.936900631027, 6323.462505686976, 6310.0, 6355.269144048401, 6356.2054743157105, 6356.904646430307, 6379.74481020754, 4851.296561998491, 4894.853154405374, 4899.632527560448, 4911.7298548580175, 4935.988122434964, 4299.247631664446, 4353.412289340962, 4376.737395756265, 4441.222786012435, 4463.308187230984, 4945.851624451272, 5773.134029895549, 5866.941330586725, 5905.704714764244, 5941.524678025748, 6349.936500635027, 6394.23269207698, 6404.007378856285, 6407.647579383085, 6409.230892292861, 5821.417858214145, 5869.20765696634, 5875.912956103135, 5901.321348044944, 5901.380355062743, 5371.006363822706, 5436.510714035756, 5458.1266997280245, 5482.862928426821, 5485.862853428696, 6384.3934826191435, 6500.089987401768, 6506.056621789719, 6598.37315455031, 6620.040094186413, 6409.3270206629495, 6410.775622853178, 6419.582727122722, 6423.646699431576, 6434.260060092962, 6530.738770449128, 6605.372489613479, 6609.306022867722, 6619.735210591522, 6620.2386725527185, 6225.875482490415, 6278.434940855345, 6280.717367718555, 6352.841178970378, 6407.455366293731, 6296.307406185408, 6341.397567231105, 6362.6818659067785, 6413.16628838248, 6421.743130274736, 5220.425753167073, 6262.937370626236, 6337.271213810286, 6342.080398342307, 6344.143540621957, 6080.966235740023, 6318.747250110128, 6375.203099612549, 6379.681015949277, 6381.329960354096, 6020.187274717857, 6049.637021778707, 6131.774283661862, 6175.413335733098, 6235.064740288993, 6364.713587888648, 6370.55406121567, 6384.968075159594, 6391.296957334596, 6395.008773640093, 6234.875302494015, 6240.344763799917, 6265.091561723433, 6267.4045965633195, 6282.340354262919], "value": 5829.820584528213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.4610177112737, 715.8353578676904, 745.4893398022355, 857.5497863621594, 876.6668665906955, 28124.29689257767, 40908.59091409085, 41549.58450415495, 41691.37462938058, 41697.9575510612, 42707.359389609184, 42913.35629965553, 42965.78517107418, 43045.35431968523, 43135.0, 7546.471746977713, 7730.458867879251, 9513.38163019405, 9640.180584650314, 9646.517674116301, 7454.25457454253, 7531.774046778605, 7542.0, 11391.202615816856, 11669.70825729355, 11559.768804623902, 11607.129465290094, 11624.360660163687, 11637.592684256053, 11681.0, 11480.196386252966, 11542.94228528852, 11547.826782598268, 11570.363630000347, 11587.478563464621, 11201.495932683049, 11277.548898044068, 11281.266717663326, 11301.0, 11301.547938082467, 40070.0, 40330.99172520681, 40341.0, 40345.0, 40403.797981010204, 3866.729328946975, 3931.783751893645, 3949.4668219790246, 3956.3867600521676, 4016.0562267867026, 27499.45001099967, 27903.302417439525, 29394.85302573495, 30076.796928122847, 30117.548236776467, 3076.400101980115, 3111.0511294055304, 3204.6795320468, 3302.240484688511, 3320.0, 368835.0, 370693.0, 370919.14540427394, 371038.0, 371065.0, 52800.0, 52879.0, 52957.47042529602, 53115.46884531183, 53151.468485314654, 54174.0, 54231.0, 54314.0, 54337.72831135898, 55756.44243557593, 14934.178620175995, 15050.849491505163, 15052.548423547092, 15059.870509711816, 15064.472743453927, 12975.0, 12997.545085921947, 13004.934975325063, 13015.869841301466, 13032.54386096482, 10421.426821524738, 10598.417087060132, 11950.820737688942, 12252.958498527725, 12263.75472490563, 4971.925421118686, 5651.802186923438, 5670.8015219467125, 5671.773129074873, 5695.373508914016, 1037.866772402852, 1149.4769879704882, 1155.572438197872, 1162.8313894485252, 1182.7338848758961, 26851.194464166107, 27018.729812702015, 27254.45491090167, 27423.725762742117, 27508.862455687595, 871.7428358634218, 1098.3025778630467, 1142.4801715219555, 1221.8411606491277, 1304.315234501894, 13704.972127090508, 13945.233012184224, 13990.440382384793, 14001.22993235382, 14027.298635068206, 464677.0, 465879.0, 466953.0, 467495.0, 468954.0, 46926.5307346929, 47846.0, 58119.0, 58649.0, 58900.70549647224, 1425.2446203512154, 1466.4207637982936, 1470.5955862137785, 1473.2486431920013, 1480.1193289992436, 28284.292892677848, 30427.54358684577, 30514.0, 30966.22584435407, 31149.0, 48594.514054859, 48847.267290989956, 48981.0, 49021.5097849017, 49042.75478622727, 416740.8325916702, 435978.0, 470006.0, 482972.0, 509954.0, 31449.37101258008, 31615.0, 31633.5254971171, 31638.367232655677, 31771.0, 36309.45535817018, 43164.568354316056, 43848.56151438445, 44664.33003504885, 45318.32022519599, 28805.0, 29315.560266596443, 30645.846770766897, 31175.688243118188, 31179.68820311768, 30024.399512010074, 30073.548896767003, 30927.381452371275, 31016.844915775277, 31043.534346985263, 36319.63680363163, 37028.81485592663, 37121.886343409744, 37163.0, 37206.81396593, 30349.0, 30678.846605766827, 30824.0, 31216.0, 31367.549569546874, 20407.352013158194, 23926.282211533682, 24633.753662463863, 25096.62355064712, 25240.74759252384], "value": 19097.518928693098, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.77629244342893, 431.2130362089188, 431.79273948504715, 436.65504251641204, 443.63178561793717, 16625.83374166258, 19782.802171978277, 20945.0, 22339.77660223397, 22450.214242501515, 17347.39284125056, 21852.890735546338, 23258.18596349128, 23692.407689807762, 23986.640200396963, 6688.431483323911, 6895.655217239143, 6941.861162776741, 6964.616946067964, 6978.092847929787, 6409.679516024204, 6841.281665425108, 6917.342852429011, 6927.3419025192525, 6946.548474349176, 6939.548929319603, 6954.513184077116, 6965.129358830146, 7276.199618042011, 7583.127940286882, 6654.2014958204845, 7713.961430192841, 7801.180876008023, 7805.41459390548, 7808.960955195188, 7419.258074192591, 7506.024216851829, 7532.246775322479, 7533.886991695129, 7554.508956917783, 26718.33204169892, 26756.732432675617, 27526.17421477359, 27784.58323125155, 28170.43659126806, 2920.780941429401, 2932.135020169055, 2952.4095180963677, 2966.466036113499, 2975.866086026134, 16521.91739041297, 16930.0, 16999.74500382495, 17055.48833534996, 17200.741988870177, 2071.564971356002, 2101.2645574049116, 2136.102836808544, 2139.208492857637, 2139.5293035532313, 139261.0, 153227.0, 157176.0, 160526.0, 184850.0, 18601.81398186028, 20074.39776806669, 20250.797492025187, 20299.898500507403, 20335.491612709506, 30441.0, 30705.69294307073, 30762.384752304828, 30921.381572368875, 31069.844650776602, 10225.693229203136, 10301.74245643865, 10467.790644187073, 10490.21323400748, 10596.523156457824, 8258.917410825936, 8427.915720842835, 8786.956065219632, 8818.603162857687, 9006.549672516348, 9199.0, 9266.583003764847, 9345.90654093464, 9346.672866449777, 9369.437833729999, 4088.9591104089172, 4129.69027322952, 4157.04849556818, 4159.875203743893, 4162.4796900387455, 681.7477533312706, 841.8316336732555, 846.3652260804397, 851.595492141228, 859.9054104048549, 19419.51451213731, 22033.0, 23185.884070579537, 23198.420039499146, 23234.65148022781, 881.5504092912661, 892.906244844282, 905.1129892705186, 915.5879854065564, 920.4983284110176, 11479.196456247982, 11750.0, 11953.46209420578, 11987.820182697267, 12052.698682532831, 596967.0, 778747.106264465, 780609.0, 788925.0, 808298.0, 164575.0, 166439.16780416263, 170121.0, 170437.0, 176132.0, 1253.3169422664669, 1259.9874001260052, 1317.0583033131356, 1321.8678132186897, 1330.294943679832, 15285.0, 18859.528511787314, 19553.413397598095, 20006.299779507648, 20098.89950550238, 59072.70463647654, 59984.400155999625, 60042.69978650079, 60070.69964650149, 60182.69908650429, 77864.0, 78653.60673196598, 80385.0, 80680.19319806961, 80773.78839317533, 11550.59572914944, 11634.53461861516, 11832.230904991147, 11937.343446110548, 12005.75988480243, 11658.825117623073, 13088.672783180496, 13507.662308441975, 13540.255285958972, 13823.0, 11614.5354185835, 11652.475638596114, 11672.358020308964, 11899.405029748652, 11986.820197696867, 10619.141650211028, 10646.095081918123, 10995.230333876552, 11012.063974562252, 11017.008469237808, 11668.0, 11740.765184696087, 12110.45502952387, 12121.0, 12176.695582610506, 7795.454318197899, 10202.948985255027, 11494.080473562262, 11632.302061876315, 11954.342511161969, 11191.104711623213, 11242.83135752948, 11426.143039271921, 11569.710757231136, 11626.069914406997], "value": 11717.980295567642, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.0813754784294, 1599.6720672262188, 1623.6996155711188, 1627.5605586491647, 1640.6718656268747, 9300.116488933558, 9743.464109473976, 9759.902400975998, 9815.0, 9817.7545561361, 9584.993575674558, 10721.035106840383, 11041.282316649413, 11121.610743623974, 11166.0, 2407.2657839358944, 2456.557819592482, 2459.754024597544, 2467.2104926423553, 2468.790152837011, 2316.3051084674617, 2438.743931887153, 2447.755224477547, 2448.853068815868, 2450.742672019439, 2047.488127968008, 2126.829853611707, 2140.1867290429614, 2142.100317866492, 2143.9571208575817, 2112.6408510553165, 2137.732783402075, 2349.4478797482748, 2422.345966589011, 2430.5503481855735, 2028.5942811437624, 2428.441458464563, 2432.7323994360604, 2432.7567243275707, 2443.706755189389, 9529.237660987104, 11154.553817847276, 11229.382383968878, 11329.376884271362, 11330.490127944262, 5840.678762668052, 6044.395560443964, 6054.182685337467, 6055.0, 6060.090986352083, 3439.8280085995852, 3549.5563054618174, 3739.3082279778328, 3740.869069582579, 3744.6629803317833, 1166.125405945541, 1232.7287996640725, 1233.3216730798115, 1278.961631151067, 1279.1941077121446, 12974.221546707227, 15707.607309817231, 15773.290201940825, 15776.290066947015, 15796.763048554281, 4689.554492323224, 4969.676970996946, 5023.422306434733, 5034.144195486776, 5066.746662666852, 13006.414711338013, 13020.739585208432, 13021.674458138434, 13027.413766380536, 13076.54232101891, 6024.337322894478, 6025.728842202111, 6026.0659597762415, 6027.48766354856, 6035.668038257938, 5161.483851614884, 5328.57371410294, 5354.678719276856, 5385.084535628953, 5405.270288511079, 3550.2721942001826, 3884.6115388461494, 3996.2407142642805, 3998.2403343364667, 4041.616046475639, 1149.1266637355502, 1152.706059954723, 1158.2645020412099, 1170.473287020827, 1173.6244401791346, 951.4529145741088, 959.2805395953035, 962.7256231973994, 983.1102851919024, 990.4700984973005, 6158.815235542941, 6691.665416729144, 6733.091032710619, 6776.864462710718, 7005.0893383859775, 908.7410088124852, 936.3492372800838, 958.0850287974968, 975.7511834482303, 985.7437066362654, 3828.8468461261236, 4693.601043911306, 4700.482946875841, 4718.2214934536105, 4845.103655823684, 13617.455301787817, 13817.309134543233, 13981.860181398055, 14002.369893354824, 14024.368903399372, 13044.673883152996, 13518.864811351958, 13821.447142114203, 13828.893688504895, 13835.0, 10122.898771012196, 10409.375437473927, 10411.895881041093, 10412.791744165226, 10427.322224055411, 6816.693248803716, 7057.61183134912, 7194.460415468756, 7230.060092187982, 7237.131544214728, 12280.078994075659, 12370.0, 12378.504859805507, 12644.30456324911, 12671.936640317108, 12119.515219391125, 12483.56307529232, 12824.038197135065, 12870.22778633285, 12890.806637900627, 8662.956685216534, 8719.171678690514, 8791.340649451444, 8887.066857980077, 8896.777580560538, 9619.18236949867, 9647.565859536478, 9737.172340350866, 9745.951270243604, 9779.0, 8283.04744954338, 8307.83384332322, 8384.371172162235, 8422.32621390275, 8440.071592124985, 8144.75565733029, 8157.714479993172, 8187.79530511742, 8213.95893020531, 8387.874181887155, 7456.888146677913, 7615.847683046197, 7630.694772209049, 7631.503952243086, 7639.732609358646, 5064.670796398223, 6058.787942421994, 6088.726007329769, 6214.627122372672, 6250.06249062626, 5993.370696077023, 6101.60339577926, 6106.267247930099, 6155.384461553809, 6214.9067763982675], "value": 5164.642191698381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.418775086367, 383.97504162229455, 390.1514206600643, 395.9445677605135, 396.577644808279, 4081.244969680604, 4522.299043648236, 4533.81864725411, 4666.883327916804, 4809.711417314955, 4068.9389659155136, 4443.5778601032935, 4665.393498845146, 4875.707457552549, 5022.447530771612, 4145.647619952292, 4184.832606695729, 4202.243596152706, 4231.259529582326, 4235.68232382571, 4002.539707933581, 4109.479492587731, 4227.302495088322, 4252.724792236154, 4306.126834826845, 3739.1026153723183, 4234.06850492891, 4260.744355338674, 4280.229558679436, 4283.785810709452, 3640.4357324614725, 3916.7649941003633, 4136.338185890243, 4147.688923330762, 4226.91546169078, 3878.4764056852528, 3881.514810648669, 3885.417187421881, 3892.2799282132896, 3893.824777885002, 4103.876883693494, 4546.795099298689, 4891.559759621616, 4903.215485522299, 4928.7535623219055, 4319.265724826787, 4442.689011769177, 4781.824269957161, 4861.270809378586, 4979.327790748239, 5379.973100134474, 5413.648112872651, 5465.4261302563455, 5486.595703232353, 5502.9174562381595, 3812.90467738306, 4162.896832339433, 4237.4491316129015, 4401.581849724271, 4465.508794032654, 4008.9376315276477, 4714.740689262124, 4989.872678020472, 5215.58275337972, 5663.518600918886, 3785.356489396809, 3815.751976121543, 4160.9375859362135, 4184.937225941613, 4410.845620403271, 4470.374147619336, 4495.505494395614, 4501.122281155209, 4501.459824821042, 4520.988160268649, 4830.6135509159185, 4859.514048595183, 4866.270059491034, 4872.78072486739, 4909.803607855717, 5166.767495462713, 5249.0, 5309.867253318621, 5342.893142137135, 5350.545203657733, 1318.5912367166209, 1500.0898257380647, 1506.9924650376897, 1518.7873697682332, 1543.3209387869301, 983.9815790656636, 1147.3230793831679, 1150.2925700694013, 1163.6858048326903, 1173.061550759389, 4359.80380882861, 4444.0, 4587.885302867388, 4866.328324808153, 4907.779149938261, 4773.880652983703, 5287.41838397776, 5290.576753859682, 5412.458754124556, 5510.842723028209, 3688.9302102390006, 4278.935815962763, 4314.0, 4755.476897541268, 4884.3650325457465, 2653.761161495475, 2682.2029240408974, 2738.602902579115, 2880.942381152407, 2892.8119672221237, 4873.31773551703, 4876.414830220326, 5053.0, 5485.314335708036, 5627.43725627434, 5170.870728231825, 5340.211029305148, 5386.771384463178, 5744.798932037359, 5758.539316854725, 5206.97396513015, 5496.725163741797, 5517.2275881377445, 5537.723113844291, 5663.943360566424, 4650.930236046394, 4769.61843052548, 4974.701517908937, 5109.897802044013, 5541.032933308778, 4453.354263631691, 5306.0632869001765, 5452.229299363057, 5462.235287059894, 5503.559715222853, 5151.896962060663, 5154.7938082476285, 5804.332501762183, 5879.20630714848, 5915.881682366243, 3835.9424608630334, 4472.821087156477, 4484.8430304939175, 4513.300438432048, 4551.522090180483, 4733.431988161443, 4744.861233304051, 4886.169351210232, 5059.089363914383, 5716.42835716425, 4667.719936803803, 5083.796648134034, 5477.356792962016, 5486.7106230035115, 5492.835214943558, 4592.494825569252, 4779.3070004848405, 5389.973050134724, 5441.83674489766, 5498.615096943176, 4931.334269873521, 4992.475790042138, 5259.789608415774, 5316.069687804541, 5380.009396523312, 5063.924041139459, 5267.78928842842, 5272.499112584298, 5397.568194544348, 5509.173623956439, 5060.367454068241, 5157.6647517911215, 5354.303940487867, 5394.352677678547, 5395.358133259955], "value": 4088.081163676705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.937445317148, 767.3209209849282, 778.1557010643448, 841.4993079117926, 848.2365870716354, 12797.360131993399, 15416.152111633855, 16145.0, 16215.02709837409, 16443.58891027722, 10931.726706832333, 14921.626959325995, 15161.469348572802, 15168.848311516882, 15234.705050070757, 940.3417607674621, 958.156821996642, 964.5707660091263, 965.1313817564178, 967.2407160379104, 977.2182254196614, 989.787195752913, 999.5851721535554, 1002.7493126718284, 1005.2812239248935, 1037.8547003419528, 1042.306865934153, 1043.118564812732, 1044.1959691037896, 1044.310754901765, 1034.7671773850861, 1035.3322107240863, 1035.5495359518634, 1035.9741006474824, 1037.9532921018535, 772.7604442622805, 774.1755030892082, 775.2983549887362, 775.3990657240628, 775.8478659191097, 16039.198040097948, 16339.34642614294, 16370.83629163705, 16383.344666213337, 16699.74950375745, 11996.820047699292, 14118.0, 14238.288085595783, 14271.00102992791, 14623.853761462462, 11935.343556104412, 11989.820152697717, 12017.93991030048, 12025.398730063462, 12200.816987745191, 3491.284286721216, 3545.3411722786254, 3547.0, 3563.1448452371505, 3615.313090512794, 105581.0, 107918.46040769746, 109245.45377273142, 110536.0, 111588.0, 10051.648192313234, 10802.081823044971, 10911.781764364669, 11205.0, 11615.883841161649, 18381.90809045973, 22495.437614059778, 22541.211057612905, 22543.887280563493, 22560.66159007616, 7470.551766894004, 7981.640826162837, 8063.0, 8148.714794982266, 8236.0, 3571.232185080168, 4390.648748100144, 4580.129775342674, 4581.198290299184, 4655.95344046562, 3553.8400771964807, 3658.1037645776955, 3665.2303016366864, 3669.743117981716, 3670.3943849265106, 646.6637348578806, 647.6988200486796, 650.2164891305965, 664.3887623386496, 670.4033410264868, 662.9337066293429, 664.9335066493409, 695.7877847256635, 696.2271878215178, 732.5201992694793, 10354.689359319233, 10387.636432724817, 10453.790924181474, 10661.946690266499, 10745.140388769036, 595.3629616310593, 658.7430901948244, 682.9043933849265, 706.1561434086218, 714.9857002860018, 4794.4486384065585, 4990.850274491771, 5010.173321401928, 5131.358580177478, 5142.768575414116, 95318.0, 96469.0, 98623.0, 99075.51386729204, 99516.0, 46649.0, 60967.39032609706, 71028.64485677642, 72863.63568182125, 83316.0, 72636.0, 73341.26658733345, 73613.0, 73965.2603473969, 75582.62208688921, 10931.289466184673, 11086.326232216159, 11190.440477976232, 11354.432278386214, 11360.943195283971, 43456.69629911108, 44186.779066104464, 45004.549954500035, 45054.0, 45536.861578460805, 48717.5128248713, 48974.510254896995, 54867.725661371434, 55161.72419137879, 55434.44565554293, 12440.626781196217, 12581.056420768296, 12901.974436763241, 13299.041150619103, 13372.52314398244, 14378.424863005363, 14497.420103196177, 14656.340464678902, 14921.328540215933, 15461.149636770086, 13138.934305328412, 13345.545461296784, 13898.791518127438, 13977.58067257984, 14364.640883977567, 13126.409311581192, 13877.444902203799, 13905.0, 14037.947153963289, 14385.928070359581, 11851.881481185077, 12291.385430728249, 12803.231806091664, 12804.487820487077, 12834.807477888025, 9450.952745236229, 9731.951340243253, 9920.950395247977, 9953.452560109263, 10038.949805250926, 9797.0, 9914.504274786375, 10930.672079837617, 11393.886061139614, 11399.829002565135], "value": 7565.488725013538, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 129.0, 174.0, 138.0, 115.0, 117.0, 116.0, 118.0, 116.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 110.0, 145.0, 110.0, 111.0, 109.0, 109.0, 131.0, 109.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 184.0, 260.0, 225.0, 186.0, 184.0, 186.0, 184.0, 184.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 62.0, 93.0, 65.0, 61.0, 61.0, 61.0, 77.0, 59.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 88.0, 70.0, 78.0, 95.0, 116.0, 94.0, 69.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 85.0, 86.0, 86.0, 85.0, 89.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 108.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 129.0, 83.0, 83.0, 83.0, 83.0, 102.0, 129.0, 69.0, 131.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 60.0, 60.0, 63.0, 64.0, 64.0, 64.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 70.0, 65.0, 61.0, 59.0, 58.0, 58.0, 57.0, 58.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 86.0, 84.0, 82.0, 84.0, 84.0, 83.0, 87.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 142.0, 136.0, 134.0, 136.0, 153.0, 151.0, 183.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 163.0, 164.0, 165.0, 164.0, 163.0, 167.0, 161.0, 208.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 63.0, 64.0, 64.0, 66.0, 75.0, 78.0, 79.0, 81.0], "value": 70.2, "unit": "score"}]}]} 17:46:37 INFO - Return code: 0 17:46:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:46:37 INFO - # TBPL SUCCESS # 17:46:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:46:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:46:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:46:37 INFO - 2017-05-25 17:46:37.749 screenresolution[1656:9696] starting screenresolution argv=screenresolution get 17:46:37 INFO - 2017-05-25 17:46:37.751 screenresolution[1656:9696] Display 0: 1600x1200x32@60 17:46:37 INFO - 2017-05-25 17:46:37.757 screenresolution[1657:9699] starting screenresolution argv=screenresolution list 17:46:37 INFO - Available Modes on Display 0 17:46:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:46:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:46:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:46:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:46:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:46:37 INFO - Intel Iris: 17:46:37 INFO - Chipset Model: Intel Iris 17:46:37 INFO - Type: GPU 17:46:37 INFO - Bus: Built-In 17:46:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:46:37 INFO - Vendor: Intel (0x8086) 17:46:37 INFO - Device ID: 0x0a2e 17:46:37 INFO - Revision ID: 0x0009 17:46:37 INFO - Displays: 17:46:37 INFO - Display: 17:46:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:46:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:46:37 INFO - Display Serial Number: 1600x1200 60 17:46:37 INFO - Main Display: Yes 17:46:37 INFO - Mirror: Off 17:46:37 INFO - Online: Yes 17:46:37 INFO - Rotation: Supported 17:46:37 INFO - Return code: 0 17:46:37 INFO - Running post-action listener: _resource_record_post_action 17:46:37 INFO - [mozharness: 2017-05-26 00:46:37.992015Z] Finished run-tests step (success) 17:46:37 INFO - Running post-run listener: _resource_record_post_run 17:46:38 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38716416; Write bytes: 510509056; Read time: 248; Write time: 1562 17:46:38 INFO - TinderboxPrint: CPU usage
53.8% 17:46:38 INFO - TinderboxPrint: I/O read bytes / time
38,716,416 / 248 17:46:38 INFO - TinderboxPrint: I/O write bytes / time
510,509,056 / 1,562 17:46:38 INFO - TinderboxPrint: CPU idle
2,215.8 (46.2%) 17:46:38 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 17:46:38 INFO - TinderboxPrint: CPU user
1,621.6 (33.8%) 17:46:38 INFO - TinderboxPrint: Swap in / out
247,468,032 / 0 17:46:38 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208441856; Write bytes: 203839488; Read time: 15920; Write time: 440 17:46:38 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36578304; Write bytes: 296519680; Read time: 226; Write time: 1088 17:46:38 INFO - Running post-run listener: _upload_blobber_files 17:46:38 INFO - Blob upload gear active. 17:46:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:46:38 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 17:46:38 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'] 17:46:38 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 17:46:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:46:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:46:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:46:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:39 INFO - (blobuploader) - INFO - Done attempting. 17:46:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:46:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:46:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:40 INFO - (blobuploader) - INFO - Done attempting. 17:46:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:46:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:46:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:41 INFO - (blobuploader) - INFO - Done attempting. 17:46:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:46:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:46:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:44 INFO - (blobuploader) - INFO - Done attempting. 17:46:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:46:44 INFO - Return code: 0 17:46:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:46:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:46:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4369a94ca2cb4b1e80c79dff39d7a4adb22f9a499890ae9f1c8f4b1284ef3a9ae7e302b6c0af7280e2cd7af307dafc18caf5e3d64fd5d811355134ae1e0a8ff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65ce752660c84daaef534b9e8be3519a84331cd7ffc3cafdbab49a4e1a03d011cb7d7d2b65b7f208341970c3d4c5e885517db88cf228c8035a82ea874055c29e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fb14ca683d65e3e3a6ae75f21baec1be1affdf10aaa56989ad12e3e518892428c974e7588ce803298571bfc851107dc34235cca77c133806408f60cf93b5490", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88dca6d45b1e45b91200fa6cc13ffcc468ee0147852618481d243f23cddd181e3093e3af7fdff3bc1b3b3c1f8b7a904c1e0cdd5a28370fddf01513448bff1619"} 17:46:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:46:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:46:44 INFO - Contents: 17:46:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4369a94ca2cb4b1e80c79dff39d7a4adb22f9a499890ae9f1c8f4b1284ef3a9ae7e302b6c0af7280e2cd7af307dafc18caf5e3d64fd5d811355134ae1e0a8ff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65ce752660c84daaef534b9e8be3519a84331cd7ffc3cafdbab49a4e1a03d011cb7d7d2b65b7f208341970c3d4c5e885517db88cf228c8035a82ea874055c29e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fb14ca683d65e3e3a6ae75f21baec1be1affdf10aaa56989ad12e3e518892428c974e7588ce803298571bfc851107dc34235cca77c133806408f60cf93b5490", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88dca6d45b1e45b91200fa6cc13ffcc468ee0147852618481d243f23cddd181e3093e3af7fdff3bc1b3b3c1f8b7a904c1e0cdd5a28370fddf01513448bff1619"} 17:46:44 INFO - Running post-run listener: copy_logs_to_upload_dir 17:46:44 INFO - Copying logs to upload dir... 17:46:44 INFO - mkdir: /builds/slave/test/build/upload/logs 17:46:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.793427 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 17:46:44.448287) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:46:44.453811) ========= 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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/4369a94ca2cb4b1e80c79dff39d7a4adb22f9a499890ae9f1c8f4b1284ef3a9ae7e302b6c0af7280e2cd7af307dafc18caf5e3d64fd5d811355134ae1e0a8ff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65ce752660c84daaef534b9e8be3519a84331cd7ffc3cafdbab49a4e1a03d011cb7d7d2b65b7f208341970c3d4c5e885517db88cf228c8035a82ea874055c29e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fb14ca683d65e3e3a6ae75f21baec1be1affdf10aaa56989ad12e3e518892428c974e7588ce803298571bfc851107dc34235cca77c133806408f60cf93b5490", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88dca6d45b1e45b91200fa6cc13ffcc468ee0147852618481d243f23cddd181e3093e3af7fdff3bc1b3b3c1f8b7a904c1e0cdd5a28370fddf01513448bff1619"} build_url:https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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/4369a94ca2cb4b1e80c79dff39d7a4adb22f9a499890ae9f1c8f4b1284ef3a9ae7e302b6c0af7280e2cd7af307dafc18caf5e3d64fd5d811355134ae1e0a8ff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65ce752660c84daaef534b9e8be3519a84331cd7ffc3cafdbab49a4e1a03d011cb7d7d2b65b7f208341970c3d4c5e885517db88cf228c8035a82ea874055c29e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1fb14ca683d65e3e3a6ae75f21baec1be1affdf10aaa56989ad12e3e518892428c974e7588ce803298571bfc851107dc34235cca77c133806408f60cf93b5490", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88dca6d45b1e45b91200fa6cc13ffcc468ee0147852618481d243f23cddd181e3093e3af7fdff3bc1b3b3c1f8b7a904c1e0cdd5a28370fddf01513448bff1619"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:46:44.487557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:46:44.487868) ========= 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.yN1T9ywkwF/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.IZHfoCxl7u/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.005310 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:46:44.537263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:46:44.537580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:46:44.537917) ========= ========= Total master_lag: 0.18 =========