builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0224 starttime: 1496173662.72 results: success (0) buildid: 20170530114616 builduid: 9a1867cd235249888596198c20cf2f81 revision: b6b58f56432af99bc6b8356da3ea96170a04190e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.724881) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.725905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.726242) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.752184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.752723) ========= 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.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-30 12:47:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2017-05-30 12:47:42 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132080 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.908396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.908753) ========= 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.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.038044 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:42.969738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 12:47:42.970497) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b6b58f56432af99bc6b8356da3ea96170a04190e --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 b6b58f56432af99bc6b8356da3ea96170a04190e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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-30 12:47:43,135 truncating revision to first 12 chars 2017-05-30 12:47:43,135 Setting DEBUG logging. 2017-05-30 12:47:43,135 attempt 1/10 2017-05-30 12:47:43,135 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b6b58f56432a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 12:47:44,750 unpacking tar archive at: mozilla-beta-b6b58f56432a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.944133 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 12:47:44.941641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:44.942429) ========= script_repo_revision: b6b58f56432af99bc6b8356da3ea96170a04190e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:44.943360) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:44.943962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 12:47:44.965801) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-30 12:47:44.966563) ========= /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.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:47:45 INFO - MultiFileLogger online at 20170530 12:47:45 in /builds/slave/test 12:47:45 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 12:47:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:47:45 INFO - {'append_to_log': False, 12:47:45 INFO - 'base_work_dir': '/builds/slave/test', 12:47:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:47:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:47:45 INFO - 'branch': 'Mozilla-Beta', 12:47:45 INFO - 'buildbot_json_path': 'buildprops.json', 12:47:45 INFO - 'config_files': ('talos/mac_config.py',), 12:47:45 INFO - 'default_actions': ('clobber', 12:47:45 INFO - 'read-buildbot-config', 12:47:45 INFO - 'download-and-extract', 12:47:45 INFO - 'populate-webroot', 12:47:45 INFO - 'create-virtualenv', 12:47:45 INFO - 'install', 12:47:45 INFO - 'run-tests'), 12:47:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:47:45 INFO - 'download_minidump_stackwalk': True, 12:47:45 INFO - 'download_symbols': 'ondemand', 12:47:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:47:45 INFO - 'tooltool.py': '/tools/tooltool.py', 12:47:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:47:45 INFO - '/tools/misc-python/virtualenv.py')}, 12:47:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:47:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:47:45 INFO - 'gecko_profile': False, 12:47:45 INFO - 'gecko_profile_interval': 0, 12:47:45 INFO - 'installer_path': 'installer.exe', 12:47:45 INFO - 'log_level': 'info', 12:47:45 INFO - 'log_name': 'talos', 12:47:45 INFO - 'log_to_console': True, 12:47:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:47:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:47:45 INFO - 'opt_config_files': (), 12:47:45 INFO - 'pip_index': False, 12:47:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:45 INFO - 'cmd': ('bash', 12:47:45 INFO - '-c', 12:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:45 INFO - 'enabled': True, 12:47:45 INFO - 'halt_on_failure': False, 12:47:45 INFO - 'name': 'check_screen_resolution'},), 12:47:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:45 INFO - 'cmd': ('bash', 12:47:45 INFO - '-c', 12:47:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:45 INFO - 'enabled': True, 12:47:45 INFO - 'halt_on_failure': False, 12:47:45 INFO - 'name': 'check_screen_resolution'},), 12:47:45 INFO - 'run_cmd_checks_enabled': True, 12:47:45 INFO - 'suite': 'dromaeojs', 12:47:45 INFO - 'system_bits': '32', 12:47:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:47:45 INFO - 'title': 't-yosemite-r7-0224', 12:47:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:47:45 INFO - 'use_talos_json': True, 12:47:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:47:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:47:45 INFO - 'work_dir': 'build'} 12:47:45 INFO - [mozharness: 2017-05-30 19:47:45.237696Z] Running clobber step. 12:47:45 INFO - Running pre-action listener: _resource_record_pre_action 12:47:45 INFO - Running main action method: clobber 12:47:45 INFO - rmtree: /builds/slave/test/build 12:47:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:47:47 INFO - Running post-action listener: _resource_record_post_action 12:47:47 INFO - [mozharness: 2017-05-30 19:47:47.669724Z] Finished clobber step (success) 12:47:47 INFO - [mozharness: 2017-05-30 19:47:47.669862Z] Running read-buildbot-config step. 12:47:47 INFO - Running pre-action listener: _resource_record_pre_action 12:47:47 INFO - Running main action method: read_buildbot_config 12:47:47 INFO - Using buildbot properties: 12:47:47 INFO - { 12:47:47 INFO - "project": "", 12:47:47 INFO - "product": "firefox", 12:47:47 INFO - "script_repo_revision": "production", 12:47:47 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 12:47:47 INFO - "repository": "", 12:47:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 12:47:47 INFO - "buildid": "20170530114616", 12:47:47 INFO - "pgo_build": "False", 12:47:47 INFO - "basedir": "/builds/slave/test", 12:47:47 INFO - "buildnumber": 8, 12:47:47 INFO - "slavename": "t-yosemite-r7-0224", 12:47:47 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:47:47 INFO - "platform": "yosemite_r7_devedition", 12:47:47 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:47:47 INFO - "branch": "mozilla-beta", 12:47:47 INFO - "repo_path": "releases/mozilla-beta", 12:47:47 INFO - "revision": "b6b58f56432af99bc6b8356da3ea96170a04190e", 12:47:47 INFO - "stage_platform": "macosx64-devedition", 12:47:47 INFO - "builduid": "9a1867cd235249888596198c20cf2f81", 12:47:47 INFO - "slavebuilddir": "test" 12:47:47 INFO - } 12:47:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:47:47 INFO - Running post-action listener: _resource_record_post_action 12:47:47 INFO - [mozharness: 2017-05-30 19:47:47.673340Z] Finished read-buildbot-config step (success) 12:47:47 INFO - [mozharness: 2017-05-30 19:47:47.673453Z] Running download-and-extract step. 12:47:47 INFO - Running pre-action listener: _resource_record_pre_action 12:47:47 INFO - Running main action method: download_and_extract 12:47:47 INFO - mkdir: /builds/slave/test/build/tests 12:47:47 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')]} 12:47:47 INFO - trying https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:47:47 INFO - Downloading https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/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 12:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/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 12:47:48 INFO - Downloaded 1287 bytes. 12:47:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:47:48 INFO - Using the following test package requirements: 12:47:48 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:47:48 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:47:48 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:47:48 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:47:48 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:47:48 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:47:48 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:47:48 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:47:48 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:47:48 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:47:48 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:47:48 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:47:48 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:47:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:47:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:47:48 INFO - Fetch https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:47:50 INFO - Expected file size: 51967114 12:47:50 INFO - Obtained file size: 51967114 12:48:06 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 12:48:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:48:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:48:06 INFO - Fetch https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:48:07 INFO - Expected file size: 51967114 12:48:07 INFO - Obtained file size: 51967114 12:48:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:48:24 INFO - Expected file size: 14404461 12:48:24 INFO - Obtained file size: 14404461 12:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:24 INFO - trying https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:48:24 INFO - Downloading https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/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 12:48:26 INFO - Downloaded 74894173 bytes. 12:48:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:48:26 INFO - mkdir: /builds/slave/test/properties 12:48:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:48:26 INFO - Writing to file /builds/slave/test/properties/build_url 12:48:26 INFO - Contents: 12:48:26 INFO - build_url:https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:48:27 INFO - Running post-action listener: _resource_record_post_action 12:48:27 INFO - Running post-action listener: set_extra_try_arguments 12:48:27 INFO - [mozharness: 2017-05-30 19:48:27.679342Z] Finished download-and-extract step (success) 12:48:27 INFO - [mozharness: 2017-05-30 19:48:27.679522Z] Running populate-webroot step. 12:48:27 INFO - Running pre-action listener: _resource_record_pre_action 12:48:27 INFO - Running main action method: populate_webroot 12:48:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:48:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:48:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:48:27 INFO - '--tppagecycles', 12:48:27 INFO - '7'], 12:48:27 INFO - 'tests': ['tsvgm']}}, 12:48:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:48:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:48:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:48:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:48:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:48:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['damp', 'tps']}, 12:48:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'tests': ['damp', 'tps']}, 12:48:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['dromaeo_dom']}, 12:48:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:48:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['basic_compositor_video']}, 12:48:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:48:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'other-e10s': {'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['a11yr', 12:48:27 INFO - 'ts_paint', 12:48:27 INFO - 'tpaint', 12:48:27 INFO - 'sessionrestore', 12:48:27 INFO - 'sessionrestore_no_auto_restore', 12:48:27 INFO - 'tabpaint']}, 12:48:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['tsvgx', 12:48:27 INFO - 'tsvgr_opacity', 12:48:27 INFO - 'tart', 12:48:27 INFO - 'tscrollx', 12:48:27 INFO - 'cart', 12:48:27 INFO - 'tsvg_static']}, 12:48:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:48:27 INFO - 'tsvgr_opacity', 12:48:27 INFO - 'tart', 12:48:27 INFO - 'tscrollx', 12:48:27 INFO - 'cart', 12:48:27 INFO - 'tsvg_static']}, 12:48:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'talos_options': ['--disable-e10s'], 12:48:27 INFO - 'tests': ['tp5o']}, 12:48:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:48:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'talos_options': ['--disable-e10s', 12:48:27 INFO - '--xperf_path', 12:48:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:27 INFO - 'tests': ['tp5n']}, 12:48:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:48:27 INFO - 'talos_options': ['--xperf_path', 12:48:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:48:27 INFO - 'tests': ['tp5n']}}, 12:48:27 INFO - 'talos.zip': {'path': '', 12:48:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:48:27 INFO - Running post-action listener: _resource_record_post_action 12:48:27 INFO - [mozharness: 2017-05-30 19:48:27.698041Z] Finished populate-webroot step (success) 12:48:27 INFO - [mozharness: 2017-05-30 19:48:27.698176Z] Running create-virtualenv step. 12:48:27 INFO - Running pre-action listener: _resource_record_pre_action 12:48:27 INFO - Running main action method: create_virtualenv 12:48:27 INFO - Creating virtualenv /builds/slave/test/build/venv 12:48:27 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 12:48:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:48:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:48:27 INFO - Using real prefix '/tools/python27' 12:48:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:48:28 INFO - Installing distribute.............................................................................................................................................................................................done. 12:48:31 INFO - Installing pip.................done. 12:48:31 INFO - Return code: 0 12:48:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:48:31 INFO - Reading from file tmpfile_stdout 12:48:31 INFO - Output received: 12:48:31 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) 12:48:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:31 INFO - 'HOME': '/Users/cltbld', 12:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:31 INFO - 'LOGNAME': 'cltbld', 12:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:31 INFO - 'MOZ_NO_REMOTE': '1', 12:48:31 INFO - 'NO_EM_RESTART': '1', 12:48:31 INFO - 'PAGER': '/bin/cat', 12:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:31 INFO - 'PWD': '/builds/slave/test', 12:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:31 INFO - 'SHELL': '/bin/bash', 12:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:31 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:31 INFO - 'USER': 'cltbld', 12:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:31 INFO - 'XPC_FLAGS': '0x0', 12:48:31 INFO - 'XPC_SERVICE_NAME': '0', 12:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:31 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 12:48:31 INFO - Cleaning up... 12:48:31 INFO - Return code: 0 12:48:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:31 INFO - 'HOME': '/Users/cltbld', 12:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:31 INFO - 'LOGNAME': 'cltbld', 12:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:31 INFO - 'MOZ_NO_REMOTE': '1', 12:48:31 INFO - 'NO_EM_RESTART': '1', 12:48:31 INFO - 'PAGER': '/bin/cat', 12:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:31 INFO - 'PWD': '/builds/slave/test', 12:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:31 INFO - 'SHELL': '/bin/bash', 12:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:31 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:31 INFO - 'USER': 'cltbld', 12:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:31 INFO - 'XPC_FLAGS': '0x0', 12:48:31 INFO - 'XPC_SERVICE_NAME': '0', 12:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:32 INFO - Downloading/unpacking psutil>=3.1.1 12:48:32 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 12:48:32 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 12:48:32 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 12:48:32 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 12:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:48:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:34 INFO - Installing collected packages: psutil 12:48:34 INFO - Running setup.py install for psutil 12:48:34 INFO - building 'psutil._psutil_osx' extension 12:48:34 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 12:48:34 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 12:48:34 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 12:48:34 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 12:48:34 INFO - building 'psutil._psutil_posix' extension 12:48:34 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 12:48:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:48:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:48:35 INFO - ^ 12:48:35 INFO - 1 warning generated. 12:48:35 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 12:48:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:48:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:48:35 INFO - Successfully installed psutil 12:48:35 INFO - Cleaning up... 12:48:35 INFO - Return code: 0 12:48:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:35 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:35 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 12:48:35 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 12:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:35 INFO - 'HOME': '/Users/cltbld', 12:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:35 INFO - 'LOGNAME': 'cltbld', 12:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:35 INFO - 'MOZ_NO_REMOTE': '1', 12:48:35 INFO - 'NO_EM_RESTART': '1', 12:48:35 INFO - 'PAGER': '/bin/cat', 12:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:35 INFO - 'PWD': '/builds/slave/test', 12:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:35 INFO - 'SHELL': '/bin/bash', 12:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:35 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:35 INFO - 'USER': 'cltbld', 12:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:35 INFO - 'XPC_FLAGS': '0x0', 12:48:35 INFO - 'XPC_SERVICE_NAME': '0', 12:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:48: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 12:48: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 12:48: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 12:48: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 12:48:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:48:36 INFO - Installing collected packages: mozsystemmonitor 12:48:36 INFO - Running setup.py install for mozsystemmonitor 12:48:37 INFO - Successfully installed mozsystemmonitor 12:48:37 INFO - Cleaning up... 12:48:37 INFO - Return code: 0 12:48:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:37 INFO - 'HOME': '/Users/cltbld', 12:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:37 INFO - 'LOGNAME': 'cltbld', 12:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:37 INFO - 'MOZ_NO_REMOTE': '1', 12:48:37 INFO - 'NO_EM_RESTART': '1', 12:48:37 INFO - 'PAGER': '/bin/cat', 12:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:37 INFO - 'PWD': '/builds/slave/test', 12:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:37 INFO - 'SHELL': '/bin/bash', 12:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:37 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:37 INFO - 'USER': 'cltbld', 12:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:37 INFO - 'XPC_FLAGS': '0x0', 12:48:37 INFO - 'XPC_SERVICE_NAME': '0', 12:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:37 INFO - Downloading/unpacking jsonschema==2.5.1 12:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:48:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:48: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 12:48: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 12:48: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 12:48: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 12:48:39 INFO - Downloading functools32-3.2.3-2.tar.gz 12:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:48:39 INFO - warning: no files found matching '*.txt' 12:48:39 INFO - no previously-included directories found matching 'build' 12:48:39 INFO - no previously-included directories found matching 'dist' 12:48:39 INFO - no previously-included directories found matching '.git*' 12:48:39 INFO - Installing collected packages: jsonschema, functools32 12:48:39 INFO - Running setup.py install for functools32 12:48:39 INFO - warning: no files found matching '*.txt' 12:48:39 INFO - no previously-included directories found matching 'build' 12:48:39 INFO - no previously-included directories found matching 'dist' 12:48:39 INFO - no previously-included directories found matching '.git*' 12:48:39 INFO - Successfully installed jsonschema functools32 12:48:39 INFO - Cleaning up... 12:48:39 INFO - Return code: 0 12:48:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:48: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')]} 12:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48: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')]} 12:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:40 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:40 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 12:48:40 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 12:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:40 INFO - 'HOME': '/Users/cltbld', 12:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:40 INFO - 'LOGNAME': 'cltbld', 12:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:40 INFO - 'MOZ_NO_REMOTE': '1', 12:48:40 INFO - 'NO_EM_RESTART': '1', 12:48:40 INFO - 'PAGER': '/bin/cat', 12:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:40 INFO - 'PWD': '/builds/slave/test', 12:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:40 INFO - 'SHELL': '/bin/bash', 12:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:40 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:40 INFO - 'USER': 'cltbld', 12:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:40 INFO - 'XPC_FLAGS': '0x0', 12:48:40 INFO - 'XPC_SERVICE_NAME': '0', 12:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:48:40 INFO - Cleaning up... 12:48:40 INFO - Return code: 0 12:48:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:48:40 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')]} 12:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:40 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')]} 12:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:40 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:40 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 12:48:40 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 12:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:40 INFO - 'HOME': '/Users/cltbld', 12:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:40 INFO - 'LOGNAME': 'cltbld', 12:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:40 INFO - 'MOZ_NO_REMOTE': '1', 12:48:40 INFO - 'NO_EM_RESTART': '1', 12:48:40 INFO - 'PAGER': '/bin/cat', 12:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:40 INFO - 'PWD': '/builds/slave/test', 12:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:40 INFO - 'SHELL': '/bin/bash', 12:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:40 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:40 INFO - 'USER': 'cltbld', 12:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:40 INFO - 'XPC_FLAGS': '0x0', 12:48:40 INFO - 'XPC_SERVICE_NAME': '0', 12:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:40 INFO - Downloading/unpacking blobuploader==1.2.4 12:48: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 12:48: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 12:48: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 12:48: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 12:48:42 INFO - Downloading blobuploader-1.2.4.tar.gz 12:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:48:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:48: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 12:48: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 12:48: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 12:48: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 12:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:48:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:48:43 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 12:48:43 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 12:48:43 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 12:48:43 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 12:48:43 INFO - Downloading docopt-0.6.1.tar.gz 12:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:48:43 INFO - Installing collected packages: blobuploader, requests, docopt 12:48:43 INFO - Running setup.py install for blobuploader 12:48:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:48:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:48:43 INFO - Running setup.py install for requests 12:48:44 INFO - Running setup.py install for docopt 12:48:44 INFO - Successfully installed blobuploader requests docopt 12:48:44 INFO - Cleaning up... 12:48:44 INFO - Return code: 0 12:48:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:44 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')]} 12:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:44 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')]} 12:48:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:44 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:44 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 12:48:44 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 12:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:44 INFO - 'HOME': '/Users/cltbld', 12:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:44 INFO - 'LOGNAME': 'cltbld', 12:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:44 INFO - 'MOZ_NO_REMOTE': '1', 12:48:44 INFO - 'NO_EM_RESTART': '1', 12:48:44 INFO - 'PAGER': '/bin/cat', 12:48:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:44 INFO - 'PWD': '/builds/slave/test', 12:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:44 INFO - 'SHELL': '/bin/bash', 12:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:44 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:44 INFO - 'USER': 'cltbld', 12:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:44 INFO - 'XPC_FLAGS': '0x0', 12:48:44 INFO - 'XPC_SERVICE_NAME': '0', 12:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:44 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-pnyhNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:44 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ar5A39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RFpccB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ceqT6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-yg54fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-WiyCG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-HUTLDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-cPKJzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-G22ffT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-PMMexL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-2OoPyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:45 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-95mNhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:46 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RVW7zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:46 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-0LzQwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:46 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-_tB_jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:46 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-wrnNjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:46 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-o1S3nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:48:46 INFO - Running setup.py install for manifestparser 12:48:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:48:46 INFO - Running setup.py install for mozcrash 12:48:46 INFO - Running setup.py install for mozdebug 12:48:47 INFO - Running setup.py install for mozdevice 12:48:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Running setup.py install for mozfile 12:48:47 INFO - Running setup.py install for mozhttpd 12:48:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Running setup.py install for mozinfo 12:48:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Running setup.py install for mozInstall 12:48:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:48:47 INFO - Running setup.py install for mozleak 12:48:47 INFO - Running setup.py install for mozlog 12:48:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Running setup.py install for moznetwork 12:48:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Running setup.py install for mozprocess 12:48:48 INFO - Running setup.py install for mozprofile 12:48:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Running setup.py install for mozrunner 12:48:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:48:48 INFO - Running setup.py install for mozscreenshot 12:48:48 INFO - Running setup.py install for moztest 12:48:49 INFO - Running setup.py install for mozversion 12:48:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:48:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:48:49 INFO - Cleaning up... 12:48:49 INFO - Return code: 0 12:48:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:49 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')]} 12:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:49 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')]} 12:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:49 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:49 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 12:48:49 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 12:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:49 INFO - 'HOME': '/Users/cltbld', 12:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:49 INFO - 'LOGNAME': 'cltbld', 12:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:49 INFO - 'MOZ_NO_REMOTE': '1', 12:48:49 INFO - 'NO_EM_RESTART': '1', 12:48:49 INFO - 'PAGER': '/bin/cat', 12:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:49 INFO - 'PWD': '/builds/slave/test', 12:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:49 INFO - 'SHELL': '/bin/bash', 12:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:49 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:49 INFO - 'USER': 'cltbld', 12:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:49 INFO - 'XPC_FLAGS': '0x0', 12:48:49 INFO - 'XPC_SERVICE_NAME': '0', 12:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:49 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-ENQGZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:49 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)) 12:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:49 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-o_8JNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:49 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)) 12:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:49 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-RCT3yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:49 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)) 12:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:49 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-E4T4nX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:49 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)) 12:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:49 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-F13ldB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-eoNjmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-aysx4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-T5i9zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-mQEeCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-A_XrBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-sITk69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-Ra6NDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-qnk5to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-hwsedW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:50 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)) 12:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:50 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-JSxwge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:51 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)) 12:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:51 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-xd9hOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:51 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)) 12:48:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:51 INFO - Running setup.py (path:/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/pip-AWZBHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:51 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)) 12:48:51 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)) 12:48:51 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)) 12:48:51 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)) 12:48:51 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)) 12:48:51 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)) 12:48:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:48:51 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 12:48:51 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 12:48:51 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 12:48:51 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 12:48:52 INFO - Downloading blessings-1.6.tar.gz 12:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:48:52 INFO - Installing collected packages: blessings 12:48:52 INFO - Running setup.py install for blessings 12:48:52 INFO - Successfully installed blessings 12:48:52 INFO - Cleaning up... 12:48:52 INFO - Return code: 0 12:48:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:53 INFO - Reading from file tmpfile_stdout 12:48:53 INFO - Current package versions: 12:48:53 INFO - blessings == 1.6 12:48:53 INFO - blobuploader == 1.2.4 12:48:53 INFO - docopt == 0.6.1 12:48:53 INFO - functools32 == 3.2.3-2 12:48:53 INFO - jsonschema == 2.5.1 12:48:53 INFO - manifestparser == 1.1 12:48:53 INFO - mozInstall == 1.12 12:48:53 INFO - mozcrash == 1.0 12:48:53 INFO - mozdebug == 0.1 12:48:53 INFO - mozdevice == 0.49 12:48:53 INFO - mozfile == 1.2 12:48:53 INFO - mozhttpd == 0.7 12:48:53 INFO - mozinfo == 0.9 12:48:53 INFO - mozleak == 0.1 12:48:53 INFO - mozlog == 3.4 12:48:53 INFO - moznetwork == 0.27 12:48:53 INFO - mozprocess == 0.25 12:48:53 INFO - mozprofile == 0.28 12:48:53 INFO - mozrunner == 6.13 12:48:53 INFO - mozscreenshot == 0.1 12:48:53 INFO - mozsystemmonitor == 0.3 12:48:53 INFO - moztest == 0.8 12:48:53 INFO - mozversion == 1.4 12:48:53 INFO - psutil == 3.1.1 12:48:53 INFO - requests == 1.2.3 12:48:53 INFO - wsgiref == 0.1.2 12:48:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:53 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')]} 12:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:53 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')]} 12:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:53 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:53 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 12:48:53 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 12:48:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:53 INFO - 'HOME': '/Users/cltbld', 12:48:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:53 INFO - 'LOGNAME': 'cltbld', 12:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:53 INFO - 'MOZ_NO_REMOTE': '1', 12:48:53 INFO - 'NO_EM_RESTART': '1', 12:48:53 INFO - 'PAGER': '/bin/cat', 12:48:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:53 INFO - 'PWD': '/builds/slave/test', 12:48:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:53 INFO - 'SHELL': '/bin/bash', 12:48:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:53 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:53 INFO - 'USER': 'cltbld', 12:48:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:53 INFO - 'XPC_FLAGS': '0x0', 12:48:53 INFO - 'XPC_SERVICE_NAME': '0', 12:48:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 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)) 12:48:53 INFO - Cleaning up... 12:48:53 INFO - Return code: 0 12:48:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:48:53 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')]} 12:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:53 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')]} 12:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:53 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 0x10770ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10770d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec4357f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, '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.cdMEiacRr6/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.cGcsGukV2c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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 12:48:53 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 12:48:53 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 12:48:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:48:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:53 INFO - 'HOME': '/Users/cltbld', 12:48:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:53 INFO - 'LOGNAME': 'cltbld', 12:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:53 INFO - 'MOZ_NO_REMOTE': '1', 12:48:53 INFO - 'NO_EM_RESTART': '1', 12:48:53 INFO - 'PAGER': '/bin/cat', 12:48:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:53 INFO - 'PWD': '/builds/slave/test', 12:48:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:53 INFO - 'SHELL': '/bin/bash', 12:48:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:48:53 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:48:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:53 INFO - 'USER': 'cltbld', 12:48:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:53 INFO - 'XPC_FLAGS': '0x0', 12:48:53 INFO - 'XPC_SERVICE_NAME': '0', 12:48:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:48:53 INFO - Cleaning up... 12:48:53 INFO - Return code: 0 12:48:53 INFO - Running post-action listener: _resource_record_post_action 12:48:53 INFO - Running post-action listener: _start_resource_monitoring 12:48:53 INFO - Starting resource monitoring. 12:48:53 INFO - [mozharness: 2017-05-30 19:48:53.827289Z] Finished create-virtualenv step (success) 12:48:53 INFO - [mozharness: 2017-05-30 19:48:53.828246Z] Running install step. 12:48:53 INFO - Running pre-action listener: _resource_record_pre_action 12:48:53 INFO - Running main action method: install 12:48:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:54 INFO - Reading from file tmpfile_stdout 12:48:54 INFO - Detecting whether we're running mozinstall >=1.0... 12:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:48:54 INFO - Reading from file tmpfile_stdout 12:48:54 INFO - Output received: 12:48:54 INFO - Usage: mozinstall [options] installer 12:48:54 INFO - Options: 12:48:54 INFO - -h, --help show this help message and exit 12:48:54 INFO - -d DEST, --destination=DEST 12:48:54 INFO - Directory to install application into. [default: 12:48:54 INFO - "/builds/slave/test"] 12:48:54 INFO - --app=APP Application being installed. [default: firefox] 12:48:54 INFO - mkdir: /builds/slave/test/build/application 12:48:54 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'] 12:48:54 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 12:49:14 INFO - Reading from file tmpfile_stdout 12:49:14 INFO - Output received: 12:49:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:49:14 INFO - Running post-action listener: _resource_record_post_action 12:49:14 INFO - [mozharness: 2017-05-30 19:49:14.249946Z] Finished install step (success) 12:49:14 INFO - [mozharness: 2017-05-30 19:49:14.250098Z] Running run-tests step. 12:49:14 INFO - Running pre-action listener: _resource_record_pre_action 12:49:14 INFO - Running main action method: run_tests 12:49:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:14 INFO - 2017-05-30 12:49:14.264 screenresolution[1629:7297] starting screenresolution argv=screenresolution get 12:49:14 INFO - 2017-05-30 12:49:14.267 screenresolution[1629:7297] Display 0: 1600x1200x32@60 12:49:14 INFO - 2017-05-30 12:49:14.273 screenresolution[1630:7300] starting screenresolution argv=screenresolution list 12:49:14 INFO - Available Modes on Display 0 12:49:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:14 INFO - Intel Iris: 12:49:14 INFO - Chipset Model: Intel Iris 12:49:14 INFO - Type: GPU 12:49:14 INFO - Bus: Built-In 12:49:14 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:14 INFO - Vendor: Intel (0x8086) 12:49:14 INFO - Device ID: 0x0a2e 12:49:14 INFO - Revision ID: 0x0009 12:49:14 INFO - Displays: 12:49:14 INFO - Display: 12:49:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:14 INFO - Display Serial Number: 1600x1200 60 12:49:14 INFO - Main Display: Yes 12:49:14 INFO - Mirror: Off 12:49:14 INFO - Online: Yes 12:49:14 INFO - Rotation: Supported 12:49:14 INFO - Return code: 0 12:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:49:14 INFO - Python 2.7.3 12:49:14 INFO - Return code: 0 12:49:14 INFO - grabbing minidump binary from tooltool 12:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:49:14 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 0x1074e8d30>, '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 0x10740fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ddbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:49:14 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 12:49:14 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 12:49:14 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 12:49:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:49:14 INFO - Return code: 0 12:49:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:49:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:49:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:49:14 INFO - ENV: RUST_BACKTRACE is now 1 12:49:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:49:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 12:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0224 --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 12:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdMEiacRr6/Render', 12:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:49:14 INFO - 'HOME': '/Users/cltbld', 12:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:49:14 INFO - 'LOGNAME': 'cltbld', 12:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:49:14 INFO - 'MOZ_NO_REMOTE': '1', 12:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:49:14 INFO - 'NO_EM_RESTART': '1', 12:49:14 INFO - 'PAGER': '/bin/cat', 12:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:49:14 INFO - 'PWD': '/builds/slave/test', 12:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:49:14 INFO - 'RUST_BACKTRACE': '1', 12:49:14 INFO - 'SHELL': '/bin/bash', 12:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cGcsGukV2c/Listeners', 12:49:14 INFO - 'TMPDIR': '/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/', 12:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:49:14 INFO - 'USER': 'cltbld', 12:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:49:14 INFO - 'XPC_FLAGS': '0x0', 12:49:14 INFO - 'XPC_SERVICE_NAME': '0', 12:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:49:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0224', '--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 12:49:14 INFO - mozversion application_buildid: 20170530114616 12:49:14 INFO - mozversion application_changeset: b6b58f56432af99bc6b8356da3ea96170a04190e 12:49:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:49:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:49:14 INFO - mozversion application_name: Firefox 12:49:14 INFO - mozversion application_remotingname: firefox-dev 12:49:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:49:14 INFO - mozversion application_vendor: Mozilla 12:49:14 INFO - mozversion application_version: 54.0 12:49:14 INFO - mozversion platform_buildid: 20170530114616 12:49:14 INFO - mozversion platform_changeset: b6b58f56432af99bc6b8356da3ea96170a04190e 12:49:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:49:14 INFO - mozversion platform_version: 54.0 12:49:14 INFO - using testdate: 1496173754 12:49:14 INFO - actual date: 1496173754 12:49:14 INFO - starting webserver on 'localhost:49220' 12:49:14 INFO - SUITE-START | Running 2 tests 12:49:14 INFO - TEST-START | dromaeo_css 12:49:14 INFO - operating with platform_type : mac_ 12:49:14 INFO - Initialising browser for dromaeo_css test... 12:49:14 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp3VgMN0/profile http://localhost:49220/getInfo.html) 12:49:17 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 12:49:17 INFO - PROCESS | 1640 | colorDepth:24 12:49:17 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 12:49:17 INFO - PROCESS | 1640 | __metrics 12:49:17 INFO - PROCESS | 1640 | 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:49:17 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:49:18 INFO - TEST-INFO | 1640: exit 0 12:49:18 INFO - Browser initialized. 12:49:18 INFO - Running cycle 1/1 for dromaeo_css test... 12:49:18 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp3VgMN0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:30 INFO - PROCESS | 1643 | RSS: Main: 193998848 12:49:30 INFO - PROCESS | 1643 | 12:52:33 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 12:52:35 INFO - PROCESS | 1643 | RSS: Main: 311197696 12:52:35 INFO - PROCESS | 1643 | 12:55:38 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 12:55:39 INFO - PROCESS | 1643 | RSS: Main: 327237632 12:55:39 INFO - PROCESS | 1643 | 12:58:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 12:58:42 INFO - PROCESS | 1643 | RSS: Main: 303337472 12:58:42 INFO - PROCESS | 1643 | 13:01:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 13:01:45 INFO - PROCESS | 1643 | RSS: Main: 287748096 13:01:45 INFO - PROCESS | 1643 | 13:04:48 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 13:04:49 INFO - PROCESS | 1643 | RSS: Main: 291405824 13:04:49 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 13:07:52 INFO - PROCESS | 1643 | RSS: Main: 486252544 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | __start_tp_report 13:07:52 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 13:07:52 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 13:07:52 INFO - PROCESS | 1643 | |i|pagename|runs| 13:07:52 INFO - PROCESS | 1643 | |0;dojo.html;2708.2823051891246;3403.84682689279;3423.845926933288;3461.290435460731;3470.490336703536;5838.591298609099;5933.614315069517;5938.198343223664;5983.850403739903;6033.7888173913925;5849.707514624273;5910.379410161936;5919.88160236795;5933.22868027157;5974.4324289192455;5362.705051222171;5410.431904649994;5420.105682562373;5457.863553411176;5484.533814625762;5279.102552566053;5289.100852855005;5301.602379821509;5479.726013699319;5483.862903427406;5390.326209223847;5399.892002159954;5400.378956420003;5402.9729851350685;5428.321459817523;5462.617616766828;5467.015937131315;5476.726163691819;5483.835484935458;5483.835484935458;5290.523852853262;5396.136618141118;5396.83809485716;5416.620836541444;5438.510534051954;5723.112917497794;5778.913316300217;5779.768809247625;5822.475977162034;5886.205362276123;6363.777267795651;6365.108884756138;6384.297727249998;6393.712282947278;6400.39196276353;6387.936120638827;6392.3927226913365;6397.040443933401;6402.295747467775;6405.871882562323;5808.244928159313;5864.618799778001;5865.736041878125;6048.213732214828;6088.573799834014;6346.841328966812;6428.485721142298;6453.225612926385;6455.160829092188;6464.612123272619;6393.009083592051;6397.136386587751;6400.39196276353;6401.391867772554;6406.071119687619;6253.812385628438;6290.49676025926;6403.48772098231;6422.614643121335;6434.646094464756;6300.527460440485;6339.239291284983;6368.363163683594;6369;6405.231372235362;6396.776112836028;6413.807585772434;6414.775482858171;6415.390537898892;6435.774747883802;4711.411073615798;4800.399950006249;4872.902541949212;4903.141950158708;4937.851864444073;4306.612404883575;4314.56854314566;4369.825206991748;4375.081232941118;4440.311751678495;5820.301563812285;5839.182514447981;5852.6195797273895;5855.414458554195;5858.326292476335;6348.841278968062;6387.552871298964;6387.584806987531;6399.328070552618;6407.807765767035;5748.5688573357165;5777.422257774189;5790.507806836382;5795.39148389413;5799.5650326225705;5347.572194224453;5391.191321301758;5404.189371594292;5410.594205434608;5450.155225939986;5360.571154307568;6302.401271879165;6352.396522330371;6362.936370636419;6366.172397588284;6258.186435763321;6342.682865856596;6347.206599175103;6347.428731414195;6351.650659213603;4902.583280421204;6557.508186886098;6573.769918052845;6580.309067547839;6641.402273795382;6361.61830290184;6379;6379.266384365672;6392.616443013434;6393.968030159819;6273.37266273369;6273.749050037947;6341.587796793194;6384.361563843579;6401.231852177769;6353.110564520878;6376.681165941592;6378.6810659467765;6384.361563843579;6407.871842563216;6182.165407669907;6357.96821015892;6365.90451143224;6374.649394283359;6383.616982981036;5933.228680271537;6224.128621993015;6225.875482490415;6241.500679945502;6242.406971337716;6172.753089876354;6190.226221722285;6198;6279.717412716347;6401.327860574573;6169.784057557964;6171.814845554641;6175.073738939195;6183.350748171557;6240.875182496416 13:07:52 INFO - PROCESS | 1643 | |1;ext.html;805.0420000199762;828.8383765165793;877.7761670773951;892.1925657280156;896.9416987895786;41987.370189447145;42994.570054299445;44624.33063504042;44742.55257447425;44991.550084499184;43291.13417731643;43632.90917727059;43647;43718.344224836655;43741.7812910935;7591.620418979057;9538.90461095387;9646.855297170549;9660.37207581505;9675.516224188797;7568.810779730496;11607.767844643102;11897;12000.639980800546;12014.939925300361;11826.94086529566;11840.467178976924;11863.110266729987;11879.70300742484;11886.989605883511;11768.235064720766;11796.882031179706;11810.232334898204;11811.822822657667;11841.703957401047;11411.54353825846;11473.082153427706;11483.368414737186;11509.769804603944;11510.539578416854;41146.9713257168;41197;41270.1745965082;41301.58698413037;41324;3882.129126867722;3941.2511622791576;3944.6055394460323;3971.2653159165357;4016.2168377166463;28423;28491;29847.104586862213;30793.846030769702;30820.075397738103;3155.8737650493954;3174.3809957058384;3175.3966746318356;3296.868125274986;3302.7192688621494;343813;348490;348520;349483;349700;42927.785361072994;43232.35151472731;54597;54698.453015469335;54859;46815;54993;55840.441595584336;60013;60014.29935652906;14947.02844315116;15031.323590438238;15043.247837608076;15054.774178387333;15117.697646047238;12847.48610055606;12865.356732163355;12866.485340586272;12911.418986145645;12945;13086.476540938445;13112.803307950388;13113.409896554487;13152.342382880817;13172.934135329262;4663.417072865892;5431.293931788921;5509.319657504419;5515;5529.253550770675;1139.4530625299737;1153.3022521374448;1168.707823044239;1174.7004513848915;1212.8180772883961;27081.86459067692;27179.592306115424;27684;27738.02916897899;27775.472349020998;1083.6478144602877;1086.1799341497276;1121.2767764791827;1128.0918860317522;1300.2848433361614;13267.73464530704;13725.265699280642;13855.722885542233;13940.512082077079;13944.790828137586;478897;480013;481522;482657;482782;49152.50847491551;49168;49220.507794922305;49382.75308623434;49402.258966115536;1396.3925692323871;1405.901586888908;1435.318223843666;1442.0698649371307;1472.6760112775173;26740.331491712863;27869.442611148068;28313.43373132567;28363.574546382235;28509;42200.36699449572;42256.36615450709;42325.15349693051;42325.36511952262;42340.78829605832;399844;400277;400460;400902;401344;24475.632865506675;24490.897909593754;25121;25669.871650641628;30488.542671859497;36525;38964.61035389723;39028.07289453246;39113;39444.80277598594;28581.714182857904;28596.713147907976;29594.852025739732;30253.546196807507;30277;28812.711872881842;29260.82956681709;29282.682279297907;29394.971176009592;29444.852775735984;33640;33919.321613568085;34494;34553.30893382169;34596.48105278473;26813.463730725667;28636.284092897844;29672.703272966995;29908;30192.84903575556;23014.539709205386;23374.532509350058;23905;23924.52150957006;23937.162199322942 13:07:52 INFO - PROCESS | 1643 | |2;jquery.html;400.90177906412924;410.16326693545153;417.58867515497246;427.3354933079063;452.8369786876724;19863.702044469326;21250.787492125095;21923.890380548095;22010.88994555025;22047.78737169455;20844;23079.769202307973;23829.165979190733;23983.760162398372;24120.396990075213;6607.702653380597;6674.833129171785;7036.577805331671;7039.894401583981;7058.85882282354;6804.863902721942;6947.166340039203;6978.651067446633;6990.545614535039;7007.894881576781;6625.60246385216;6793.762218322359;6900.3099690031095;7006.7197312107455;7014.929850701478;6547.836304092388;6843.452523798109;6913.446924246048;6920.307969203064;6926.68829902653;6605.9669701651665;6690.498212634072;6694.263631000635;6699.330066993261;6705.597664140168;27003;27516.312092197655;27518.862405688044;27558.311042223904;27901.720982790117;2933.8679759410666;2963.9110826675233;2975.821450712964;2990.8953186638364;2996.3408050228913;16456.83543164565;16660.416885409075;16671.49985500437;17272.481825545256;17599;2043.9080241389174;2078.2206672497814;2099.265257159997;2148.677698345245;2168.9783102168935;137623;139500;141777;142113;145187.274063629;16605;17606.735898961524;19816.801831981495;21172.576548468944;21244.681329780065;34555;35099.473507897404;35540.64459355425;35544.755933542205;35568.46647300241;10004.649837255662;10144.391336519833;10576.259661823753;10755.085817705583;11002.944985275022;7749.023998880362;8958.059403762642;8988.280937524982;9178.81642367149;9178.852643419572;8561.52911589856;10136.493175341204;10162.796744065077;10177.592896284066;10192.541335639915;3141;3977.940330895039;4168.374743788456;4248.893777655521;4249.405083288342;876.5660997806;906.4273485502648;930.1117432851611;965.3098034905076;1075.3440401355167;21672.89163554172;22271.665925011137;22277;22349.994250258776;22360.55278894413;779.2713812585251;932.8647346134773;952.3999880075509;963.8938918463181;969.3989726369696;11133.10935125188;11461.885381146249;11559.76880462386;11628.88371116278;11652.766944661229;751490;772234;786607;793434;805643;156382;157057;158306;158956;161278.38721612634;1020.6325722739808;1258.1633213912828;1302.908796384263;1306.45129045801;1307.751527209846;15680.745540356973;19302.806971930662;19857.322200745202;19924.529054320068;19940.900295498428;63464.36535634769;63658;63996.68001659962;65405;65640.01539976809;87648.56175719081;89250.55374723085;89842.55078724565;90981.6352754696;91164.70670377067;10028.548715307974;11249.943750281196;11357.943210284226;11552.480138393961;11677.182597218112;12145.210561313486;12478.875211248134;13494.865051349754;13703.246321452416;13704.24626645544;11192.608258710907;11307.095432365555;11641.47613357384;11728.41357932117;11767;10411.271211015157;10462.215333850145;10486.842697359698;10698.251122421356;10775.137988961022;9457.905420945703;10299.279050466395;11682.831716828588;11756.823647645464;11895.345755983159;9672.419654820733;9673.467959262027;11321.830172547583;11592.768144637228;12088.697782555506;9305.720828375159;11427.828582571101;11454.312741235553;11609.825852612386;11611.071114311004 13:07:52 INFO - PROCESS | 1643 | |3;mootools.html;1590.0777549021568;1601.1994002998501;1619.0366731794581;1620.0522694223878;1641.6060145565064;10670.78658426832;11299;11314.038306743916;11318.717032074203;11325;10843.566257349696;11041.39272340021;11276.774464510725;11406.201565890391;11412.31526108434;2331.184085570045;2369.2655276864225;2382.523495300938;2382.60686986647;2385.355953892448;2293.0369244917174;2355.8233132515043;2358.7169539655265;2365.3377054424705;2368.2421625079983;2303.884805759714;2331.1491305673476;2335.1476711000532;2346.495503466754;2379.119725701493;2339.6607491913746;2340.7659234076627;2359.103540654549;2379.1316169598144;2386.7613238676167;2317.5480781247666;2340.8127349812144;2341.379534423379;2343.824213184018;2347.0377145370485;10968.890311096866;11234.831477527843;11235.101191904629;11240.269382490194;11248.325100493996;4773.80904763809;5482;5693.886122277573;5699.116636921284;5721.427857214287;3179.639135401538;3213.662565430643;3284.556584861037;3322.7009569138895;3328.051505320972;1085.9239853210279;1094.9945250273777;1106.1758989552754;1127.7349822791637;1137.795196864564;13493.460261589526;14013.229272390014;14305.499307524187;15334.463293784776;15396;3579.83890724918;4498.190325741398;4520.751358675305;4525.954740452619;4531.796069176895;10068.39589624625;11611.245269057485;11624.825627615592;11636.069114470822;11654.76690466186;4800.183968725325;5314.468553144732;5542.224088627595;5557.5553955683445;5567.66594004361;4903.803847846046;4987.80048798044;4995.675281106716;5033.546980771748;5181.35233095863;3707.647773461517;3732.122951106488;3760.924781504409;3791.5829258781514;3792.1088544192094;937.1565590968183;948.2935213266161;951.6098399656108;954.7708549947962;960.1406740966795;802.1697543043039;816.542736067804;827.0736774812249;840.315143158324;854.5001174312972;6396.808095757135;6469.4177524022125;6518.57629254097;6684.799456016327;6794.592324460548;1033.0857191385678;1047.9476026198658;1053.2942928238099;1060.5651682810164;1064.1114669251263;3939.3106206414336;4114.670826333886;4254.212970600449;4338.6312163466455;4380.123975204971;13470.461181552824;14080.507182248572;14089;14096.788548171786;14106.647333816736;13726.245056521788;13789.51736689231;13981.790273145707;14029.649258768408;14063.367148478343;10067.848982265272;10227.181825453947;10230.23273254509;10231.976802319708;10237.078662920225;5561.082421400585;5959.67221802805;6194.45326138706;6381.87236255263;6535.640539770358;10548.683539493828;10938.398388088413;12248.693782655504;12266.75466490683;12346.691332716753;11051.17116216271;11828.34944078084;12130.272183669009;12596.118271721258;12695.301758403377;9231.538423078953;9516.904830951878;9532.285078618992;9532.618695252113;9545.093216144454;9958.15355694774;10362.274640775073;10411;10423.322484038514;10477.1094456972;8670.869936951078;8809.471431714117;8851.513166775889;9073.909260907307;9128.817423651622;8555.31557475413;8582.399232053946;8626.309895208493;8654.78363040929;8786.604602793017;7821.413393995587;7846.803829904298;7926.960365198137;7934.1272460030505;7939.28546430824;4647.535246475325;5720.227769251264;5810.215620891295;5925.614835035882;5964.731587078505;5756.3380211276235;5828.067509198508;5849.970750146393;5948.839976204772;6016.669083200466 13:07:52 INFO - PROCESS | 1643 | |4;prototype.html;378.8617154738521;383.12838292883686;383.1436738888582;384.80759620189906;394.184039039189;4212.525616034386;4685;4865.440474345447;4906.632002599806;4907.75461226939;4558.6125179359715;4626.768661566925;4663.580277775;4797.808087676497;4888.97555512222;3403.723603648632;3577.481265216542;3931.3906344516645;4054.3107671695734;4072.694547908904;3825.101101241206;4099.590040995907;4191.643710284629;4208.5380052669225;4208.579142085798;3341.7994920304736;4128.545859955402;4208.852690155845;4212.1575684863;4229.978850105746;3497.527833742463;3861.899358682762;3863.8123548072954;3875.65119139276;4224.007358270804;3594.4428613564937;3686.0600546860646;3807.600201978808;3822.4075268333454;3843.8654647087496;4135.441715368417;4212.662986961036;4853.3690620219495;4861.9546797438725;4865.664481049081;3884.76691398517;4250.723702959298;4274.3071864725025;4438.733675979419;4567.853109038747;4712.078511322968;4905.975470122662;5303.310569625963;5313.335833020872;5324.790212061989;3705.94441083384;3970.563238043813;4255.659547236244;4270.935935960963;4309.375140604626;4707.7881495332795;4763.833265835679;5417.566594672417;5618.859528511779;5633.661980281156;3723.9759066256456;4313.525512193656;4340.414044104069;4345.413369195118;4377.255866502702;4081.6122468365456;4142.79286035697;4362.76004819738;4423;4446.933296000562;4507.774611269423;4782.473927867932;4806.279058141306;4829.348038014893;4873.610111191097;4183.309753890635;4387.868363949086;4731.905361892743;4756.809727610926;5349.545288650508;1459.408939379553;1482.570054684151;1505.4053648808658;1525.542337298806;1526.1224795742492;967.0426277984744;1019.9439030853226;1022.3763504262542;1101.003591749452;1122.7698321844002;4354.390385346054;4437.269464908688;4610.585047345755;4705.505921878222;4789.856304310876;4742.7865746040825;4965.478624744422;5345.625806193529;5495.6153069284765;5524.392316845144;4180.749155050707;4232.851850185229;4689.85930422081;4829.323894654681;4833.719064447924;2387.3912152401026;2674.0239812468308;2799.4261176458776;2849.9597646858747;2872.4973129702216;5593.035691083732;5617.517139373615;5623.325200975909;5645.661260324394;5693.157118944696;4019.5980401960155;4719.018012434785;4865.5864251538305;5557.9435608825;5699.715014249271;4539.273716205391;4696.759236127062;5268.683878967274;5636.97181514098;5654.293213348332;4941.975290123526;5131.461196574455;5285.969235998943;5460.552953467414;5494.835154945198;5050.80693365218;5302.787888484418;5304.446021165905;5441.129419292894;5484;4879.634027447884;5039.488153553993;5219.634625576326;5487.176923461513;5573.331200255995;3750.168622689852;3921.745086569364;4358.716683415568;4366.061296821199;4377.562243775597;4589.8657124437295;5263.7894484220205;5265.21021846726;5418.965379387556;5549.278593782782;4743.1343455084;4751.59611433032;4859.854204373874;4865.635077369141;5253.527182553588;4594.163873165944;4737.431508219036;4850.6119510438375;5016.739119572161;5327.7746118392215;4558.384618076518;4984.0874913192965;5097.61767867404;5121.976819156338;5188.818391356284;4813.663043586916;5266;5307.840764777063;5318.547923426552;5359.437259087739;4549.545045495424;5151.0470562945975;5178.844634660966;5180.378354597472;5187.559057480156 13:07:52 INFO - PROCESS | 1643 | |5;yui.html;759.4721668440436;765.2041876448493;795.5147360110332;850.5067061104552;865.6321063547992;13001.284929328884;16097.83902160978;16374.672506549861;16429.342826286964;16447;14885.627859303522;15020.549383518484;15025.098494090362;15036.022658527216;15039.398424063053;970.7184916374243;981.1856159387692;993.46849435552;1001.3241062282966;1011.5599714124343;983.5475681186663;996.875390576178;998.0817647764038;998.8551660009331;998.8951160128155;1053.6364954090827;1056.392574269794;1056.9958539387574;1057.043375744952;1057.4659796802641;1003.703907347327;1049.931754435967;1052.1057101463732;1053.9262251642388;1054.3199636234663;783.2363445640511;784.5018413307533;784.666516730393;785.701433455289;785.7642707187821;16264.593385165466;16507;16567.17164141788;16766.07786571738;17025.31898724062;14373.281335933267;14432.278386080654;14447.638809029753;14526.346314415876;14553.345099470549;11636.650900473;11685.766284674259;11890.762184756342;11937.28376297425;12268.877311226863;3773.396256598931;3796.414884657352;3804.8478060877533;3817.3892177251782;3926.9018274543364;105144;111144.4442777781;111461;111821.88178118278;114006.42996784963;10269.589216431408;10290.485475726184;10787.892121078688;10879.075278601249;11190.720231994264;23247;23514.941827617797;23523.17668881581;23576.64635030476;23608.17371391993;7870.921290787019;7946.015770337605;7992.880106798402;8073.798155046171;8123.18768123183;3976.840926362971;4278.807453664593;4284.507281662586;4428.402165707588;4659.4175728034;3423.2639982403903;3424.8630054798027;3476.1135910343205;3484.9303013940084;3504.5444092267844;612.1735656863199;619.0837560410617;623.6788054152115;634.0425956805258;634.4733870887153;636.2619361540591;641.2689533931322;645.8121112023152;647.7085311609794;660.6762686283782;9160.633574657073;9727;9806.705798826046;9825;9888.20894328452;637.6684124255361;667.7028722218663;670.936261055199;673.0610797936948;678.5589366911547;4582.793774280165;4769.451513075971;4777.880552986203;4788.569028787426;4796.37647105874;102332;104374.47812760888;104744.47627761813;104946.4752676247;105182;89129;89389.55305223563;91034;92308.53845730728;92958.53520732488;78605;78927.21072789314;80689.59655201687;81059.59470202729;81612;9330.393524420891;9336.906630933603;9350;9360.719178424659;9374.25005999532;48877.044918202875;51434.97130057303;53362.46637533575;54333;54943.45056549383;68242;68503.65748171227;69909.30090699231;70416.64791676009;70438.64780676064;9804.705858824247;11510.884891151316;11874;11929.28424294545;12101.81847272274;11844.881551184722;11964.401779911144;12010.75978480443;12061.216020958609;12369.196002259825;11500.827487587525;11526.88473115258;11973.820392693942;11973.940130299292;12232.327222002876;11110.944445277722;11230.94384528072;11906.34515101643;11911.404429778304;12186;9560.62672000859;11169.218154729091;11194.888051119384;11551;11659.766804664028;7926.801829954066;8315.251627353568;8395.538245396561;8482.363822713198;8644.351673624378;8559.95720021396;8802.38383313162;9489.762755931157;9523.428594284365;9536.189423899046 13:07:52 INFO - PROCESS | 1643 | __end_tp_report 13:07:52 INFO - PROCESS | 1643 | __start_cc_report 13:07:52 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1930 13:07:52 INFO - PROCESS | 1643 | __end_cc_report 13:07:52 INFO - PROCESS | 1643 | __startTimestamp1496174872797__endTimestamp 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | ------- Summary: start ------- 13:07:52 INFO - PROCESS | 1643 | Number of tests: 6 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5881.23 Median:6182.17 stddev:697.20 (11.3%) stddev-sans-first:653.40 13:07:52 INFO - PROCESS | 1643 | Values: 2708.3 3403.8 3423.8 3461.3 3470.5 5838.6 5933.6 5938.2 5983.9 6033.8 5849.7 5910.4 5919.9 5933.2 5974.4 5362.7 5410.4 5420.1 5457.9 5484.5 5279.1 5289.1 5301.6 5479.7 5483.9 5390.3 5399.9 5400.4 5403.0 5428.3 5462.6 5467.0 5476.7 5483.8 5483.8 5290.5 5396.1 5396.8 5416.6 5438.5 5723.1 5778.9 5779.8 5822.5 5886.2 6363.8 6365.1 6384.3 6393.7 6400.4 6387.9 6392.4 6397.0 6402.3 6405.9 5808.2 5864.6 5865.7 6048.2 6088.6 6346.8 6428.5 6453.2 6455.2 6464.6 6393.0 6397.1 6400.4 6401.4 6406.1 6253.8 6290.5 6403.5 6422.6 6434.6 6300.5 6339.2 6368.4 6369.0 6405.2 6396.8 6413.8 6414.8 6415.4 6435.8 4711.4 4800.4 4872.9 4903.1 4937.9 4306.6 4314.6 4369.8 4375.1 4440.3 5820.3 5839.2 5852.6 5855.4 5858.3 6348.8 6387.6 6387.6 6399.3 6407.8 5748.6 5777.4 5790.5 5795.4 5799.6 5347.6 5391.2 5404.2 5410.6 5450.2 5360.6 6302.4 6352.4 6362.9 6366.2 6258.2 6342.7 6347.2 6347.4 6351.7 4902.6 6557.5 6573.8 6580.3 6641.4 6361.6 6379.0 6379.3 6392.6 6394.0 6273.4 6273.7 6341.6 6384.4 6401.2 6353.1 6376.7 6378.7 6384.4 6407.9 6182.2 6358.0 6365.9 6374.6 6383.6 5933.2 6224.1 6225.9 6241.5 6242.4 6172.8 6190.2 6198.0 6279.7 6401.3 6169.8 6171.8 6175.1 6183.4 6240.9 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:54953.12 Median:30382.77 stddev:109608.26 (360.8%) stddev-sans-first:109840.35 13:07:52 INFO - PROCESS | 1643 | Values: 805.0 828.8 877.8 892.2 896.9 41987.4 42994.6 44624.3 44742.6 44991.6 43291.1 43632.9 43647.0 43718.3 43741.8 7591.6 9538.9 9646.9 9660.4 9675.5 7568.8 11607.8 11897.0 12000.6 12014.9 11826.9 11840.5 11863.1 11879.7 11887.0 11768.2 11796.9 11810.2 11811.8 11841.7 11411.5 11473.1 11483.4 11509.8 11510.5 41147.0 41197.0 41270.2 41301.6 41324.0 3882.1 3941.3 3944.6 3971.3 4016.2 28423.0 28491.0 29847.1 30793.8 30820.1 3155.9 3174.4 3175.4 3296.9 3302.7 343813.0 348490.0 348520.0 349483.0 349700.0 42927.8 43232.4 54597.0 54698.5 54859.0 46815.0 54993.0 55840.4 60013.0 60014.3 14947.0 15031.3 15043.2 15054.8 15117.7 12847.5 12865.4 12866.5 12911.4 12945.0 13086.5 13112.8 13113.4 13152.3 13172.9 4663.4 5431.3 5509.3 5515.0 5529.3 1139.5 1153.3 1168.7 1174.7 1212.8 27081.9 27179.6 27684.0 27738.0 27775.5 1083.6 1086.2 1121.3 1128.1 1300.3 13267.7 13725.3 13855.7 13940.5 13944.8 478897.0 480013.0 481522.0 482657.0 482782.0 49152.5 49168.0 49220.5 49382.8 49402.3 1396.4 1405.9 1435.3 1442.1 1472.7 26740.3 27869.4 28313.4 28363.6 28509.0 42200.4 42256.4 42325.2 42325.4 42340.8 399844.0 400277.0 400460.0 400902.0 401344.0 24475.6 24490.9 25121.0 25669.9 30488.5 36525.0 38964.6 39028.1 39113.0 39444.8 28581.7 28596.7 29594.9 30253.5 30277.0 28812.7 29260.8 29282.7 29395.0 29444.9 33640.0 33919.3 34494.0 34553.3 34596.5 26813.5 28636.3 29672.7 29908.0 30192.8 23014.5 23374.5 23905.0 23924.5 23937.2 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:44088.55 Median:22355.27 stddev:130006.06 (581.5%) stddev-sans-first:130329.38 13:07:52 INFO - PROCESS | 1643 | Values: 400.9 410.2 417.6 427.3 452.8 19863.7 21250.8 21923.9 22010.9 22047.8 20844.0 23079.8 23829.2 23983.8 24120.4 6607.7 6674.8 7036.6 7039.9 7058.9 6804.9 6947.2 6978.7 6990.5 7007.9 6625.6 6793.8 6900.3 7006.7 7014.9 6547.8 6843.5 6913.4 6920.3 6926.7 6606.0 6690.5 6694.3 6699.3 6705.6 27003.0 27516.3 27518.9 27558.3 27901.7 2933.9 2963.9 2975.8 2990.9 2996.3 16456.8 16660.4 16671.5 17272.5 17599.0 2043.9 2078.2 2099.3 2148.7 2169.0 137623.0 139500.0 141777.0 142113.0 145187.3 16605.0 17606.7 19816.8 21172.6 21244.7 34555.0 35099.5 35540.6 35544.8 35568.5 10004.6 10144.4 10576.3 10755.1 11002.9 7749.0 8958.1 8988.3 9178.8 9178.9 8561.5 10136.5 10162.8 10177.6 10192.5 3141.0 3977.9 4168.4 4248.9 4249.4 876.6 906.4 930.1 965.3 1075.3 21672.9 22271.7 22277.0 22350.0 22360.6 779.3 932.9 952.4 963.9 969.4 11133.1 11461.9 11559.8 11628.9 11652.8 751490.0 772234.0 786607.0 793434.0 805643.0 156382.0 157057.0 158306.0 158956.0 161278.4 1020.6 1258.2 1302.9 1306.5 1307.8 15680.7 19302.8 19857.3 19924.5 19940.9 63464.4 63658.0 63996.7 65405.0 65640.0 87648.6 89250.6 89842.6 90981.6 91164.7 10028.5 11249.9 11357.9 11552.5 11677.2 12145.2 12478.9 13494.9 13703.2 13704.2 11192.6 11307.1 11641.5 11728.4 11767.0 10411.3 10462.2 10486.8 10698.3 10775.1 9457.9 10299.3 11682.8 11756.8 11895.3 9672.4 9673.5 11321.8 11592.8 12088.7 9305.7 11427.8 11454.3 11609.8 11611.1 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6649.04 Median:2382.57 stddev:4224.39 (177.3%) stddev-sans-first:4219.14 13:07:52 INFO - PROCESS | 1643 | Values: 1590.1 1601.2 1619.0 1620.1 1641.6 10670.8 11299.0 11314.0 11318.7 11325.0 10843.6 11041.4 11276.8 11406.2 11412.3 2331.2 2369.3 2382.5 2382.6 2385.4 2293.0 2355.8 2358.7 2365.3 2368.2 2303.9 2331.1 2335.1 2346.5 2379.1 2339.7 2340.8 2359.1 2379.1 2386.8 2317.5 2340.8 2341.4 2343.8 2347.0 10968.9 11234.8 11235.1 11240.3 11248.3 4773.8 5482.0 5693.9 5699.1 5721.4 3179.6 3213.7 3284.6 3322.7 3328.1 1085.9 1095.0 1106.2 1127.7 1137.8 13493.5 14013.2 14305.5 15334.5 15396.0 3579.8 4498.2 4520.8 4526.0 4531.8 10068.4 11611.2 11624.8 11636.1 11654.8 4800.2 5314.5 5542.2 5557.6 5567.7 4903.8 4987.8 4995.7 5033.5 5181.4 3707.6 3732.1 3760.9 3791.6 3792.1 937.2 948.3 951.6 954.8 960.1 802.2 816.5 827.1 840.3 854.5 6396.8 6469.4 6518.6 6684.8 6794.6 1033.1 1047.9 1053.3 1060.6 1064.1 3939.3 4114.7 4254.2 4338.6 4380.1 13470.5 14080.5 14089.0 14096.8 14106.6 13726.2 13789.5 13981.8 14029.6 14063.4 10067.8 10227.2 10230.2 10232.0 10237.1 5561.1 5959.7 6194.5 6381.9 6535.6 10548.7 10938.4 12248.7 12266.8 12346.7 11051.2 11828.3 12130.3 12596.1 12695.3 9231.5 9516.9 9532.3 9532.6 9545.1 9958.2 10362.3 10411.0 10423.3 10477.1 8670.9 8809.5 8851.5 9073.9 9128.8 8555.3 8582.4 8626.3 8654.8 8786.6 7821.4 7846.8 7927.0 7934.1 7939.3 4647.5 5720.2 5810.2 5925.6 5964.7 5756.3 5828.1 5850.0 5948.8 6016.7 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4340.23 Median:4693.31 stddev:1206.48 (25.7%) stddev-sans-first:1172.66 13:07:52 INFO - PROCESS | 1643 | Values: 378.9 383.1 383.1 384.8 394.2 4212.5 4685.0 4865.4 4906.6 4907.8 4558.6 4626.8 4663.6 4797.8 4889.0 3403.7 3577.5 3931.4 4054.3 4072.7 3825.1 4099.6 4191.6 4208.5 4208.6 3341.8 4128.5 4208.9 4212.2 4230.0 3497.5 3861.9 3863.8 3875.7 4224.0 3594.4 3686.1 3807.6 3822.4 3843.9 4135.4 4212.7 4853.4 4862.0 4865.7 3884.8 4250.7 4274.3 4438.7 4567.9 4712.1 4906.0 5303.3 5313.3 5324.8 3705.9 3970.6 4255.7 4270.9 4309.4 4707.8 4763.8 5417.6 5618.9 5633.7 3724.0 4313.5 4340.4 4345.4 4377.3 4081.6 4142.8 4362.8 4423.0 4446.9 4507.8 4782.5 4806.3 4829.3 4873.6 4183.3 4387.9 4731.9 4756.8 5349.5 1459.4 1482.6 1505.4 1525.5 1526.1 967.0 1019.9 1022.4 1101.0 1122.8 4354.4 4437.3 4610.6 4705.5 4789.9 4742.8 4965.5 5345.6 5495.6 5524.4 4180.7 4232.9 4689.9 4829.3 4833.7 2387.4 2674.0 2799.4 2850.0 2872.5 5593.0 5617.5 5623.3 5645.7 5693.2 4019.6 4719.0 4865.6 5557.9 5699.7 4539.3 4696.8 5268.7 5637.0 5654.3 4942.0 5131.5 5286.0 5460.6 5494.8 5050.8 5302.8 5304.4 5441.1 5484.0 4879.6 5039.5 5219.6 5487.2 5573.3 3750.2 3921.7 4358.7 4366.1 4377.6 4589.9 5263.8 5265.2 5419.0 5549.3 4743.1 4751.6 4859.9 4865.6 5253.5 4594.2 4737.4 4850.6 5016.7 5327.8 4558.4 4984.1 5097.6 5122.0 5188.8 4813.7 5266.0 5307.8 5318.5 5359.4 4549.5 5151.0 5178.8 5180.4 5187.6 13:07:52 INFO - PROCESS | 1643 | 13:07:52 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20629.50 Median:4127.82 stddev:30385.43 (736.1%) stddev-sans-first:30434.04 13:07:52 INFO - PROCESS | 1643 | Values: 759.5 765.2 795.5 850.5 865.6 13001.3 16097.8 16374.7 16429.3 16447.0 14885.6 15020.5 15025.1 15036.0 15039.4 970.7 981.2 993.5 1001.3 1011.6 983.5 996.9 998.1 998.9 998.9 1053.6 1056.4 1057.0 1057.0 1057.5 1003.7 1049.9 1052.1 1053.9 1054.3 783.2 784.5 784.7 785.7 785.8 16264.6 16507.0 16567.2 16766.1 17025.3 14373.3 14432.3 14447.6 14526.3 14553.3 11636.7 11685.8 11890.8 11937.3 12268.9 3773.4 3796.4 3804.8 3817.4 3926.9 105144.0 111144.4 111461.0 111821.9 114006.4 10269.6 10290.5 10787.9 10879.1 11190.7 23247.0 23514.9 23523.2 23576.6 23608.2 7870.9 7946.0 7992.9 8073.8 8123.2 3976.8 4278.8 4284.5 4428.4 4659.4 3423.3 3424.9 3476.1 3484.9 3504.5 612.2 619.1 623.7 634.0 634.5 636.3 641.3 645.8 647.7 660.7 9160.6 9727.0 9806.7 9825.0 9888.2 637.7 667.7 670.9 673.1 678.6 4582.8 4769.5 4777.9 4788.6 4796.4 102332.0 104374.5 104744.5 104946.5 105182.0 89129.0 89389.6 91034.0 92308.5 92958.5 78605.0 78927.2 80689.6 81059.6 81612.0 9330.4 9336.9 9350.0 9360.7 9374.3 48877.0 51435.0 53362.5 54333.0 54943.5 68242.0 68503.7 69909.3 70416.6 70438.6 9804.7 11510.9 11874.0 11929.3 12101.8 11844.9 11964.4 12010.8 12061.2 12369.2 11500.8 11526.9 11973.8 11973.9 12232.3 11110.9 11230.9 11906.3 11911.4 12186.0 9560.6 11169.2 11194.9 11551.0 11659.8 7926.8 8315.3 8395.5 8482.4 8644.4 8560.0 8802.4 9489.8 9523.4 9536.2 13:07:52 INFO - PROCESS | 1643 | -------- Summary: end -------- 13:07:52 INFO - PROCESS | 1643 | 13:07:53 INFO - TEST-INFO | 1643: exit 0 13:07:53 INFO - TEST-OK | dromaeo_css | took 1118439ms 13:07:53 INFO - TEST-START | kraken 13:07:53 INFO - operating with platform_type : mac_ 13:07:53 INFO - Initialising browser for kraken test... 13:07:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp3AQbtO/profile http://localhost:49220/getInfo.html) 13:07:55 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 13:07:55 INFO - PROCESS | 1653 | colorDepth:24 13:07:55 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 13:07:55 INFO - PROCESS | 1653 | __metrics 13:07:55 INFO - PROCESS | 1653 | 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" 13:07:55 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:56 INFO - TEST-INFO | 1653: exit 0 13:07:56 INFO - Browser initialized. 13:07:56 INFO - Running cycle 1/1 for kraken test... 13:07:56 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/T/tmp3AQbtO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:08:08 INFO - PROCESS | 1655 | RSS: Main: 193581056 13:08:08 INFO - PROCESS | 1655 | 13:08:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection) 13:08:11 INFO - PROCESS | 1655 | RSS: Main: 244584448 13:08:11 INFO - PROCESS | 1655 | 13:08:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-dft) 13:08:15 INFO - PROCESS | 1655 | RSS: Main: 258912256 13:08:15 INFO - PROCESS | 1655 | 13:08:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-fft) 13:08:18 INFO - PROCESS | 1655 | RSS: Main: 356757504 13:08:18 INFO - PROCESS | 1655 | 13:08:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator) 13:08:21 INFO - PROCESS | 1655 | RSS: Main: 259964928 13:08:21 INFO - PROCESS | 1655 | 13:08:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:08:23 INFO - PROCESS | 1655 | RSS: Main: 260579328 13:08:23 INFO - PROCESS | 1655 | 13:08:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom) 13:08:27 INFO - PROCESS | 1655 | RSS: Main: 299704320 13:08:27 INFO - PROCESS | 1655 | 13:08:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate) 13:08:31 INFO - PROCESS | 1655 | RSS: Main: 300584960 13:08:31 INFO - PROCESS | 1655 | 13:08:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial) 13:08:34 INFO - PROCESS | 1655 | RSS: Main: 300470272 13:08:34 INFO - PROCESS | 1655 | 13:08:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:08:36 INFO - PROCESS | 1655 | RSS: Main: 277409792 13:08:36 INFO - PROCESS | 1655 | 13:08:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:08:39 INFO - PROCESS | 1655 | RSS: Main: 281612288 13:08:39 INFO - PROCESS | 1655 | 13:08:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:08:42 INFO - PROCESS | 1655 | RSS: Main: 285921280 13:08:42 INFO - PROCESS | 1655 | 13:08:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:08:45 INFO - PROCESS | 1655 | RSS: Main: 291930112 13:08:45 INFO - PROCESS | 1655 | 13:08:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:08:49 INFO - PROCESS | 1655 | RSS: Main: 293048320 13:08:49 INFO - PROCESS | 1655 | 13:08:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49220/tests/kraken/driver.html?testName=ai-astar) 13:08:51 INFO - PROCESS | 1655 | RSS: Main: 337592320 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | __start_tp_report 13:08:51 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 13:08:51 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 13:08:51 INFO - PROCESS | 1655 | |i|pagename|runs| 13:08:51 INFO - PROCESS | 1655 | |0;ai-astar;106;119;117;116;115;116;117;116;118;116 13:08:51 INFO - PROCESS | 1655 | |1;audio-beat-detection;138;107;107;142;109;108;106;89;129;135 13:08:51 INFO - PROCESS | 1655 | |2;audio-dft;184;188;184;186;186;185;184;186;188;182 13:08:51 INFO - PROCESS | 1655 | |3;audio-fft;83;61;61;89;64;63;62;62;77;61 13:08:51 INFO - PROCESS | 1655 | |4;audio-oscillator;91;69;86;69;69;68;82;69;69;84 13:08:51 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;87;87;88;88;88;88;88;87;88 13:08:51 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;94;95;96;94;94;95;95;95;99 13:08:51 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;83;84;86;84;84;87;85;83;86 13:08:51 INFO - PROCESS | 1655 | |8;json-parse-financial;61;62;62;62;62;62;63;63;62;64 13:08:51 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;54;61;63;62;60;58;57;57;58;58 13:08:51 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;84;82;86;85;83;84;87;107;83 13:08:51 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;137;136;136;144;135;136;135;135;171;142 13:08:51 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;163;164;165;164;165;166;164;164;163;165 13:08:51 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;65;65;64;65;64;64;65;63;63 13:08:51 INFO - PROCESS | 1655 | __end_tp_report 13:08:51 INFO - PROCESS | 1655 | __start_cc_report 13:08:51 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2059 13:08:51 INFO - PROCESS | 1655 | __end_cc_report 13:08:51 INFO - PROCESS | 1655 | __startTimestamp1496174930999__endTimestamp 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | ------- Summary: start ------- 13:08:51 INFO - PROCESS | 1655 | Number of tests: 14 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.50 stddev:3.57 (3.1%) stddev-sans-first:1.22 13:08:51 INFO - PROCESS | 1655 | Values: 106.0 119.0 117.0 116.0 115.0 116.0 117.0 116.0 118.0 116.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:132.00 stddev:17.59 (13.3%) stddev-sans-first:16.93 13:08:51 INFO - PROCESS | 1655 | Values: 138.0 107.0 107.0 142.0 109.0 108.0 106.0 89.0 129.0 135.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.00 stddev:1.89 (1.0%) stddev-sans-first:1.94 13:08:51 INFO - PROCESS | 1655 | Values: 184.0 188.0 184.0 186.0 186.0 185.0 184.0 186.0 188.0 182.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:68.30 Median:63.50 stddev:10.57 (16.7%) stddev-sans-first:9.79 13:08:51 INFO - PROCESS | 1655 | Values: 83.0 61.0 61.0 89.0 64.0 63.0 62.0 62.0 77.0 61.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:75.50 stddev:9.02 (11.9%) stddev-sans-first:7.66 13:08:51 INFO - PROCESS | 1655 | Values: 91.0 69.0 86.0 69.0 69.0 68.0 82.0 69.0 69.0 84.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 13:08:51 INFO - PROCESS | 1655 | Values: 87.0 87.0 87.0 88.0 88.0 88.0 88.0 88.0 87.0 88.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:1.48 (1.6%) stddev-sans-first:1.56 13:08:51 INFO - PROCESS | 1655 | Values: 95.0 94.0 95.0 96.0 94.0 94.0 95.0 95.0 95.0 99.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.41 13:08:51 INFO - PROCESS | 1655 | Values: 85.0 83.0 84.0 86.0 84.0 84.0 87.0 85.0 83.0 86.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.73 13:08:51 INFO - PROCESS | 1655 | Values: 61.0 62.0 62.0 62.0 62.0 62.0 63.0 63.0 62.0 64.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:2.70 (4.6%) stddev-sans-first:2.24 13:08:51 INFO - PROCESS | 1655 | Values: 54.0 61.0 63.0 62.0 60.0 58.0 57.0 57.0 58.0 58.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:84.50 stddev:7.32 (8.7%) stddev-sans-first:7.74 13:08:51 INFO - PROCESS | 1655 | Values: 85.0 84.0 82.0 86.0 85.0 83.0 84.0 87.0 107.0 83.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:136.50 stddev:11.10 (8.1%) stddev-sans-first:11.69 13:08:51 INFO - PROCESS | 1655 | Values: 137.0 136.0 136.0 144.0 135.0 136.0 135.0 135.0 171.0 142.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:164.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 13:08:51 INFO - PROCESS | 1655 | Values: 163.0 164.0 165.0 164.0 165.0 166.0 164.0 164.0 163.0 165.0 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:0.97 (1.5%) stddev-sans-first:0.83 13:08:51 INFO - PROCESS | 1655 | Values: 66.0 65.0 65.0 64.0 65.0 64.0 64.0 65.0 63.0 63.0 13:08:51 INFO - PROCESS | 1655 | -------- Summary: end -------- 13:08:51 INFO - PROCESS | 1655 | 13:08:51 INFO - TEST-INFO | 1655: exit 0 13:08:51 INFO - TEST-OK | kraken | took 58377ms 13:08:51 INFO - SUITE-END | took 1176s 13:08:52 INFO - Completed test suite (00:19:37) 13:08:52 INFO - javascript benchmark 13:08:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7610.398438660722, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2708.2823051891246, 3403.84682689279, 3423.845926933288, 3461.290435460731, 3470.490336703536, 5838.591298609099, 5933.614315069517, 5938.198343223664, 5983.850403739903, 6033.7888173913925, 5849.707514624273, 5910.379410161936, 5919.88160236795, 5933.22868027157, 5974.4324289192455, 5362.705051222171, 5410.431904649994, 5420.105682562373, 5457.863553411176, 5484.533814625762, 5279.102552566053, 5289.100852855005, 5301.602379821509, 5479.726013699319, 5483.862903427406, 5390.326209223847, 5399.892002159954, 5400.378956420003, 5402.9729851350685, 5428.321459817523, 5462.617616766828, 5467.015937131315, 5476.726163691819, 5483.835484935458, 5483.835484935458, 5290.523852853262, 5396.136618141118, 5396.83809485716, 5416.620836541444, 5438.510534051954, 5723.112917497794, 5778.913316300217, 5779.768809247625, 5822.475977162034, 5886.205362276123, 6363.777267795651, 6365.108884756138, 6384.297727249998, 6393.712282947278, 6400.39196276353, 6387.936120638827, 6392.3927226913365, 6397.040443933401, 6402.295747467775, 6405.871882562323, 5808.244928159313, 5864.618799778001, 5865.736041878125, 6048.213732214828, 6088.573799834014, 6346.841328966812, 6428.485721142298, 6453.225612926385, 6455.160829092188, 6464.612123272619, 6393.009083592051, 6397.136386587751, 6400.39196276353, 6401.391867772554, 6406.071119687619, 6253.812385628438, 6290.49676025926, 6403.48772098231, 6422.614643121335, 6434.646094464756, 6300.527460440485, 6339.239291284983, 6368.363163683594, 6369.0, 6405.231372235362, 6396.776112836028, 6413.807585772434, 6414.775482858171, 6415.390537898892, 6435.774747883802, 4711.411073615798, 4800.399950006249, 4872.902541949212, 4903.141950158708, 4937.851864444073, 4306.612404883575, 4314.56854314566, 4369.825206991748, 4375.081232941118, 4440.311751678495, 5820.301563812285, 5839.182514447981, 5852.6195797273895, 5855.414458554195, 5858.326292476335, 6348.841278968062, 6387.552871298964, 6387.584806987531, 6399.328070552618, 6407.807765767035, 5748.5688573357165, 5777.422257774189, 5790.507806836382, 5795.39148389413, 5799.5650326225705, 5347.572194224453, 5391.191321301758, 5404.189371594292, 5410.594205434608, 5450.155225939986, 5360.571154307568, 6302.401271879165, 6352.396522330371, 6362.936370636419, 6366.172397588284, 6258.186435763321, 6342.682865856596, 6347.206599175103, 6347.428731414195, 6351.650659213603, 4902.583280421204, 6557.508186886098, 6573.769918052845, 6580.309067547839, 6641.402273795382, 6361.61830290184, 6379.0, 6379.266384365672, 6392.616443013434, 6393.968030159819, 6273.37266273369, 6273.749050037947, 6341.587796793194, 6384.361563843579, 6401.231852177769, 6353.110564520878, 6376.681165941592, 6378.6810659467765, 6384.361563843579, 6407.871842563216, 6182.165407669907, 6357.96821015892, 6365.90451143224, 6374.649394283359, 6383.616982981036, 5933.228680271537, 6224.128621993015, 6225.875482490415, 6241.500679945502, 6242.406971337716, 6172.753089876354, 6190.226221722285, 6198.0, 6279.717412716347, 6401.327860574573, 6169.784057557964, 6171.814845554641, 6175.073738939195, 6183.350748171557, 6240.875182496416], "value": 5833.3547041476995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.0420000199762, 828.8383765165793, 877.7761670773951, 892.1925657280156, 896.9416987895786, 41987.370189447145, 42994.570054299445, 44624.33063504042, 44742.55257447425, 44991.550084499184, 43291.13417731643, 43632.90917727059, 43647.0, 43718.344224836655, 43741.7812910935, 7591.620418979057, 9538.90461095387, 9646.855297170549, 9660.37207581505, 9675.516224188797, 7568.810779730496, 11607.767844643102, 11897.0, 12000.639980800546, 12014.939925300361, 11826.94086529566, 11840.467178976924, 11863.110266729987, 11879.70300742484, 11886.989605883511, 11768.235064720766, 11796.882031179706, 11810.232334898204, 11811.822822657667, 11841.703957401047, 11411.54353825846, 11473.082153427706, 11483.368414737186, 11509.769804603944, 11510.539578416854, 41146.9713257168, 41197.0, 41270.1745965082, 41301.58698413037, 41324.0, 3882.129126867722, 3941.2511622791576, 3944.6055394460323, 3971.2653159165357, 4016.2168377166463, 28423.0, 28491.0, 29847.104586862213, 30793.846030769702, 30820.075397738103, 3155.8737650493954, 3174.3809957058384, 3175.3966746318356, 3296.868125274986, 3302.7192688621494, 343813.0, 348490.0, 348520.0, 349483.0, 349700.0, 42927.785361072994, 43232.35151472731, 54597.0, 54698.453015469335, 54859.0, 46815.0, 54993.0, 55840.441595584336, 60013.0, 60014.29935652906, 14947.02844315116, 15031.323590438238, 15043.247837608076, 15054.774178387333, 15117.697646047238, 12847.48610055606, 12865.356732163355, 12866.485340586272, 12911.418986145645, 12945.0, 13086.476540938445, 13112.803307950388, 13113.409896554487, 13152.342382880817, 13172.934135329262, 4663.417072865892, 5431.293931788921, 5509.319657504419, 5515.0, 5529.253550770675, 1139.4530625299737, 1153.3022521374448, 1168.707823044239, 1174.7004513848915, 1212.8180772883961, 27081.86459067692, 27179.592306115424, 27684.0, 27738.02916897899, 27775.472349020998, 1083.6478144602877, 1086.1799341497276, 1121.2767764791827, 1128.0918860317522, 1300.2848433361614, 13267.73464530704, 13725.265699280642, 13855.722885542233, 13940.512082077079, 13944.790828137586, 478897.0, 480013.0, 481522.0, 482657.0, 482782.0, 49152.50847491551, 49168.0, 49220.507794922305, 49382.75308623434, 49402.258966115536, 1396.3925692323871, 1405.901586888908, 1435.318223843666, 1442.0698649371307, 1472.6760112775173, 26740.331491712863, 27869.442611148068, 28313.43373132567, 28363.574546382235, 28509.0, 42200.36699449572, 42256.36615450709, 42325.15349693051, 42325.36511952262, 42340.78829605832, 399844.0, 400277.0, 400460.0, 400902.0, 401344.0, 24475.632865506675, 24490.897909593754, 25121.0, 25669.871650641628, 30488.542671859497, 36525.0, 38964.61035389723, 39028.07289453246, 39113.0, 39444.80277598594, 28581.714182857904, 28596.713147907976, 29594.852025739732, 30253.546196807507, 30277.0, 28812.711872881842, 29260.82956681709, 29282.682279297907, 29394.971176009592, 29444.852775735984, 33640.0, 33919.321613568085, 34494.0, 34553.30893382169, 34596.48105278473, 26813.463730725667, 28636.284092897844, 29672.703272966995, 29908.0, 30192.84903575556, 23014.539709205386, 23374.532509350058, 23905.0, 23924.52150957006, 23937.162199322942], "value": 18908.648099576094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.90177906412924, 410.16326693545153, 417.58867515497246, 427.3354933079063, 452.8369786876724, 19863.702044469326, 21250.787492125095, 21923.890380548095, 22010.88994555025, 22047.78737169455, 20844.0, 23079.769202307973, 23829.165979190733, 23983.760162398372, 24120.396990075213, 6607.702653380597, 6674.833129171785, 7036.577805331671, 7039.894401583981, 7058.85882282354, 6804.863902721942, 6947.166340039203, 6978.651067446633, 6990.545614535039, 7007.894881576781, 6625.60246385216, 6793.762218322359, 6900.3099690031095, 7006.7197312107455, 7014.929850701478, 6547.836304092388, 6843.452523798109, 6913.446924246048, 6920.307969203064, 6926.68829902653, 6605.9669701651665, 6690.498212634072, 6694.263631000635, 6699.330066993261, 6705.597664140168, 27003.0, 27516.312092197655, 27518.862405688044, 27558.311042223904, 27901.720982790117, 2933.8679759410666, 2963.9110826675233, 2975.821450712964, 2990.8953186638364, 2996.3408050228913, 16456.83543164565, 16660.416885409075, 16671.49985500437, 17272.481825545256, 17599.0, 2043.9080241389174, 2078.2206672497814, 2099.265257159997, 2148.677698345245, 2168.9783102168935, 137623.0, 139500.0, 141777.0, 142113.0, 145187.274063629, 16605.0, 17606.735898961524, 19816.801831981495, 21172.576548468944, 21244.681329780065, 34555.0, 35099.473507897404, 35540.64459355425, 35544.755933542205, 35568.46647300241, 10004.649837255662, 10144.391336519833, 10576.259661823753, 10755.085817705583, 11002.944985275022, 7749.023998880362, 8958.059403762642, 8988.280937524982, 9178.81642367149, 9178.852643419572, 8561.52911589856, 10136.493175341204, 10162.796744065077, 10177.592896284066, 10192.541335639915, 3141.0, 3977.940330895039, 4168.374743788456, 4248.893777655521, 4249.405083288342, 876.5660997806, 906.4273485502648, 930.1117432851611, 965.3098034905076, 1075.3440401355167, 21672.89163554172, 22271.665925011137, 22277.0, 22349.994250258776, 22360.55278894413, 779.2713812585251, 932.8647346134773, 952.3999880075509, 963.8938918463181, 969.3989726369696, 11133.10935125188, 11461.885381146249, 11559.76880462386, 11628.88371116278, 11652.766944661229, 751490.0, 772234.0, 786607.0, 793434.0, 805643.0, 156382.0, 157057.0, 158306.0, 158956.0, 161278.38721612634, 1020.6325722739808, 1258.1633213912828, 1302.908796384263, 1306.45129045801, 1307.751527209846, 15680.745540356973, 19302.806971930662, 19857.322200745202, 19924.529054320068, 19940.900295498428, 63464.36535634769, 63658.0, 63996.68001659962, 65405.0, 65640.01539976809, 87648.56175719081, 89250.55374723085, 89842.55078724565, 90981.6352754696, 91164.70670377067, 10028.548715307974, 11249.943750281196, 11357.943210284226, 11552.480138393961, 11677.182597218112, 12145.210561313486, 12478.875211248134, 13494.865051349754, 13703.246321452416, 13704.24626645544, 11192.608258710907, 11307.095432365555, 11641.47613357384, 11728.41357932117, 11767.0, 10411.271211015157, 10462.215333850145, 10486.842697359698, 10698.251122421356, 10775.137988961022, 9457.905420945703, 10299.279050466395, 11682.831716828588, 11756.823647645464, 11895.345755983159, 9672.419654820733, 9673.467959262027, 11321.830172547583, 11592.768144637228, 12088.697782555506, 9305.720828375159, 11427.828582571101, 11454.312741235553, 11609.825852612386, 11611.071114311004], "value": 11697.14128964112, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1590.0777549021568, 1601.1994002998501, 1619.0366731794581, 1620.0522694223878, 1641.6060145565064, 10670.78658426832, 11299.0, 11314.038306743916, 11318.717032074203, 11325.0, 10843.566257349696, 11041.39272340021, 11276.774464510725, 11406.201565890391, 11412.31526108434, 2331.184085570045, 2369.2655276864225, 2382.523495300938, 2382.60686986647, 2385.355953892448, 2293.0369244917174, 2355.8233132515043, 2358.7169539655265, 2365.3377054424705, 2368.2421625079983, 2303.884805759714, 2331.1491305673476, 2335.1476711000532, 2346.495503466754, 2379.119725701493, 2339.6607491913746, 2340.7659234076627, 2359.103540654549, 2379.1316169598144, 2386.7613238676167, 2317.5480781247666, 2340.8127349812144, 2341.379534423379, 2343.824213184018, 2347.0377145370485, 10968.890311096866, 11234.831477527843, 11235.101191904629, 11240.269382490194, 11248.325100493996, 4773.80904763809, 5482.0, 5693.886122277573, 5699.116636921284, 5721.427857214287, 3179.639135401538, 3213.662565430643, 3284.556584861037, 3322.7009569138895, 3328.051505320972, 1085.9239853210279, 1094.9945250273777, 1106.1758989552754, 1127.7349822791637, 1137.795196864564, 13493.460261589526, 14013.229272390014, 14305.499307524187, 15334.463293784776, 15396.0, 3579.83890724918, 4498.190325741398, 4520.751358675305, 4525.954740452619, 4531.796069176895, 10068.39589624625, 11611.245269057485, 11624.825627615592, 11636.069114470822, 11654.76690466186, 4800.183968725325, 5314.468553144732, 5542.224088627595, 5557.5553955683445, 5567.66594004361, 4903.803847846046, 4987.80048798044, 4995.675281106716, 5033.546980771748, 5181.35233095863, 3707.647773461517, 3732.122951106488, 3760.924781504409, 3791.5829258781514, 3792.1088544192094, 937.1565590968183, 948.2935213266161, 951.6098399656108, 954.7708549947962, 960.1406740966795, 802.1697543043039, 816.542736067804, 827.0736774812249, 840.315143158324, 854.5001174312972, 6396.808095757135, 6469.4177524022125, 6518.57629254097, 6684.799456016327, 6794.592324460548, 1033.0857191385678, 1047.9476026198658, 1053.2942928238099, 1060.5651682810164, 1064.1114669251263, 3939.3106206414336, 4114.670826333886, 4254.212970600449, 4338.6312163466455, 4380.123975204971, 13470.461181552824, 14080.507182248572, 14089.0, 14096.788548171786, 14106.647333816736, 13726.245056521788, 13789.51736689231, 13981.790273145707, 14029.649258768408, 14063.367148478343, 10067.848982265272, 10227.181825453947, 10230.23273254509, 10231.976802319708, 10237.078662920225, 5561.082421400585, 5959.67221802805, 6194.45326138706, 6381.87236255263, 6535.640539770358, 10548.683539493828, 10938.398388088413, 12248.693782655504, 12266.75466490683, 12346.691332716753, 11051.17116216271, 11828.34944078084, 12130.272183669009, 12596.118271721258, 12695.301758403377, 9231.538423078953, 9516.904830951878, 9532.285078618992, 9532.618695252113, 9545.093216144454, 9958.15355694774, 10362.274640775073, 10411.0, 10423.322484038514, 10477.1094456972, 8670.869936951078, 8809.471431714117, 8851.513166775889, 9073.909260907307, 9128.817423651622, 8555.31557475413, 8582.399232053946, 8626.309895208493, 8654.78363040929, 8786.604602793017, 7821.413393995587, 7846.803829904298, 7926.960365198137, 7934.1272460030505, 7939.28546430824, 4647.535246475325, 5720.227769251264, 5810.215620891295, 5925.614835035882, 5964.731587078505, 5756.3380211276235, 5828.067509198508, 5849.970750146393, 5948.839976204772, 6016.669083200466], "value": 5028.475113745931, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.8617154738521, 383.12838292883686, 383.1436738888582, 384.80759620189906, 394.184039039189, 4212.525616034386, 4685.0, 4865.440474345447, 4906.632002599806, 4907.75461226939, 4558.6125179359715, 4626.768661566925, 4663.580277775, 4797.808087676497, 4888.97555512222, 3403.723603648632, 3577.481265216542, 3931.3906344516645, 4054.3107671695734, 4072.694547908904, 3825.101101241206, 4099.590040995907, 4191.643710284629, 4208.5380052669225, 4208.579142085798, 3341.7994920304736, 4128.545859955402, 4208.852690155845, 4212.1575684863, 4229.978850105746, 3497.527833742463, 3861.899358682762, 3863.8123548072954, 3875.65119139276, 4224.007358270804, 3594.4428613564937, 3686.0600546860646, 3807.600201978808, 3822.4075268333454, 3843.8654647087496, 4135.441715368417, 4212.662986961036, 4853.3690620219495, 4861.9546797438725, 4865.664481049081, 3884.76691398517, 4250.723702959298, 4274.3071864725025, 4438.733675979419, 4567.853109038747, 4712.078511322968, 4905.975470122662, 5303.310569625963, 5313.335833020872, 5324.790212061989, 3705.94441083384, 3970.563238043813, 4255.659547236244, 4270.935935960963, 4309.375140604626, 4707.7881495332795, 4763.833265835679, 5417.566594672417, 5618.859528511779, 5633.661980281156, 3723.9759066256456, 4313.525512193656, 4340.414044104069, 4345.413369195118, 4377.255866502702, 4081.6122468365456, 4142.79286035697, 4362.76004819738, 4423.0, 4446.933296000562, 4507.774611269423, 4782.473927867932, 4806.279058141306, 4829.348038014893, 4873.610111191097, 4183.309753890635, 4387.868363949086, 4731.905361892743, 4756.809727610926, 5349.545288650508, 1459.408939379553, 1482.570054684151, 1505.4053648808658, 1525.542337298806, 1526.1224795742492, 967.0426277984744, 1019.9439030853226, 1022.3763504262542, 1101.003591749452, 1122.7698321844002, 4354.390385346054, 4437.269464908688, 4610.585047345755, 4705.505921878222, 4789.856304310876, 4742.7865746040825, 4965.478624744422, 5345.625806193529, 5495.6153069284765, 5524.392316845144, 4180.749155050707, 4232.851850185229, 4689.85930422081, 4829.323894654681, 4833.719064447924, 2387.3912152401026, 2674.0239812468308, 2799.4261176458776, 2849.9597646858747, 2872.4973129702216, 5593.035691083732, 5617.517139373615, 5623.325200975909, 5645.661260324394, 5693.157118944696, 4019.5980401960155, 4719.018012434785, 4865.5864251538305, 5557.9435608825, 5699.715014249271, 4539.273716205391, 4696.759236127062, 5268.683878967274, 5636.97181514098, 5654.293213348332, 4941.975290123526, 5131.461196574455, 5285.969235998943, 5460.552953467414, 5494.835154945198, 5050.80693365218, 5302.787888484418, 5304.446021165905, 5441.129419292894, 5484.0, 4879.634027447884, 5039.488153553993, 5219.634625576326, 5487.176923461513, 5573.331200255995, 3750.168622689852, 3921.745086569364, 4358.716683415568, 4366.061296821199, 4377.562243775597, 4589.8657124437295, 5263.7894484220205, 5265.21021846726, 5418.965379387556, 5549.278593782782, 4743.1343455084, 4751.59611433032, 4859.854204373874, 4865.635077369141, 5253.527182553588, 4594.163873165944, 4737.431508219036, 4850.6119510438375, 5016.739119572161, 5327.7746118392215, 4558.384618076518, 4984.0874913192965, 5097.61767867404, 5121.976819156338, 5188.818391356284, 4813.663043586916, 5266.0, 5307.840764777063, 5318.547923426552, 5359.437259087739, 4549.545045495424, 5151.0470562945975, 5178.844634660966, 5180.378354597472, 5187.559057480156], "value": 3996.1622669446615, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.4721668440436, 765.2041876448493, 795.5147360110332, 850.5067061104552, 865.6321063547992, 13001.284929328884, 16097.83902160978, 16374.672506549861, 16429.342826286964, 16447.0, 14885.627859303522, 15020.549383518484, 15025.098494090362, 15036.022658527216, 15039.398424063053, 970.7184916374243, 981.1856159387692, 993.46849435552, 1001.3241062282966, 1011.5599714124343, 983.5475681186663, 996.875390576178, 998.0817647764038, 998.8551660009331, 998.8951160128155, 1053.6364954090827, 1056.392574269794, 1056.9958539387574, 1057.043375744952, 1057.4659796802641, 1003.703907347327, 1049.931754435967, 1052.1057101463732, 1053.9262251642388, 1054.3199636234663, 783.2363445640511, 784.5018413307533, 784.666516730393, 785.701433455289, 785.7642707187821, 16264.593385165466, 16507.0, 16567.17164141788, 16766.07786571738, 17025.31898724062, 14373.281335933267, 14432.278386080654, 14447.638809029753, 14526.346314415876, 14553.345099470549, 11636.650900473, 11685.766284674259, 11890.762184756342, 11937.28376297425, 12268.877311226863, 3773.396256598931, 3796.414884657352, 3804.8478060877533, 3817.3892177251782, 3926.9018274543364, 105144.0, 111144.4442777781, 111461.0, 111821.88178118278, 114006.42996784963, 10269.589216431408, 10290.485475726184, 10787.892121078688, 10879.075278601249, 11190.720231994264, 23247.0, 23514.941827617797, 23523.17668881581, 23576.64635030476, 23608.17371391993, 7870.921290787019, 7946.015770337605, 7992.880106798402, 8073.798155046171, 8123.18768123183, 3976.840926362971, 4278.807453664593, 4284.507281662586, 4428.402165707588, 4659.4175728034, 3423.2639982403903, 3424.8630054798027, 3476.1135910343205, 3484.9303013940084, 3504.5444092267844, 612.1735656863199, 619.0837560410617, 623.6788054152115, 634.0425956805258, 634.4733870887153, 636.2619361540591, 641.2689533931322, 645.8121112023152, 647.7085311609794, 660.6762686283782, 9160.633574657073, 9727.0, 9806.705798826046, 9825.0, 9888.20894328452, 637.6684124255361, 667.7028722218663, 670.936261055199, 673.0610797936948, 678.5589366911547, 4582.793774280165, 4769.451513075971, 4777.880552986203, 4788.569028787426, 4796.37647105874, 102332.0, 104374.47812760888, 104744.47627761813, 104946.4752676247, 105182.0, 89129.0, 89389.55305223563, 91034.0, 92308.53845730728, 92958.53520732488, 78605.0, 78927.21072789314, 80689.59655201687, 81059.59470202729, 81612.0, 9330.393524420891, 9336.906630933603, 9350.0, 9360.719178424659, 9374.25005999532, 48877.044918202875, 51434.97130057303, 53362.46637533575, 54333.0, 54943.45056549383, 68242.0, 68503.65748171227, 69909.30090699231, 70416.64791676009, 70438.64780676064, 9804.705858824247, 11510.884891151316, 11874.0, 11929.28424294545, 12101.81847272274, 11844.881551184722, 11964.401779911144, 12010.75978480443, 12061.216020958609, 12369.196002259825, 11500.827487587525, 11526.88473115258, 11973.820392693942, 11973.940130299292, 12232.327222002876, 11110.944445277722, 11230.94384528072, 11906.34515101643, 11911.404429778304, 12186.0, 9560.62672000859, 11169.218154729091, 11194.888051119384, 11551.0, 11659.766804664028, 7926.801829954066, 8315.251627353568, 8395.538245396561, 8482.363822713198, 8644.351673624378, 8559.95720021396, 8802.38383313162, 9489.762755931157, 9523.428594284365, 9536.189423899046], "value": 7493.094412440893, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1406.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 117.0, 116.0, 115.0, 116.0, 117.0, 116.0, 118.0, 116.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 107.0, 107.0, 142.0, 109.0, 108.0, 106.0, 89.0, 129.0, 135.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 184.0, 186.0, 186.0, 185.0, 184.0, 186.0, 188.0, 182.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 61.0, 89.0, 64.0, 63.0, 62.0, 62.0, 77.0, 61.0], "value": 68.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 86.0, 69.0, 69.0, 68.0, 82.0, 69.0, 69.0, 84.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 88.0, 88.0, 88.0, 88.0, 88.0, 87.0, 88.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 94.0, 94.0, 95.0, 95.0, 95.0, 99.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 86.0, 84.0, 84.0, 87.0, 85.0, 83.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0, 62.0, 64.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 63.0, 62.0, 60.0, 58.0, 57.0, 57.0, 58.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 82.0, 86.0, 85.0, 83.0, 84.0, 87.0, 107.0, 83.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 136.0, 144.0, 135.0, 136.0, 135.0, 135.0, 171.0, 142.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 164.0, 165.0, 166.0, 164.0, 164.0, 163.0, 165.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0, 63.0, 63.0], "value": 64.4, "unit": "score"}]}]} 13:08:52 INFO - Return code: 0 13:08:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:08:52 INFO - # TBPL SUCCESS # 13:08:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:08:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:08:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:08:52 INFO - 2017-05-30 13:08:52.355 screenresolution[1659:9693] starting screenresolution argv=screenresolution get 13:08:52 INFO - 2017-05-30 13:08:52.358 screenresolution[1659:9693] Display 0: 1600x1200x32@60 13:08:52 INFO - 2017-05-30 13:08:52.364 screenresolution[1660:9696] starting screenresolution argv=screenresolution list 13:08:52 INFO - Available Modes on Display 0 13:08:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:08:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:08:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:08:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:08:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:08:52 INFO - Intel Iris: 13:08:52 INFO - Chipset Model: Intel Iris 13:08:52 INFO - Type: GPU 13:08:52 INFO - Bus: Built-In 13:08:52 INFO - VRAM (Dynamic, Max): 1536 MB 13:08:52 INFO - Vendor: Intel (0x8086) 13:08:52 INFO - Device ID: 0x0a2e 13:08:52 INFO - Revision ID: 0x0009 13:08:52 INFO - Displays: 13:08:52 INFO - Display: 13:08:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:08:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:08:52 INFO - Display Serial Number: 1600x1200 60 13:08:52 INFO - Main Display: Yes 13:08:52 INFO - Mirror: Off 13:08:52 INFO - Online: Yes 13:08:52 INFO - Rotation: Supported 13:08:52 INFO - Return code: 0 13:08:52 INFO - Running post-action listener: _resource_record_post_action 13:08:52 INFO - [mozharness: 2017-05-30 20:08:52.598708Z] Finished run-tests step (success) 13:08:52 INFO - Running post-run listener: _resource_record_post_run 13:08:52 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39683072; Write bytes: 510930944; Read time: 259; Write time: 1545 13:08:52 INFO - TinderboxPrint: CPU usage
53.8% 13:08:52 INFO - TinderboxPrint: I/O read bytes / time
39,683,072 / 259 13:08:52 INFO - TinderboxPrint: I/O write bytes / time
510,930,944 / 1,545 13:08:52 INFO - TinderboxPrint: CPU idle
2,215.0 (46.2%) 13:08:52 INFO - TinderboxPrint: CPU system
957.1 (20.0%) 13:08:52 INFO - TinderboxPrint: CPU user
1,621.5 (33.8%) 13:08:52 INFO - TinderboxPrint: Swap in / out
247,320,576 / 0 13:08:52 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204306944; Write bytes: 202782720; Read time: 15589; Write time: 403 13:08:52 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37540864; Write bytes: 297224192; Read time: 237; Write time: 1106 13:08:53 INFO - Running post-run listener: _upload_blobber_files 13:08:53 INFO - Blob upload gear active. 13:08:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:08:53 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 13:08:53 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'] 13:08:53 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 13:08:53 INFO - (blobuploader) - INFO - Open directory for files ... 13:08:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:08:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:54 INFO - (blobuploader) - INFO - Done attempting. 13:08:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:08:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:55 INFO - (blobuploader) - INFO - Done attempting. 13:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:08:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:56 INFO - (blobuploader) - INFO - Done attempting. 13:08:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:08:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:58 INFO - (blobuploader) - INFO - Done attempting. 13:08:58 INFO - (blobuploader) - INFO - Iteration through files over. 13:08:58 INFO - Return code: 0 13:08:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:08:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:08:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61f37d86905f5d5377882eee564e6a54448113d4be03b92fc737f76b6b9995568bdc2756569f630d169e2592f7199802c71670138656f827aec8e7a4fb024301", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0c3d24f5aac776d9af3b2e3ce3e98361695773080fdaf6cace875c1018eb2ac4a0f3aae2396f320816a13311d70c802da930ca36a16c067e2694f4400535b86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ce2df090b64e25eecf66206dfbc4596489135f92fa4f28174903b46d74dd15a1fa1da9f65b8aa71dad26fc3e2e57e8a10318242f9d50073ad11146b0b615415", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddf77c2d4018bef1ceaf736836cc00957cda93b53ff3e157a86952312cf67d7d1e92acccfa3e7186c0d28198d18396d471832d12287d87257b783535b3bdf0f7"} 13:08:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:08:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:08:58 INFO - Contents: 13:08:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61f37d86905f5d5377882eee564e6a54448113d4be03b92fc737f76b6b9995568bdc2756569f630d169e2592f7199802c71670138656f827aec8e7a4fb024301", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0c3d24f5aac776d9af3b2e3ce3e98361695773080fdaf6cace875c1018eb2ac4a0f3aae2396f320816a13311d70c802da930ca36a16c067e2694f4400535b86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ce2df090b64e25eecf66206dfbc4596489135f92fa4f28174903b46d74dd15a1fa1da9f65b8aa71dad26fc3e2e57e8a10318242f9d50073ad11146b0b615415", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddf77c2d4018bef1ceaf736836cc00957cda93b53ff3e157a86952312cf67d7d1e92acccfa3e7186c0d28198d18396d471832d12287d87257b783535b3bdf0f7"} 13:08:58 INFO - Running post-run listener: copy_logs_to_upload_dir 13:08:58 INFO - Copying logs to upload dir... 13:08:58 INFO - mkdir: /builds/slave/test/build/upload/logs 13:08:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.320701 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-30 13:08:58.326208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 13:08:58.329386) ========= 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.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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/61f37d86905f5d5377882eee564e6a54448113d4be03b92fc737f76b6b9995568bdc2756569f630d169e2592f7199802c71670138656f827aec8e7a4fb024301", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0c3d24f5aac776d9af3b2e3ce3e98361695773080fdaf6cace875c1018eb2ac4a0f3aae2396f320816a13311d70c802da930ca36a16c067e2694f4400535b86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ce2df090b64e25eecf66206dfbc4596489135f92fa4f28174903b46d74dd15a1fa1da9f65b8aa71dad26fc3e2e57e8a10318242f9d50073ad11146b0b615415", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddf77c2d4018bef1ceaf736836cc00957cda93b53ff3e157a86952312cf67d7d1e92acccfa3e7186c0d28198d18396d471832d12287d87257b783535b3bdf0f7"} build_url:https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011006 build_url: 'https://queue.taskcluster.net/v1/task/bjAEe1VaQzSmGqhtwEw8ig/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/61f37d86905f5d5377882eee564e6a54448113d4be03b92fc737f76b6b9995568bdc2756569f630d169e2592f7199802c71670138656f827aec8e7a4fb024301", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d0c3d24f5aac776d9af3b2e3ce3e98361695773080fdaf6cace875c1018eb2ac4a0f3aae2396f320816a13311d70c802da930ca36a16c067e2694f4400535b86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ce2df090b64e25eecf66206dfbc4596489135f92fa4f28174903b46d74dd15a1fa1da9f65b8aa71dad26fc3e2e57e8a10318242f9d50073ad11146b0b615415", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ddf77c2d4018bef1ceaf736836cc00957cda93b53ff3e157a86952312cf67d7d1e92acccfa3e7186c0d28198d18396d471832d12287d87257b783535b3bdf0f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 13:08:58.361937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 13:08:58.362268) ========= 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.cdMEiacRr6/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.cGcsGukV2c/Listeners TMPDIR=/var/folders/lr/dk8sq3xj3n7gfb5sczdhf_qr00000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 13:08:58.410646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 13:08:58.411067) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 13:08:58.411362) ========= ========= Total master_lag: 0.18 =========