builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0355 starttime: 1495564906.82 results: success (0) buildid: 20170523100428 builduid: e1f0bd5becdd4836ab86ed8a34346e4f revision: a2d407b9da248932f50c95533d13cd8043ca2e21 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:46.824452) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:46.824869) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:46.825152) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:46.848186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:46.848466) ========= 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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-23 11:41:46-- 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% 46.6M=0s 2017-05-23 11:41:47 (46.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.199148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:47.069567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:47.069879) ========= 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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.022970 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:47.118850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 11:41:47.119229) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a2d407b9da248932f50c95533d13cd8043ca2e21 --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 a2d407b9da248932f50c95533d13cd8043ca2e21 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-23 11:41:47,212 truncating revision to first 12 chars 2017-05-23 11:41:47,213 Setting DEBUG logging. 2017-05-23 11:41:47,213 attempt 1/10 2017-05-23 11:41:47,213 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a2d407b9da24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 11:41:48,525 unpacking tar archive at: mozilla-beta-a2d407b9da24/testing/mozharness/ program finished with exit code 0 elapsedTime=1.589293 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 11:41:48.730778) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:48.731124) ========= script_repo_revision: a2d407b9da248932f50c95533d13cd8043ca2e21 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:48.731506) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:48.731768) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:48.750397) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-23 11:41:48.750787) ========= /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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:48 INFO - MultiFileLogger online at 20170523 11:41:48 in /builds/slave/test 11:41:48 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 11:41:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:41:48 INFO - {'append_to_log': False, 11:41:48 INFO - 'base_work_dir': '/builds/slave/test', 11:41:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:41:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:41:48 INFO - 'branch': 'Mozilla-Beta', 11:41:48 INFO - 'buildbot_json_path': 'buildprops.json', 11:41:48 INFO - 'config_files': ('talos/mac_config.py',), 11:41:48 INFO - 'default_actions': ('clobber', 11:41:48 INFO - 'read-buildbot-config', 11:41:48 INFO - 'download-and-extract', 11:41:48 INFO - 'populate-webroot', 11:41:48 INFO - 'create-virtualenv', 11:41:48 INFO - 'install', 11:41:48 INFO - 'run-tests'), 11:41:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:41:48 INFO - 'download_minidump_stackwalk': True, 11:41:48 INFO - 'download_symbols': 'ondemand', 11:41:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:41:48 INFO - 'tooltool.py': '/tools/tooltool.py', 11:41:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:41:48 INFO - '/tools/misc-python/virtualenv.py')}, 11:41:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:41:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:41:48 INFO - 'gecko_profile': False, 11:41:48 INFO - 'gecko_profile_interval': 0, 11:41:48 INFO - 'installer_path': 'installer.exe', 11:41:48 INFO - 'log_level': 'info', 11:41:48 INFO - 'log_name': 'talos', 11:41:48 INFO - 'log_to_console': True, 11:41:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:41:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:41:48 INFO - 'opt_config_files': (), 11:41:48 INFO - 'pip_index': False, 11:41:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:41:48 INFO - 'cmd': ('bash', 11:41:48 INFO - '-c', 11:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:41:48 INFO - 'enabled': True, 11:41:48 INFO - 'halt_on_failure': False, 11:41:48 INFO - 'name': 'check_screen_resolution'},), 11:41:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:41:48 INFO - 'cmd': ('bash', 11:41:48 INFO - '-c', 11:41:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:41:48 INFO - 'enabled': True, 11:41:48 INFO - 'halt_on_failure': False, 11:41:48 INFO - 'name': 'check_screen_resolution'},), 11:41:48 INFO - 'run_cmd_checks_enabled': True, 11:41:48 INFO - 'suite': 'dromaeojs', 11:41:48 INFO - 'system_bits': '32', 11:41:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:41:48 INFO - 'title': 't-yosemite-r7-0355', 11:41:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:41:48 INFO - 'use_talos_json': True, 11:41:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:41:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:41:48 INFO - 'work_dir': 'build'} 11:41:48 INFO - [mozharness: 2017-05-23 18:41:48.965656Z] Running clobber step. 11:41:48 INFO - Running pre-action listener: _resource_record_pre_action 11:41:48 INFO - Running main action method: clobber 11:41:48 INFO - rmtree: /builds/slave/test/build 11:41:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:41:50 INFO - Running post-action listener: _resource_record_post_action 11:41:50 INFO - [mozharness: 2017-05-23 18:41:50.871724Z] Finished clobber step (success) 11:41:50 INFO - [mozharness: 2017-05-23 18:41:50.871926Z] Running read-buildbot-config step. 11:41:50 INFO - Running pre-action listener: _resource_record_pre_action 11:41:50 INFO - Running main action method: read_buildbot_config 11:41:50 INFO - Using buildbot properties: 11:41:50 INFO - { 11:41:50 INFO - "project": "", 11:41:50 INFO - "product": "firefox", 11:41:50 INFO - "script_repo_revision": "production", 11:41:50 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 11:41:50 INFO - "repository": "", 11:41:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 11:41:50 INFO - "buildid": "20170523100428", 11:41:50 INFO - "pgo_build": "False", 11:41:50 INFO - "basedir": "/builds/slave/test", 11:41:50 INFO - "buildnumber": 1, 11:41:50 INFO - "slavename": "t-yosemite-r7-0355", 11:41:50 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:41:50 INFO - "platform": "yosemite_r7", 11:41:50 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:41:50 INFO - "branch": "mozilla-beta", 11:41:50 INFO - "repo_path": "releases/mozilla-beta", 11:41:50 INFO - "revision": "a2d407b9da248932f50c95533d13cd8043ca2e21", 11:41:50 INFO - "stage_platform": "macosx64", 11:41:50 INFO - "builduid": "e1f0bd5becdd4836ab86ed8a34346e4f", 11:41:50 INFO - "slavebuilddir": "test" 11:41:50 INFO - } 11:41:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:41:50 INFO - Running post-action listener: _resource_record_post_action 11:41:50 INFO - [mozharness: 2017-05-23 18:41:50.875340Z] Finished read-buildbot-config step (success) 11:41:50 INFO - [mozharness: 2017-05-23 18:41:50.875474Z] Running download-and-extract step. 11:41:50 INFO - Running pre-action listener: _resource_record_pre_action 11:41:50 INFO - Running main action method: download_and_extract 11:41:50 INFO - mkdir: /builds/slave/test/build/tests 11:41:50 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')]} 11:41:50 INFO - trying https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:41:51 INFO - Downloaded 1287 bytes. 11:41:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:41:51 INFO - Using the following test package requirements: 11:41:51 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:41:51 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:41:51 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:41:51 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:41:51 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:41:51 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:41:51 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:41:51 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:41:51 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:41:51 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:41:51 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:51 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:41:51 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:41:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:41:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:41:51 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:41:53 INFO - Expected file size: 51397043 11:41:53 INFO - Obtained file size: 51397043 11:42:09 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 11:42:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:42:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:42:09 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:42:11 INFO - Expected file size: 51397043 11:42:11 INFO - Obtained file size: 51397043 11:42:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:42:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:42:26 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:42:27 INFO - Expected file size: 14404449 11:42:27 INFO - Obtained file size: 14404449 11:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:28 INFO - trying https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:28 INFO - Downloading https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:42:30 INFO - Downloaded 59649797 bytes. 11:42:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:30 INFO - mkdir: /builds/slave/test/properties 11:42:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:42:30 INFO - Writing to file /builds/slave/test/properties/build_url 11:42:30 INFO - Contents: 11:42:30 INFO - build_url:https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:30 INFO - Running post-action listener: _resource_record_post_action 11:42:30 INFO - Running post-action listener: set_extra_try_arguments 11:42:30 INFO - [mozharness: 2017-05-23 18:42:30.663343Z] Finished download-and-extract step (success) 11:42:30 INFO - [mozharness: 2017-05-23 18:42:30.663513Z] Running populate-webroot step. 11:42:30 INFO - Running pre-action listener: _resource_record_pre_action 11:42:30 INFO - Running main action method: populate_webroot 11:42:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:42:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:42:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:42:30 INFO - '--tppagecycles', 11:42:30 INFO - '7'], 11:42:30 INFO - 'tests': ['tsvgm']}}, 11:42:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:42:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:42:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:42:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:42:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:42:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:42:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['damp', 'tps']}, 11:42:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'tests': ['damp', 'tps']}, 11:42:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['dromaeo_dom']}, 11:42:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:42:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['basic_compositor_video']}, 11:42:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:42:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'other-e10s': {'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['a11yr', 11:42:30 INFO - 'ts_paint', 11:42:30 INFO - 'tpaint', 11:42:30 INFO - 'sessionrestore', 11:42:30 INFO - 'sessionrestore_no_auto_restore', 11:42:30 INFO - 'tabpaint']}, 11:42:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['tsvgx', 11:42:30 INFO - 'tsvgr_opacity', 11:42:30 INFO - 'tart', 11:42:30 INFO - 'tscrollx', 11:42:30 INFO - 'cart', 11:42:30 INFO - 'tsvg_static']}, 11:42:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:42:30 INFO - 'tsvgr_opacity', 11:42:30 INFO - 'tart', 11:42:30 INFO - 'tscrollx', 11:42:30 INFO - 'cart', 11:42:30 INFO - 'tsvg_static']}, 11:42:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'talos_options': ['--disable-e10s'], 11:42:30 INFO - 'tests': ['tp5o']}, 11:42:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:42:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'talos_options': ['--disable-e10s', 11:42:30 INFO - '--xperf_path', 11:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:42:30 INFO - 'tests': ['tp5n']}, 11:42:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:42:30 INFO - 'talos_options': ['--xperf_path', 11:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:42:30 INFO - 'tests': ['tp5n']}}, 11:42:30 INFO - 'talos.zip': {'path': '', 11:42:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:42:30 INFO - Running post-action listener: _resource_record_post_action 11:42:30 INFO - [mozharness: 2017-05-23 18:42:30.683720Z] Finished populate-webroot step (success) 11:42:30 INFO - [mozharness: 2017-05-23 18:42:30.683842Z] Running create-virtualenv step. 11:42:30 INFO - Running pre-action listener: _resource_record_pre_action 11:42:30 INFO - Running main action method: create_virtualenv 11:42:30 INFO - Creating virtualenv /builds/slave/test/build/venv 11:42:30 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 11:42:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:42:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:42:30 INFO - Using real prefix '/tools/python27' 11:42:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:42:31 INFO - Installing distribute.............................................................................................................................................................................................done. 11:42:34 INFO - Installing pip.................done. 11:42:34 INFO - Return code: 0 11:42:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:42:34 INFO - Reading from file tmpfile_stdout 11:42:34 INFO - Output received: 11:42:34 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) 11:42:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:42: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')]} 11:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42: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')]} 11:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42: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', 'pip>=1.5'] in /builds/slave/test/build 11:42: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 pip>=1.5 11:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:34 INFO - 'HOME': '/Users/cltbld', 11:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:34 INFO - 'LOGNAME': 'cltbld', 11:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:34 INFO - 'MOZ_NO_REMOTE': '1', 11:42:34 INFO - 'NO_EM_RESTART': '1', 11:42:34 INFO - 'PAGER': '/bin/cat', 11:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:34 INFO - 'PWD': '/builds/slave/test', 11:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:34 INFO - 'SHELL': '/bin/bash', 11:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:34 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:34 INFO - 'USER': 'cltbld', 11:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:34 INFO - 'XPC_FLAGS': '0x0', 11:42:34 INFO - 'XPC_SERVICE_NAME': '0', 11:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:34 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 11:42:34 INFO - Cleaning up... 11:42:34 INFO - Return code: 0 11:42:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:42: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')]} 11:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42: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')]} 11:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42: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', '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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:42: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 psutil>=3.1.1 11:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:34 INFO - 'HOME': '/Users/cltbld', 11:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:34 INFO - 'LOGNAME': 'cltbld', 11:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:34 INFO - 'MOZ_NO_REMOTE': '1', 11:42:34 INFO - 'NO_EM_RESTART': '1', 11:42:34 INFO - 'PAGER': '/bin/cat', 11:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:34 INFO - 'PWD': '/builds/slave/test', 11:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:34 INFO - 'SHELL': '/bin/bash', 11:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:34 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:34 INFO - 'USER': 'cltbld', 11:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:34 INFO - 'XPC_FLAGS': '0x0', 11:42:34 INFO - 'XPC_SERVICE_NAME': '0', 11:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:35 INFO - Downloading/unpacking psutil>=3.1.1 11:42: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 11:42: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 11:42: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 11:42: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 11:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:42:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:42:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:42:37 INFO - Installing collected packages: psutil 11:42:37 INFO - Running setup.py install for psutil 11:42:37 INFO - building 'psutil._psutil_osx' extension 11:42:37 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 11:42:37 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 11:42:37 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 11:42:37 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 11:42:37 INFO - building 'psutil._psutil_posix' extension 11:42:37 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 11:42:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:42:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:42:37 INFO - ^ 11:42:37 INFO - 1 warning generated. 11:42:37 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 11:42:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:42:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:42:38 INFO - Successfully installed psutil 11:42:38 INFO - Cleaning up... 11:42:38 INFO - Return code: 0 11:42:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:42:38 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')]} 11:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:38 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')]} 11:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:38 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:38 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 11:42:38 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 11:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:38 INFO - 'HOME': '/Users/cltbld', 11:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:38 INFO - 'LOGNAME': 'cltbld', 11:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:38 INFO - 'MOZ_NO_REMOTE': '1', 11:42:38 INFO - 'NO_EM_RESTART': '1', 11:42:38 INFO - 'PAGER': '/bin/cat', 11:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:38 INFO - 'PWD': '/builds/slave/test', 11:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:38 INFO - 'SHELL': '/bin/bash', 11:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:38 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:38 INFO - 'USER': 'cltbld', 11:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:38 INFO - 'XPC_FLAGS': '0x0', 11:42:38 INFO - 'XPC_SERVICE_NAME': '0', 11:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:42:38 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 11:42:38 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 11:42:38 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 11:42:38 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 11:42:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:42:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:42:39 INFO - Installing collected packages: mozsystemmonitor 11:42:39 INFO - Running setup.py install for mozsystemmonitor 11:42:39 INFO - Successfully installed mozsystemmonitor 11:42:39 INFO - Cleaning up... 11:42:39 INFO - Return code: 0 11:42:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11: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')]} 11:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11: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')]} 11:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:39 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:39 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 11:42:39 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 11:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:39 INFO - 'HOME': '/Users/cltbld', 11:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:39 INFO - 'LOGNAME': 'cltbld', 11:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:39 INFO - 'MOZ_NO_REMOTE': '1', 11:42:39 INFO - 'NO_EM_RESTART': '1', 11:42:39 INFO - 'PAGER': '/bin/cat', 11:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:39 INFO - 'PWD': '/builds/slave/test', 11:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:39 INFO - 'SHELL': '/bin/bash', 11:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:39 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:39 INFO - 'USER': 'cltbld', 11:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:39 INFO - 'XPC_FLAGS': '0x0', 11:42:39 INFO - 'XPC_SERVICE_NAME': '0', 11:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:40 INFO - Downloading/unpacking jsonschema==2.5.1 11:42:40 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 11:42:40 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 11:42:40 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 11:42:40 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 11:42:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:42:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:42:41 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 11:42:41 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 11:42:41 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 11:42:41 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 11:42:41 INFO - Downloading functools32-3.2.3-2.tar.gz 11:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:42:41 INFO - warning: no files found matching '*.txt' 11:42:41 INFO - no previously-included directories found matching 'build' 11:42:41 INFO - no previously-included directories found matching 'dist' 11:42:41 INFO - no previously-included directories found matching '.git*' 11:42:41 INFO - Installing collected packages: jsonschema, functools32 11:42:42 INFO - Running setup.py install for functools32 11:42:42 INFO - warning: no files found matching '*.txt' 11:42:42 INFO - no previously-included directories found matching 'build' 11:42:42 INFO - no previously-included directories found matching 'dist' 11:42:42 INFO - no previously-included directories found matching '.git*' 11:42:42 INFO - Successfully installed jsonschema functools32 11:42:42 INFO - Cleaning up... 11:42:42 INFO - Return code: 0 11:42:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:42: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')]} 11:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42: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')]} 11:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:42 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 11:42:42 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 11:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:42 INFO - 'HOME': '/Users/cltbld', 11:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:42 INFO - 'LOGNAME': 'cltbld', 11:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:42 INFO - 'MOZ_NO_REMOTE': '1', 11:42:42 INFO - 'NO_EM_RESTART': '1', 11:42:42 INFO - 'PAGER': '/bin/cat', 11:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:42 INFO - 'PWD': '/builds/slave/test', 11:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:42 INFO - 'SHELL': '/bin/bash', 11:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:42 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:42 INFO - 'USER': 'cltbld', 11:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:42 INFO - 'XPC_FLAGS': '0x0', 11:42:42 INFO - 'XPC_SERVICE_NAME': '0', 11:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:42:42 INFO - Cleaning up... 11:42:42 INFO - Return code: 0 11:42:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:42: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')]} 11:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42: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')]} 11:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:42 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 11:42:42 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 11:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:42 INFO - 'HOME': '/Users/cltbld', 11:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:42 INFO - 'LOGNAME': 'cltbld', 11:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:42 INFO - 'MOZ_NO_REMOTE': '1', 11:42:42 INFO - 'NO_EM_RESTART': '1', 11:42:42 INFO - 'PAGER': '/bin/cat', 11:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:42 INFO - 'PWD': '/builds/slave/test', 11:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:42 INFO - 'SHELL': '/bin/bash', 11:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:42 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:42 INFO - 'USER': 'cltbld', 11:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:42 INFO - 'XPC_FLAGS': '0x0', 11:42:42 INFO - 'XPC_SERVICE_NAME': '0', 11:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:42 INFO - Downloading/unpacking blobuploader==1.2.4 11:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:44 INFO - Downloading blobuploader-1.2.4.tar.gz 11:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:42:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:42: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 11:42: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 11:42: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 11:42: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 11:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:42:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:42:45 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 11:42:45 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 11:42:45 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 11:42:45 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 11:42:45 INFO - Downloading docopt-0.6.1.tar.gz 11:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:42:45 INFO - Installing collected packages: blobuploader, requests, docopt 11:42:45 INFO - Running setup.py install for blobuploader 11:42:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:42:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:42:45 INFO - Running setup.py install for requests 11:42:46 INFO - Running setup.py install for docopt 11:42:46 INFO - Successfully installed blobuploader requests docopt 11:42:46 INFO - Cleaning up... 11:42:46 INFO - Return code: 0 11:42:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42: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')]} 11:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42: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')]} 11:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:46 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:46 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 11:42:46 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 11:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:46 INFO - 'HOME': '/Users/cltbld', 11:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:46 INFO - 'LOGNAME': 'cltbld', 11:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:46 INFO - 'MOZ_NO_REMOTE': '1', 11:42:46 INFO - 'NO_EM_RESTART': '1', 11:42:46 INFO - 'PAGER': '/bin/cat', 11:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:46 INFO - 'PWD': '/builds/slave/test', 11:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:46 INFO - 'SHELL': '/bin/bash', 11:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:46 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:46 INFO - 'USER': 'cltbld', 11:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:46 INFO - 'XPC_FLAGS': '0x0', 11:42:46 INFO - 'XPC_SERVICE_NAME': '0', 11:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-qfXepR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-9lUEPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-TBMBsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-sq5Ibp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-6bEzG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-eMWBbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-WrBZ68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:42:47 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-aG6wSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-uzjfCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-kxjgdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-NP09eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-juL1QM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-GZik2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-zosCkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-hWCuRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-r81fjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:42:48 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-4d1Yq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:42:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:42:48 INFO - Running setup.py install for manifestparser 11:42:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:42:49 INFO - Running setup.py install for mozcrash 11:42:49 INFO - Running setup.py install for mozdebug 11:42:49 INFO - Running setup.py install for mozdevice 11:42:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:42:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:42:49 INFO - Running setup.py install for mozfile 11:42:49 INFO - Running setup.py install for mozhttpd 11:42:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:42:49 INFO - Running setup.py install for mozinfo 11:42:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Running setup.py install for mozInstall 11:42:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Running setup.py install for mozleak 11:42:50 INFO - Running setup.py install for mozlog 11:42:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Running setup.py install for moznetwork 11:42:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Running setup.py install for mozprocess 11:42:50 INFO - Running setup.py install for mozprofile 11:42:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:42:50 INFO - Running setup.py install for mozrunner 11:42:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:42:51 INFO - Running setup.py install for mozscreenshot 11:42:51 INFO - Running setup.py install for moztest 11:42:51 INFO - Running setup.py install for mozversion 11:42:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:42:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:42:51 INFO - Cleaning up... 11:42:51 INFO - Return code: 0 11:42:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:51 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:51 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 11:42:51 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 11:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:51 INFO - 'HOME': '/Users/cltbld', 11:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:51 INFO - 'LOGNAME': 'cltbld', 11:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:51 INFO - 'MOZ_NO_REMOTE': '1', 11:42:51 INFO - 'NO_EM_RESTART': '1', 11:42:51 INFO - 'PAGER': '/bin/cat', 11:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:51 INFO - 'PWD': '/builds/slave/test', 11:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:51 INFO - 'SHELL': '/bin/bash', 11:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:51 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:51 INFO - 'USER': 'cltbld', 11:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:51 INFO - 'XPC_FLAGS': '0x0', 11:42:51 INFO - 'XPC_SERVICE_NAME': '0', 11:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:42:51 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-bUcvU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:42:51 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)) 11:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:42:51 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-Hth1uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-nZbSmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-1lQZd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-sDKBnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-IFalJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-4yFk3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-SEby2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-NmppYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-is85nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:42:52 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)) 11:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:42:52 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-6KIg0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-Ue4eVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-PY9uuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-VtHi_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-Yt5oYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-LEFxNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:42:53 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)) 11:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:42:53 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-diCXZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:42:53 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)) 11:42:53 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)) 11:42:53 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)) 11:42:53 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)) 11:42:53 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)) 11:42:53 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)) 11:42:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:55 INFO - Downloading blessings-1.6.tar.gz 11:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:42:55 INFO - Installing collected packages: blessings 11:42:55 INFO - Running setup.py install for blessings 11:42:55 INFO - Successfully installed blessings 11:42:55 INFO - Cleaning up... 11:42:55 INFO - Return code: 0 11:42:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:42:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:42:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:42:55 INFO - Reading from file tmpfile_stdout 11:42:55 INFO - Current package versions: 11:42:55 INFO - blessings == 1.6 11:42:55 INFO - blobuploader == 1.2.4 11:42:55 INFO - docopt == 0.6.1 11:42:55 INFO - functools32 == 3.2.3-2 11:42:55 INFO - jsonschema == 2.5.1 11:42:55 INFO - manifestparser == 1.1 11:42:55 INFO - mozInstall == 1.12 11:42:55 INFO - mozcrash == 1.0 11:42:55 INFO - mozdebug == 0.1 11:42:55 INFO - mozdevice == 0.49 11:42:55 INFO - mozfile == 1.2 11:42:55 INFO - mozhttpd == 0.7 11:42:55 INFO - mozinfo == 0.9 11:42:55 INFO - mozleak == 0.1 11:42:55 INFO - mozlog == 3.4 11:42:55 INFO - moznetwork == 0.27 11:42:55 INFO - mozprocess == 0.25 11:42:55 INFO - mozprofile == 0.28 11:42:55 INFO - mozrunner == 6.13 11:42:55 INFO - mozscreenshot == 0.1 11:42:55 INFO - mozsystemmonitor == 0.3 11:42:55 INFO - moztest == 0.8 11:42:55 INFO - mozversion == 1.4 11:42:55 INFO - psutil == 3.1.1 11:42:55 INFO - requests == 1.2.3 11:42:55 INFO - wsgiref == 0.1.2 11:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42:55 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')]} 11:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:55 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')]} 11:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:55 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:55 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 11:42:55 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 11:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:55 INFO - 'HOME': '/Users/cltbld', 11:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:55 INFO - 'LOGNAME': 'cltbld', 11:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:55 INFO - 'MOZ_NO_REMOTE': '1', 11:42:55 INFO - 'NO_EM_RESTART': '1', 11:42:55 INFO - 'PAGER': '/bin/cat', 11:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:55 INFO - 'PWD': '/builds/slave/test', 11:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:55 INFO - 'SHELL': '/bin/bash', 11:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:55 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:55 INFO - 'USER': 'cltbld', 11:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:55 INFO - 'XPC_FLAGS': '0x0', 11:42:55 INFO - 'XPC_SERVICE_NAME': '0', 11:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 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)) 11:42:55 INFO - Cleaning up... 11:42:55 INFO - Return code: 0 11:42:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:42:55 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')]} 11:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:55 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')]} 11:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:55 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 0x10d9b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5c3573450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, '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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:42:55 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 11:42:55 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 11:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:55 INFO - 'HOME': '/Users/cltbld', 11:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:55 INFO - 'LOGNAME': 'cltbld', 11:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:55 INFO - 'MOZ_NO_REMOTE': '1', 11:42:55 INFO - 'NO_EM_RESTART': '1', 11:42:55 INFO - 'PAGER': '/bin/cat', 11:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:55 INFO - 'PWD': '/builds/slave/test', 11:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:55 INFO - 'SHELL': '/bin/bash', 11:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:42:55 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:55 INFO - 'USER': 'cltbld', 11:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:55 INFO - 'XPC_FLAGS': '0x0', 11:42:55 INFO - 'XPC_SERVICE_NAME': '0', 11:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:42:56 INFO - Cleaning up... 11:42:56 INFO - Return code: 0 11:42:56 INFO - Running post-action listener: _resource_record_post_action 11:42:56 INFO - Running post-action listener: _start_resource_monitoring 11:42:56 INFO - Starting resource monitoring. 11:42:56 INFO - [mozharness: 2017-05-23 18:42:56.269617Z] Finished create-virtualenv step (success) 11:42:56 INFO - [mozharness: 2017-05-23 18:42:56.270629Z] Running install step. 11:42:56 INFO - Running pre-action listener: _resource_record_pre_action 11:42:56 INFO - Running main action method: install 11:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:42:56 INFO - Reading from file tmpfile_stdout 11:42:56 INFO - Detecting whether we're running mozinstall >=1.0... 11:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:42:56 INFO - Reading from file tmpfile_stdout 11:42:56 INFO - Output received: 11:42:56 INFO - Usage: mozinstall [options] installer 11:42:56 INFO - Options: 11:42:56 INFO - -h, --help show this help message and exit 11:42:56 INFO - -d DEST, --destination=DEST 11:42:56 INFO - Directory to install application into. [default: 11:42:56 INFO - "/builds/slave/test"] 11:42:56 INFO - --app=APP Application being installed. [default: firefox] 11:42:56 INFO - mkdir: /builds/slave/test/build/application 11:42:56 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'] 11:42:56 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 11:43:11 INFO - Reading from file tmpfile_stdout 11:43:11 INFO - Output received: 11:43:11 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:43:11 INFO - Running post-action listener: _resource_record_post_action 11:43:11 INFO - [mozharness: 2017-05-23 18:43:11.503142Z] Finished install step (success) 11:43:11 INFO - [mozharness: 2017-05-23 18:43:11.503268Z] Running run-tests step. 11:43:11 INFO - Running pre-action listener: _resource_record_pre_action 11:43:11 INFO - Running main action method: run_tests 11:43:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:11 INFO - 2017-05-23 11:43:11.517 screenresolution[3037:44832] starting screenresolution argv=screenresolution get 11:43:11 INFO - 2017-05-23 11:43:11.520 screenresolution[3037:44832] Display 0: 1600x1200x32@60 11:43:11 INFO - 2017-05-23 11:43:11.526 screenresolution[3038:44834] starting screenresolution argv=screenresolution list 11:43:11 INFO - Available Modes on Display 0 11:43:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:11 INFO - Intel Iris: 11:43:11 INFO - Chipset Model: Intel Iris 11:43:11 INFO - Type: GPU 11:43:11 INFO - Bus: Built-In 11:43:11 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:11 INFO - Vendor: Intel (0x8086) 11:43:11 INFO - Device ID: 0x0a2e 11:43:11 INFO - Revision ID: 0x0009 11:43:11 INFO - Displays: 11:43:11 INFO - Display: 11:43:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:11 INFO - Display Serial Number: 1600x1200 60 11:43:11 INFO - Main Display: Yes 11:43:11 INFO - Mirror: Off 11:43:11 INFO - Online: Yes 11:43:11 INFO - Rotation: Supported 11:43:11 INFO - Return code: 0 11:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:43:11 INFO - Python 2.7.3 11:43:11 INFO - Return code: 0 11:43:11 INFO - grabbing minidump binary from tooltool 11:43:11 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')]} 11:43:11 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 0x10d797d30>, '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 0x10d6bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:43:11 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 11:43:11 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 11:43:11 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 11:43:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:43:11 INFO - Return code: 0 11:43:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:43:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:43:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:43:11 INFO - ENV: RUST_BACKTRACE is now 1 11:43:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:43:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:43:11 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--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 11:43:11 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0355 --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 11:43:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIazE3lGbh/Render', 11:43:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:43:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:43:11 INFO - 'HOME': '/Users/cltbld', 11:43:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:43:11 INFO - 'LOGNAME': 'cltbld', 11:43:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:43:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:11 INFO - 'MOZ_NO_REMOTE': '1', 11:43:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:43:11 INFO - 'NO_EM_RESTART': '1', 11:43:11 INFO - 'PAGER': '/bin/cat', 11:43:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:11 INFO - 'PWD': '/builds/slave/test', 11:43:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:43:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:43:11 INFO - 'RUST_BACKTRACE': '1', 11:43:11 INFO - 'SHELL': '/bin/bash', 11:43:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZYMU7Qn7vX/Listeners', 11:43:11 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:43:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:43:11 INFO - 'USER': 'cltbld', 11:43:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:43:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:11 INFO - 'XPC_FLAGS': '0x0', 11:43:11 INFO - 'XPC_SERVICE_NAME': '0', 11:43:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:43:11 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--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 11:43:12 INFO - mozversion application_buildid: 20170523100428 11:43:12 INFO - mozversion application_changeset: a2d407b9da248932f50c95533d13cd8043ca2e21 11:43:12 INFO - mozversion application_display_name: Firefox 11:43:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:43:12 INFO - mozversion application_name: Firefox 11:43:12 INFO - mozversion application_remotingname: firefox 11:43:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:43:12 INFO - mozversion application_vendor: Mozilla 11:43:12 INFO - mozversion application_version: 54.0 11:43:12 INFO - mozversion platform_buildid: 20170523100428 11:43:12 INFO - mozversion platform_changeset: a2d407b9da248932f50c95533d13cd8043ca2e21 11:43:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:43:12 INFO - mozversion platform_version: 54.0 11:43:12 INFO - using testdate: 1495564992 11:43:12 INFO - actual date: 1495564992 11:43:12 INFO - starting webserver on 'localhost:57974' 11:43:12 INFO - SUITE-START | Running 2 tests 11:43:12 INFO - TEST-START | dromaeo_css 11:43:12 INFO - operating with platform_type : mac_ 11:43:12 INFO - Initialising browser for dromaeo_css test... 11:43:12 INFO - TEST-INFO | started process 3048 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpZfBEUB/profile http://localhost:57974/getInfo.html) 11:43:14 INFO - PROCESS | 3048 | __metrics Screen width/height:1600/1200 11:43:14 INFO - PROCESS | 3048 | colorDepth:24 11:43:14 INFO - PROCESS | 3048 | Browser inner width/height: 1024/689 11:43:14 INFO - PROCESS | 3048 | __metrics 11:43:14 INFO - PROCESS | 3048 | 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" 11:43:14 INFO - PROCESS | 3048 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:43:15 INFO - TEST-INFO | 3048: exit 0 11:43:15 INFO - Browser initialized. 11:43:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:43:15 INFO - TEST-INFO | started process 3051 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpZfBEUB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:43:27 INFO - PROCESS | 3051 | RSS: Main: 165142528 11:43:27 INFO - PROCESS | 3051 | 11:46:30 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57974/tests/dromaeo/cssquery-ext.html) 11:46:33 INFO - PROCESS | 3051 | RSS: Main: 389730304 11:46:33 INFO - PROCESS | 3051 | 11:49:36 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-ext.html (next: http://localhost:57974/tests/dromaeo/cssquery-jquery.html) 11:49:36 INFO - PROCESS | 3051 | RSS: Main: 302424064 11:49:36 INFO - PROCESS | 3051 | 11:52:39 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57974/tests/dromaeo/cssquery-mootools.html) 11:52:39 INFO - PROCESS | 3051 | RSS: Main: 279941120 11:52:39 INFO - PROCESS | 3051 | 11:55:42 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57974/tests/dromaeo/cssquery-prototype.html) 11:55:42 INFO - PROCESS | 3051 | RSS: Main: 266489856 11:55:42 INFO - PROCESS | 3051 | 11:58:45 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57974/tests/dromaeo/cssquery-yui.html) 11:58:46 INFO - PROCESS | 3051 | RSS: Main: 280064000 11:58:46 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | Cycle 1(1): loaded http://localhost:57974/tests/dromaeo/cssquery-yui.html (next: http://localhost:57974/tests/dromaeo/cssquery-dojo.html) 12:01:49 INFO - PROCESS | 3051 | RSS: Main: 520548352 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | __start_tp_report 12:01:49 INFO - PROCESS | 3051 | _x_x_mozilla_page_load 12:01:49 INFO - PROCESS | 3051 | _x_x_mozilla_page_load_details 12:01:49 INFO - PROCESS | 3051 | |i|pagename|runs| 12:01:49 INFO - PROCESS | 3051 | |0;dojo.html;2996.7452766514843;3525.5769307683067;3540.9822950885246;3541.645835416458;3581.588117366502;6142.416470435307;6174.845628859281;6178.567500274982;6185.690715464226;6193.04007878779;6173.074038894169;6219.222597175353;6232.6883655817255;6245.56281060326;6254.9374506255035;4648.930266046012;5504.80733174339;5536.529395001409;5590.8043218487155;5618.438156184369;5486.670799752008;5489.670619762807;5508.6970216638065;5653.293338332708;5662.094064949609;5541.639793413436;5543.6119471637;5546.029444847156;5558.499735023847;5562.805301814437;5482.753276102585;5522.861928451781;5525.502704756591;5547.611667183299;5547.77808887648;5412.83761487156;5483.122700367961;5483.561315094783;5492.5605951523785;5565.6104072714925;5062.620303477217;5114.641975061747;6108.633481991094;6117.309816958451;6132.967444181115;6350.396712312323;6351.714172862232;6355.3644635536075;6358.713857876407;6364.840878978016;6287.025511045796;6297.685115744194;6303.338149494283;6406.903896441557;6448.387403196689;6253.749850005949;6321.810345689637;6324.905126423061;6328.367163283635;6345.841353966188;6758.831029224309;6768.424683901825;6779.72881084761;6794.592324460449;6817.0796942413135;6318.431341179224;6371.776987805404;6423.710933008026;6424.100625912376;6429.742810287629;6515.804525864231;6523.18460192476;6527.5757075789925;6549.541532092708;6552.148220731275;6467.159269294962;6489.545731798728;6501.544891857619;6504.772332968323;6513.283538810727;6260.342664020303;6410.134631824644;6441.677916104176;6498.740050397932;6511.73953041873;5259.684418934876;5261.421243663271;5265.315508983807;5267.183586544015;5267.26258323835;4668.996165824364;4684.5549672781035;4706.529347065266;4712.52874712526;4730.455997560256;6277.654728989858;6289.276733175743;6300.495960323163;6311.305756366796;6311.33730958252;6550.7707230246715;6599.802005939733;6609.140811694545;6612.537122401482;6623.86752264952;6018.097285407224;6044.667543285073;6115.6330620162935;6247.125402443661;6257.405546473078;5681.687507187062;5683.971580142155;5694.857628559319;5695.658260504383;5709.857253568611;6591.769288074895;6757.966210168917;6772.5936443813525;6840.0765896603325;6881.931180688129;6599;6608.5374023819795;6608.900866486911;6613.801585952429;6623.072769812134;6511.641859697762;6862.965685171542;6870.931290687029;6873.656317184221;6894.827629309308;6337.841553961188;6378.872422551616;6397.040443933447;6431.678416079271;6475.805725828232;6544.345565443608;6584.703688333941;6594.868102637824;6595.8021259362295;6598.439132673777;5339.813106541408;6395.8720825582295;6410.743570257324;6412.166418365583;6414.005829096497;6269.780557680459;6285.371462853678;6317.147185129948;6322.399372059647;6399.296077431386;4680.859574212779;6183.876322473615;6229.283632382153;6230.314665386894;6287.811365659038;6267.310595834363;6281.7173227203975;6296.433321001132;6338.271098823694;6340.873182536416;6200.56596038291;6283.434490895841;6330.936690633219;6331.9366806331345;6336.778212762715 12:01:49 INFO - PROCESS | 3051 | |1;ext.html;813.9511629302241;831.5301854452234;899.7030979776674;909.2907532124945;910.4855756497574;41307.79346103269;41755.58244417559;41979.370309445345;42062.789686051605;42074;43325.783371083104;43492.912677183085;43622;43636.56363436364;43666.12667746643;8060.959695201515;9695.466749328783;9852.211823054175;9852.507374631276;9871.703848884545;8154.714584989527;11825.526978920832;11998.580049698263;12140;12239.449224784861;11927.045836333073;11953.701157471089;11970.640880773592;11984.940075299655;11989.820152697717;11935.94032029843;11948.163628545919;11961.222520536225;11965.94017029918;11971.760564788656;11735.4132293385;11781.410929453494;11786.528538858436;11795.351255680935;11806.23259488139;39962.60037399647;40137.99655008619;40176.39735403971;40230.79884600588;40300.19399611992;4074.5314288856866;4094.508658960914;4119.526254480743;4119.773412462313;4131.111810960658;28485.869548915292;29165.979190728223;30525.93159239416;30671.539926900892;30741.846290768626;3297.8351082445784;3301.570795796555;3337.7496687748517;3459.3254315408503;3462.6537346265422;333046;337197;338190;338746;339486.99999999756;44712.8700990934;45915.704214789126;53235;53284.73357633187;53427.73286133545;46995.06009879783;47066.294005589945;47106.76446617814;47164;54122.72938635282;15241.466548670744;15614.921925390301;15634.82738794595;15663.765043524356;15670.451534196476;10994.28537145083;13213.075084743976;13221.405036773369;13225.404856781468;13319.66700832487;12169.391530423443;12186.878131218751;12198.756024879453;12205;12290.692732681755;5678.630888992202;5734;5734.827955161351;5738.655680659173;5753.769849205984;1090.7109615951779;1119.3843386137755;1134.172054400276;1156.2888823373733;1188.7681902029021;27127.32181695434;27916;27961.042727008913;28309.292267693472;28356.291092722848;1149.218531398657;1177.587844254504;1179.929204247748;1222.5293262094158;1223.5839814463125;14031.929840350937;14380.712385752226;14463.49377771773;14797.630059248604;14819;453094;470607;470746;471643;472394;56843;56848.715756420956;56885.715571421875;56922.43077569254;56932.715336423054;1472.7422701027278;1531.8391568885106;1542.144110018928;1544.5520798968623;1547.8684311833395;26260.868695656398;27574.586381203895;27609.03368382097;27631.894724210808;27756;45633.771831140635;45705.085898282516;45789;45795.85510362268;45856.8535786608;424571.87714061234;425687;426046;426670;427279;28713.569296460988;28946;30045.399092018473;35309.646903530636;35431.75988840378;36589.902302930954;45275.32087018631;46430.767846160554;46692.7665361671;46930.296045560026;30837.691623083483;31998.52002220015;32037;32224.355512889142;32920.341593167526;31195.376092478476;31250.906218282253;32249;32513.674863251064;32558.34883302368;36761.448578270814;37477;37764.43353349757;37995.050123747125;38221.80889095537;30977;30995.915142969887;31138.22154446157;31818;31982.52026219562;24000.759992399853;25037.12370067039;25147.49705005926;25229.99080036778;25549.744502554735 12:01:49 INFO - PROCESS | 3051 | |2;jquery.html;366.6004055579419;392.1822999046987;407.814444427785;429.37311525173243;465.59958435745256;13954.790678139836;15697.45058922938;22039.669404958917;22465.55068898621;22739.431514212152;21745.565088698215;22390.776092239073;22680.88659556704;22795.430114247152;23990.400239994007;6530.477561795069;7018.33325834045;7071.575705457663;7099.077119974422;7120.145582530078;5804.780995990847;6940.30596940307;7079;7887.684492620288;8071.434999550034;7427.257274272583;8038.799030024237;8060.193980601922;8060.798480038016;8066.677332906676;6661.666916654172;7639.3124618784295;7761.3402860756905;7789.493682910593;7791.727289544895;7320;7555.5088919220625;7609.771706848804;7626.6186690665245;7636.312731854105;25736.74263257381;26507.73492265072;26660.46679066427;26803.597946030826;26932.326691832666;2630.7237740037403;2940.294329360959;3018.803777754439;3034.317278612317;3054.2517083314506;15893.761593576106;16011.398860114012;16035.91982040094;16377.508674739776;16758.83241167585;2143.95712085759;2193.7038499802484;2226.421130506064;2244.753077161511;2275.3970197897406;167502.162489188;172252;174856.12571937186;175882.24117758914;180839.09580452146;15933.12367819781;18284.63430731378;18923.621527569372;20097.799022009884;20243.493912652008;31994.68005319917;32549.674503255137;32551.348973020406;32559.348813023607;32619;10292.897071029343;10583.94708026455;10713.892861071445;10801.351918884891;10926.617568385069;8954.910450895539;8970.295643827652;8971.846756778457;9151.63393464254;9151.771205719777;9507.144357007903;9574.288912886163;9658.323917325719;9681.419114853132;9703.010882769015;2954.542045982876;3430.8456119474686;3507.3336066147863;3580.606133325332;3985.5289720373553;1007.6644169124503;1061.1828891753307;1063.3885515828429;1065.6536625596711;1102.7408558988632;21491.817950012588;22026;22413.327600172022;22418.55162896733;22463;1049.0191670787838;1070.8875568065396;1093.606301731376;1121.9046381057537;1123.8707548631849;10913.508892099875;11605.88394116065;11620.767584648429;11640.534378624929;11646.359450230319;737359;741275;742729;751300;765009;98330.05009849716;140731;163265;163862.9999999976;169212;1270.1997741423029;1287.7617640736494;1339.591424615482;1339.8727120923497;1343.8387393512842;19988.50028749293;20296.695549566473;20835.583288334452;20846.687299690213;20856.374308770763;67811;68535;70175.29824701892;70189.19243230417;70871.29128708853;100947;101762;102443.46334805133;104408;104742.47628761808;11776.470058847544;11780.823287650863;11923.224990375598;12312.938435307766;12344.876551234733;11198.608048718255;13198.340082995619;13477.326133693472;13699.904007679163;13796.310184490936;11157.386343751172;11597.826032609686;11966.34185119792;12022.038236941198;12041.397930103634;10868.239223254297;10892.61875834342;10980;11328.546858125583;11377.658670239905;10545.9472702636;11705.648830535098;11728.41357932083;11781.882181178078;11881.287122772661;9903.603855845973;9986.800263994824;11743.295402275891;12018.399080046138;12391.62825115248;11397.601083962021;11623.302601843916;11691.824622630838;11980;12034.699132521757 12:01:49 INFO - PROCESS | 3051 | |3;mootools.html;1592.2357268511114;1603.254486663701;1622.6430185359222;1624.7075526405242;1633.379315859973;10987.39569323687;11769.529218831236;11772.823407648892;11791.292522448659;11804.468798904049;10537.84193237102;10794.622188223413;11017.779644407106;11025.393603351813;11319.434028298572;2452.70567531896;2491.700995880488;2494.4013436775226;2498.1631153563576;2502.2618327593314;2449.289705985262;2490.5517006938744;2525.2802951158924;2526.3810366460243;2529.8229123961332;2473.2951108933958;2483.9379015524664;2497.7502249774966;2521.218422289087;2526.7094284157374;2184.9781502184933;2432.2581612608146;2484.3168128764646;2490.713567939674;2493.1772515070074;2466.987665061681;2489.601663733812;2503.2740505253455;2503.7996960243136;2504.6243063540433;11234.943825280903;11437.828432573519;11452.541898324058;11456.0262377698;11461.025812805921;5964.135200395962;6128.908066379008;6132.570720049598;6138.938610613882;6146.477549408306;3660.8352624131976;3722.590515043343;3794.677452416548;3806.259493164917;3878.26313000529;1293.1788314420317;1876.5402476393372;1897;1937.6122685588302;1973.4770285874256;13334.666633334147;13512.86487135126;13635.659108522266;13907.165570065727;15929.681406371808;4202.978985105055;4264.33902745075;4265.189613973397;4873.95126048735;4889.193283108319;10094.596216151273;12042.63872083839;12060.638180854588;12061.396930153458;12077.18842173681;5106.285120083191;5263.5525980292105;5316.893662126813;6050.576459647782;6062.302835173923;4910.361652985089;5384.530949078516;5570.6657600544095;5575.860603484945;5631.099024156116;3882.281777871103;3966.7024973126554;4026.4765580474937;4030.838766449368;4032.2540330039037;1039.1686650679426;1052.8789189243334;1068.060107105742;1068.529846867376;1073.6939972108069;941.6986564299496;958.3435346787472;974.6004138303261;994.164901482758;1006.6527048168371;6529.183852018497;6560.507961902877;6674.899876501856;6735.461163106939;6817.625030623264;914.2503147419053;955.8279509688323;958.942463452195;1011.5498603121688;1018.0481250031245;3970.6029397060643;4156.355764856452;4167.458230430025;4275.636570891447;4290.656747460258;13539.39072741729;14152.646183845487;14185.929070354583;14201.928990355189;14246.287685615678;13441.059125861291;13994.230317332442;14129.717405652034;14138.85861141396;14158.646033849236;10076;10376.221783366278;10377.273590848568;10386.338185890221;10401.27191096631;6586.736530538725;6846.178458585002;6975.651217439209;7031.296870313133;7081.4334853210585;12331;12621.558245461365;12637.810432843331;12644.3677816107;12748.426320815772;12724.982001440048;12774.105812593401;12949.352532373156;12953.222806631633;13088.73822523525;7844.607769611611;9084.273258139201;9563.234941204826;9564.473953932322;9568.330216884751;10293.330933489298;10412.583496660049;10417;10498.475076246004;10604;7526.473146879666;8751.824963500821;8785.033646298774;8871.822563548822;8907.821843563223;8647.351448641502;8719.084496128069;8805.64777408922;8811.603477843384;8813.986391565051;8060.226750114965;8097.2712455879255;8101.068377136705;8210.261076503144;8210.671573137008;5527.778888844401;5796.275465566804;5868.972929737364;5945.643261404157;6068.696565171812;5873.794417195378;5911.408859114054;5951.732172052355;6050.727717252822;6079.1793107931635 12:01:49 INFO - PROCESS | 3051 | |4;prototype.html;394.06999481224307;396.3856023164095;401.70850714951433;402.1956087824351;404.81783197561106;4716.740579268139;5235.293235413218;5254.894902101955;5261.316028916235;5287.444818294077;4074.694397920153;4694.413198350207;4768.928466073003;4860.441049279334;5254.894902101955;4088.8773336799795;4139.875803725893;4154.356074808395;4156.376543518466;4194.643455306303;4131.566185550519;4164.7917604119675;4189.916201675965;4190.099128687331;4218.742972509708;3904.511936007999;4012.2285052051125;4161.708680392374;4171.434567740833;4201.054762678388;4074.7555146691293;4140.937885931713;4151.481064866892;4173;4195.210185226821;3050.0239923224576;3137.796043257204;3175.666555011737;3603.94594081089;3635.1820840310984;4455.6658250631335;4878.56071964018;5029.7736601853;5069.340985671839;5161.225816127573;3929.6659783918403;4565.543445655408;4725.385699859031;4771.880702982418;4774.591495508833;4750.548697873696;5150.845474635729;5493.395726470085;5535.418781027975;5555.166724991243;3888.494495715567;4039;4052.49343832021;4086.979565102185;4108.794560271975;5533.170024496318;5697.644214438415;5728.7110400160045;5780.421957804186;5823.563232757518;3739.354129723498;3913.8434462621176;4160.251154792167;4176.248275310473;4178.979105104455;3544.503769472276;4010.318245898204;4248.532661407243;4280.636145927569;4324.286492728665;4011;4451.9760455094865;4504.752238626907;4594.333821595917;4813.7593120343845;5513.724313784295;5545.197810711535;5604.271444712162;5613.221455379613;5620.9437905621235;1626.3250750938464;1731.995442643264;1737.1759652364967;1746.318935615111;1762.9471115866545;1269.1243042300782;1279.9360031998363;1305.11252348404;1330.9134906231063;1362.4944436397639;4930.309756634074;5187.325647665854;5213.843584692389;5236.790528378899;5237.633365664443;5360.490753378423;5429.755660995264;5468.507834294933;5536.169574563848;5732.541396688255;4964.971367465843;5034.370703662043;5050.090983622909;5306.787728490895;5318;2717.415755612553;2749.752522273005;2888.7400133988044;3037.9063537126617;3045.3148041690442;5204.34945631796;5543.916841247385;5647.6893770843;6123.142420275287;6158.998325544216;5701.173329867229;5800.767969281266;5872.354041055481;5934.347221805684;6062.575619706667;5231.659942103751;5946.524278057745;5983.81590719393;5987.850303742442;6014.338422773491;5283.815066472655;5289.127293996447;5460.945390546044;5722.828315150552;5734.3978882218435;4936.580390666833;5275.709835959059;5400.540954018952;5475.4524547544925;5795.1165871091225;5972.522198224045;6041.6375017499095;6062.393760623902;6072.848178795565;6097.378127405258;3805.8011726306127;3914.6868250540456;4195.559466256122;4273.252180868273;4498.122866041222;4879.365682461257;5433.402325744086;5554.750036248298;5643.633163844337;5760.366359700346;4957.041968422381;5083.262926875656;5241.266222728897;5533.667979921218;5584.776608935597;5060.746962651926;5299;5413.891722165614;5490.039243132515;5607.8878422432135;4768.380110585602;4825.686330388514;5114.769835357492;5393.759435329975;5509.862253443696;4900.852974410774;5168.58651307887;5239.371275446971;5470.370907345706;5632.267805185464;4500.477020594281;5054.19132938728;5231.973840130775;5354.437784032776;5363.785448582169 12:01:49 INFO - PROCESS | 3051 | |5;yui.html;760.0954863712178;781.3983232910663;812.1431889356726;828.8881001064856;908.2824568590813;14143.646408839772;17255.827441725578;17260.568485787844;17263.74104388435;17266.482005539852;15668.843311566881;15844;16022.59943501413;16065.196740162974;16075.115868627221;957.1624828275259;983.8573406856002;988.4217732626386;990.1138481059447;1006.788574399377;1016.2124353625962;1021.0351218098899;1035.1253191053547;1036.8030074285862;1038.9220808439359;1066.1204506282309;1068.1081297116916;1068.8076146293663;1068.978620427591;1069.5989004123453;1062.1768129699506;1062.6918193723811;1063.2344711807486;1064.1433645915035;1065.073386154047;792.8136887831355;794.2137284088768;794.9562774047425;795.0022721484557;796.577813758703;17691.93848369102;17743.822561774345;17817.287308507643;18243.72634410485;18475;14800.925995369955;14987.925060374737;15042.247887605576;15046.473373431987;15306.081635101818;11942.641720748392;12217.633470995885;12288.447019884126;12588.05589580776;12689.23864568129;3848.422736589506;3938.0942383252013;3941.2905676978135;4093.2427500912427;4094.7133700640966;96158.9999999993;126306;126506.367468163;128644;128940.35529822385;9920.751981200528;11591.478383472764;11633.243839150427;11882.28706277626;12013.879861201276;23305.88347058254;23329.3001209964;23335.066597335917;23340.2997910063;23340.7665923342;8132.878006829902;8262.752117436487;8413;8655.134486551295;8716.08481109487;4521.637206160647;4602.033572949651;4644.530050802605;4653.069386122789;4665.066986602626;3694.6674799268194;3806.1626442182674;3806.638369354907;3808.0479880029993;3811.942820857689;669.4945316286173;680.6664734280266;685.3215316836296;701.8666453373945;702.2485940044104;694.6283738200038;726.3426598927979;730.0800990751585;746.6565379925187;757.5189754505928;9876.35803672759;10727;10782.622608208832;10863.29388589755;11276.041536469327;591.5770224289568;664.5248647217263;716.330231233798;745.3068646159124;752.6011214056559;4873.561379475864;5181.715005674649;5192.792288308501;5203.687778733288;5204.9219261711105;105861;106504.46747766211;109727;111533.44233278782;112793;93856.53071734597;94259;94711;96044;96434;81149.59425202836;81873;82955;83566.58216708878;84515.5774221125;8129.634166462642;9963.50182490887;10200.183985281308;10212.234082443698;10220.131288840532;53667.731661341444;53945.65135871634;54428;54984.45015549954;55319.17021244765;48937.75531122321;61905.69047154736;62279.75440491256;63027;64058;11668.124890633406;11710.212129318874;11888.405579720806;11903.928646421864;12463.750724985268;11424.771504570028;12865.807012894627;12993.220406775623;14131.788023179866;14150.221737804519;12064.215825971283;12713.618591442271;12751.426185821474;12952.740945180854;13007.739845203232;11756.58851940178;11834.644960651194;12572.05709571804;12579.811302830281;12978.480860765463;11708.531658733555;12111.576094836639;12352.073594480271;12466.563670271496;12634.368281586068;8803.647854085764;9331.953340233254;9349.532523373668;9494.762630934281;9579.616815327308;8403.201695838885;9254.76999965167;10161.187105031728;10167.644132455329;10205 12:01:49 INFO - PROCESS | 3051 | __end_tp_report 12:01:49 INFO - PROCESS | 3051 | __start_cc_report 12:01:49 INFO - PROCESS | 3051 | _x_x_mozilla_cycle_collect,2602 12:01:49 INFO - PROCESS | 3051 | __end_cc_report 12:01:49 INFO - PROCESS | 3051 | __startTimestamp1495566109966__endTimestamp 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | ------- Summary: start ------- 12:01:49 INFO - PROCESS | 3051 | Number of tests: 6 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#0] dojo.html Cycles:165 Average:6048.42 Median:6296.43 stddev:699.91 (11.1%) stddev-sans-first:659.85 12:01:49 INFO - PROCESS | 3051 | Values: 2996.7 3525.6 3541.0 3541.6 3581.6 6142.4 6174.8 6178.6 6185.7 6193.0 6173.1 6219.2 6232.7 6245.6 6254.9 4648.9 5504.8 5536.5 5590.8 5618.4 5486.7 5489.7 5508.7 5653.3 5662.1 5541.6 5543.6 5546.0 5558.5 5562.8 5482.8 5522.9 5525.5 5547.6 5547.8 5412.8 5483.1 5483.6 5492.6 5565.6 5062.6 5114.6 6108.6 6117.3 6133.0 6350.4 6351.7 6355.4 6358.7 6364.8 6287.0 6297.7 6303.3 6406.9 6448.4 6253.7 6321.8 6324.9 6328.4 6345.8 6758.8 6768.4 6779.7 6794.6 6817.1 6318.4 6371.8 6423.7 6424.1 6429.7 6515.8 6523.2 6527.6 6549.5 6552.1 6467.2 6489.5 6501.5 6504.8 6513.3 6260.3 6410.1 6441.7 6498.7 6511.7 5259.7 5261.4 5265.3 5267.2 5267.3 4669.0 4684.6 4706.5 4712.5 4730.5 6277.7 6289.3 6300.5 6311.3 6311.3 6550.8 6599.8 6609.1 6612.5 6623.9 6018.1 6044.7 6115.6 6247.1 6257.4 5681.7 5684.0 5694.9 5695.7 5709.9 6591.8 6758.0 6772.6 6840.1 6881.9 6599.0 6608.5 6608.9 6613.8 6623.1 6511.6 6863.0 6870.9 6873.7 6894.8 6337.8 6378.9 6397.0 6431.7 6475.8 6544.3 6584.7 6594.9 6595.8 6598.4 5339.8 6395.9 6410.7 6412.2 6414.0 6269.8 6285.4 6317.1 6322.4 6399.3 4680.9 6183.9 6229.3 6230.3 6287.8 6267.3 6281.7 6296.4 6338.3 6340.9 6200.6 6283.4 6330.9 6331.9 6336.8 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#1] ext.html Cycles:180 Average:55738.62 Median:32739.35 stddev:109559.78 (334.6%) stddev-sans-first:109789.51 12:01:49 INFO - PROCESS | 3051 | Values: 814.0 831.5 899.7 909.3 910.5 41307.8 41755.6 41979.4 42062.8 42074.0 43325.8 43492.9 43622.0 43636.6 43666.1 8061.0 9695.5 9852.2 9852.5 9871.7 8154.7 11825.5 11998.6 12140.0 12239.4 11927.0 11953.7 11970.6 11984.9 11989.8 11935.9 11948.2 11961.2 11965.9 11971.8 11735.4 11781.4 11786.5 11795.4 11806.2 39962.6 40138.0 40176.4 40230.8 40300.2 4074.5 4094.5 4119.5 4119.8 4131.1 28485.9 29166.0 30525.9 30671.5 30741.8 3297.8 3301.6 3337.7 3459.3 3462.7 333046.0 337197.0 338190.0 338746.0 339487.0 44712.9 45915.7 53235.0 53284.7 53427.7 46995.1 47066.3 47106.8 47164.0 54122.7 15241.5 15614.9 15634.8 15663.8 15670.5 10994.3 13213.1 13221.4 13225.4 13319.7 12169.4 12186.9 12198.8 12205.0 12290.7 5678.6 5734.0 5734.8 5738.7 5753.8 1090.7 1119.4 1134.2 1156.3 1188.8 27127.3 27916.0 27961.0 28309.3 28356.3 1149.2 1177.6 1179.9 1222.5 1223.6 14031.9 14380.7 14463.5 14797.6 14819.0 453094.0 470607.0 470746.0 471643.0 472394.0 56843.0 56848.7 56885.7 56922.4 56932.7 1472.7 1531.8 1542.1 1544.6 1547.9 26260.9 27574.6 27609.0 27631.9 27756.0 45633.8 45705.1 45789.0 45795.9 45856.9 424571.9 425687.0 426046.0 426670.0 427279.0 28713.6 28946.0 30045.4 35309.6 35431.8 36589.9 45275.3 46430.8 46692.8 46930.3 30837.7 31998.5 32037.0 32224.4 32920.3 31195.4 31250.9 32249.0 32513.7 32558.3 36761.4 37477.0 37764.4 37995.1 38221.8 30977.0 30995.9 31138.2 31818.0 31982.5 24000.8 25037.1 25147.5 25230.0 25549.7 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#2] jquery.html Cycles:180 Average:44246.77 Median:21618.69 stddev:125342.91 (579.8%) stddev-sans-first:125651.23 12:01:49 INFO - PROCESS | 3051 | Values: 366.6 392.2 407.8 429.4 465.6 13954.8 15697.5 22039.7 22465.6 22739.4 21745.6 22390.8 22680.9 22795.4 23990.4 6530.5 7018.3 7071.6 7099.1 7120.1 5804.8 6940.3 7079.0 7887.7 8071.4 7427.3 8038.8 8060.2 8060.8 8066.7 6661.7 7639.3 7761.3 7789.5 7791.7 7320.0 7555.5 7609.8 7626.6 7636.3 25736.7 26507.7 26660.5 26803.6 26932.3 2630.7 2940.3 3018.8 3034.3 3054.3 15893.8 16011.4 16035.9 16377.5 16758.8 2144.0 2193.7 2226.4 2244.8 2275.4 167502.2 172252.0 174856.1 175882.2 180839.1 15933.1 18284.6 18923.6 20097.8 20243.5 31994.7 32549.7 32551.3 32559.3 32619.0 10292.9 10583.9 10713.9 10801.4 10926.6 8954.9 8970.3 8971.8 9151.6 9151.8 9507.1 9574.3 9658.3 9681.4 9703.0 2954.5 3430.8 3507.3 3580.6 3985.5 1007.7 1061.2 1063.4 1065.7 1102.7 21491.8 22026.0 22413.3 22418.6 22463.0 1049.0 1070.9 1093.6 1121.9 1123.9 10913.5 11605.9 11620.8 11640.5 11646.4 737359.0 741275.0 742729.0 751300.0 765009.0 98330.1 140731.0 163265.0 163863.0 169212.0 1270.2 1287.8 1339.6 1339.9 1343.8 19988.5 20296.7 20835.6 20846.7 20856.4 67811.0 68535.0 70175.3 70189.2 70871.3 100947.0 101762.0 102443.5 104408.0 104742.5 11776.5 11780.8 11923.2 12312.9 12344.9 11198.6 13198.3 13477.3 13699.9 13796.3 11157.4 11597.8 11966.3 12022.0 12041.4 10868.2 10892.6 10980.0 11328.5 11377.7 10545.9 11705.6 11728.4 11781.9 11881.3 9903.6 9986.8 11743.3 12018.4 12391.6 11397.6 11623.3 11691.8 11980.0 12034.7 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#3] mootools.html Cycles:180 Average:6841.84 Median:2503.54 stddev:4218.17 (168.5%) stddev-sans-first:4211.56 12:01:49 INFO - PROCESS | 3051 | Values: 1592.2 1603.3 1622.6 1624.7 1633.4 10987.4 11769.5 11772.8 11791.3 11804.5 10537.8 10794.6 11017.8 11025.4 11319.4 2452.7 2491.7 2494.4 2498.2 2502.3 2449.3 2490.6 2525.3 2526.4 2529.8 2473.3 2483.9 2497.8 2521.2 2526.7 2185.0 2432.3 2484.3 2490.7 2493.2 2467.0 2489.6 2503.3 2503.8 2504.6 11234.9 11437.8 11452.5 11456.0 11461.0 5964.1 6128.9 6132.6 6138.9 6146.5 3660.8 3722.6 3794.7 3806.3 3878.3 1293.2 1876.5 1897.0 1937.6 1973.5 13334.7 13512.9 13635.7 13907.2 15929.7 4203.0 4264.3 4265.2 4874.0 4889.2 10094.6 12042.6 12060.6 12061.4 12077.2 5106.3 5263.6 5316.9 6050.6 6062.3 4910.4 5384.5 5570.7 5575.9 5631.1 3882.3 3966.7 4026.5 4030.8 4032.3 1039.2 1052.9 1068.1 1068.5 1073.7 941.7 958.3 974.6 994.2 1006.7 6529.2 6560.5 6674.9 6735.5 6817.6 914.3 955.8 958.9 1011.5 1018.0 3970.6 4156.4 4167.5 4275.6 4290.7 13539.4 14152.6 14185.9 14201.9 14246.3 13441.1 13994.2 14129.7 14138.9 14158.6 10076.0 10376.2 10377.3 10386.3 10401.3 6586.7 6846.2 6975.7 7031.3 7081.4 12331.0 12621.6 12637.8 12644.4 12748.4 12725.0 12774.1 12949.4 12953.2 13088.7 7844.6 9084.3 9563.2 9564.5 9568.3 10293.3 10412.6 10417.0 10498.5 10604.0 7526.5 8751.8 8785.0 8871.8 8907.8 8647.4 8719.1 8805.6 8811.6 8814.0 8060.2 8097.3 8101.1 8210.3 8210.7 5527.8 5796.3 5869.0 5945.6 6068.7 5873.8 5911.4 5951.7 6050.7 6079.2 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#4] prototype.html Cycles:180 Average:4574.76 Median:4961.01 stddev:1296.89 (26.1%) stddev-sans-first:1261.99 12:01:49 INFO - PROCESS | 3051 | Values: 394.1 396.4 401.7 402.2 404.8 4716.7 5235.3 5254.9 5261.3 5287.4 4074.7 4694.4 4768.9 4860.4 5254.9 4088.9 4139.9 4154.4 4156.4 4194.6 4131.6 4164.8 4189.9 4190.1 4218.7 3904.5 4012.2 4161.7 4171.4 4201.1 4074.8 4140.9 4151.5 4173.0 4195.2 3050.0 3137.8 3175.7 3603.9 3635.2 4455.7 4878.6 5029.8 5069.3 5161.2 3929.7 4565.5 4725.4 4771.9 4774.6 4750.5 5150.8 5493.4 5535.4 5555.2 3888.5 4039.0 4052.5 4087.0 4108.8 5533.2 5697.6 5728.7 5780.4 5823.6 3739.4 3913.8 4160.3 4176.2 4179.0 3544.5 4010.3 4248.5 4280.6 4324.3 4011.0 4452.0 4504.8 4594.3 4813.8 5513.7 5545.2 5604.3 5613.2 5620.9 1626.3 1732.0 1737.2 1746.3 1762.9 1269.1 1279.9 1305.1 1330.9 1362.5 4930.3 5187.3 5213.8 5236.8 5237.6 5360.5 5429.8 5468.5 5536.2 5732.5 4965.0 5034.4 5050.1 5306.8 5318.0 2717.4 2749.8 2888.7 3037.9 3045.3 5204.3 5543.9 5647.7 6123.1 6159.0 5701.2 5800.8 5872.4 5934.3 6062.6 5231.7 5946.5 5983.8 5987.9 6014.3 5283.8 5289.1 5460.9 5722.8 5734.4 4936.6 5275.7 5400.5 5475.5 5795.1 5972.5 6041.6 6062.4 6072.8 6097.4 3805.8 3914.7 4195.6 4273.3 4498.1 4879.4 5433.4 5554.8 5643.6 5760.4 4957.0 5083.3 5241.3 5533.7 5584.8 5060.7 5299.0 5413.9 5490.0 5607.9 4768.4 4825.7 5114.8 5393.8 5509.9 4900.9 5168.6 5239.4 5470.4 5632.3 4500.5 5054.2 5232.0 5354.4 5363.8 12:01:49 INFO - PROCESS | 3051 | 12:01:49 INFO - PROCESS | 3051 | [#5] yui.html Cycles:180 Average:21423.05 Median:23332.18 stddev:31728.87 (136.0%) stddev-sans-first:31779.95 12:01:49 INFO - PROCESS | 3051 | Values: 760.1 781.4 812.1 828.9 908.3 14143.6 17255.8 17260.6 17263.7 17266.5 15668.8 15844.0 16022.6 16065.2 16075.1 957.2 983.9 988.4 990.1 1006.8 1016.2 1021.0 1035.1 1036.8 1038.9 1066.1 1068.1 1068.8 1069.0 1069.6 1062.2 1062.7 1063.2 1064.1 1065.1 792.8 794.2 795.0 795.0 796.6 17691.9 17743.8 17817.3 18243.7 18475.0 14800.9 14987.9 15042.2 15046.5 15306.1 11942.6 12217.6 12288.4 12588.1 12689.2 3848.4 3938.1 3941.3 4093.2 4094.7 96159.0 126306.0 126506.4 128644.0 128940.4 9920.8 11591.5 11633.2 11882.3 12013.9 23305.9 23329.3 23335.1 23340.3 23340.8 8132.9 8262.8 8413.0 8655.1 8716.1 4521.6 4602.0 4644.5 4653.1 4665.1 3694.7 3806.2 3806.6 3808.0 3811.9 669.5 680.7 685.3 701.9 702.2 694.6 726.3 730.1 746.7 757.5 9876.4 10727.0 10782.6 10863.3 11276.0 591.6 664.5 716.3 745.3 752.6 4873.6 5181.7 5192.8 5203.7 5204.9 105861.0 106504.5 109727.0 111533.4 112793.0 93856.5 94259.0 94711.0 96044.0 96434.0 81149.6 81873.0 82955.0 83566.6 84515.6 8129.6 9963.5 10200.2 10212.2 10220.1 53667.7 53945.7 54428.0 54984.5 55319.2 48937.8 61905.7 62279.8 63027.0 64058.0 11668.1 11710.2 11888.4 11903.9 12463.8 11424.8 12865.8 12993.2 14131.8 14150.2 12064.2 12713.6 12751.4 12952.7 13007.7 11756.6 11834.6 12572.1 12579.8 12978.5 11708.5 12111.6 12352.1 12466.6 12634.4 8803.6 9332.0 9349.5 9494.8 9579.6 8403.2 9254.8 10161.2 10167.6 10205.0 12:01:49 INFO - PROCESS | 3051 | -------- Summary: end -------- 12:01:49 INFO - PROCESS | 3051 | 12:01:50 INFO - TEST-INFO | 3051: exit 0 12:01:50 INFO - TEST-OK | dromaeo_css | took 1118470ms 12:01:50 INFO - TEST-START | kraken 12:01:50 INFO - operating with platform_type : mac_ 12:01:50 INFO - Initialising browser for kraken test... 12:01:50 INFO - TEST-INFO | started process 3054 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpZxlLvq/profile http://localhost:57974/getInfo.html) 12:01:53 INFO - PROCESS | 3054 | __metrics Screen width/height:1600/1200 12:01:53 INFO - PROCESS | 3054 | colorDepth:24 12:01:53 INFO - PROCESS | 3054 | Browser inner width/height: 1024/689 12:01:53 INFO - PROCESS | 3054 | __metrics 12:01:53 INFO - PROCESS | 3054 | 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" 12:01:53 INFO - PROCESS | 3054 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:01:53 INFO - TEST-INFO | 3054: exit 0 12:01:53 INFO - Browser initialized. 12:01:53 INFO - Running cycle 1/1 for kraken test... 12:01:53 INFO - TEST-INFO | started process 3056 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpZxlLvq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:05 INFO - PROCESS | 3056 | RSS: Main: 168857600 12:02:05 INFO - PROCESS | 3056 | 12:02:08 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57974/tests/kraken/driver.html?testName=audio-beat-detection) 12:02:09 INFO - PROCESS | 3056 | RSS: Main: 220008448 12:02:09 INFO - PROCESS | 3056 | 12:02:11 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57974/tests/kraken/driver.html?testName=audio-dft) 12:02:12 INFO - PROCESS | 3056 | RSS: Main: 231018496 12:02:12 INFO - PROCESS | 3056 | 12:02:15 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57974/tests/kraken/driver.html?testName=audio-fft) 12:02:16 INFO - PROCESS | 3056 | RSS: Main: 327016448 12:02:16 INFO - PROCESS | 3056 | 12:02:18 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57974/tests/kraken/driver.html?testName=audio-oscillator) 12:02:18 INFO - PROCESS | 3056 | RSS: Main: 230731776 12:02:18 INFO - PROCESS | 3056 | 12:02:20 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57974/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:02:21 INFO - PROCESS | 3056 | RSS: Main: 232271872 12:02:21 INFO - PROCESS | 3056 | 12:02:24 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57974/tests/kraken/driver.html?testName=imaging-darkroom) 12:02:25 INFO - PROCESS | 3056 | RSS: Main: 272838656 12:02:25 INFO - PROCESS | 3056 | 12:02:28 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57974/tests/kraken/driver.html?testName=imaging-desaturate) 12:02:28 INFO - PROCESS | 3056 | RSS: Main: 274534400 12:02:28 INFO - PROCESS | 3056 | 12:02:31 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57974/tests/kraken/driver.html?testName=json-parse-financial) 12:02:32 INFO - PROCESS | 3056 | RSS: Main: 272834560 12:02:32 INFO - PROCESS | 3056 | 12:02:33 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57974/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:02:34 INFO - PROCESS | 3056 | RSS: Main: 249864192 12:02:34 INFO - PROCESS | 3056 | 12:02:36 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:02:37 INFO - PROCESS | 3056 | RSS: Main: 252563456 12:02:37 INFO - PROCESS | 3056 | 12:02:39 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:02:39 INFO - PROCESS | 3056 | RSS: Main: 259543040 12:02:39 INFO - PROCESS | 3056 | 12:02:42 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:02:43 INFO - PROCESS | 3056 | RSS: Main: 262410240 12:02:43 INFO - PROCESS | 3056 | 12:02:46 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:02:46 INFO - PROCESS | 3056 | RSS: Main: 263700480 12:02:46 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:57974/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57974/tests/kraken/driver.html?testName=ai-astar) 12:02:48 INFO - PROCESS | 3056 | RSS: Main: 308072448 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | __start_tp_report 12:02:48 INFO - PROCESS | 3056 | _x_x_mozilla_page_load 12:02:48 INFO - PROCESS | 3056 | _x_x_mozilla_page_load_details 12:02:48 INFO - PROCESS | 3056 | |i|pagename|runs| 12:02:48 INFO - PROCESS | 3056 | |0;ai-astar;116;114;114;114;116;111;114;115;114;114 12:02:48 INFO - PROCESS | 3056 | |1;audio-beat-detection;136;108;105;140;106;108;105;104;129;129 12:02:48 INFO - PROCESS | 3056 | |2;audio-dft;186;185;185;183;184;184;183;184;185;182 12:02:48 INFO - PROCESS | 3056 | |3;audio-fft;82;59;59;93;58;60;60;57;74;59 12:02:48 INFO - PROCESS | 3056 | |4;audio-oscillator;91;69;86;68;69;68;80;69;94;112 12:02:48 INFO - PROCESS | 3056 | |5;imaging-gaussian-blur;88;87;87;87;88;87;88;87;87;87 12:02:48 INFO - PROCESS | 3056 | |6;imaging-darkroom;92;94;94;94;94;93;108;128;105;93 12:02:48 INFO - PROCESS | 3056 | |7;imaging-desaturate;85;86;83;84;86;85;85;85;84;84 12:02:48 INFO - PROCESS | 3056 | |8;json-parse-financial;62;59;59;60;63;66;64;64;64;63 12:02:48 INFO - PROCESS | 3056 | |9;json-stringify-tinderbox;56;63;92;91;89;75;60;61;60;59 12:02:48 INFO - PROCESS | 3056 | |10;stanford-crypto-aes;84;83;84;83;83;83;80;87;83;119 12:02:48 INFO - PROCESS | 3056 | |11;stanford-crypto-ccm;136;136;140;128;140;132;140;122;151;134 12:02:48 INFO - PROCESS | 3056 | |12;stanford-crypto-pbkdf2;159;162;163;161;159;162;160;159;202;208 12:02:48 INFO - PROCESS | 3056 | |13;stanford-crypto-sha256-iterative;66;65;63;64;63;62;62;61;62;62 12:02:48 INFO - PROCESS | 3056 | __end_tp_report 12:02:48 INFO - PROCESS | 3056 | __start_cc_report 12:02:48 INFO - PROCESS | 3056 | _x_x_mozilla_cycle_collect,2016 12:02:48 INFO - PROCESS | 3056 | __end_cc_report 12:02:48 INFO - PROCESS | 3056 | __startTimestamp1495566168754__endTimestamp 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | ------- Summary: start ------- 12:02:48 INFO - PROCESS | 3056 | Number of tests: 14 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.40 (1.2%) stddev-sans-first:1.32 12:02:48 INFO - PROCESS | 3056 | Values: 116.0 114.0 114.0 114.0 116.0 111.0 114.0 115.0 114.0 114.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:118.50 stddev:14.60 (12.3%) stddev-sans-first:13.77 12:02:48 INFO - PROCESS | 3056 | Values: 136.0 108.0 105.0 140.0 106.0 108.0 105.0 104.0 129.0 129.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.20 (0.6%) stddev-sans-first:1.05 12:02:48 INFO - PROCESS | 3056 | Values: 186.0 185.0 185.0 183.0 184.0 184.0 183.0 184.0 185.0 182.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.53 (20.9%) stddev-sans-first:11.90 12:02:48 INFO - PROCESS | 3056 | Values: 82.0 59.0 59.0 93.0 58.0 60.0 60.0 57.0 74.0 59.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:74.50 stddev:15.00 (20.1%) stddev-sans-first:15.43 12:02:48 INFO - PROCESS | 3056 | Values: 91.0 69.0 86.0 68.0 69.0 68.0 80.0 69.0 94.0 112.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 12:02:48 INFO - PROCESS | 3056 | Values: 88.0 87.0 87.0 87.0 88.0 87.0 88.0 87.0 87.0 87.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#6] imaging-darkroom Cycles:10 Average:99.50 Median:93.50 stddev:11.43 (12.2%) stddev-sans-first:11.80 12:02:48 INFO - PROCESS | 3056 | Values: 92.0 94.0 94.0 94.0 94.0 93.0 108.0 128.0 105.0 93.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 12:02:48 INFO - PROCESS | 3056 | Values: 85.0 86.0 83.0 84.0 86.0 85.0 85.0 85.0 84.0 84.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.50 stddev:2.37 (3.7%) stddev-sans-first:2.51 12:02:48 INFO - PROCESS | 3056 | Values: 62.0 59.0 59.0 60.0 63.0 66.0 64.0 64.0 64.0 63.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:69.00 stddev:14.74 (21.4%) stddev-sans-first:14.65 12:02:48 INFO - PROCESS | 3056 | Values: 56.0 63.0 92.0 91.0 89.0 75.0 60.0 61.0 60.0 59.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:83.00 stddev:11.41 (13.7%) stddev-sans-first:12.05 12:02:48 INFO - PROCESS | 3056 | Values: 84.0 83.0 84.0 83.0 83.0 83.0 80.0 87.0 83.0 119.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:138.00 stddev:7.84 (5.7%) stddev-sans-first:8.31 12:02:48 INFO - PROCESS | 3056 | Values: 136.0 136.0 140.0 128.0 140.0 132.0 140.0 122.0 151.0 134.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:162.00 stddev:18.82 (11.6%) stddev-sans-first:19.57 12:02:48 INFO - PROCESS | 3056 | Values: 159.0 162.0 163.0 161.0 159.0 162.0 160.0 159.0 202.0 208.0 12:02:48 INFO - PROCESS | 3056 | 12:02:48 INFO - PROCESS | 3056 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.56 (2.5%) stddev-sans-first:1.22 12:02:48 INFO - PROCESS | 3056 | Values: 66.0 65.0 63.0 64.0 63.0 62.0 62.0 61.0 62.0 62.0 12:02:48 INFO - PROCESS | 3056 | -------- Summary: end -------- 12:02:48 INFO - PROCESS | 3056 | 12:02:49 INFO - TEST-INFO | 3056: exit 0 12:02:49 INFO - TEST-OK | kraken | took 59008ms 12:02:49 INFO - SUITE-END | took 1177s 12:02:49 INFO - Completed test suite (00:19:37) 12:02:49 INFO - javascript benchmark 12:02:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7919.762278187389, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2996.7452766514843, 3525.5769307683067, 3540.9822950885246, 3541.645835416458, 3581.588117366502, 6142.416470435307, 6174.845628859281, 6178.567500274982, 6185.690715464226, 6193.04007878779, 6173.074038894169, 6219.222597175353, 6232.6883655817255, 6245.56281060326, 6254.9374506255035, 4648.930266046012, 5504.80733174339, 5536.529395001409, 5590.8043218487155, 5618.438156184369, 5486.670799752008, 5489.670619762807, 5508.6970216638065, 5653.293338332708, 5662.094064949609, 5541.639793413436, 5543.6119471637, 5546.029444847156, 5558.499735023847, 5562.805301814437, 5482.753276102585, 5522.861928451781, 5525.502704756591, 5547.611667183299, 5547.77808887648, 5412.83761487156, 5483.122700367961, 5483.561315094783, 5492.5605951523785, 5565.6104072714925, 5062.620303477217, 5114.641975061747, 6108.633481991094, 6117.309816958451, 6132.967444181115, 6350.396712312323, 6351.714172862232, 6355.3644635536075, 6358.713857876407, 6364.840878978016, 6287.025511045796, 6297.685115744194, 6303.338149494283, 6406.903896441557, 6448.387403196689, 6253.749850005949, 6321.810345689637, 6324.905126423061, 6328.367163283635, 6345.841353966188, 6758.831029224309, 6768.424683901825, 6779.72881084761, 6794.592324460449, 6817.0796942413135, 6318.431341179224, 6371.776987805404, 6423.710933008026, 6424.100625912376, 6429.742810287629, 6515.804525864231, 6523.18460192476, 6527.5757075789925, 6549.541532092708, 6552.148220731275, 6467.159269294962, 6489.545731798728, 6501.544891857619, 6504.772332968323, 6513.283538810727, 6260.342664020303, 6410.134631824644, 6441.677916104176, 6498.740050397932, 6511.73953041873, 5259.684418934876, 5261.421243663271, 5265.315508983807, 5267.183586544015, 5267.26258323835, 4668.996165824364, 4684.5549672781035, 4706.529347065266, 4712.52874712526, 4730.455997560256, 6277.654728989858, 6289.276733175743, 6300.495960323163, 6311.305756366796, 6311.33730958252, 6550.7707230246715, 6599.802005939733, 6609.140811694545, 6612.537122401482, 6623.86752264952, 6018.097285407224, 6044.667543285073, 6115.6330620162935, 6247.125402443661, 6257.405546473078, 5681.687507187062, 5683.971580142155, 5694.857628559319, 5695.658260504383, 5709.857253568611, 6591.769288074895, 6757.966210168917, 6772.5936443813525, 6840.0765896603325, 6881.931180688129, 6599.0, 6608.5374023819795, 6608.900866486911, 6613.801585952429, 6623.072769812134, 6511.641859697762, 6862.965685171542, 6870.931290687029, 6873.656317184221, 6894.827629309308, 6337.841553961188, 6378.872422551616, 6397.040443933447, 6431.678416079271, 6475.805725828232, 6544.345565443608, 6584.703688333941, 6594.868102637824, 6595.8021259362295, 6598.439132673777, 5339.813106541408, 6395.8720825582295, 6410.743570257324, 6412.166418365583, 6414.005829096497, 6269.780557680459, 6285.371462853678, 6317.147185129948, 6322.399372059647, 6399.296077431386, 4680.859574212779, 6183.876322473615, 6229.283632382153, 6230.314665386894, 6287.811365659038, 6267.310595834363, 6281.7173227203975, 6296.433321001132, 6338.271098823694, 6340.873182536416, 6200.56596038291, 6283.434490895841, 6330.936690633219, 6331.9366806331345, 6336.778212762715], "value": 6003.020723936716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.9511629302241, 831.5301854452234, 899.7030979776674, 909.2907532124945, 910.4855756497574, 41307.79346103269, 41755.58244417559, 41979.370309445345, 42062.789686051605, 42074.0, 43325.783371083104, 43492.912677183085, 43622.0, 43636.56363436364, 43666.12667746643, 8060.959695201515, 9695.466749328783, 9852.211823054175, 9852.507374631276, 9871.703848884545, 8154.714584989527, 11825.526978920832, 11998.580049698263, 12140.0, 12239.449224784861, 11927.045836333073, 11953.701157471089, 11970.640880773592, 11984.940075299655, 11989.820152697717, 11935.94032029843, 11948.163628545919, 11961.222520536225, 11965.94017029918, 11971.760564788656, 11735.4132293385, 11781.410929453494, 11786.528538858436, 11795.351255680935, 11806.23259488139, 39962.60037399647, 40137.99655008619, 40176.39735403971, 40230.79884600588, 40300.19399611992, 4074.5314288856866, 4094.508658960914, 4119.526254480743, 4119.773412462313, 4131.111810960658, 28485.869548915292, 29165.979190728223, 30525.93159239416, 30671.539926900892, 30741.846290768626, 3297.8351082445784, 3301.570795796555, 3337.7496687748517, 3459.3254315408503, 3462.6537346265422, 333046.0, 337197.0, 338190.0, 338746.0, 339486.99999999756, 44712.8700990934, 45915.704214789126, 53235.0, 53284.73357633187, 53427.73286133545, 46995.06009879783, 47066.294005589945, 47106.76446617814, 47164.0, 54122.72938635282, 15241.466548670744, 15614.921925390301, 15634.82738794595, 15663.765043524356, 15670.451534196476, 10994.28537145083, 13213.075084743976, 13221.405036773369, 13225.404856781468, 13319.66700832487, 12169.391530423443, 12186.878131218751, 12198.756024879453, 12205.0, 12290.692732681755, 5678.630888992202, 5734.0, 5734.827955161351, 5738.655680659173, 5753.769849205984, 1090.7109615951779, 1119.3843386137755, 1134.172054400276, 1156.2888823373733, 1188.7681902029021, 27127.32181695434, 27916.0, 27961.042727008913, 28309.292267693472, 28356.291092722848, 1149.218531398657, 1177.587844254504, 1179.929204247748, 1222.5293262094158, 1223.5839814463125, 14031.929840350937, 14380.712385752226, 14463.49377771773, 14797.630059248604, 14819.0, 453094.0, 470607.0, 470746.0, 471643.0, 472394.0, 56843.0, 56848.715756420956, 56885.715571421875, 56922.43077569254, 56932.715336423054, 1472.7422701027278, 1531.8391568885106, 1542.144110018928, 1544.5520798968623, 1547.8684311833395, 26260.868695656398, 27574.586381203895, 27609.03368382097, 27631.894724210808, 27756.0, 45633.771831140635, 45705.085898282516, 45789.0, 45795.85510362268, 45856.8535786608, 424571.87714061234, 425687.0, 426046.0, 426670.0, 427279.0, 28713.569296460988, 28946.0, 30045.399092018473, 35309.646903530636, 35431.75988840378, 36589.902302930954, 45275.32087018631, 46430.767846160554, 46692.7665361671, 46930.296045560026, 30837.691623083483, 31998.52002220015, 32037.0, 32224.355512889142, 32920.341593167526, 31195.376092478476, 31250.906218282253, 32249.0, 32513.674863251064, 32558.34883302368, 36761.448578270814, 37477.0, 37764.43353349757, 37995.050123747125, 38221.80889095537, 30977.0, 30995.915142969887, 31138.22154446157, 31818.0, 31982.52026219562, 24000.759992399853, 25037.12370067039, 25147.49705005926, 25229.99080036778, 25549.744502554735], "value": 19498.88410908249, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.6004055579419, 392.1822999046987, 407.814444427785, 429.37311525173243, 465.59958435745256, 13954.790678139836, 15697.45058922938, 22039.669404958917, 22465.55068898621, 22739.431514212152, 21745.565088698215, 22390.776092239073, 22680.88659556704, 22795.430114247152, 23990.400239994007, 6530.477561795069, 7018.33325834045, 7071.575705457663, 7099.077119974422, 7120.145582530078, 5804.780995990847, 6940.30596940307, 7079.0, 7887.684492620288, 8071.434999550034, 7427.257274272583, 8038.799030024237, 8060.193980601922, 8060.798480038016, 8066.677332906676, 6661.666916654172, 7639.3124618784295, 7761.3402860756905, 7789.493682910593, 7791.727289544895, 7320.0, 7555.5088919220625, 7609.771706848804, 7626.6186690665245, 7636.312731854105, 25736.74263257381, 26507.73492265072, 26660.46679066427, 26803.597946030826, 26932.326691832666, 2630.7237740037403, 2940.294329360959, 3018.803777754439, 3034.317278612317, 3054.2517083314506, 15893.761593576106, 16011.398860114012, 16035.91982040094, 16377.508674739776, 16758.83241167585, 2143.95712085759, 2193.7038499802484, 2226.421130506064, 2244.753077161511, 2275.3970197897406, 167502.162489188, 172252.0, 174856.12571937186, 175882.24117758914, 180839.09580452146, 15933.12367819781, 18284.63430731378, 18923.621527569372, 20097.799022009884, 20243.493912652008, 31994.68005319917, 32549.674503255137, 32551.348973020406, 32559.348813023607, 32619.0, 10292.897071029343, 10583.94708026455, 10713.892861071445, 10801.351918884891, 10926.617568385069, 8954.910450895539, 8970.295643827652, 8971.846756778457, 9151.63393464254, 9151.771205719777, 9507.144357007903, 9574.288912886163, 9658.323917325719, 9681.419114853132, 9703.010882769015, 2954.542045982876, 3430.8456119474686, 3507.3336066147863, 3580.606133325332, 3985.5289720373553, 1007.6644169124503, 1061.1828891753307, 1063.3885515828429, 1065.6536625596711, 1102.7408558988632, 21491.817950012588, 22026.0, 22413.327600172022, 22418.55162896733, 22463.0, 1049.0191670787838, 1070.8875568065396, 1093.606301731376, 1121.9046381057537, 1123.8707548631849, 10913.508892099875, 11605.88394116065, 11620.767584648429, 11640.534378624929, 11646.359450230319, 737359.0, 741275.0, 742729.0, 751300.0, 765009.0, 98330.05009849716, 140731.0, 163265.0, 163862.9999999976, 169212.0, 1270.1997741423029, 1287.7617640736494, 1339.591424615482, 1339.8727120923497, 1343.8387393512842, 19988.50028749293, 20296.695549566473, 20835.583288334452, 20846.687299690213, 20856.374308770763, 67811.0, 68535.0, 70175.29824701892, 70189.19243230417, 70871.29128708853, 100947.0, 101762.0, 102443.46334805133, 104408.0, 104742.47628761808, 11776.470058847544, 11780.823287650863, 11923.224990375598, 12312.938435307766, 12344.876551234733, 11198.608048718255, 13198.340082995619, 13477.326133693472, 13699.904007679163, 13796.310184490936, 11157.386343751172, 11597.826032609686, 11966.34185119792, 12022.038236941198, 12041.397930103634, 10868.239223254297, 10892.61875834342, 10980.0, 11328.546858125583, 11377.658670239905, 10545.9472702636, 11705.648830535098, 11728.41357932083, 11781.882181178078, 11881.287122772661, 9903.603855845973, 9986.800263994824, 11743.295402275891, 12018.399080046138, 12391.62825115248, 11397.601083962021, 11623.302601843916, 11691.824622630838, 11980.0, 12034.699132521757], "value": 12026.609175088439, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1592.2357268511114, 1603.254486663701, 1622.6430185359222, 1624.7075526405242, 1633.379315859973, 10987.39569323687, 11769.529218831236, 11772.823407648892, 11791.292522448659, 11804.468798904049, 10537.84193237102, 10794.622188223413, 11017.779644407106, 11025.393603351813, 11319.434028298572, 2452.70567531896, 2491.700995880488, 2494.4013436775226, 2498.1631153563576, 2502.2618327593314, 2449.289705985262, 2490.5517006938744, 2525.2802951158924, 2526.3810366460243, 2529.8229123961332, 2473.2951108933958, 2483.9379015524664, 2497.7502249774966, 2521.218422289087, 2526.7094284157374, 2184.9781502184933, 2432.2581612608146, 2484.3168128764646, 2490.713567939674, 2493.1772515070074, 2466.987665061681, 2489.601663733812, 2503.2740505253455, 2503.7996960243136, 2504.6243063540433, 11234.943825280903, 11437.828432573519, 11452.541898324058, 11456.0262377698, 11461.025812805921, 5964.135200395962, 6128.908066379008, 6132.570720049598, 6138.938610613882, 6146.477549408306, 3660.8352624131976, 3722.590515043343, 3794.677452416548, 3806.259493164917, 3878.26313000529, 1293.1788314420317, 1876.5402476393372, 1897.0, 1937.6122685588302, 1973.4770285874256, 13334.666633334147, 13512.86487135126, 13635.659108522266, 13907.165570065727, 15929.681406371808, 4202.978985105055, 4264.33902745075, 4265.189613973397, 4873.95126048735, 4889.193283108319, 10094.596216151273, 12042.63872083839, 12060.638180854588, 12061.396930153458, 12077.18842173681, 5106.285120083191, 5263.5525980292105, 5316.893662126813, 6050.576459647782, 6062.302835173923, 4910.361652985089, 5384.530949078516, 5570.6657600544095, 5575.860603484945, 5631.099024156116, 3882.281777871103, 3966.7024973126554, 4026.4765580474937, 4030.838766449368, 4032.2540330039037, 1039.1686650679426, 1052.8789189243334, 1068.060107105742, 1068.529846867376, 1073.6939972108069, 941.6986564299496, 958.3435346787472, 974.6004138303261, 994.164901482758, 1006.6527048168371, 6529.183852018497, 6560.507961902877, 6674.899876501856, 6735.461163106939, 6817.625030623264, 914.2503147419053, 955.8279509688323, 958.942463452195, 1011.5498603121688, 1018.0481250031245, 3970.6029397060643, 4156.355764856452, 4167.458230430025, 4275.636570891447, 4290.656747460258, 13539.39072741729, 14152.646183845487, 14185.929070354583, 14201.928990355189, 14246.287685615678, 13441.059125861291, 13994.230317332442, 14129.717405652034, 14138.85861141396, 14158.646033849236, 10076.0, 10376.221783366278, 10377.273590848568, 10386.338185890221, 10401.27191096631, 6586.736530538725, 6846.178458585002, 6975.651217439209, 7031.296870313133, 7081.4334853210585, 12331.0, 12621.558245461365, 12637.810432843331, 12644.3677816107, 12748.426320815772, 12724.982001440048, 12774.105812593401, 12949.352532373156, 12953.222806631633, 13088.73822523525, 7844.607769611611, 9084.273258139201, 9563.234941204826, 9564.473953932322, 9568.330216884751, 10293.330933489298, 10412.583496660049, 10417.0, 10498.475076246004, 10604.0, 7526.473146879666, 8751.824963500821, 8785.033646298774, 8871.822563548822, 8907.821843563223, 8647.351448641502, 8719.084496128069, 8805.64777408922, 8811.603477843384, 8813.986391565051, 8060.226750114965, 8097.2712455879255, 8101.068377136705, 8210.261076503144, 8210.671573137008, 5527.778888844401, 5796.275465566804, 5868.972929737364, 5945.643261404157, 6068.696565171812, 5873.794417195378, 5911.408859114054, 5951.732172052355, 6050.727717252822, 6079.1793107931635], "value": 5281.283026259481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.06999481224307, 396.3856023164095, 401.70850714951433, 402.1956087824351, 404.81783197561106, 4716.740579268139, 5235.293235413218, 5254.894902101955, 5261.316028916235, 5287.444818294077, 4074.694397920153, 4694.413198350207, 4768.928466073003, 4860.441049279334, 5254.894902101955, 4088.8773336799795, 4139.875803725893, 4154.356074808395, 4156.376543518466, 4194.643455306303, 4131.566185550519, 4164.7917604119675, 4189.916201675965, 4190.099128687331, 4218.742972509708, 3904.511936007999, 4012.2285052051125, 4161.708680392374, 4171.434567740833, 4201.054762678388, 4074.7555146691293, 4140.937885931713, 4151.481064866892, 4173.0, 4195.210185226821, 3050.0239923224576, 3137.796043257204, 3175.666555011737, 3603.94594081089, 3635.1820840310984, 4455.6658250631335, 4878.56071964018, 5029.7736601853, 5069.340985671839, 5161.225816127573, 3929.6659783918403, 4565.543445655408, 4725.385699859031, 4771.880702982418, 4774.591495508833, 4750.548697873696, 5150.845474635729, 5493.395726470085, 5535.418781027975, 5555.166724991243, 3888.494495715567, 4039.0, 4052.49343832021, 4086.979565102185, 4108.794560271975, 5533.170024496318, 5697.644214438415, 5728.7110400160045, 5780.421957804186, 5823.563232757518, 3739.354129723498, 3913.8434462621176, 4160.251154792167, 4176.248275310473, 4178.979105104455, 3544.503769472276, 4010.318245898204, 4248.532661407243, 4280.636145927569, 4324.286492728665, 4011.0, 4451.9760455094865, 4504.752238626907, 4594.333821595917, 4813.7593120343845, 5513.724313784295, 5545.197810711535, 5604.271444712162, 5613.221455379613, 5620.9437905621235, 1626.3250750938464, 1731.995442643264, 1737.1759652364967, 1746.318935615111, 1762.9471115866545, 1269.1243042300782, 1279.9360031998363, 1305.11252348404, 1330.9134906231063, 1362.4944436397639, 4930.309756634074, 5187.325647665854, 5213.843584692389, 5236.790528378899, 5237.633365664443, 5360.490753378423, 5429.755660995264, 5468.507834294933, 5536.169574563848, 5732.541396688255, 4964.971367465843, 5034.370703662043, 5050.090983622909, 5306.787728490895, 5318.0, 2717.415755612553, 2749.752522273005, 2888.7400133988044, 3037.9063537126617, 3045.3148041690442, 5204.34945631796, 5543.916841247385, 5647.6893770843, 6123.142420275287, 6158.998325544216, 5701.173329867229, 5800.767969281266, 5872.354041055481, 5934.347221805684, 6062.575619706667, 5231.659942103751, 5946.524278057745, 5983.81590719393, 5987.850303742442, 6014.338422773491, 5283.815066472655, 5289.127293996447, 5460.945390546044, 5722.828315150552, 5734.3978882218435, 4936.580390666833, 5275.709835959059, 5400.540954018952, 5475.4524547544925, 5795.1165871091225, 5972.522198224045, 6041.6375017499095, 6062.393760623902, 6072.848178795565, 6097.378127405258, 3805.8011726306127, 3914.6868250540456, 4195.559466256122, 4273.252180868273, 4498.122866041222, 4879.365682461257, 5433.402325744086, 5554.750036248298, 5643.633163844337, 5760.366359700346, 4957.041968422381, 5083.262926875656, 5241.266222728897, 5533.667979921218, 5584.776608935597, 5060.746962651926, 5299.0, 5413.891722165614, 5490.039243132515, 5607.8878422432135, 4768.380110585602, 4825.686330388514, 5114.769835357492, 5393.759435329975, 5509.862253443696, 4900.852974410774, 5168.58651307887, 5239.371275446971, 5470.370907345706, 5632.267805185464, 4500.477020594281, 5054.19132938728, 5231.973840130775, 5354.437784032776, 5363.785448582169], "value": 4216.919045283152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.0954863712178, 781.3983232910663, 812.1431889356726, 828.8881001064856, 908.2824568590813, 14143.646408839772, 17255.827441725578, 17260.568485787844, 17263.74104388435, 17266.482005539852, 15668.843311566881, 15844.0, 16022.59943501413, 16065.196740162974, 16075.115868627221, 957.1624828275259, 983.8573406856002, 988.4217732626386, 990.1138481059447, 1006.788574399377, 1016.2124353625962, 1021.0351218098899, 1035.1253191053547, 1036.8030074285862, 1038.9220808439359, 1066.1204506282309, 1068.1081297116916, 1068.8076146293663, 1068.978620427591, 1069.5989004123453, 1062.1768129699506, 1062.6918193723811, 1063.2344711807486, 1064.1433645915035, 1065.073386154047, 792.8136887831355, 794.2137284088768, 794.9562774047425, 795.0022721484557, 796.577813758703, 17691.93848369102, 17743.822561774345, 17817.287308507643, 18243.72634410485, 18475.0, 14800.925995369955, 14987.925060374737, 15042.247887605576, 15046.473373431987, 15306.081635101818, 11942.641720748392, 12217.633470995885, 12288.447019884126, 12588.05589580776, 12689.23864568129, 3848.422736589506, 3938.0942383252013, 3941.2905676978135, 4093.2427500912427, 4094.7133700640966, 96158.9999999993, 126306.0, 126506.367468163, 128644.0, 128940.35529822385, 9920.751981200528, 11591.478383472764, 11633.243839150427, 11882.28706277626, 12013.879861201276, 23305.88347058254, 23329.3001209964, 23335.066597335917, 23340.2997910063, 23340.7665923342, 8132.878006829902, 8262.752117436487, 8413.0, 8655.134486551295, 8716.08481109487, 4521.637206160647, 4602.033572949651, 4644.530050802605, 4653.069386122789, 4665.066986602626, 3694.6674799268194, 3806.1626442182674, 3806.638369354907, 3808.0479880029993, 3811.942820857689, 669.4945316286173, 680.6664734280266, 685.3215316836296, 701.8666453373945, 702.2485940044104, 694.6283738200038, 726.3426598927979, 730.0800990751585, 746.6565379925187, 757.5189754505928, 9876.35803672759, 10727.0, 10782.622608208832, 10863.29388589755, 11276.041536469327, 591.5770224289568, 664.5248647217263, 716.330231233798, 745.3068646159124, 752.6011214056559, 4873.561379475864, 5181.715005674649, 5192.792288308501, 5203.687778733288, 5204.9219261711105, 105861.0, 106504.46747766211, 109727.0, 111533.44233278782, 112793.0, 93856.53071734597, 94259.0, 94711.0, 96044.0, 96434.0, 81149.59425202836, 81873.0, 82955.0, 83566.58216708878, 84515.5774221125, 8129.634166462642, 9963.50182490887, 10200.183985281308, 10212.234082443698, 10220.131288840532, 53667.731661341444, 53945.65135871634, 54428.0, 54984.45015549954, 55319.17021244765, 48937.75531122321, 61905.69047154736, 62279.75440491256, 63027.0, 64058.0, 11668.124890633406, 11710.212129318874, 11888.405579720806, 11903.928646421864, 12463.750724985268, 11424.771504570028, 12865.807012894627, 12993.220406775623, 14131.788023179866, 14150.221737804519, 12064.215825971283, 12713.618591442271, 12751.426185821474, 12952.740945180854, 13007.739845203232, 11756.58851940178, 11834.644960651194, 12572.05709571804, 12579.811302830281, 12978.480860765463, 11708.531658733555, 12111.576094836639, 12352.073594480271, 12466.563670271496, 12634.368281586068, 8803.647854085764, 9331.953340233254, 9349.532523373668, 9494.762630934281, 9579.616815327308, 8403.201695838885, 9254.76999965167, 10161.187105031728, 10167.644132455329, 10205.0], "value": 7869.973452222737, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 116.0, 111.0, 114.0, 115.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 108.0, 105.0, 140.0, 106.0, 108.0, 105.0, 104.0, 129.0, 129.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 185.0, 183.0, 184.0, 184.0, 183.0, 184.0, 185.0, 182.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 93.0, 58.0, 60.0, 60.0, 57.0, 74.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 86.0, 68.0, 69.0, 68.0, 80.0, 69.0, 94.0, 112.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 87.0, 88.0, 87.0, 88.0, 87.0, 87.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 94.0, 94.0, 93.0, 108.0, 128.0, 105.0, 93.0], "value": 99.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 84.0, 86.0, 85.0, 85.0, 85.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 59.0, 60.0, 63.0, 66.0, 64.0, 64.0, 64.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 92.0, 91.0, 89.0, 75.0, 60.0, 61.0, 60.0, 59.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 83.0, 83.0, 83.0, 80.0, 87.0, 83.0, 119.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 140.0, 128.0, 140.0, 132.0, 140.0, 122.0, 151.0, 134.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 163.0, 161.0, 159.0, 162.0, 160.0, 159.0, 202.0, 208.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 63.0, 64.0, 63.0, 62.0, 62.0, 61.0, 62.0, 62.0], "value": 63.0, "unit": "score"}]}]} 12:02:49 INFO - Return code: 0 12:02:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:02:50 INFO - # TBPL SUCCESS # 12:02:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:50 INFO - 2017-05-23 12:02:50.069 screenresolution[3060:46905] starting screenresolution argv=screenresolution get 12:02:50 INFO - 2017-05-23 12:02:50.072 screenresolution[3060:46905] Display 0: 1600x1200x32@60 12:02:50 INFO - 2017-05-23 12:02:50.078 screenresolution[3061:46908] starting screenresolution argv=screenresolution list 12:02:50 INFO - Available Modes on Display 0 12:02:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:50 INFO - Intel Iris: 12:02:50 INFO - Chipset Model: Intel Iris 12:02:50 INFO - Type: GPU 12:02:50 INFO - Bus: Built-In 12:02:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:50 INFO - Vendor: Intel (0x8086) 12:02:50 INFO - Device ID: 0x0a2e 12:02:50 INFO - Revision ID: 0x0009 12:02:50 INFO - Displays: 12:02:50 INFO - Display: 12:02:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:50 INFO - Display Serial Number: 1600x1200 60 12:02:50 INFO - Main Display: Yes 12:02:50 INFO - Mirror: Off 12:02:50 INFO - Online: Yes 12:02:50 INFO - Rotation: Supported 12:02:50 INFO - Return code: 0 12:02:50 INFO - Running post-action listener: _resource_record_post_action 12:02:50 INFO - [mozharness: 2017-05-23 19:02:50.322271Z] Finished run-tests step (success) 12:02:50 INFO - Running post-run listener: _resource_record_post_run 12:02:50 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 319488; Write bytes: 422077440; Read time: 3; Write time: 1393 12:02:50 INFO - TinderboxPrint: CPU usage
53.9% 12:02:50 INFO - TinderboxPrint: I/O read bytes / time
319,488 / 3 12:02:50 INFO - TinderboxPrint: I/O write bytes / time
422,077,440 / 1,393 12:02:50 INFO - TinderboxPrint: CPU idle
2,201.3 (46.1%) 12:02:50 INFO - TinderboxPrint: CPU system
953.5 (20.0%) 12:02:50 INFO - TinderboxPrint: CPU user
1,618.9 (33.9%) 12:02:50 INFO - TinderboxPrint: Swap in / out
121,942,016 / 0 12:02:50 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119568896; Write bytes: 115472384; Read time: 10887; Write time: 263 12:02:50 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 307200; Write bytes: 295406592; Read time: 2; Write time: 1088 12:02:50 INFO - Running post-run listener: _upload_blobber_files 12:02:50 INFO - Blob upload gear active. 12:02:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:02:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:02:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:02:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:02:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:02:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:02:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:52 INFO - (blobuploader) - INFO - Done attempting. 12:02:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:02:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:02:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:53 INFO - (blobuploader) - INFO - Done attempting. 12:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:02:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:55 INFO - (blobuploader) - INFO - Done attempting. 12:02:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:02:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:02:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:57 INFO - (blobuploader) - INFO - Done attempting. 12:02:57 INFO - (blobuploader) - INFO - Iteration through files over. 12:02:57 INFO - Return code: 0 12:02:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:02:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:02:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3ffc9de410deb5c52d5589f7dccb463def740ea0b397a7358f4502728bf5d7a1a975eb166c2fb584193fcb62d2754d39b6737152b024df899ab886078011a4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e7dfbcd5cc359b0dfcaaca090a18b410f6a9a3d3878da93fbff6d6db9d716d59bc184d794a57ebdc4608e44901a2c69eb8978d76ec81555abaee125c25218b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef2f6f34bb3c9b0852aa78951411ed850597e7d283ca71af81a273341a01042044228f1694b1b84d75e1f1a45d89e98f1be5a0f414916fc6df4a58e26dfa1ea5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/74d0c51942721d631875e46a7b8a74a90c9180b12be43934ff7ab09a9ad9f6d1c477b86cc51b19ea02e771383534b19a1537c8291202405624ab34de2d388939"} 12:02:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:02:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:02:57 INFO - Contents: 12:02:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3ffc9de410deb5c52d5589f7dccb463def740ea0b397a7358f4502728bf5d7a1a975eb166c2fb584193fcb62d2754d39b6737152b024df899ab886078011a4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e7dfbcd5cc359b0dfcaaca090a18b410f6a9a3d3878da93fbff6d6db9d716d59bc184d794a57ebdc4608e44901a2c69eb8978d76ec81555abaee125c25218b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef2f6f34bb3c9b0852aa78951411ed850597e7d283ca71af81a273341a01042044228f1694b1b84d75e1f1a45d89e98f1be5a0f414916fc6df4a58e26dfa1ea5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/74d0c51942721d631875e46a7b8a74a90c9180b12be43934ff7ab09a9ad9f6d1c477b86cc51b19ea02e771383534b19a1537c8291202405624ab34de2d388939"} 12:02:57 INFO - Running post-run listener: copy_logs_to_upload_dir 12:02:57 INFO - Copying logs to upload dir... 12:02:57 INFO - mkdir: /builds/slave/test/build/upload/logs 12:02:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.495256 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-23 12:02:57.290428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:57.296413) ========= 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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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/b3ffc9de410deb5c52d5589f7dccb463def740ea0b397a7358f4502728bf5d7a1a975eb166c2fb584193fcb62d2754d39b6737152b024df899ab886078011a4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e7dfbcd5cc359b0dfcaaca090a18b410f6a9a3d3878da93fbff6d6db9d716d59bc184d794a57ebdc4608e44901a2c69eb8978d76ec81555abaee125c25218b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef2f6f34bb3c9b0852aa78951411ed850597e7d283ca71af81a273341a01042044228f1694b1b84d75e1f1a45d89e98f1be5a0f414916fc6df4a58e26dfa1ea5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/74d0c51942721d631875e46a7b8a74a90c9180b12be43934ff7ab09a9ad9f6d1c477b86cc51b19ea02e771383534b19a1537c8291202405624ab34de2d388939"} build_url:https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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/b3ffc9de410deb5c52d5589f7dccb463def740ea0b397a7358f4502728bf5d7a1a975eb166c2fb584193fcb62d2754d39b6737152b024df899ab886078011a4a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e7dfbcd5cc359b0dfcaaca090a18b410f6a9a3d3878da93fbff6d6db9d716d59bc184d794a57ebdc4608e44901a2c69eb8978d76ec81555abaee125c25218b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ef2f6f34bb3c9b0852aa78951411ed850597e7d283ca71af81a273341a01042044228f1694b1b84d75e1f1a45d89e98f1be5a0f414916fc6df4a58e26dfa1ea5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/74d0c51942721d631875e46a7b8a74a90c9180b12be43934ff7ab09a9ad9f6d1c477b86cc51b19ea02e771383534b19a1537c8291202405624ab34de2d388939"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:57.332443) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:57.332844) ========= 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.XIazE3lGbh/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.ZYMU7Qn7vX/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.005394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:57.381769) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 12:02:57.382113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 12:02:57.382458) ========= ========= Total master_lag: 0.18 =========