builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0009 starttime: 1496767355.26 results: success (0) buildid: 20170606081521 builduid: f359bd6784d54e019d1ff85f25513d48 revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.257633) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.258138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.258427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.283934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.284319) ========= 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.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-06-06 09:42:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2017-06-06 09:42:35 (14.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.442217) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.442543) ========= 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.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.027544 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:35.492790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:42:35.493410) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fe438f1ec216c0269d353df141ea376055f6130c --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 fe438f1ec216c0269d353df141ea376055f6130c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-06-06 09:42:35,582 truncating revision to first 12 chars 2017-06-06 09:42:35,583 Setting DEBUG logging. 2017-06-06 09:42:35,583 attempt 1/10 2017-06-06 09:42:35,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fe438f1ec216?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 09:42:35,887 unpacking tar archive at: mozilla-beta-fe438f1ec216/testing/mozharness/ program finished with exit code 0 elapsedTime=1.576861 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 09:42:37.093870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:37.094314) ========= script_repo_revision: fe438f1ec216c0269d353df141ea376055f6130c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:37.094829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:37.095138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 09:42:37.114291) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-06-06 09:42:37.114678) ========= /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.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:42:37 INFO - MultiFileLogger online at 20170606 09:42:37 in /builds/slave/test 09:42:37 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 09:42:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:42:37 INFO - {'append_to_log': False, 09:42:37 INFO - 'base_work_dir': '/builds/slave/test', 09:42:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:42:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:42:37 INFO - 'branch': 'Mozilla-Beta', 09:42:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:42:37 INFO - 'config_files': ('talos/mac_config.py',), 09:42:37 INFO - 'default_actions': ('clobber', 09:42:37 INFO - 'read-buildbot-config', 09:42:37 INFO - 'download-and-extract', 09:42:37 INFO - 'populate-webroot', 09:42:37 INFO - 'create-virtualenv', 09:42:37 INFO - 'install', 09:42:37 INFO - 'run-tests'), 09:42:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:42:37 INFO - 'download_minidump_stackwalk': True, 09:42:37 INFO - 'download_symbols': 'ondemand', 09:42:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:42:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:42:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:42:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:42:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:42:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:42:37 INFO - 'gecko_profile': False, 09:42:37 INFO - 'gecko_profile_interval': 0, 09:42:37 INFO - 'installer_path': 'installer.exe', 09:42:37 INFO - 'log_level': 'info', 09:42:37 INFO - 'log_name': 'talos', 09:42:37 INFO - 'log_to_console': True, 09:42:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:42:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:42:37 INFO - 'opt_config_files': (), 09:42:37 INFO - 'pip_index': False, 09:42:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:37 INFO - 'cmd': ('bash', 09:42:37 INFO - '-c', 09:42:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:37 INFO - 'enabled': True, 09:42:37 INFO - 'halt_on_failure': False, 09:42:37 INFO - 'name': 'check_screen_resolution'},), 09:42:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:37 INFO - 'cmd': ('bash', 09:42:37 INFO - '-c', 09:42:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:37 INFO - 'enabled': True, 09:42:37 INFO - 'halt_on_failure': False, 09:42:37 INFO - 'name': 'check_screen_resolution'},), 09:42:37 INFO - 'run_cmd_checks_enabled': True, 09:42:37 INFO - 'suite': 'dromaeojs', 09:42:37 INFO - 'system_bits': '32', 09:42:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:42:37 INFO - 'title': 't-yosemite-r7-0009', 09:42:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:42:37 INFO - 'use_talos_json': True, 09:42:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:42:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:42:37 INFO - 'work_dir': 'build'} 09:42:37 INFO - [mozharness: 2017-06-06 16:42:37.316909Z] Running clobber step. 09:42:37 INFO - Running pre-action listener: _resource_record_pre_action 09:42:37 INFO - Running main action method: clobber 09:42:37 INFO - rmtree: /builds/slave/test/build 09:42:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:42:39 INFO - Running post-action listener: _resource_record_post_action 09:42:39 INFO - [mozharness: 2017-06-06 16:42:39.927147Z] Finished clobber step (success) 09:42:39 INFO - [mozharness: 2017-06-06 16:42:39.927387Z] Running read-buildbot-config step. 09:42:39 INFO - Running pre-action listener: _resource_record_pre_action 09:42:39 INFO - Running main action method: read_buildbot_config 09:42:39 INFO - Using buildbot properties: 09:42:39 INFO - { 09:42:39 INFO - "project": "", 09:42:39 INFO - "product": "firefox", 09:42:39 INFO - "script_repo_revision": "production", 09:42:39 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:42:39 INFO - "repository": "", 09:42:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:42:39 INFO - "buildid": "20170606081521", 09:42:39 INFO - "pgo_build": "False", 09:42:39 INFO - "basedir": "/builds/slave/test", 09:42:39 INFO - "buildnumber": 17, 09:42:39 INFO - "slavename": "t-yosemite-r7-0009", 09:42:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:42:39 INFO - "platform": "yosemite_r7", 09:42:39 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:42:39 INFO - "branch": "mozilla-beta", 09:42:39 INFO - "repo_path": "releases/mozilla-beta", 09:42:39 INFO - "revision": "fe438f1ec216c0269d353df141ea376055f6130c", 09:42:39 INFO - "stage_platform": "macosx64", 09:42:39 INFO - "builduid": "f359bd6784d54e019d1ff85f25513d48", 09:42:39 INFO - "slavebuilddir": "test" 09:42:39 INFO - } 09:42:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:42:39 INFO - Running post-action listener: _resource_record_post_action 09:42:39 INFO - [mozharness: 2017-06-06 16:42:39.932968Z] Finished read-buildbot-config step (success) 09:42:39 INFO - [mozharness: 2017-06-06 16:42:39.933195Z] Running download-and-extract step. 09:42:39 INFO - Running pre-action listener: _resource_record_pre_action 09:42:39 INFO - Running main action method: download_and_extract 09:42:39 INFO - mkdir: /builds/slave/test/build/tests 09:42:39 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')]} 09:42:39 INFO - trying https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:42:39 INFO - Downloading https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/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 09:42:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/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 09:42:41 INFO - Downloaded 1287 bytes. 09:42:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:42:41 INFO - Using the following test package requirements: 09:42:41 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:42:41 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:42:41 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:42:41 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:42:41 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:42:41 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:42:41 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:42:41 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:42:41 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:42:41 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:42:41 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:42:41 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:42:41 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:42:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:42:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:42:41 INFO - Fetch https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:42:42 INFO - Expected file size: 51394041 09:42:42 INFO - Obtained file size: 51394041 09:42:58 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 09:42:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:42:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:42:58 INFO - Fetch https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:43:00 INFO - Expected file size: 51394041 09:43:00 INFO - Obtained file size: 51394041 09:43:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:43:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:43:15 INFO - Fetch https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:43:17 INFO - Expected file size: 14404461 09:43:17 INFO - Obtained file size: 14404461 09:43:17 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')]} 09:43:17 INFO - trying https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:43:17 INFO - Downloading https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:43:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/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 09:43:19 INFO - Downloaded 59677540 bytes. 09:43:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:43:19 INFO - mkdir: /builds/slave/test/properties 09:43:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:19 INFO - Contents: 09:43:19 INFO - build_url:https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:43:20 INFO - Running post-action listener: _resource_record_post_action 09:43:20 INFO - Running post-action listener: set_extra_try_arguments 09:43:20 INFO - [mozharness: 2017-06-06 16:43:20.203949Z] Finished download-and-extract step (success) 09:43:20 INFO - [mozharness: 2017-06-06 16:43:20.204101Z] Running populate-webroot step. 09:43:20 INFO - Running pre-action listener: _resource_record_pre_action 09:43:20 INFO - Running main action method: populate_webroot 09:43:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:20 INFO - '--tppagecycles', 09:43:20 INFO - '7'], 09:43:20 INFO - 'tests': ['tsvgm']}}, 09:43:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['damp', 'tps']}, 09:43:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'tests': ['damp', 'tps']}, 09:43:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['dromaeo_dom']}, 09:43:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['basic_compositor_video']}, 09:43:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:43:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['a11yr', 09:43:20 INFO - 'ts_paint', 09:43:20 INFO - 'tpaint', 09:43:20 INFO - 'sessionrestore', 09:43:20 INFO - 'sessionrestore_no_auto_restore', 09:43:20 INFO - 'tabpaint']}, 09:43:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tsvgx', 09:43:20 INFO - 'tsvgr_opacity', 09:43:20 INFO - 'tart', 09:43:20 INFO - 'tscrollx', 09:43:20 INFO - 'cart', 09:43:20 INFO - 'tsvg_static']}, 09:43:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:20 INFO - 'tsvgr_opacity', 09:43:20 INFO - 'tart', 09:43:20 INFO - 'tscrollx', 09:43:20 INFO - 'cart', 09:43:20 INFO - 'tsvg_static']}, 09:43:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s'], 09:43:20 INFO - 'tests': ['tp5o']}, 09:43:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:43:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--disable-e10s', 09:43:20 INFO - '--xperf_path', 09:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:20 INFO - 'tests': ['tp5n']}, 09:43:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:43:20 INFO - 'talos_options': ['--xperf_path', 09:43:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:20 INFO - 'tests': ['tp5n']}}, 09:43:20 INFO - 'talos.zip': {'path': '', 09:43:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:20 INFO - Running post-action listener: _resource_record_post_action 09:43:20 INFO - [mozharness: 2017-06-06 16:43:20.222558Z] Finished populate-webroot step (success) 09:43:20 INFO - [mozharness: 2017-06-06 16:43:20.222690Z] Running create-virtualenv step. 09:43:20 INFO - Running pre-action listener: _resource_record_pre_action 09:43:20 INFO - Running main action method: create_virtualenv 09:43:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:20 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 09:43:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:43:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:20 INFO - Using real prefix '/tools/python27' 09:43:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:21 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:23 INFO - Installing pip.................done. 09:43:23 INFO - Return code: 0 09:43:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:43:24 INFO - Reading from file tmpfile_stdout 09:43:24 INFO - Output received: 09:43:24 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) 09:43:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43: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', 'pip>=1.5'] in /builds/slave/test/build 09:43: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 pip>=1.5 09:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:24 INFO - 'HOME': '/Users/cltbld', 09:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:24 INFO - 'LOGNAME': 'cltbld', 09:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:24 INFO - 'MOZ_NO_REMOTE': '1', 09:43:24 INFO - 'NO_EM_RESTART': '1', 09:43:24 INFO - 'PAGER': '/bin/cat', 09:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:24 INFO - 'PWD': '/builds/slave/test', 09:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:24 INFO - 'SHELL': '/bin/bash', 09:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:24 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:24 INFO - 'USER': 'cltbld', 09:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:24 INFO - 'XPC_FLAGS': '0x0', 09:43:24 INFO - 'XPC_SERVICE_NAME': '0', 09:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:24 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 09:43:24 INFO - Cleaning up... 09:43:24 INFO - Return code: 0 09:43:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:25 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')]} 09:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:25 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 09:43:25 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 09:43:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:25 INFO - 'HOME': '/Users/cltbld', 09:43:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:25 INFO - 'LOGNAME': 'cltbld', 09:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:25 INFO - 'MOZ_NO_REMOTE': '1', 09:43:25 INFO - 'NO_EM_RESTART': '1', 09:43:25 INFO - 'PAGER': '/bin/cat', 09:43:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:25 INFO - 'PWD': '/builds/slave/test', 09:43:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:25 INFO - 'SHELL': '/bin/bash', 09:43:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:25 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:25 INFO - 'USER': 'cltbld', 09:43:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:25 INFO - 'XPC_FLAGS': '0x0', 09:43:25 INFO - 'XPC_SERVICE_NAME': '0', 09:43:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:25 INFO - Downloading/unpacking psutil>=3.1.1 09:43: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 09:43: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 09:43: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 09:43: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 09:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:28 INFO - Installing collected packages: psutil 09:43:28 INFO - Running setup.py install for psutil 09:43:28 INFO - building 'psutil._psutil_osx' extension 09:43:28 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 09:43:28 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 09:43:28 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 09:43:29 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 09:43:29 INFO - building 'psutil._psutil_posix' extension 09:43:29 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 09:43:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:29 INFO - ^ 09:43:29 INFO - 1 warning generated. 09:43:29 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 09:43:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:29 INFO - Successfully installed psutil 09:43:29 INFO - Cleaning up... 09:43:29 INFO - Return code: 0 09:43:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:43:29 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')]} 09:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:29 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')]} 09:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:29 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:29 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 09:43:29 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 09:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:29 INFO - 'HOME': '/Users/cltbld', 09:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:29 INFO - 'LOGNAME': 'cltbld', 09:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:29 INFO - 'MOZ_NO_REMOTE': '1', 09:43:29 INFO - 'NO_EM_RESTART': '1', 09:43:29 INFO - 'PAGER': '/bin/cat', 09:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:29 INFO - 'PWD': '/builds/slave/test', 09:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:29 INFO - 'SHELL': '/bin/bash', 09:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:29 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:29 INFO - 'USER': 'cltbld', 09:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:29 INFO - 'XPC_FLAGS': '0x0', 09:43:29 INFO - 'XPC_SERVICE_NAME': '0', 09:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:43:29 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 09:43:29 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 09:43:29 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 09:43:29 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 09:43:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:43:31 INFO - Installing collected packages: mozsystemmonitor 09:43:31 INFO - Running setup.py install for mozsystemmonitor 09:43:31 INFO - Successfully installed mozsystemmonitor 09:43:31 INFO - Cleaning up... 09:43:31 INFO - Return code: 0 09:43:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:43:31 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')]} 09:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:31 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')]} 09:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:31 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:31 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 09:43:31 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 09:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:31 INFO - 'HOME': '/Users/cltbld', 09:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:31 INFO - 'LOGNAME': 'cltbld', 09:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:31 INFO - 'MOZ_NO_REMOTE': '1', 09:43:31 INFO - 'NO_EM_RESTART': '1', 09:43:31 INFO - 'PAGER': '/bin/cat', 09:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:31 INFO - 'PWD': '/builds/slave/test', 09:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:31 INFO - 'SHELL': '/bin/bash', 09:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:31 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:31 INFO - 'USER': 'cltbld', 09:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:31 INFO - 'XPC_FLAGS': '0x0', 09:43:31 INFO - 'XPC_SERVICE_NAME': '0', 09:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:43:31 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 09:43:31 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 09:43:31 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 09:43:31 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 09:43:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:33 INFO - warning: no files found matching '*.txt' 09:43:33 INFO - no previously-included directories found matching 'build' 09:43:33 INFO - no previously-included directories found matching 'dist' 09:43:33 INFO - no previously-included directories found matching '.git*' 09:43:33 INFO - Installing collected packages: jsonschema, functools32 09:43:33 INFO - Running setup.py install for functools32 09:43:33 INFO - warning: no files found matching '*.txt' 09:43:33 INFO - no previously-included directories found matching 'build' 09:43:33 INFO - no previously-included directories found matching 'dist' 09:43:33 INFO - no previously-included directories found matching '.git*' 09:43:33 INFO - Successfully installed jsonschema functools32 09:43:33 INFO - Cleaning up... 09:43:33 INFO - Return code: 0 09:43:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:33 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:33 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 09:43:33 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 09:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:33 INFO - 'HOME': '/Users/cltbld', 09:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:33 INFO - 'LOGNAME': 'cltbld', 09:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:33 INFO - 'MOZ_NO_REMOTE': '1', 09:43:33 INFO - 'NO_EM_RESTART': '1', 09:43:33 INFO - 'PAGER': '/bin/cat', 09:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:33 INFO - 'PWD': '/builds/slave/test', 09:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:33 INFO - 'SHELL': '/bin/bash', 09:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:33 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:33 INFO - 'USER': 'cltbld', 09:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:33 INFO - 'XPC_FLAGS': '0x0', 09:43:33 INFO - 'XPC_SERVICE_NAME': '0', 09:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:43:34 INFO - Cleaning up... 09:43:34 INFO - Return code: 0 09:43:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:43:34 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')]} 09:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:34 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')]} 09:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:34 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:34 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 09:43:34 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 09:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:34 INFO - 'HOME': '/Users/cltbld', 09:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:34 INFO - 'LOGNAME': 'cltbld', 09:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:34 INFO - 'MOZ_NO_REMOTE': '1', 09:43:34 INFO - 'NO_EM_RESTART': '1', 09:43:34 INFO - 'PAGER': '/bin/cat', 09:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:34 INFO - 'PWD': '/builds/slave/test', 09:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:34 INFO - 'SHELL': '/bin/bash', 09:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:34 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:34 INFO - 'USER': 'cltbld', 09:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:34 INFO - 'XPC_FLAGS': '0x0', 09:43:34 INFO - 'XPC_SERVICE_NAME': '0', 09:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:43:34 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 09:43:34 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 09:43:34 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 09:43:34 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 09:43:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:43:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43: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 09:43: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 09:43: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 09:43: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 09:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:43:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:36 INFO - Downloading docopt-0.6.1.tar.gz 09:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:43:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:36 INFO - Running setup.py install for blobuploader 09:43:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:43:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:43:36 INFO - Running setup.py install for requests 09:43:37 INFO - Running setup.py install for docopt 09:43:37 INFO - Successfully installed blobuploader requests docopt 09:43:37 INFO - Cleaning up... 09:43:37 INFO - Return code: 0 09:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43: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')]} 09:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:37 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:37 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 09:43:37 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 09:43:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:37 INFO - 'HOME': '/Users/cltbld', 09:43:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:37 INFO - 'LOGNAME': 'cltbld', 09:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:37 INFO - 'MOZ_NO_REMOTE': '1', 09:43:37 INFO - 'NO_EM_RESTART': '1', 09:43:37 INFO - 'PAGER': '/bin/cat', 09:43:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:37 INFO - 'PWD': '/builds/slave/test', 09:43:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:37 INFO - 'SHELL': '/bin/bash', 09:43:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:37 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:37 INFO - 'USER': 'cltbld', 09:43:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:37 INFO - 'XPC_FLAGS': '0x0', 09:43:37 INFO - 'XPC_SERVICE_NAME': '0', 09:43:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-DhufoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:37 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-xAHuO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-omRt75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-aBPS8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-eByfx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-nFYr7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-vyO11i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-wqbvxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KNewbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2lmpRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:38 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-q8bfKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-GlVH6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ma6XvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-5Mp4GS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-xClZCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Ej6inz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:39 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-SQ8ZGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:43:39 INFO - Running setup.py install for manifestparser 09:43:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:43:39 INFO - Running setup.py install for mozcrash 09:43:39 INFO - Running setup.py install for mozdebug 09:43:40 INFO - Running setup.py install for mozdevice 09:43:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozfile 09:43:40 INFO - Running setup.py install for mozhttpd 09:43:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozinfo 09:43:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozInstall 09:43:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:43:40 INFO - Running setup.py install for mozleak 09:43:40 INFO - Running setup.py install for mozlog 09:43:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for moznetwork 09:43:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozprocess 09:43:41 INFO - Running setup.py install for mozprofile 09:43:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozrunner 09:43:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:43:41 INFO - Running setup.py install for mozscreenshot 09:43:41 INFO - Running setup.py install for moztest 09:43:42 INFO - Running setup.py install for mozversion 09:43:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:43:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:43:42 INFO - Cleaning up... 09:43:42 INFO - Return code: 0 09:43:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:42 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')]} 09:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:42 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')]} 09:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:42 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:42 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 09:43:42 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 09:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:42 INFO - 'HOME': '/Users/cltbld', 09:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:42 INFO - 'LOGNAME': 'cltbld', 09:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:42 INFO - 'MOZ_NO_REMOTE': '1', 09:43:42 INFO - 'NO_EM_RESTART': '1', 09:43:42 INFO - 'PAGER': '/bin/cat', 09:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:42 INFO - 'PWD': '/builds/slave/test', 09:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:42 INFO - 'SHELL': '/bin/bash', 09:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:42 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:42 INFO - 'USER': 'cltbld', 09:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:42 INFO - 'XPC_FLAGS': '0x0', 09:43:42 INFO - 'XPC_SERVICE_NAME': '0', 09:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:42 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-vZTCzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:42 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KQTde_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:42 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-aSqbQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:42 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-waZSi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:42 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)) 09:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:42 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FPhyPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-7b67mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-t9o7M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-HY2vpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-_NYlAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-eopO_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-QJklrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Xs2OcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FbTkMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:43 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)) 09:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:43 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Adqrxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:44 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)) 09:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-7jSs8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:44 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)) 09:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-zYPnvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:44 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)) 09:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:44 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-6QyiPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 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)) 09:43:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:45 INFO - Downloading blessings-1.6.tar.gz 09:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:43:45 INFO - Installing collected packages: blessings 09:43:45 INFO - Running setup.py install for blessings 09:43:46 INFO - Successfully installed blessings 09:43:46 INFO - Cleaning up... 09:43:46 INFO - Return code: 0 09:43:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:43:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:46 INFO - Reading from file tmpfile_stdout 09:43:46 INFO - Current package versions: 09:43:46 INFO - blessings == 1.6 09:43:46 INFO - blobuploader == 1.2.4 09:43:46 INFO - docopt == 0.6.1 09:43:46 INFO - functools32 == 3.2.3-2 09:43:46 INFO - jsonschema == 2.5.1 09:43:46 INFO - manifestparser == 1.1 09:43:46 INFO - mozInstall == 1.12 09:43:46 INFO - mozcrash == 1.0 09:43:46 INFO - mozdebug == 0.1 09:43:46 INFO - mozdevice == 0.49 09:43:46 INFO - mozfile == 1.2 09:43:46 INFO - mozhttpd == 0.7 09:43:46 INFO - mozinfo == 0.9 09:43:46 INFO - mozleak == 0.1 09:43:46 INFO - mozlog == 3.4 09:43:46 INFO - moznetwork == 0.27 09:43:46 INFO - mozprocess == 0.25 09:43:46 INFO - mozprofile == 0.28 09:43:46 INFO - mozrunner == 6.13 09:43:46 INFO - mozscreenshot == 0.1 09:43:46 INFO - mozsystemmonitor == 0.3 09:43:46 INFO - moztest == 0.8 09:43:46 INFO - mozversion == 1.4 09:43:46 INFO - psutil == 3.1.1 09:43:46 INFO - requests == 1.2.3 09:43:46 INFO - wsgiref == 0.1.2 09:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:46 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')]} 09:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:46 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')]} 09:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:46 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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:46 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 09:43:46 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 09:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:46 INFO - 'HOME': '/Users/cltbld', 09:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:46 INFO - 'LOGNAME': 'cltbld', 09:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:46 INFO - 'MOZ_NO_REMOTE': '1', 09:43:46 INFO - 'NO_EM_RESTART': '1', 09:43:46 INFO - 'PAGER': '/bin/cat', 09:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:46 INFO - 'PWD': '/builds/slave/test', 09:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:46 INFO - 'SHELL': '/bin/bash', 09:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:46 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:46 INFO - 'USER': 'cltbld', 09:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:46 INFO - 'XPC_FLAGS': '0x0', 09:43:46 INFO - 'XPC_SERVICE_NAME': '0', 09:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 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)) 09:43:46 INFO - Cleaning up... 09:43:46 INFO - Return code: 0 09:43:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:43:46 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')]} 09:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:46 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')]} 09:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x107b92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f05a3e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, '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.XpHEbJKYSo/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.wVp33ueVoM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 09:43:46 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 09:43:46 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 09:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:46 INFO - 'HOME': '/Users/cltbld', 09:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:46 INFO - 'LOGNAME': 'cltbld', 09:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:46 INFO - 'MOZ_NO_REMOTE': '1', 09:43:46 INFO - 'NO_EM_RESTART': '1', 09:43:46 INFO - 'PAGER': '/bin/cat', 09:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:46 INFO - 'PWD': '/builds/slave/test', 09:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:46 INFO - 'SHELL': '/bin/bash', 09:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:43:46 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:46 INFO - 'USER': 'cltbld', 09:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:46 INFO - 'XPC_FLAGS': '0x0', 09:43:46 INFO - 'XPC_SERVICE_NAME': '0', 09:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:43:46 INFO - Cleaning up... 09:43:46 INFO - Return code: 0 09:43:46 INFO - Running post-action listener: _resource_record_post_action 09:43:46 INFO - Running post-action listener: _start_resource_monitoring 09:43:46 INFO - Starting resource monitoring. 09:43:46 INFO - [mozharness: 2017-06-06 16:43:46.989978Z] Finished create-virtualenv step (success) 09:43:46 INFO - [mozharness: 2017-06-06 16:43:46.990947Z] Running install step. 09:43:46 INFO - Running pre-action listener: _resource_record_pre_action 09:43:46 INFO - Running main action method: install 09:43:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:47 INFO - Reading from file tmpfile_stdout 09:43:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:43:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:43:47 INFO - Reading from file tmpfile_stdout 09:43:47 INFO - Output received: 09:43:47 INFO - Usage: mozinstall [options] installer 09:43:47 INFO - Options: 09:43:47 INFO - -h, --help show this help message and exit 09:43:47 INFO - -d DEST, --destination=DEST 09:43:47 INFO - Directory to install application into. [default: 09:43:47 INFO - "/builds/slave/test"] 09:43:47 INFO - --app=APP Application being installed. [default: firefox] 09:43:47 INFO - mkdir: /builds/slave/test/build/application 09:43:47 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'] 09:43:47 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 09:44:02 INFO - Reading from file tmpfile_stdout 09:44:02 INFO - Output received: 09:44:02 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:44:02 INFO - Running post-action listener: _resource_record_post_action 09:44:02 INFO - [mozharness: 2017-06-06 16:44:02.328797Z] Finished install step (success) 09:44:02 INFO - [mozharness: 2017-06-06 16:44:02.328933Z] Running run-tests step. 09:44:02 INFO - Running pre-action listener: _resource_record_pre_action 09:44:02 INFO - Running main action method: run_tests 09:44:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:02 INFO - 2017-06-06 09:44:02.343 screenresolution[1630:7343] starting screenresolution argv=screenresolution get 09:44:02 INFO - 2017-06-06 09:44:02.346 screenresolution[1630:7343] Display 0: 1600x1200x32@60 09:44:02 INFO - 2017-06-06 09:44:02.352 screenresolution[1631:7345] starting screenresolution argv=screenresolution list 09:44:02 INFO - Available Modes on Display 0 09:44:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:02 INFO - Intel Iris: 09:44:02 INFO - Chipset Model: Intel Iris 09:44:02 INFO - Type: GPU 09:44:02 INFO - Bus: Built-In 09:44:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:02 INFO - Vendor: Intel (0x8086) 09:44:02 INFO - Device ID: 0x0a2e 09:44:02 INFO - Revision ID: 0x0009 09:44:02 INFO - Displays: 09:44:02 INFO - Display: 09:44:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:02 INFO - Display Serial Number: 1600x1200 60 09:44:02 INFO - Main Display: Yes 09:44:02 INFO - Mirror: Off 09:44:02 INFO - Online: Yes 09:44:02 INFO - Rotation: Supported 09:44:02 INFO - Return code: 0 09:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:02 INFO - Python 2.7.3 09:44:02 INFO - Return code: 0 09:44:02 INFO - grabbing minidump binary from tooltool 09:44:02 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')]} 09:44:02 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 0x107970d30>, '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 0x107897db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107965be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:02 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 09:44:02 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 09:44:02 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 09:44:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:02 INFO - Return code: 0 09:44:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:02 INFO - ENV: RUST_BACKTRACE is now 1 09:44:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 09:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --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 09:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpHEbJKYSo/Render', 09:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:02 INFO - 'HOME': '/Users/cltbld', 09:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:02 INFO - 'LOGNAME': 'cltbld', 09:44:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:02 INFO - 'MOZ_NO_REMOTE': '1', 09:44:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:02 INFO - 'NO_EM_RESTART': '1', 09:44:02 INFO - 'PAGER': '/bin/cat', 09:44:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:02 INFO - 'PWD': '/builds/slave/test', 09:44:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:02 INFO - 'RUST_BACKTRACE': '1', 09:44:02 INFO - 'SHELL': '/bin/bash', 09:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wVp33ueVoM/Listeners', 09:44:02 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 09:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:02 INFO - 'USER': 'cltbld', 09:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:02 INFO - 'XPC_FLAGS': '0x0', 09:44:02 INFO - 'XPC_SERVICE_NAME': '0', 09:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 09:44:02 INFO - mozversion application_buildid: 20170606081521 09:44:02 INFO - mozversion application_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:44:02 INFO - mozversion application_display_name: Firefox 09:44:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:02 INFO - mozversion application_name: Firefox 09:44:02 INFO - mozversion application_remotingname: firefox 09:44:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:02 INFO - mozversion application_vendor: Mozilla 09:44:02 INFO - mozversion application_version: 54.0 09:44:02 INFO - mozversion platform_buildid: 20170606081521 09:44:02 INFO - mozversion platform_changeset: fe438f1ec216c0269d353df141ea376055f6130c 09:44:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:02 INFO - mozversion platform_version: 54.0 09:44:02 INFO - using testdate: 1496767442 09:44:02 INFO - actual date: 1496767442 09:44:02 INFO - starting webserver on 'localhost:49223' 09:44:02 INFO - SUITE-START | Running 2 tests 09:44:02 INFO - TEST-START | dromaeo_css 09:44:02 INFO - operating with platform_type : mac_ 09:44:02 INFO - Initialising browser for dromaeo_css test... 09:44:02 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp0GKd4s/profile http://localhost:49223/getInfo.html) 09:44:05 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 09:44:05 INFO - PROCESS | 1641 | colorDepth:24 09:44:05 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 09:44:05 INFO - PROCESS | 1641 | __metrics 09:44:05 INFO - PROCESS | 1641 | 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" 09:44:05 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:06 INFO - TEST-INFO | 1641: exit 0 09:44:06 INFO - Browser initialized. 09:44:06 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:06 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp0GKd4s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:18 INFO - PROCESS | 1644 | RSS: Main: 165343232 09:44:18 INFO - PROCESS | 1644 | 09:47:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 09:47:23 INFO - PROCESS | 1644 | RSS: Main: 299454464 09:47:23 INFO - PROCESS | 1644 | 09:50:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 09:50:27 INFO - PROCESS | 1644 | RSS: Main: 303775744 09:50:27 INFO - PROCESS | 1644 | 09:53:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 09:53:30 INFO - PROCESS | 1644 | RSS: Main: 281350144 09:53:30 INFO - PROCESS | 1644 | 09:56:32 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 09:56:33 INFO - PROCESS | 1644 | RSS: Main: 268066816 09:56:33 INFO - PROCESS | 1644 | 09:59:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 09:59:37 INFO - PROCESS | 1644 | RSS: Main: 271654912 09:59:37 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 10:02:40 INFO - PROCESS | 1644 | RSS: Main: 367054848 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | __start_tp_report 10:02:40 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 10:02:40 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 10:02:40 INFO - PROCESS | 1644 | |i|pagename|runs| 10:02:40 INFO - PROCESS | 1644 | |0;dojo.html;3560.821958902055;3588.8923332300033;3595.8381872815735;3619.5656521217443;3636.7272454565914;5090.236464530313;6154.9076763848525;6168.352323006087;6170.598911070783;6184.783532576361;6134.9079763803575;6188.75244990201;6192.628442293466;6210.410011048954;6222.284437289713;5487.314085739273;5503.091989821674;5532.861678458051;5581.49766521013;5629.6340737852115;5428.050091234038;5429.321334833146;5635;5645.266115405012;5690.60165788395;5466.398696143421;5538.0585300499015;5570.777168913239;5576.498115169634;5598.664080155183;5569.443055694439;5575.804846830361;5594.496495315441;5594.524465420444;5607.046802043662;5338.733063346817;5485.6434331768305;5528.087865502187;5528.585356098309;5547.639403438763;6118.969405152946;6138.294096178952;6144;6146.293176284695;6173.753049878;6517.706703198368;6520.18497687789;6549.181352330958;6552.410283074499;6556.27880933097;5822.359540450547;6577.14497115372;6597.47220222381;6603.93396066038;6608.603483790988;6008.098785182214;6122.357152499013;6146.016637338004;6164.106204600397;6304.243490781089;6483.157189565326;6526.379993900572;6532.902006469907;6555.344465553407;6580.934190658032;6376.362363763679;6378.744850206032;6388.425041746173;6401.4238718514625;6403.3276505966205;6516.837079073061;6518.73925042993;6530.444912182423;6534.117894084237;6538.738450462023;5412.972935135299;6258.249010118724;6332.8416789579705;6429.7428102875365;6439.774607888701;6264.279607845065;6385.201849768779;6444;6480.060391243243;6494.935050649433;5266.657667251617;5304.522592966651;5321.361436627552;5324.760385782649;5348.545373643284;4709.411323584552;4751.382320298339;4760.45254795696;4770.928436073462;4787.42550893895;5999.6400215986305;6064.211652485149;6083.239595050619;6151.446369826737;6172.351903050205;5635.492805647511;6502.057201705727;6553.246376666744;6558.475321974231;6595.70319335631;6250;6254.655993920378;6263.4362907338555;6269.46709529686;6281.183446151971;5568.16477528374;5656.85857853557;5667.093265077568;5727.312722473246;5752.798652047159;6791.72833086671;6852.5203235774015;6871.347222013901;6871.931280687129;6879.380855723009;6542.149520562297;6556.704948277244;6558.8360290993105;6576.309487503743;6586.934130658632;5949.19685842423;6523.445507131947;6601.471882249312;6793.218779840382;6864.965675171592;6383.138276332821;6435.935640643721;6472.676366181766;6496.610203387811;6531.444827189742;6266.467350275278;6301.275353334425;6590.275069742421;6594.406503414524;6597.439217666552;4929.457759646508;6180.381961803963;6375.713092910737;6423.614583125028;6442.226932768098;6304.401081897213;6442.35576442352;6449.161608990801;6455.580387274812;6456.289808121197;6257;6294.339094395205;6297.874042519215;6328.272248691459;6351.428371446592;6377.394147555975;6380.808575742735;6380.9042864358;6383.9361606383345;6392.328805475544;6326.8734625306315;6349.015902535114;6359.87280254383;6377.681115944091;6378.968105159444 10:02:40 INFO - PROCESS | 1644 | |1;ext.html;775.4804281131642;799.1329407592763;817.9468334558253;894.9015608283091;900.8873890763655;28783.856080719594;41387.58612413875;41971.79014104929;43138.137237255236;43592.692219233395;42803.571964280345;43064.70805875821;43065.78467107668;43113;43433.34849977245;9586.328956973031;9765;9773.26700497462;9775.511224438786;9781.951090244538;7882.132965373804;8072.838543229132;8108.351331893435;8122.350211983056;8128.715494957678;11779.646610601696;11956.641300760992;11957.103217258697;11963.102767292445;12198.268103913793;11834.408279585987;11950.342731149827;11999;12011.339376334297;12031.63905082849;11577.421128943519;11645.767084658259;11655.533778648844;11663.94168029163;11674.41627918609;40752.38871416931;40915.181696366206;40968;41006.79496602498;41117.97205069867;3983.98008009961;4057.3102572562734;4058.066644671742;4066.2477441673386;4110.013596736791;28450.430991380264;29208.561871571943;30693.693063069306;30921.690783092105;31281.217969550944;3267.918302042468;3268.7711860169798;3291.325278317939;3378.560787097686;3384.4923261510726;346952;351374.2431287853;351720;351890;352378.2381088104;54095;54166.916661666546;54240;54339.72830135824;54514;46496.535034649896;56272.71863640656;56291.87416251734;56335.87328253412;56411;15536.611584710472;15661.60845978837;15683;15696.215189240493;15702.921485392728;13386.933065334611;13403.865961340456;13410.865891341156;13423.932880335731;13432.19406835593;8758.518281494451;10645.467726613639;11000.449977501094;11802.822957655642;12231.571894983634;5752.9712351437975;5808.767649294065;5818.21454103699;5828.154917537018;5831.650100993954;977.9608815647294;1111.8776934537195;1166.1487114406532;1168.7019809948408;1209.806430971058;27127.72872271252;27668.723312766615;27700.307492312855;27743.722562774517;27753.306167345574;1241.509603706549;1267.2649863079391;1270.2188154285234;1315.4409376015155;1325.1452812935795;13687;13731.313434328244;14186.290685465892;14292.826701716669;14412.711745765235;463505;464547;464990;465606;466997;57893.13160302599;58008.12987805186;58062;58081;58112.54718631983;1379.9448022079205;1446.3419144289323;1509.6603264265452;1525.42034027069;1528.9388424462898;25698.357541060876;26464.735352646996;27114.593281100406;27166.320841979108;27166.72833271721;43164.784176078916;43203.78398107989;43357.56642433661;43430.56569434392;43472.56527434685;409735;411630.94184528885;412135;412161;412488;33824.49263261004;34600;34679.826600866836;34745.826270868485;34828.303433930676;44465.55534444614;45430.09139817119;45644.54355456403;45919;46065.84835379142;25549.61675574905;27207.72792272131;28380.71619283781;29740.85129574338;30228.94198703035;25848.224553263433;30525.389492209586;30644.387112257184;31550.684493154775;32204.355912882078;36799.44800827936;36990.815045924595;37679.62320376871;37718.62281377151;38107.42838857475;30993;31098.689013110485;32067.35865282728;32141.357172855944;32280.67719322871;23660;24466.266012019667;24583.262502124966;25108.874455627603;25169.87415062913 10:02:40 INFO - PROCESS | 1644 | |2;jquery.html;398.05351829553473;408.62610711199255;418.6462439238844;435.55138207646127;444.8887778055486;14986.475473358421;18704.532386690356;21909.561808763814;23262;23524.411889702766;23561.17535886244;23614.645780313265;24270.27189184323;24427.389315267126;24467.877660611673;5706.828795136153;6659.234188068373;6677.732890684367;7206.279372062804;7207.49547531673;6690.7323707051655;6950.505641287123;7016.947457881308;7174.2108368079735;7176.354128128467;6721.697523611425;6726.293739157391;6732.663366831639;6732.730690772363;6734.8989765153565;6751.088603038601;6814.4889133314955;7023.086998690189;7121.575684862996;7127.536710113826;6824.454043676494;6869.828254293633;6953.304669533006;6958.443324534025;6965.303469652994;28566.428671426664;28613.85693071542;28812.42375152506;28825.423491530262;28958.986435474657;3024.894128705485;3261.3314270574474;3477.5861811707073;3593.2633810068874;3608.9278214435562;15789.21053947298;17174.91412542942;17957.37149199785;18842.77521961068;19357.806421935882;2066.1528773203063;2187.671849222613;2199.571083638691;2203.0967303405528;2256.1539422716482;144941;146827;156917.2154139222;162927;163022.184889076;18739.906300468683;24669;25546.872265638554;25846.095386661378;25891.74108258931;45451.31823022657;46012.76993615078;46659;46706.83232919204;46710;10433.843492347622;10809.513571889283;10970.890291096986;11028.724281893017;11206.719832004264;6849.315068493111;8965.417247878997;9124.95437522808;9321.161095501302;9382.24942004638;7686.942659293623;8585.626299792128;8799.208071273617;9299.628014879328;9885.25860560461;3514.697872893259;3739.775613463201;3812.3519001769764;3981.880543583697;4023.5372932112596;1012.8632634594383;1054.18300816866;1056.2183983852049;1090.5038207615517;1104.4091411095026;22648.980795863896;22997.54004919926;23014.769852301597;23320.53358932812;23344;1099.301943266032;1111.2887751838668;1117.7205698575356;1130.4347826086957;1135.2961164078324;11259.94370028161;11550.249233799776;11676.88323116758;11695.473703683354;12023.579174728842;680540;708317;797380;806023;819809;155512;158266.20866895592;168836.15581922012;169165;170367.1481642584;1134.461130962786;1297.9610611681665;1305.2494815480948;1345.643404497809;1357.050064954516;19332.516687082374;20686.275980340615;21808.563828723018;21924.342269731933;22086.558268834855;55330;61699.07451388143;63663.6816815913;67110.32224194435;67544.66227668831;72024.63987680238;92471;92645.5367723184;93008.53495732749;94206.52896735747;9593.376430531993;11930.940345298219;12028.218165819193;12072.879271207175;12086.758264834478;11029.007389334998;13249.80125298102;13345.799813003006;13580.728385432434;13817.516386926409;11210.159238057015;11357.409962605077;11417.828732569184;11541.884581154416;11788.115891308014;9929.404235745878;10270.691879243635;10437.68686939393;10546.20903432261;10844.891551084704;11584.362860042778;11783.998360139483;11798.705032374259;11881.524739010343;11910.583129590423;10021.549030293509;11912.344821034927;12147;12275;12307.753844922872;11505.424728763695;11797;11846.229995050295;11894;11920.225185362924 10:02:40 INFO - PROCESS | 1644 | |3;mootools.html;1589.2927647196996;1607.236562632749;1619.522240938923;1636.6153953820854;1643.391944980357;10894.891051089488;11570.999999999989;11571.479283432245;11573.942130289337;11580.884191158097;10879;11141.498632561534;11224.326540407581;11405.828912566318;11413.885861141385;2133;2368.910301261422;2449.9955018442442;2494.5135698538793;2495.4759500504824;1825.698759704647;2275;2357.6325731075935;2506.5613517634433;2512.8869200886006;2390;2445.8287919845616;2445.902163913441;2480.8635525046116;2495;2379.202967006055;2440.1093600835657;2448.485817978227;2450.6079027355536;2462.495188486356;2419.2379400488967;2438.6463962725306;2450.154696629678;2451.472933319345;2455.1529722245796;10972.780544389068;11201;11212.775744485065;11227.775444491146;11232.663020109328;5965.612235204698;6138.907916381258;6141.263048434172;6150.938490615082;6154.26148862135;3631.346357655621;3693.464447655102;3722.9255414891823;3784.413415920509;3786;1211.3882489342914;1226.7669142862828;1252.8308678328492;1278.5972418688177;1285.4729560880087;13918.234497102656;14236.359363828653;14554.19951902645;15826.76259856103;15983.680326393407;4686.320483529869;4831.4685384607665;4844.128056949712;4881.121398148367;4901.9019619608125;10319.587216511405;11856.644300670994;11859.703507412383;11867.406629668481;11879.346635935106;5145.845624631267;5202.115640341151;5202.531772140525;5203.661761985459;5210;5332.866678333073;5389.299391079135;5528.834134975958;5559.27729395176;5597.944020559742;3959.1092004298803;3967.8809635710977;4016.6184212499766;4060.4315395844606;4062.207869465426;1057.582255009267;1058.1481907064733;1059.195011791029;1071.844582535528;1074.3231763988638;914.9954250228707;927.3415874729018;937.779621788879;938.3712912348743;988.0958922585897;6382;6464.838379040468;6508.967455162789;6602.075709400688;6734;1017.954192061359;1036.8185567525652;1040.683535327811;1071.839224116373;1074.3339129739454;4107.322291821875;4217.957820421818;4313.870583882489;4383.9342409863875;4441.511433742286;13652.249126297951;14261.144331340152;14267.85732142686;14276;14301.070430421989;13715.862841371458;13917.23455209953;14225.502107426191;14235.28823558818;14244;10413.5834566618;10458.581656733797;10465.26743127989;10502;10622.20333475008;6540.607563546202;6562.639054852029;6622;6711.295313991961;6727.831804204934;12270.87729122733;12680.23918564889;12680.302583358003;12729;12757.808632870328;10034.749131271485;12653.367331633566;12860.807087893501;12994.740105198032;13218.272994985367;8352.164783521599;8608.440451370641;8706.390552661507;8714;8721.084286150117;10296.485175741333;10320.12278956267;10326.122279606318;10444.895551044696;10481.161507079567;9031.787940646424;9053.321000924825;9059.275257979478;9142.542872856464;9296.814063718553;8657.350698697748;8758.430702004349;8782.516961567175;8854.424462409923;8872.689455869013;6959.478039147186;7867.7246296381645;7968.402369822171;8071.748878923776;8090.433669643068;5886.264216972879;5895.174675545512;5908.881822363443;6058.182145410313;6109.17526133984;6015.909761353671;6040.783766266107;6080.3615620361;6129.111278864629;6252.718627661857 10:02:40 INFO - PROCESS | 1644 | |4;prototype.html;388.44258489068187;395.691360738624;396.0810918668689;397.86806535406765;399.89202915212894;5221.373435187773;5277.551408130309;5296.073187192245;5343.091674415349;5398.2982212312345;4397;4420.823167073313;4794.352762377078;5023.698578085317;5189.325387699603;3836.3286424875673;3875.9728671901967;3882.069771390333;4043.555208927016;4069.186162767451;3816.4466152407886;4048.5708574855;4083.305838007531;4096.938545921814;4137.358709400033;4078.775667338295;4101.466809314785;4105.480972040335;4146.688998325122;4203;3496.3253111369822;3829.272438236754;3831.409964864578;4285.46431696038;4288.185244803477;3545;3607.963920360789;3978.5822488638855;3982.4424580558743;4013.7391069580385;4543.8182472701055;4817.8313759018265;5036.8415264489;5073.822416215452;5080.602834220619;4859.857933385651;4938.555530002282;4978.551930326252;5014.674046186986;5029.321041659366;4669.7665116744365;5434.2935418395755;5438.646487978308;5438.776275338057;5520.392756796749;3742.158014446755;4268.058033594707;4485.932711009337;4489.842855500075;4509.436320459942;5094.6688465249645;5800.592754007082;5800.651960882361;5821.3596504384905;6078.696065196723;3994.3024214708635;4149.883559384735;4479.507254202035;4574.817007319736;4690.053627744516;4301.827926882889;4702.976485117552;4720.905581888343;4760.691574125422;4860.295257187759;4377.5116460403615;4476.194285028726;4607.838725644587;4873.732829464365;5079.339685840817;4632;5440.129579267298;5450.5367043800925;5485.833095927086;5512.6968016758665;1574.9291281892342;1609.414726494402;1610.2673283655909;1613.3465946291772;1627.4466681328406;1101.9669409917715;1179.775842589905;1197.6646538969103;1210.1226610707342;1211.7273613436905;4742.790588399936;4907.582855457326;5109.284700141983;5127.820601261731;5130.152765215266;4715.669903106818;5068.518490743373;5149.871253218699;5565.638233514808;5653.519450846642;4326.459192600925;4502.662300327489;4726.456457507431;4889.339939108246;5062.569681577107;2758.7792976561827;2775.6114144019853;2832.0229520815287;2843.70141135182;2960.2451374899265;4896.363472748591;5394.1099718546;5801.651900885876;5861.7655293787775;5886.793962211302;5066.71997601081;5334.811065015183;5579.107342825128;5785.30576330843;5790.305163380337;5520.972395137998;5719.542436605062;5752.769889204469;5797.8258153192555;5875.265591801025;5016.473270306566;5282.867928301823;5622.662640241599;5660.547156227574;5672.7730890763905;4862.270659401118;5221.61627168793;5301.496357845998;5569.610127291051;5583.385827559046;4819.903601928012;5606.523445507178;5727.0550359190265;5731.684757338219;5837;4183.790810459404;4497.235469970047;4521.773911304487;4536.160810250114;4556.154757323289;4873.3664623600125;5646.887062258814;5686.744096515584;5732.684702341245;5777.602295047508;5100.928804951001;5182.445266420134;5509.768543739792;5515.503604675599;5546.500814926676;5051.585556044313;5317.548008419173;5501.284832971688;5560.638558493685;5576.243483302746;4221.873343799691;5022.422421421583;5229.6862188267305;5244.082285600081;5459.727013649381;4618.769061546842;5045.7042045869775;5132.484248289933;5559.499645031967;5565.526930210977;5097.592192624507;5315.787368505371;5344.946550534445;5404.757265546527;5434.945650543444 10:02:40 INFO - PROCESS | 1644 | |5;yui.html;782.8708263136583;784.9512771665951;798.744401791427;812.2324403438749;909.2680392284212;15643.608909777262;18231;19134.61730765384;19168.041597920084;19171.61656766864;15353.69292614147;16858.241379137937;18894.811051889475;18935.52661183471;18946.905265473688;996.5963784667185;997.4015590645591;998.8801343838713;999.5202302894579;1002.956924798209;986.8667729856485;988.2637435110817;1005.8742657167854;1020.209337763232;1027.7584767579651;1071.0628200324716;1072.2387105155349;1072.7479042425025;1073.1897417449854;1074.1084899533369;1065.408698172514;1065.477915821242;1066.7173199102206;1067.0023528001343;1067.546292825546;792.4730054513799;793.5357815677835;793.6626933553217;795.312055072364;796.2992566541465;16995.660086798194;17178.914105429394;17238.482845514656;17684.557886052822;17976.730349044774;12168.330741809197;14704.926475367554;14707.705845883022;14894.478693245792;14929.552113436614;11936.343501107436;12037;12098.39508024604;12125.029997600172;12301.507939682402;3789.5831458539537;3859.6526312632;3931.1351502669654;3944.467496887912;4021.155557332964;99230.0076999228;107298;107574;108209;109378;10819.350838949687;11053.502592383362;11186.049185819134;11675.474603642857;11769.234999724991;23142.42143946381;23292.65061024086;23308.533829323656;23322.65016024761;23388.18141365044;7921.960390198012;7942.166072562413;8013.759587212393;8110.148434414419;8206.876896846552;4390.736555806662;4531.297648864432;4559.475660299108;4608.953910460919;4756.952430475721;3608.530890984155;3686.078480379905;3700.6114357992024;3702.7963462009848;3704.111013356829;598.5032423088826;605.9939400606025;701.0640794539245;748.8090536913053;749.4641331448041;685.5945312110254;707.2467821769799;733.6625152429835;741.5550669598306;777.2848978939277;9569.760755981157;10617.386901748245;10894.67315980506;11087.33475991443;11235.83146252807;677.6848765324148;707.9575225486487;724.3915111306528;734.9559026458429;791.125805984379;5164.509371609691;5257.395399529027;5264.210368444764;5266.552343050884;5267.26258323835;88125;89582;92483;92932.53533732289;92976;64391.67804161043;88249;89792;91420;92438;63973.36026639767;64717;71536.64231678809;72637.54724905473;73745.6312718433;11132.38771867555;11196.440177990906;11426.82859757121;11512.251703639236;11574.88425115738;43942.901427463294;46368;48320.75839620779;49375;51432.74283628558;47250.76374618105;57844.421555783905;59028;59029.40970590239;59137.70431147817;12920.353982301036;13073.607791766262;13208.867911320764;13275.070745047755;13880.236586987812;12727.681807954876;14974.47589334368;15153.242337883283;15269.6182595436;15380.769288460906;13676.726465470834;13972.161670299814;14134.042468108946;14283;14552.49066282675;13926.721465570834;14047.227402492961;14103.224322662352;14153.43386264538;14230.573082807532;13487.258200798658;13677.110987785762;13727;14032.368543415367;14366.281685915872;9853.507324633596;10360.430176340073;10500.684979450629;10806.081483073713;10828.620998265023;11299.830502542303;11359.48882300315;11366.772664546497;11411.714707132389;11517.424128793695 10:02:40 INFO - PROCESS | 1644 | __end_tp_report 10:02:40 INFO - PROCESS | 1644 | __start_cc_report 10:02:40 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1428 10:02:40 INFO - PROCESS | 1644 | __end_cc_report 10:02:40 INFO - PROCESS | 1644 | __startTimestamp1496768560846__endTimestamp 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | ------- Summary: start ------- 10:02:40 INFO - PROCESS | 1644 | Number of tests: 6 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:6051.64 Median:6269.47 stddev:652.16 (10.4%) stddev-sans-first:624.20 10:02:40 INFO - PROCESS | 1644 | Values: 3560.8 3588.9 3595.8 3619.6 3636.7 5090.2 6154.9 6168.4 6170.6 6184.8 6134.9 6188.8 6192.6 6210.4 6222.3 5487.3 5503.1 5532.9 5581.5 5629.6 5428.1 5429.3 5635.0 5645.3 5690.6 5466.4 5538.1 5570.8 5576.5 5598.7 5569.4 5575.8 5594.5 5594.5 5607.0 5338.7 5485.6 5528.1 5528.6 5547.6 6119.0 6138.3 6144.0 6146.3 6173.8 6517.7 6520.2 6549.2 6552.4 6556.3 5822.4 6577.1 6597.5 6603.9 6608.6 6008.1 6122.4 6146.0 6164.1 6304.2 6483.2 6526.4 6532.9 6555.3 6580.9 6376.4 6378.7 6388.4 6401.4 6403.3 6516.8 6518.7 6530.4 6534.1 6538.7 5413.0 6258.2 6332.8 6429.7 6439.8 6264.3 6385.2 6444.0 6480.1 6494.9 5266.7 5304.5 5321.4 5324.8 5348.5 4709.4 4751.4 4760.5 4770.9 4787.4 5999.6 6064.2 6083.2 6151.4 6172.4 5635.5 6502.1 6553.2 6558.5 6595.7 6250.0 6254.7 6263.4 6269.5 6281.2 5568.2 5656.9 5667.1 5727.3 5752.8 6791.7 6852.5 6871.3 6871.9 6879.4 6542.1 6556.7 6558.8 6576.3 6586.9 5949.2 6523.4 6601.5 6793.2 6865.0 6383.1 6435.9 6472.7 6496.6 6531.4 6266.5 6301.3 6590.3 6594.4 6597.4 4929.5 6180.4 6375.7 6423.6 6442.2 6304.4 6442.4 6449.2 6455.6 6456.3 6257.0 6294.3 6297.9 6328.3 6351.4 6377.4 6380.8 6380.9 6383.9 6392.3 6326.9 6349.0 6359.9 6377.7 6379.0 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:55648.92 Median:34639.91 stddev:109018.82 (314.7%) stddev-sans-first:109246.80 10:02:40 INFO - PROCESS | 1644 | Values: 775.5 799.1 817.9 894.9 900.9 28783.9 41387.6 41971.8 43138.1 43592.7 42803.6 43064.7 43065.8 43113.0 43433.3 9586.3 9765.0 9773.3 9775.5 9782.0 7882.1 8072.8 8108.4 8122.4 8128.7 11779.6 11956.6 11957.1 11963.1 12198.3 11834.4 11950.3 11999.0 12011.3 12031.6 11577.4 11645.8 11655.5 11663.9 11674.4 40752.4 40915.2 40968.0 41006.8 41118.0 3984.0 4057.3 4058.1 4066.2 4110.0 28450.4 29208.6 30693.7 30921.7 31281.2 3267.9 3268.8 3291.3 3378.6 3384.5 346952.0 351374.2 351720.0 351890.0 352378.2 54095.0 54166.9 54240.0 54339.7 54514.0 46496.5 56272.7 56291.9 56335.9 56411.0 15536.6 15661.6 15683.0 15696.2 15702.9 13386.9 13403.9 13410.9 13423.9 13432.2 8758.5 10645.5 11000.4 11802.8 12231.6 5753.0 5808.8 5818.2 5828.2 5831.7 978.0 1111.9 1166.1 1168.7 1209.8 27127.7 27668.7 27700.3 27743.7 27753.3 1241.5 1267.3 1270.2 1315.4 1325.1 13687.0 13731.3 14186.3 14292.8 14412.7 463505.0 464547.0 464990.0 465606.0 466997.0 57893.1 58008.1 58062.0 58081.0 58112.5 1379.9 1446.3 1509.7 1525.4 1528.9 25698.4 26464.7 27114.6 27166.3 27166.7 43164.8 43203.8 43357.6 43430.6 43472.6 409735.0 411630.9 412135.0 412161.0 412488.0 33824.5 34600.0 34679.8 34745.8 34828.3 44465.6 45430.1 45644.5 45919.0 46065.8 25549.6 27207.7 28380.7 29740.9 30228.9 25848.2 30525.4 30644.4 31550.7 32204.4 36799.4 36990.8 37679.6 37718.6 38107.4 30993.0 31098.7 32067.4 32141.4 32280.7 23660.0 24466.3 24583.3 25108.9 25169.9 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:44692.96 Median:23291.27 stddev:127633.61 (548.0%) stddev-sans-first:127948.32 10:02:40 INFO - PROCESS | 1644 | Values: 398.1 408.6 418.6 435.6 444.9 14986.5 18704.5 21909.6 23262.0 23524.4 23561.2 23614.6 24270.3 24427.4 24467.9 5706.8 6659.2 6677.7 7206.3 7207.5 6690.7 6950.5 7016.9 7174.2 7176.4 6721.7 6726.3 6732.7 6732.7 6734.9 6751.1 6814.5 7023.1 7121.6 7127.5 6824.5 6869.8 6953.3 6958.4 6965.3 28566.4 28613.9 28812.4 28825.4 28959.0 3024.9 3261.3 3477.6 3593.3 3608.9 15789.2 17174.9 17957.4 18842.8 19357.8 2066.2 2187.7 2199.6 2203.1 2256.2 144941.0 146827.0 156917.2 162927.0 163022.2 18739.9 24669.0 25546.9 25846.1 25891.7 45451.3 46012.8 46659.0 46706.8 46710.0 10433.8 10809.5 10970.9 11028.7 11206.7 6849.3 8965.4 9125.0 9321.2 9382.2 7686.9 8585.6 8799.2 9299.6 9885.3 3514.7 3739.8 3812.4 3981.9 4023.5 1012.9 1054.2 1056.2 1090.5 1104.4 22649.0 22997.5 23014.8 23320.5 23344.0 1099.3 1111.3 1117.7 1130.4 1135.3 11259.9 11550.2 11676.9 11695.5 12023.6 680540.0 708317.0 797380.0 806023.0 819809.0 155512.0 158266.2 168836.2 169165.0 170367.1 1134.5 1298.0 1305.2 1345.6 1357.1 19332.5 20686.3 21808.6 21924.3 22086.6 55330.0 61699.1 63663.7 67110.3 67544.7 72024.6 92471.0 92645.5 93008.5 94206.5 9593.4 11930.9 12028.2 12072.9 12086.8 11029.0 13249.8 13345.8 13580.7 13817.5 11210.2 11357.4 11417.8 11541.9 11788.1 9929.4 10270.7 10437.7 10546.2 10844.9 11584.4 11784.0 11798.7 11881.5 11910.6 10021.5 11912.3 12147.0 12275.0 12307.8 11505.4 11797.0 11846.2 11894.0 11920.2 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6819.03 Median:2451.04 stddev:4264.95 (174.0%) stddev-sans-first:4258.82 10:02:40 INFO - PROCESS | 1644 | Values: 1589.3 1607.2 1619.5 1636.6 1643.4 10894.9 11571.0 11571.5 11573.9 11580.9 10879.0 11141.5 11224.3 11405.8 11413.9 2133.0 2368.9 2450.0 2494.5 2495.5 1825.7 2275.0 2357.6 2506.6 2512.9 2390.0 2445.8 2445.9 2480.9 2495.0 2379.2 2440.1 2448.5 2450.6 2462.5 2419.2 2438.6 2450.2 2451.5 2455.2 10972.8 11201.0 11212.8 11227.8 11232.7 5965.6 6138.9 6141.3 6150.9 6154.3 3631.3 3693.5 3722.9 3784.4 3786.0 1211.4 1226.8 1252.8 1278.6 1285.5 13918.2 14236.4 14554.2 15826.8 15983.7 4686.3 4831.5 4844.1 4881.1 4901.9 10319.6 11856.6 11859.7 11867.4 11879.3 5145.8 5202.1 5202.5 5203.7 5210.0 5332.9 5389.3 5528.8 5559.3 5597.9 3959.1 3967.9 4016.6 4060.4 4062.2 1057.6 1058.1 1059.2 1071.8 1074.3 915.0 927.3 937.8 938.4 988.1 6382.0 6464.8 6509.0 6602.1 6734.0 1018.0 1036.8 1040.7 1071.8 1074.3 4107.3 4218.0 4313.9 4383.9 4441.5 13652.2 14261.1 14267.9 14276.0 14301.1 13715.9 13917.2 14225.5 14235.3 14244.0 10413.6 10458.6 10465.3 10502.0 10622.2 6540.6 6562.6 6622.0 6711.3 6727.8 12270.9 12680.2 12680.3 12729.0 12757.8 10034.7 12653.4 12860.8 12994.7 13218.3 8352.2 8608.4 8706.4 8714.0 8721.1 10296.5 10320.1 10326.1 10444.9 10481.2 9031.8 9053.3 9059.3 9142.5 9296.8 8657.4 8758.4 8782.5 8854.4 8872.7 6959.5 7867.7 7968.4 8071.7 8090.4 5886.3 5895.2 5908.9 6058.2 6109.2 6015.9 6040.8 6080.4 6129.1 6252.7 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4571.10 Median:4958.55 stddev:1269.03 (25.6%) stddev-sans-first:1233.15 10:02:40 INFO - PROCESS | 1644 | Values: 388.4 395.7 396.1 397.9 399.9 5221.4 5277.6 5296.1 5343.1 5398.3 4397.0 4420.8 4794.4 5023.7 5189.3 3836.3 3876.0 3882.1 4043.6 4069.2 3816.4 4048.6 4083.3 4096.9 4137.4 4078.8 4101.5 4105.5 4146.7 4203.0 3496.3 3829.3 3831.4 4285.5 4288.2 3545.0 3608.0 3978.6 3982.4 4013.7 4543.8 4817.8 5036.8 5073.8 5080.6 4859.9 4938.6 4978.6 5014.7 5029.3 4669.8 5434.3 5438.6 5438.8 5520.4 3742.2 4268.1 4485.9 4489.8 4509.4 5094.7 5800.6 5800.7 5821.4 6078.7 3994.3 4149.9 4479.5 4574.8 4690.1 4301.8 4703.0 4720.9 4760.7 4860.3 4377.5 4476.2 4607.8 4873.7 5079.3 4632.0 5440.1 5450.5 5485.8 5512.7 1574.9 1609.4 1610.3 1613.3 1627.4 1102.0 1179.8 1197.7 1210.1 1211.7 4742.8 4907.6 5109.3 5127.8 5130.2 4715.7 5068.5 5149.9 5565.6 5653.5 4326.5 4502.7 4726.5 4889.3 5062.6 2758.8 2775.6 2832.0 2843.7 2960.2 4896.4 5394.1 5801.7 5861.8 5886.8 5066.7 5334.8 5579.1 5785.3 5790.3 5521.0 5719.5 5752.8 5797.8 5875.3 5016.5 5282.9 5622.7 5660.5 5672.8 4862.3 5221.6 5301.5 5569.6 5583.4 4819.9 5606.5 5727.1 5731.7 5837.0 4183.8 4497.2 4521.8 4536.2 4556.2 4873.4 5646.9 5686.7 5732.7 5777.6 5100.9 5182.4 5509.8 5515.5 5546.5 5051.6 5317.5 5501.3 5560.6 5576.2 4221.9 5022.4 5229.7 5244.1 5459.7 4618.8 5045.7 5132.5 5559.5 5565.5 5097.6 5315.8 5344.9 5404.8 5434.9 10:02:40 INFO - PROCESS | 1644 | 10:02:40 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19950.85 Median:21157.02 stddev:27270.31 (128.9%) stddev-sans-first:27308.83 10:02:40 INFO - PROCESS | 1644 | Values: 782.9 785.0 798.7 812.2 909.3 15643.6 18231.0 19134.6 19168.0 19171.6 15353.7 16858.2 18894.8 18935.5 18946.9 996.6 997.4 998.9 999.5 1003.0 986.9 988.3 1005.9 1020.2 1027.8 1071.1 1072.2 1072.7 1073.2 1074.1 1065.4 1065.5 1066.7 1067.0 1067.5 792.5 793.5 793.7 795.3 796.3 16995.7 17178.9 17238.5 17684.6 17976.7 12168.3 14704.9 14707.7 14894.5 14929.6 11936.3 12037.0 12098.4 12125.0 12301.5 3789.6 3859.7 3931.1 3944.5 4021.2 99230.0 107298.0 107574.0 108209.0 109378.0 10819.4 11053.5 11186.0 11675.5 11769.2 23142.4 23292.7 23308.5 23322.7 23388.2 7922.0 7942.2 8013.8 8110.1 8206.9 4390.7 4531.3 4559.5 4609.0 4757.0 3608.5 3686.1 3700.6 3702.8 3704.1 598.5 606.0 701.1 748.8 749.5 685.6 707.2 733.7 741.6 777.3 9569.8 10617.4 10894.7 11087.3 11235.8 677.7 708.0 724.4 735.0 791.1 5164.5 5257.4 5264.2 5266.6 5267.3 88125.0 89582.0 92483.0 92932.5 92976.0 64391.7 88249.0 89792.0 91420.0 92438.0 63973.4 64717.0 71536.6 72637.5 73745.6 11132.4 11196.4 11426.8 11512.3 11574.9 43942.9 46368.0 48320.8 49375.0 51432.7 47250.8 57844.4 59028.0 59029.4 59137.7 12920.4 13073.6 13208.9 13275.1 13880.2 12727.7 14974.5 15153.2 15269.6 15380.8 13676.7 13972.2 14134.0 14283.0 14552.5 13926.7 14047.2 14103.2 14153.4 14230.6 13487.3 13677.1 13727.0 14032.4 14366.3 9853.5 10360.4 10500.7 10806.1 10828.6 11299.8 11359.5 11366.8 11411.7 11517.4 10:02:40 INFO - PROCESS | 1644 | -------- Summary: end -------- 10:02:40 INFO - PROCESS | 1644 | 10:02:41 INFO - TEST-INFO | 1644: exit 0 10:02:41 INFO - TEST-OK | dromaeo_css | took 1118544ms 10:02:41 INFO - TEST-START | kraken 10:02:41 INFO - operating with platform_type : mac_ 10:02:41 INFO - Initialising browser for kraken test... 10:02:41 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpPkUcOT/profile http://localhost:49223/getInfo.html) 10:02:43 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 10:02:43 INFO - PROCESS | 1654 | colorDepth:24 10:02:43 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 10:02:43 INFO - PROCESS | 1654 | __metrics 10:02:43 INFO - PROCESS | 1654 | 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" 10:02:43 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:02:44 INFO - TEST-INFO | 1654: exit 0 10:02:44 INFO - Browser initialized. 10:02:44 INFO - Running cycle 1/1 for kraken test... 10:02:44 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpPkUcOT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:56 INFO - PROCESS | 1656 | RSS: Main: 165404672 10:02:56 INFO - PROCESS | 1656 | 10:02:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:00 INFO - PROCESS | 1656 | RSS: Main: 216485888 10:03:00 INFO - PROCESS | 1656 | 10:03:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 10:03:03 INFO - PROCESS | 1656 | RSS: Main: 231485440 10:03:03 INFO - PROCESS | 1656 | 10:03:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 10:03:07 INFO - PROCESS | 1656 | RSS: Main: 327815168 10:03:07 INFO - PROCESS | 1656 | 10:03:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 10:03:10 INFO - PROCESS | 1656 | RSS: Main: 230412288 10:03:10 INFO - PROCESS | 1656 | 10:03:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:03:12 INFO - PROCESS | 1656 | RSS: Main: 232308736 10:03:12 INFO - PROCESS | 1656 | 10:03:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 10:03:16 INFO - PROCESS | 1656 | RSS: Main: 272629760 10:03:16 INFO - PROCESS | 1656 | 10:03:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 10:03:19 INFO - PROCESS | 1656 | RSS: Main: 273575936 10:03:19 INFO - PROCESS | 1656 | 10:03:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 10:03:23 INFO - PROCESS | 1656 | RSS: Main: 270835712 10:03:23 INFO - PROCESS | 1656 | 10:03:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:03:25 INFO - PROCESS | 1656 | RSS: Main: 246808576 10:03:25 INFO - PROCESS | 1656 | 10:03:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:03:28 INFO - PROCESS | 1656 | RSS: Main: 253059072 10:03:28 INFO - PROCESS | 1656 | 10:03:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:03:31 INFO - PROCESS | 1656 | RSS: Main: 257966080 10:03:31 INFO - PROCESS | 1656 | 10:03:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:03:34 INFO - PROCESS | 1656 | RSS: Main: 261697536 10:03:34 INFO - PROCESS | 1656 | 10:03:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:03:38 INFO - PROCESS | 1656 | RSS: Main: 262811648 10:03:38 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 10:03:39 INFO - PROCESS | 1656 | RSS: Main: 306958336 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | __start_tp_report 10:03:39 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:03:39 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:03:39 INFO - PROCESS | 1656 | |i|pagename|runs| 10:03:39 INFO - PROCESS | 1656 | |0;ai-astar;103;115;118;115;116;117;115;115;116;116 10:03:39 INFO - PROCESS | 1656 | |1;audio-beat-detection;168;107;119;143;111;133;134;135;126;108 10:03:39 INFO - PROCESS | 1656 | |2;audio-dft;187;184;184;185;186;185;184;185;186;185 10:03:39 INFO - PROCESS | 1656 | |3;audio-fft;81;60;59;96;84;84;82;69;76;58 10:03:39 INFO - PROCESS | 1656 | |4;audio-oscillator;83;68;90;90;98;97;81;69;68;82 10:03:39 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;86;87;87;88;86;88;88;88;87 10:03:39 INFO - PROCESS | 1656 | |6;imaging-darkroom;93;94;94;94;93;94;93;95;101;95 10:03:39 INFO - PROCESS | 1656 | |7;imaging-desaturate;83;86;82;85;87;90;84;84;99;128 10:03:39 INFO - PROCESS | 1656 | |8;json-parse-financial;61;60;60;59;63;63;62;63;62;63 10:03:39 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;61;59;58;62;60;58;58;58;57;58 10:03:39 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;84;84;83;81;84;84;86;107;86 10:03:39 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;138;140;138;137;122;145;133;138;157;135 10:03:39 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;164;162;179;164;159;207;210;166;177;162 10:03:39 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;65;65;65;63;65;63;63;64;63;64 10:03:39 INFO - PROCESS | 1656 | __end_tp_report 10:03:39 INFO - PROCESS | 1656 | __start_cc_report 10:03:39 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2004 10:03:39 INFO - PROCESS | 1656 | __end_cc_report 10:03:39 INFO - PROCESS | 1656 | __startTimestamp1496768619979__endTimestamp 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:03:39 INFO - PROCESS | 1656 | Number of tests: 14 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:4.20 (3.6%) stddev-sans-first:1.05 10:03:39 INFO - PROCESS | 1656 | Values: 103.0 115.0 118.0 115.0 116.0 117.0 115.0 115.0 116.0 116.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:133.50 stddev:18.70 (14.0%) stddev-sans-first:13.26 10:03:39 INFO - PROCESS | 1656 | Values: 168.0 107.0 119.0 143.0 111.0 133.0 134.0 135.0 126.0 108.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.00 stddev:0.99 (0.5%) stddev-sans-first:0.78 10:03:39 INFO - PROCESS | 1656 | Values: 187.0 184.0 184.0 185.0 186.0 185.0 184.0 185.0 186.0 185.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:74.90 Median:81.50 stddev:12.89 (15.8%) stddev-sans-first:13.48 10:03:39 INFO - PROCESS | 1656 | Values: 81.0 60.0 59.0 96.0 84.0 84.0 82.0 69.0 76.0 58.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:86.50 stddev:11.39 (13.2%) stddev-sans-first:12.08 10:03:39 INFO - PROCESS | 1656 | Values: 83.0 68.0 90.0 90.0 98.0 97.0 81.0 69.0 68.0 82.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 10:03:39 INFO - PROCESS | 1656 | Values: 88.0 86.0 87.0 87.0 88.0 86.0 88.0 88.0 88.0 87.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:2.37 (2.5%) stddev-sans-first:2.44 10:03:39 INFO - PROCESS | 1656 | Values: 93.0 94.0 94.0 94.0 93.0 94.0 93.0 95.0 101.0 95.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:85.50 stddev:13.96 (16.3%) stddev-sans-first:14.52 10:03:39 INFO - PROCESS | 1656 | Values: 83.0 86.0 82.0 85.0 87.0 90.0 84.0 84.0 99.0 128.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:62.50 stddev:1.51 (2.4%) stddev-sans-first:1.58 10:03:39 INFO - PROCESS | 1656 | Values: 61.0 60.0 60.0 59.0 63.0 63.0 62.0 63.0 62.0 63.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:58.50 stddev:1.60 (2.7%) stddev-sans-first:1.50 10:03:39 INFO - PROCESS | 1656 | Values: 61.0 59.0 58.0 62.0 60.0 58.0 58.0 58.0 57.0 58.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:84.00 stddev:7.38 (8.8%) stddev-sans-first:7.81 10:03:39 INFO - PROCESS | 1656 | Values: 85.0 84.0 84.0 83.0 81.0 84.0 84.0 86.0 107.0 86.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:138.00 stddev:8.84 (6.4%) stddev-sans-first:9.38 10:03:39 INFO - PROCESS | 1656 | Values: 138.0 140.0 138.0 137.0 122.0 145.0 133.0 138.0 157.0 135.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:171.50 stddev:18.81 (11.0%) stddev-sans-first:19.53 10:03:39 INFO - PROCESS | 1656 | Values: 164.0 162.0 179.0 164.0 159.0 207.0 210.0 166.0 177.0 162.0 10:03:39 INFO - PROCESS | 1656 | 10:03:39 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:0.94 (1.5%) stddev-sans-first:0.93 10:03:39 INFO - PROCESS | 1656 | Values: 65.0 65.0 65.0 63.0 65.0 63.0 63.0 64.0 63.0 64.0 10:03:39 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:03:39 INFO - PROCESS | 1656 | 10:03:40 INFO - TEST-INFO | 1656: exit 0 10:03:40 INFO - TEST-OK | kraken | took 59444ms 10:03:40 INFO - SUITE-END | took 1177s 10:03:41 INFO - Completed test suite (00:19:38) 10:03:41 INFO - javascript benchmark 10:03:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7912.765296577496, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3560.821958902055, 3588.8923332300033, 3595.8381872815735, 3619.5656521217443, 3636.7272454565914, 5090.236464530313, 6154.9076763848525, 6168.352323006087, 6170.598911070783, 6184.783532576361, 6134.9079763803575, 6188.75244990201, 6192.628442293466, 6210.410011048954, 6222.284437289713, 5487.314085739273, 5503.091989821674, 5532.861678458051, 5581.49766521013, 5629.6340737852115, 5428.050091234038, 5429.321334833146, 5635.0, 5645.266115405012, 5690.60165788395, 5466.398696143421, 5538.0585300499015, 5570.777168913239, 5576.498115169634, 5598.664080155183, 5569.443055694439, 5575.804846830361, 5594.496495315441, 5594.524465420444, 5607.046802043662, 5338.733063346817, 5485.6434331768305, 5528.087865502187, 5528.585356098309, 5547.639403438763, 6118.969405152946, 6138.294096178952, 6144.0, 6146.293176284695, 6173.753049878, 6517.706703198368, 6520.18497687789, 6549.181352330958, 6552.410283074499, 6556.27880933097, 5822.359540450547, 6577.14497115372, 6597.47220222381, 6603.93396066038, 6608.603483790988, 6008.098785182214, 6122.357152499013, 6146.016637338004, 6164.106204600397, 6304.243490781089, 6483.157189565326, 6526.379993900572, 6532.902006469907, 6555.344465553407, 6580.934190658032, 6376.362363763679, 6378.744850206032, 6388.425041746173, 6401.4238718514625, 6403.3276505966205, 6516.837079073061, 6518.73925042993, 6530.444912182423, 6534.117894084237, 6538.738450462023, 5412.972935135299, 6258.249010118724, 6332.8416789579705, 6429.7428102875365, 6439.774607888701, 6264.279607845065, 6385.201849768779, 6444.0, 6480.060391243243, 6494.935050649433, 5266.657667251617, 5304.522592966651, 5321.361436627552, 5324.760385782649, 5348.545373643284, 4709.411323584552, 4751.382320298339, 4760.45254795696, 4770.928436073462, 4787.42550893895, 5999.6400215986305, 6064.211652485149, 6083.239595050619, 6151.446369826737, 6172.351903050205, 5635.492805647511, 6502.057201705727, 6553.246376666744, 6558.475321974231, 6595.70319335631, 6250.0, 6254.655993920378, 6263.4362907338555, 6269.46709529686, 6281.183446151971, 5568.16477528374, 5656.85857853557, 5667.093265077568, 5727.312722473246, 5752.798652047159, 6791.72833086671, 6852.5203235774015, 6871.347222013901, 6871.931280687129, 6879.380855723009, 6542.149520562297, 6556.704948277244, 6558.8360290993105, 6576.309487503743, 6586.934130658632, 5949.19685842423, 6523.445507131947, 6601.471882249312, 6793.218779840382, 6864.965675171592, 6383.138276332821, 6435.935640643721, 6472.676366181766, 6496.610203387811, 6531.444827189742, 6266.467350275278, 6301.275353334425, 6590.275069742421, 6594.406503414524, 6597.439217666552, 4929.457759646508, 6180.381961803963, 6375.713092910737, 6423.614583125028, 6442.226932768098, 6304.401081897213, 6442.35576442352, 6449.161608990801, 6455.580387274812, 6456.289808121197, 6257.0, 6294.339094395205, 6297.874042519215, 6328.272248691459, 6351.428371446592, 6377.394147555975, 6380.808575742735, 6380.9042864358, 6383.9361606383345, 6392.328805475544, 6326.8734625306315, 6349.015902535114, 6359.87280254383, 6377.681115944091, 6378.968105159444], "value": 6013.028489834602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.4804281131642, 799.1329407592763, 817.9468334558253, 894.9015608283091, 900.8873890763655, 28783.856080719594, 41387.58612413875, 41971.79014104929, 43138.137237255236, 43592.692219233395, 42803.571964280345, 43064.70805875821, 43065.78467107668, 43113.0, 43433.34849977245, 9586.328956973031, 9765.0, 9773.26700497462, 9775.511224438786, 9781.951090244538, 7882.132965373804, 8072.838543229132, 8108.351331893435, 8122.350211983056, 8128.715494957678, 11779.646610601696, 11956.641300760992, 11957.103217258697, 11963.102767292445, 12198.268103913793, 11834.408279585987, 11950.342731149827, 11999.0, 12011.339376334297, 12031.63905082849, 11577.421128943519, 11645.767084658259, 11655.533778648844, 11663.94168029163, 11674.41627918609, 40752.38871416931, 40915.181696366206, 40968.0, 41006.79496602498, 41117.97205069867, 3983.98008009961, 4057.3102572562734, 4058.066644671742, 4066.2477441673386, 4110.013596736791, 28450.430991380264, 29208.561871571943, 30693.693063069306, 30921.690783092105, 31281.217969550944, 3267.918302042468, 3268.7711860169798, 3291.325278317939, 3378.560787097686, 3384.4923261510726, 346952.0, 351374.2431287853, 351720.0, 351890.0, 352378.2381088104, 54095.0, 54166.916661666546, 54240.0, 54339.72830135824, 54514.0, 46496.535034649896, 56272.71863640656, 56291.87416251734, 56335.87328253412, 56411.0, 15536.611584710472, 15661.60845978837, 15683.0, 15696.215189240493, 15702.921485392728, 13386.933065334611, 13403.865961340456, 13410.865891341156, 13423.932880335731, 13432.19406835593, 8758.518281494451, 10645.467726613639, 11000.449977501094, 11802.822957655642, 12231.571894983634, 5752.9712351437975, 5808.767649294065, 5818.21454103699, 5828.154917537018, 5831.650100993954, 977.9608815647294, 1111.8776934537195, 1166.1487114406532, 1168.7019809948408, 1209.806430971058, 27127.72872271252, 27668.723312766615, 27700.307492312855, 27743.722562774517, 27753.306167345574, 1241.509603706549, 1267.2649863079391, 1270.2188154285234, 1315.4409376015155, 1325.1452812935795, 13687.0, 13731.313434328244, 14186.290685465892, 14292.826701716669, 14412.711745765235, 463505.0, 464547.0, 464990.0, 465606.0, 466997.0, 57893.13160302599, 58008.12987805186, 58062.0, 58081.0, 58112.54718631983, 1379.9448022079205, 1446.3419144289323, 1509.6603264265452, 1525.42034027069, 1528.9388424462898, 25698.357541060876, 26464.735352646996, 27114.593281100406, 27166.320841979108, 27166.72833271721, 43164.784176078916, 43203.78398107989, 43357.56642433661, 43430.56569434392, 43472.56527434685, 409735.0, 411630.94184528885, 412135.0, 412161.0, 412488.0, 33824.49263261004, 34600.0, 34679.826600866836, 34745.826270868485, 34828.303433930676, 44465.55534444614, 45430.09139817119, 45644.54355456403, 45919.0, 46065.84835379142, 25549.61675574905, 27207.72792272131, 28380.71619283781, 29740.85129574338, 30228.94198703035, 25848.224553263433, 30525.389492209586, 30644.387112257184, 31550.684493154775, 32204.355912882078, 36799.44800827936, 36990.815045924595, 37679.62320376871, 37718.62281377151, 38107.42838857475, 30993.0, 31098.689013110485, 32067.35865282728, 32141.357172855944, 32280.67719322871, 23660.0, 24466.266012019667, 24583.262502124966, 25108.874455627603, 25169.87415062913], "value": 19268.062795298734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.05351829553473, 408.62610711199255, 418.6462439238844, 435.55138207646127, 444.8887778055486, 14986.475473358421, 18704.532386690356, 21909.561808763814, 23262.0, 23524.411889702766, 23561.17535886244, 23614.645780313265, 24270.27189184323, 24427.389315267126, 24467.877660611673, 5706.828795136153, 6659.234188068373, 6677.732890684367, 7206.279372062804, 7207.49547531673, 6690.7323707051655, 6950.505641287123, 7016.947457881308, 7174.2108368079735, 7176.354128128467, 6721.697523611425, 6726.293739157391, 6732.663366831639, 6732.730690772363, 6734.8989765153565, 6751.088603038601, 6814.4889133314955, 7023.086998690189, 7121.575684862996, 7127.536710113826, 6824.454043676494, 6869.828254293633, 6953.304669533006, 6958.443324534025, 6965.303469652994, 28566.428671426664, 28613.85693071542, 28812.42375152506, 28825.423491530262, 28958.986435474657, 3024.894128705485, 3261.3314270574474, 3477.5861811707073, 3593.2633810068874, 3608.9278214435562, 15789.21053947298, 17174.91412542942, 17957.37149199785, 18842.77521961068, 19357.806421935882, 2066.1528773203063, 2187.671849222613, 2199.571083638691, 2203.0967303405528, 2256.1539422716482, 144941.0, 146827.0, 156917.2154139222, 162927.0, 163022.184889076, 18739.906300468683, 24669.0, 25546.872265638554, 25846.095386661378, 25891.74108258931, 45451.31823022657, 46012.76993615078, 46659.0, 46706.83232919204, 46710.0, 10433.843492347622, 10809.513571889283, 10970.890291096986, 11028.724281893017, 11206.719832004264, 6849.315068493111, 8965.417247878997, 9124.95437522808, 9321.161095501302, 9382.24942004638, 7686.942659293623, 8585.626299792128, 8799.208071273617, 9299.628014879328, 9885.25860560461, 3514.697872893259, 3739.775613463201, 3812.3519001769764, 3981.880543583697, 4023.5372932112596, 1012.8632634594383, 1054.18300816866, 1056.2183983852049, 1090.5038207615517, 1104.4091411095026, 22648.980795863896, 22997.54004919926, 23014.769852301597, 23320.53358932812, 23344.0, 1099.301943266032, 1111.2887751838668, 1117.7205698575356, 1130.4347826086957, 1135.2961164078324, 11259.94370028161, 11550.249233799776, 11676.88323116758, 11695.473703683354, 12023.579174728842, 680540.0, 708317.0, 797380.0, 806023.0, 819809.0, 155512.0, 158266.20866895592, 168836.15581922012, 169165.0, 170367.1481642584, 1134.461130962786, 1297.9610611681665, 1305.2494815480948, 1345.643404497809, 1357.050064954516, 19332.516687082374, 20686.275980340615, 21808.563828723018, 21924.342269731933, 22086.558268834855, 55330.0, 61699.07451388143, 63663.6816815913, 67110.32224194435, 67544.66227668831, 72024.63987680238, 92471.0, 92645.5367723184, 93008.53495732749, 94206.52896735747, 9593.376430531993, 11930.940345298219, 12028.218165819193, 12072.879271207175, 12086.758264834478, 11029.007389334998, 13249.80125298102, 13345.799813003006, 13580.728385432434, 13817.516386926409, 11210.159238057015, 11357.409962605077, 11417.828732569184, 11541.884581154416, 11788.115891308014, 9929.404235745878, 10270.691879243635, 10437.68686939393, 10546.20903432261, 10844.891551084704, 11584.362860042778, 11783.998360139483, 11798.705032374259, 11881.524739010343, 11910.583129590423, 10021.549030293509, 11912.344821034927, 12147.0, 12275.0, 12307.753844922872, 11505.424728763695, 11797.0, 11846.229995050295, 11894.0, 11920.225185362924], "value": 12175.547978686447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1589.2927647196996, 1607.236562632749, 1619.522240938923, 1636.6153953820854, 1643.391944980357, 10894.891051089488, 11570.999999999989, 11571.479283432245, 11573.942130289337, 11580.884191158097, 10879.0, 11141.498632561534, 11224.326540407581, 11405.828912566318, 11413.885861141385, 2133.0, 2368.910301261422, 2449.9955018442442, 2494.5135698538793, 2495.4759500504824, 1825.698759704647, 2275.0, 2357.6325731075935, 2506.5613517634433, 2512.8869200886006, 2390.0, 2445.8287919845616, 2445.902163913441, 2480.8635525046116, 2495.0, 2379.202967006055, 2440.1093600835657, 2448.485817978227, 2450.6079027355536, 2462.495188486356, 2419.2379400488967, 2438.6463962725306, 2450.154696629678, 2451.472933319345, 2455.1529722245796, 10972.780544389068, 11201.0, 11212.775744485065, 11227.775444491146, 11232.663020109328, 5965.612235204698, 6138.907916381258, 6141.263048434172, 6150.938490615082, 6154.26148862135, 3631.346357655621, 3693.464447655102, 3722.9255414891823, 3784.413415920509, 3786.0, 1211.3882489342914, 1226.7669142862828, 1252.8308678328492, 1278.5972418688177, 1285.4729560880087, 13918.234497102656, 14236.359363828653, 14554.19951902645, 15826.76259856103, 15983.680326393407, 4686.320483529869, 4831.4685384607665, 4844.128056949712, 4881.121398148367, 4901.9019619608125, 10319.587216511405, 11856.644300670994, 11859.703507412383, 11867.406629668481, 11879.346635935106, 5145.845624631267, 5202.115640341151, 5202.531772140525, 5203.661761985459, 5210.0, 5332.866678333073, 5389.299391079135, 5528.834134975958, 5559.27729395176, 5597.944020559742, 3959.1092004298803, 3967.8809635710977, 4016.6184212499766, 4060.4315395844606, 4062.207869465426, 1057.582255009267, 1058.1481907064733, 1059.195011791029, 1071.844582535528, 1074.3231763988638, 914.9954250228707, 927.3415874729018, 937.779621788879, 938.3712912348743, 988.0958922585897, 6382.0, 6464.838379040468, 6508.967455162789, 6602.075709400688, 6734.0, 1017.954192061359, 1036.8185567525652, 1040.683535327811, 1071.839224116373, 1074.3339129739454, 4107.322291821875, 4217.957820421818, 4313.870583882489, 4383.9342409863875, 4441.511433742286, 13652.249126297951, 14261.144331340152, 14267.85732142686, 14276.0, 14301.070430421989, 13715.862841371458, 13917.23455209953, 14225.502107426191, 14235.28823558818, 14244.0, 10413.5834566618, 10458.581656733797, 10465.26743127989, 10502.0, 10622.20333475008, 6540.607563546202, 6562.639054852029, 6622.0, 6711.295313991961, 6727.831804204934, 12270.87729122733, 12680.23918564889, 12680.302583358003, 12729.0, 12757.808632870328, 10034.749131271485, 12653.367331633566, 12860.807087893501, 12994.740105198032, 13218.272994985367, 8352.164783521599, 8608.440451370641, 8706.390552661507, 8714.0, 8721.084286150117, 10296.485175741333, 10320.12278956267, 10326.122279606318, 10444.895551044696, 10481.161507079567, 9031.787940646424, 9053.321000924825, 9059.275257979478, 9142.542872856464, 9296.814063718553, 8657.350698697748, 8758.430702004349, 8782.516961567175, 8854.424462409923, 8872.689455869013, 6959.478039147186, 7867.7246296381645, 7968.402369822171, 8071.748878923776, 8090.433669643068, 5886.264216972879, 5895.174675545512, 5908.881822363443, 6058.182145410313, 6109.17526133984, 6015.909761353671, 6040.783766266107, 6080.3615620361, 6129.111278864629, 6252.718627661857], "value": 5212.697794180874, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.44258489068187, 395.691360738624, 396.0810918668689, 397.86806535406765, 399.89202915212894, 5221.373435187773, 5277.551408130309, 5296.073187192245, 5343.091674415349, 5398.2982212312345, 4397.0, 4420.823167073313, 4794.352762377078, 5023.698578085317, 5189.325387699603, 3836.3286424875673, 3875.9728671901967, 3882.069771390333, 4043.555208927016, 4069.186162767451, 3816.4466152407886, 4048.5708574855, 4083.305838007531, 4096.938545921814, 4137.358709400033, 4078.775667338295, 4101.466809314785, 4105.480972040335, 4146.688998325122, 4203.0, 3496.3253111369822, 3829.272438236754, 3831.409964864578, 4285.46431696038, 4288.185244803477, 3545.0, 3607.963920360789, 3978.5822488638855, 3982.4424580558743, 4013.7391069580385, 4543.8182472701055, 4817.8313759018265, 5036.8415264489, 5073.822416215452, 5080.602834220619, 4859.857933385651, 4938.555530002282, 4978.551930326252, 5014.674046186986, 5029.321041659366, 4669.7665116744365, 5434.2935418395755, 5438.646487978308, 5438.776275338057, 5520.392756796749, 3742.158014446755, 4268.058033594707, 4485.932711009337, 4489.842855500075, 4509.436320459942, 5094.6688465249645, 5800.592754007082, 5800.651960882361, 5821.3596504384905, 6078.696065196723, 3994.3024214708635, 4149.883559384735, 4479.507254202035, 4574.817007319736, 4690.053627744516, 4301.827926882889, 4702.976485117552, 4720.905581888343, 4760.691574125422, 4860.295257187759, 4377.5116460403615, 4476.194285028726, 4607.838725644587, 4873.732829464365, 5079.339685840817, 4632.0, 5440.129579267298, 5450.5367043800925, 5485.833095927086, 5512.6968016758665, 1574.9291281892342, 1609.414726494402, 1610.2673283655909, 1613.3465946291772, 1627.4466681328406, 1101.9669409917715, 1179.775842589905, 1197.6646538969103, 1210.1226610707342, 1211.7273613436905, 4742.790588399936, 4907.582855457326, 5109.284700141983, 5127.820601261731, 5130.152765215266, 4715.669903106818, 5068.518490743373, 5149.871253218699, 5565.638233514808, 5653.519450846642, 4326.459192600925, 4502.662300327489, 4726.456457507431, 4889.339939108246, 5062.569681577107, 2758.7792976561827, 2775.6114144019853, 2832.0229520815287, 2843.70141135182, 2960.2451374899265, 4896.363472748591, 5394.1099718546, 5801.651900885876, 5861.7655293787775, 5886.793962211302, 5066.71997601081, 5334.811065015183, 5579.107342825128, 5785.30576330843, 5790.305163380337, 5520.972395137998, 5719.542436605062, 5752.769889204469, 5797.8258153192555, 5875.265591801025, 5016.473270306566, 5282.867928301823, 5622.662640241599, 5660.547156227574, 5672.7730890763905, 4862.270659401118, 5221.61627168793, 5301.496357845998, 5569.610127291051, 5583.385827559046, 4819.903601928012, 5606.523445507178, 5727.0550359190265, 5731.684757338219, 5837.0, 4183.790810459404, 4497.235469970047, 4521.773911304487, 4536.160810250114, 4556.154757323289, 4873.3664623600125, 5646.887062258814, 5686.744096515584, 5732.684702341245, 5777.602295047508, 5100.928804951001, 5182.445266420134, 5509.768543739792, 5515.503604675599, 5546.500814926676, 5051.585556044313, 5317.548008419173, 5501.284832971688, 5560.638558493685, 5576.243483302746, 4221.873343799691, 5022.422421421583, 5229.6862188267305, 5244.082285600081, 5459.727013649381, 4618.769061546842, 5045.7042045869775, 5132.484248289933, 5559.499645031967, 5565.526930210977, 5097.592192624507, 5315.787368505371, 5344.946550534445, 5404.757265546527, 5434.945650543444], "value": 4210.046793264179, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.8708263136583, 784.9512771665951, 798.744401791427, 812.2324403438749, 909.2680392284212, 15643.608909777262, 18231.0, 19134.61730765384, 19168.041597920084, 19171.61656766864, 15353.69292614147, 16858.241379137937, 18894.811051889475, 18935.52661183471, 18946.905265473688, 996.5963784667185, 997.4015590645591, 998.8801343838713, 999.5202302894579, 1002.956924798209, 986.8667729856485, 988.2637435110817, 1005.8742657167854, 1020.209337763232, 1027.7584767579651, 1071.0628200324716, 1072.2387105155349, 1072.7479042425025, 1073.1897417449854, 1074.1084899533369, 1065.408698172514, 1065.477915821242, 1066.7173199102206, 1067.0023528001343, 1067.546292825546, 792.4730054513799, 793.5357815677835, 793.6626933553217, 795.312055072364, 796.2992566541465, 16995.660086798194, 17178.914105429394, 17238.482845514656, 17684.557886052822, 17976.730349044774, 12168.330741809197, 14704.926475367554, 14707.705845883022, 14894.478693245792, 14929.552113436614, 11936.343501107436, 12037.0, 12098.39508024604, 12125.029997600172, 12301.507939682402, 3789.5831458539537, 3859.6526312632, 3931.1351502669654, 3944.467496887912, 4021.155557332964, 99230.0076999228, 107298.0, 107574.0, 108209.0, 109378.0, 10819.350838949687, 11053.502592383362, 11186.049185819134, 11675.474603642857, 11769.234999724991, 23142.42143946381, 23292.65061024086, 23308.533829323656, 23322.65016024761, 23388.18141365044, 7921.960390198012, 7942.166072562413, 8013.759587212393, 8110.148434414419, 8206.876896846552, 4390.736555806662, 4531.297648864432, 4559.475660299108, 4608.953910460919, 4756.952430475721, 3608.530890984155, 3686.078480379905, 3700.6114357992024, 3702.7963462009848, 3704.111013356829, 598.5032423088826, 605.9939400606025, 701.0640794539245, 748.8090536913053, 749.4641331448041, 685.5945312110254, 707.2467821769799, 733.6625152429835, 741.5550669598306, 777.2848978939277, 9569.760755981157, 10617.386901748245, 10894.67315980506, 11087.33475991443, 11235.83146252807, 677.6848765324148, 707.9575225486487, 724.3915111306528, 734.9559026458429, 791.125805984379, 5164.509371609691, 5257.395399529027, 5264.210368444764, 5266.552343050884, 5267.26258323835, 88125.0, 89582.0, 92483.0, 92932.53533732289, 92976.0, 64391.67804161043, 88249.0, 89792.0, 91420.0, 92438.0, 63973.36026639767, 64717.0, 71536.64231678809, 72637.54724905473, 73745.6312718433, 11132.38771867555, 11196.440177990906, 11426.82859757121, 11512.251703639236, 11574.88425115738, 43942.901427463294, 46368.0, 48320.75839620779, 49375.0, 51432.74283628558, 47250.76374618105, 57844.421555783905, 59028.0, 59029.40970590239, 59137.70431147817, 12920.353982301036, 13073.607791766262, 13208.867911320764, 13275.070745047755, 13880.236586987812, 12727.681807954876, 14974.47589334368, 15153.242337883283, 15269.6182595436, 15380.769288460906, 13676.726465470834, 13972.161670299814, 14134.042468108946, 14283.0, 14552.49066282675, 13926.721465570834, 14047.227402492961, 14103.224322662352, 14153.43386264538, 14230.573082807532, 13487.258200798658, 13677.110987785762, 13727.0, 14032.368543415367, 14366.281685915872, 9853.507324633596, 10360.430176340073, 10500.684979450629, 10806.081483073713, 10828.620998265023, 11299.830502542303, 11359.48882300315, 11366.772664546497, 11411.714707132389, 11517.424128793695], "value": 7927.897742182018, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1442.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 118.0, 115.0, 116.0, 117.0, 115.0, 115.0, 116.0, 116.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 107.0, 119.0, 143.0, 111.0, 133.0, 134.0, 135.0, 126.0, 108.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 184.0, 185.0, 186.0, 185.0, 184.0, 185.0, 186.0, 185.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 96.0, 84.0, 84.0, 82.0, 69.0, 76.0, 58.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 90.0, 90.0, 98.0, 97.0, 81.0, 69.0, 68.0, 82.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 88.0, 86.0, 88.0, 88.0, 88.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 95.0, 101.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 82.0, 85.0, 87.0, 90.0, 84.0, 84.0, 99.0, 128.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 59.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 62.0, 60.0, 58.0, 58.0, 58.0, 57.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 83.0, 81.0, 84.0, 84.0, 86.0, 107.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 138.0, 137.0, 122.0, 145.0, 133.0, 138.0, 157.0, 135.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 179.0, 164.0, 159.0, 207.0, 210.0, 166.0, 177.0, 162.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 63.0, 65.0, 63.0, 63.0, 64.0, 63.0, 64.0], "value": 64.0, "unit": "score"}]}]} 10:03:41 INFO - Return code: 0 10:03:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:03:41 INFO - # TBPL SUCCESS # 10:03:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:03:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:03:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:03:41 INFO - 2017-06-06 10:03:41.298 screenresolution[1660:9707] starting screenresolution argv=screenresolution get 10:03:41 INFO - 2017-06-06 10:03:41.301 screenresolution[1660:9707] Display 0: 1600x1200x32@60 10:03:41 INFO - 2017-06-06 10:03:41.307 screenresolution[1661:9710] starting screenresolution argv=screenresolution list 10:03:41 INFO - Available Modes on Display 0 10:03:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:03:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:03:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:03:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:03:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:03:41 INFO - Intel Iris: 10:03:41 INFO - Chipset Model: Intel Iris 10:03:41 INFO - Type: GPU 10:03:41 INFO - Bus: Built-In 10:03:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:03:41 INFO - Vendor: Intel (0x8086) 10:03:41 INFO - Device ID: 0x0a2e 10:03:41 INFO - Revision ID: 0x0009 10:03:41 INFO - Displays: 10:03:41 INFO - Display: 10:03:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:03:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:03:41 INFO - Display Serial Number: 1600x1200 60 10:03:41 INFO - Main Display: Yes 10:03:41 INFO - Mirror: Off 10:03:41 INFO - Online: Yes 10:03:41 INFO - Rotation: Supported 10:03:41 INFO - Return code: 0 10:03:41 INFO - Running post-action listener: _resource_record_post_action 10:03:41 INFO - [mozharness: 2017-06-06 17:03:41.554657Z] Finished run-tests step (success) 10:03:41 INFO - Running post-run listener: _resource_record_post_run 10:03:41 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 44372992; Write bytes: 423516160; Read time: 472; Write time: 1512 10:03:41 INFO - TinderboxPrint: CPU usage
53.8% 10:03:41 INFO - TinderboxPrint: I/O read bytes / time
44,372,992 / 472 10:03:41 INFO - TinderboxPrint: I/O write bytes / time
423,516,160 / 1,512 10:03:41 INFO - TinderboxPrint: CPU idle
2,206.1 (46.2%) 10:03:41 INFO - TinderboxPrint: CPU system
954.9 (20.0%) 10:03:41 INFO - TinderboxPrint: CPU user
1,616.6 (33.8%) 10:03:41 INFO - TinderboxPrint: Swap in / out
161,947,648 / 0 10:03:41 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119865856; Write bytes: 116037632; Read time: 10912; Write time: 302 10:03:41 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 42193920; Write bytes: 296407040; Read time: 441; Write time: 1175 10:03:42 INFO - Running post-run listener: _upload_blobber_files 10:03:42 INFO - Blob upload gear active. 10:03:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:03:42 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 10:03:42 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'] 10:03:42 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 10:03:42 INFO - (blobuploader) - INFO - Open directory for files ... 10:03:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:03:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:03:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:43 INFO - (blobuploader) - INFO - Done attempting. 10:03:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:03:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:03:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:44 INFO - (blobuploader) - INFO - Done attempting. 10:03:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:03:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:03:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:46 INFO - (blobuploader) - INFO - Done attempting. 10:03:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:03:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:48 INFO - (blobuploader) - INFO - Done attempting. 10:03:48 INFO - (blobuploader) - INFO - Iteration through files over. 10:03:48 INFO - Return code: 0 10:03:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:03:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:03:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b65dfbd198637dc4d07b4ab9752c1a27b471e81f81891e7bdb10ec2fa773b733e052289517d08900a13b141540e9884f066e8508c16974986e4464e00f47ffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc4f2ab63e2b35b5424947b56b618e1e3d5d7adf3378d6332a8313fd17ad9bccab2b9954f127e593cb0a7a9b0e6f5bdf93c5ea04b6e233bed9891dedec458dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b08f8315e904614585b325d74ab02f1bb0b0e8600f2a8273111b5c2bda2c24fa6e8234e425462c3a6a18a87375ab4e49f384c135581443863adb8d40a73fa701", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3da006ef2950b9484c30c35412c2634c6a49fbd6ee062bfe41c16680882756d34d23537c653e15be42b643adb910eb7a2235708779445fe0b85a49183076e98a"} 10:03:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:03:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:03:48 INFO - Contents: 10:03:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b65dfbd198637dc4d07b4ab9752c1a27b471e81f81891e7bdb10ec2fa773b733e052289517d08900a13b141540e9884f066e8508c16974986e4464e00f47ffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc4f2ab63e2b35b5424947b56b618e1e3d5d7adf3378d6332a8313fd17ad9bccab2b9954f127e593cb0a7a9b0e6f5bdf93c5ea04b6e233bed9891dedec458dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b08f8315e904614585b325d74ab02f1bb0b0e8600f2a8273111b5c2bda2c24fa6e8234e425462c3a6a18a87375ab4e49f384c135581443863adb8d40a73fa701", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3da006ef2950b9484c30c35412c2634c6a49fbd6ee062bfe41c16680882756d34d23537c653e15be42b643adb910eb7a2235708779445fe0b85a49183076e98a"} 10:03:48 INFO - Running post-run listener: copy_logs_to_upload_dir 10:03:48 INFO - Copying logs to upload dir... 10:03:48 INFO - mkdir: /builds/slave/test/build/upload/logs 10:03:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.007757 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-06-06 10:03:48.159103) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 10:03:48.162405) ========= 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.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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/2b65dfbd198637dc4d07b4ab9752c1a27b471e81f81891e7bdb10ec2fa773b733e052289517d08900a13b141540e9884f066e8508c16974986e4464e00f47ffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc4f2ab63e2b35b5424947b56b618e1e3d5d7adf3378d6332a8313fd17ad9bccab2b9954f127e593cb0a7a9b0e6f5bdf93c5ea04b6e233bed9891dedec458dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b08f8315e904614585b325d74ab02f1bb0b0e8600f2a8273111b5c2bda2c24fa6e8234e425462c3a6a18a87375ab4e49f384c135581443863adb8d40a73fa701", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3da006ef2950b9484c30c35412c2634c6a49fbd6ee062bfe41c16680882756d34d23537c653e15be42b643adb910eb7a2235708779445fe0b85a49183076e98a"} build_url:https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/EGT72uMjRD2JduJZWMPClg/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/2b65dfbd198637dc4d07b4ab9752c1a27b471e81f81891e7bdb10ec2fa773b733e052289517d08900a13b141540e9884f066e8508c16974986e4464e00f47ffb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc4f2ab63e2b35b5424947b56b618e1e3d5d7adf3378d6332a8313fd17ad9bccab2b9954f127e593cb0a7a9b0e6f5bdf93c5ea04b6e233bed9891dedec458dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b08f8315e904614585b325d74ab02f1bb0b0e8600f2a8273111b5c2bda2c24fa6e8234e425462c3a6a18a87375ab4e49f384c135581443863adb8d40a73fa701", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3da006ef2950b9484c30c35412c2634c6a49fbd6ee062bfe41c16680882756d34d23537c653e15be42b643adb910eb7a2235708779445fe0b85a49183076e98a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 10:03:48.196623) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:03:48.197113) ========= 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.XpHEbJKYSo/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.wVp33ueVoM/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:03:48.246716) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 10:03:48.247032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 10:03:48.247349) ========= ========= Total master_lag: 0.17 =========