builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0329 starttime: 1494951918.43 results: success (0) buildid: 20170516074024 builduid: 02f9ef755fb241409788cbc9682986bf revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.429753) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.430237) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.430526) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.455948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.456268) ========= 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.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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-16 09:25:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-05-16 09:25:19 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.559870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.039133) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.039456) ========= 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.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.026943 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.088244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 09:25:19.088608) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --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 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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-16 09:25:19,197 truncating revision to first 12 chars 2017-05-16 09:25:19,197 Setting DEBUG logging. 2017-05-16 09:25:19,197 attempt 1/10 2017-05-16 09:25:19,198 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/13ff34d82062?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 09:25:20,830 unpacking tar archive at: mozilla-beta-13ff34d82062/testing/mozharness/ program finished with exit code 0 elapsedTime=1.915374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 09:25:21.028023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.028353) ========= script_repo_revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.028797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.029064) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.047892) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-16 09:25:21.048272) ========= /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.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:25:21 INFO - MultiFileLogger online at 20170516 09:25:21 in /builds/slave/test 09:25:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:25:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:21 INFO - {'append_to_log': False, 09:25:21 INFO - 'base_work_dir': '/builds/slave/test', 09:25:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:25:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:21 INFO - 'branch': 'Mozilla-Beta', 09:25:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:21 INFO - 'config_files': ('talos/mac_config.py',), 09:25:21 INFO - 'default_actions': ('clobber', 09:25:21 INFO - 'read-buildbot-config', 09:25:21 INFO - 'download-and-extract', 09:25:21 INFO - 'populate-webroot', 09:25:21 INFO - 'create-virtualenv', 09:25:21 INFO - 'install', 09:25:21 INFO - 'run-tests'), 09:25:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:21 INFO - 'download_minidump_stackwalk': True, 09:25:21 INFO - 'download_symbols': 'ondemand', 09:25:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:25:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:25:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:25:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:25:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:21 INFO - 'gecko_profile': False, 09:25:21 INFO - 'gecko_profile_interval': 0, 09:25:21 INFO - 'installer_path': 'installer.exe', 09:25:21 INFO - 'log_level': 'info', 09:25:21 INFO - 'log_name': 'talos', 09:25:21 INFO - 'log_to_console': True, 09:25:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:25:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:25:21 INFO - 'opt_config_files': (), 09:25:21 INFO - 'pip_index': False, 09:25:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:21 INFO - 'cmd': ('bash', 09:25:21 INFO - '-c', 09:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:21 INFO - 'enabled': True, 09:25:21 INFO - 'halt_on_failure': False, 09:25:21 INFO - 'name': 'check_screen_resolution'},), 09:25:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:21 INFO - 'cmd': ('bash', 09:25:21 INFO - '-c', 09:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:21 INFO - 'enabled': True, 09:25:21 INFO - 'halt_on_failure': False, 09:25:21 INFO - 'name': 'check_screen_resolution'},), 09:25:21 INFO - 'run_cmd_checks_enabled': True, 09:25:21 INFO - 'suite': 'dromaeojs', 09:25:21 INFO - 'system_bits': '32', 09:25:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:21 INFO - 'title': 't-yosemite-r7-0329', 09:25:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:21 INFO - 'use_talos_json': True, 09:25:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:21 INFO - 'work_dir': 'build'} 09:25:21 INFO - [mozharness: 2017-05-16 16:25:21.259802Z] Running clobber step. 09:25:21 INFO - Running pre-action listener: _resource_record_pre_action 09:25:21 INFO - Running main action method: clobber 09:25:21 INFO - rmtree: /builds/slave/test/build 09:25:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:25 INFO - Running post-action listener: _resource_record_post_action 09:25:25 INFO - [mozharness: 2017-05-16 16:25:25.730211Z] Finished clobber step (success) 09:25:25 INFO - [mozharness: 2017-05-16 16:25:25.730467Z] Running read-buildbot-config step. 09:25:25 INFO - Running pre-action listener: _resource_record_pre_action 09:25:25 INFO - Running main action method: read_buildbot_config 09:25:25 INFO - Using buildbot properties: 09:25:25 INFO - { 09:25:25 INFO - "project": "", 09:25:25 INFO - "product": "firefox", 09:25:25 INFO - "script_repo_revision": "production", 09:25:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:25:25 INFO - "repository": "", 09:25:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:25:25 INFO - "buildid": "20170516074024", 09:25:25 INFO - "pgo_build": "False", 09:25:25 INFO - "basedir": "/builds/slave/test", 09:25:25 INFO - "buildnumber": 12, 09:25:25 INFO - "slavename": "t-yosemite-r7-0329", 09:25:25 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:25:25 INFO - "platform": "yosemite_r7", 09:25:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:25:25 INFO - "branch": "mozilla-beta", 09:25:25 INFO - "repo_path": "releases/mozilla-beta", 09:25:25 INFO - "revision": "13ff34d820621cdaa52a9cf2dfd2a66998438bf8", 09:25:25 INFO - "stage_platform": "macosx64", 09:25:25 INFO - "builduid": "02f9ef755fb241409788cbc9682986bf", 09:25:25 INFO - "slavebuilddir": "test" 09:25:25 INFO - } 09:25:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:25:25 INFO - Running post-action listener: _resource_record_post_action 09:25:25 INFO - [mozharness: 2017-05-16 16:25:25.736177Z] Finished read-buildbot-config step (success) 09:25:25 INFO - [mozharness: 2017-05-16 16:25:25.736405Z] Running download-and-extract step. 09:25:25 INFO - Running pre-action listener: _resource_record_pre_action 09:25:25 INFO - Running main action method: download_and_extract 09:25:25 INFO - mkdir: /builds/slave/test/build/tests 09:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:25 INFO - trying https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:25:25 INFO - Downloading https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:25:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:25:27 INFO - Downloaded 1287 bytes. 09:25:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:25:27 INFO - Using the following test package requirements: 09:25:27 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:25:27 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:25:27 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:25:27 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:25:27 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:25:27 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:25:27 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:25:27 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:25:27 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:25:27 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:25:27 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:27 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:25:27 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:25:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:25:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:25:27 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:25:28 INFO - Expected file size: 51224753 09:25:28 INFO - Obtained file size: 51224753 09:25:44 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:25:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:25:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:25:44 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:25:46 INFO - Expected file size: 51224753 09:25:46 INFO - Obtained file size: 51224753 09:26:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:26:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:26:01 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:26:02 INFO - Expected file size: 14404449 09:26:02 INFO - Obtained file size: 14404449 09:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:02 INFO - trying https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:02 INFO - Downloading https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:26:04 INFO - Downloaded 59541045 bytes. 09:26:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:04 INFO - mkdir: /builds/slave/test/properties 09:26:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:04 INFO - Contents: 09:26:04 INFO - build_url:https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:05 INFO - Running post-action listener: _resource_record_post_action 09:26:05 INFO - Running post-action listener: set_extra_try_arguments 09:26:05 INFO - [mozharness: 2017-05-16 16:26:05.306346Z] Finished download-and-extract step (success) 09:26:05 INFO - [mozharness: 2017-05-16 16:26:05.306472Z] Running populate-webroot step. 09:26:05 INFO - Running pre-action listener: _resource_record_pre_action 09:26:05 INFO - Running main action method: populate_webroot 09:26:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:05 INFO - '--tppagecycles', 09:26:05 INFO - '7'], 09:26:05 INFO - 'tests': ['tsvgm']}}, 09:26:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['damp', 'tps']}, 09:26:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'tests': ['damp', 'tps']}, 09:26:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['dromaeo_dom']}, 09:26:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['basic_compositor_video']}, 09:26:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['a11yr', 09:26:05 INFO - 'ts_paint', 09:26:05 INFO - 'tpaint', 09:26:05 INFO - 'sessionrestore', 09:26:05 INFO - 'sessionrestore_no_auto_restore', 09:26:05 INFO - 'tabpaint']}, 09:26:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['tsvgx', 09:26:05 INFO - 'tsvgr_opacity', 09:26:05 INFO - 'tart', 09:26:05 INFO - 'tscrollx', 09:26:05 INFO - 'cart', 09:26:05 INFO - 'tsvg_static']}, 09:26:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:05 INFO - 'tsvgr_opacity', 09:26:05 INFO - 'tart', 09:26:05 INFO - 'tscrollx', 09:26:05 INFO - 'cart', 09:26:05 INFO - 'tsvg_static']}, 09:26:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'talos_options': ['--disable-e10s'], 09:26:05 INFO - 'tests': ['tp5o']}, 09:26:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:26:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'talos_options': ['--disable-e10s', 09:26:05 INFO - '--xperf_path', 09:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:05 INFO - 'tests': ['tp5n']}, 09:26:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:26:05 INFO - 'talos_options': ['--xperf_path', 09:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:05 INFO - 'tests': ['tp5n']}}, 09:26:05 INFO - 'talos.zip': {'path': '', 09:26:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:05 INFO - Running post-action listener: _resource_record_post_action 09:26:05 INFO - [mozharness: 2017-05-16 16:26:05.325059Z] Finished populate-webroot step (success) 09:26:05 INFO - [mozharness: 2017-05-16 16:26:05.325165Z] Running create-virtualenv step. 09:26:05 INFO - Running pre-action listener: _resource_record_pre_action 09:26:05 INFO - Running main action method: create_virtualenv 09:26:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:26:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:05 INFO - Using real prefix '/tools/python27' 09:26:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:09 INFO - Installing pip.................done. 09:26:09 INFO - Return code: 0 09:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:26:09 INFO - Reading from file tmpfile_stdout 09:26:09 INFO - Output received: 09:26:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:26:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:09 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:09 INFO - 'HOME': '/Users/cltbld', 09:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:09 INFO - 'LOGNAME': 'cltbld', 09:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:09 INFO - 'MOZ_NO_REMOTE': '1', 09:26:09 INFO - 'NO_EM_RESTART': '1', 09:26:09 INFO - 'PAGER': '/bin/cat', 09:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:09 INFO - 'PWD': '/builds/slave/test', 09:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:09 INFO - 'SHELL': '/bin/bash', 09:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:09 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:09 INFO - 'USER': 'cltbld', 09:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:09 INFO - 'XPC_FLAGS': '0x0', 09:26:09 INFO - 'XPC_SERVICE_NAME': '0', 09:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:26:09 INFO - Cleaning up... 09:26:09 INFO - Return code: 0 09:26:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:09 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:09 INFO - 'HOME': '/Users/cltbld', 09:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:09 INFO - 'LOGNAME': 'cltbld', 09:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:09 INFO - 'MOZ_NO_REMOTE': '1', 09:26:09 INFO - 'NO_EM_RESTART': '1', 09:26:09 INFO - 'PAGER': '/bin/cat', 09:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:09 INFO - 'PWD': '/builds/slave/test', 09:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:09 INFO - 'SHELL': '/bin/bash', 09:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:09 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:09 INFO - 'USER': 'cltbld', 09:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:09 INFO - 'XPC_FLAGS': '0x0', 09:26:09 INFO - 'XPC_SERVICE_NAME': '0', 09:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:09 INFO - Downloading/unpacking psutil>=3.1.1 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:12 INFO - Installing collected packages: psutil 09:26:12 INFO - Running setup.py install for psutil 09:26:12 INFO - building 'psutil._psutil_osx' extension 09:26:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:26:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:26:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:26:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:26:13 INFO - building 'psutil._psutil_posix' extension 09:26:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:26:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:13 INFO - ^ 09:26:13 INFO - 1 warning generated. 09:26:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:13 INFO - Successfully installed psutil 09:26:13 INFO - Cleaning up... 09:26:13 INFO - Return code: 0 09:26:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:13 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:13 INFO - 'HOME': '/Users/cltbld', 09:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:13 INFO - 'LOGNAME': 'cltbld', 09:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:13 INFO - 'MOZ_NO_REMOTE': '1', 09:26:13 INFO - 'NO_EM_RESTART': '1', 09:26:13 INFO - 'PAGER': '/bin/cat', 09:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:13 INFO - 'PWD': '/builds/slave/test', 09:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:13 INFO - 'SHELL': '/bin/bash', 09:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:13 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:13 INFO - 'USER': 'cltbld', 09:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:13 INFO - 'XPC_FLAGS': '0x0', 09:26:13 INFO - 'XPC_SERVICE_NAME': '0', 09:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:15 INFO - Installing collected packages: mozsystemmonitor 09:26:15 INFO - Running setup.py install for mozsystemmonitor 09:26:15 INFO - Successfully installed mozsystemmonitor 09:26:15 INFO - Cleaning up... 09:26:15 INFO - Return code: 0 09:26:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:15 INFO - 'HOME': '/Users/cltbld', 09:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:15 INFO - 'LOGNAME': 'cltbld', 09:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:15 INFO - 'MOZ_NO_REMOTE': '1', 09:26:15 INFO - 'NO_EM_RESTART': '1', 09:26:15 INFO - 'PAGER': '/bin/cat', 09:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:15 INFO - 'PWD': '/builds/slave/test', 09:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:15 INFO - 'SHELL': '/bin/bash', 09:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:15 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:15 INFO - 'USER': 'cltbld', 09:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:15 INFO - 'XPC_FLAGS': '0x0', 09:26:15 INFO - 'XPC_SERVICE_NAME': '0', 09:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:15 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:17 INFO - warning: no files found matching '*.txt' 09:26:17 INFO - no previously-included directories found matching 'build' 09:26:17 INFO - no previously-included directories found matching 'dist' 09:26:17 INFO - no previously-included directories found matching '.git*' 09:26:17 INFO - Installing collected packages: jsonschema, functools32 09:26:17 INFO - Running setup.py install for functools32 09:26:17 INFO - warning: no files found matching '*.txt' 09:26:17 INFO - no previously-included directories found matching 'build' 09:26:17 INFO - no previously-included directories found matching 'dist' 09:26:17 INFO - no previously-included directories found matching '.git*' 09:26:17 INFO - Successfully installed jsonschema functools32 09:26:17 INFO - Cleaning up... 09:26:17 INFO - Return code: 0 09:26:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:17 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:17 INFO - 'HOME': '/Users/cltbld', 09:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:17 INFO - 'LOGNAME': 'cltbld', 09:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:17 INFO - 'MOZ_NO_REMOTE': '1', 09:26:17 INFO - 'NO_EM_RESTART': '1', 09:26:17 INFO - 'PAGER': '/bin/cat', 09:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:17 INFO - 'PWD': '/builds/slave/test', 09:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:17 INFO - 'SHELL': '/bin/bash', 09:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:17 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:17 INFO - 'USER': 'cltbld', 09:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:17 INFO - 'XPC_FLAGS': '0x0', 09:26:17 INFO - 'XPC_SERVICE_NAME': '0', 09:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:17 INFO - Cleaning up... 09:26:17 INFO - Return code: 0 09:26:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:17 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:17 INFO - 'HOME': '/Users/cltbld', 09:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:17 INFO - 'LOGNAME': 'cltbld', 09:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:17 INFO - 'MOZ_NO_REMOTE': '1', 09:26:17 INFO - 'NO_EM_RESTART': '1', 09:26:17 INFO - 'PAGER': '/bin/cat', 09:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:17 INFO - 'PWD': '/builds/slave/test', 09:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:17 INFO - 'SHELL': '/bin/bash', 09:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:17 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:17 INFO - 'USER': 'cltbld', 09:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:17 INFO - 'XPC_FLAGS': '0x0', 09:26:17 INFO - 'XPC_SERVICE_NAME': '0', 09:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - Downloading docopt-0.6.1.tar.gz 09:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:20 INFO - Running setup.py install for blobuploader 09:26:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:20 INFO - Running setup.py install for requests 09:26:21 INFO - Running setup.py install for docopt 09:26:21 INFO - Successfully installed blobuploader requests docopt 09:26:21 INFO - Cleaning up... 09:26:21 INFO - Return code: 0 09:26:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:21 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:21 INFO - 'HOME': '/Users/cltbld', 09:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:21 INFO - 'LOGNAME': 'cltbld', 09:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:21 INFO - 'MOZ_NO_REMOTE': '1', 09:26:21 INFO - 'NO_EM_RESTART': '1', 09:26:21 INFO - 'PAGER': '/bin/cat', 09:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:21 INFO - 'PWD': '/builds/slave/test', 09:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:21 INFO - 'SHELL': '/bin/bash', 09:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:21 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:21 INFO - 'USER': 'cltbld', 09:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:21 INFO - 'XPC_FLAGS': '0x0', 09:26:21 INFO - 'XPC_SERVICE_NAME': '0', 09:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:21 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-HG9LeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:21 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-0yLMyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:21 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-p0AGFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:21 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-6oooyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-hVmrJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-JeZTxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-16OgTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-y8Z4el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-hAmhTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-fMxn0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-rpwLgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-cBqMI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:22 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-NtKpwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:23 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-DFjrkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:23 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8_6Et7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:23 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-cYN4g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:23 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-gZrY7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:23 INFO - Running setup.py install for manifestparser 09:26:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Running setup.py install for mozcrash 09:26:23 INFO - Running setup.py install for mozdebug 09:26:23 INFO - Running setup.py install for mozdevice 09:26:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Running setup.py install for mozfile 09:26:24 INFO - Running setup.py install for mozhttpd 09:26:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Running setup.py install for mozinfo 09:26:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Running setup.py install for mozInstall 09:26:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Running setup.py install for mozleak 09:26:24 INFO - Running setup.py install for mozlog 09:26:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Running setup.py install for moznetwork 09:26:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Running setup.py install for mozprocess 09:26:25 INFO - Running setup.py install for mozprofile 09:26:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Running setup.py install for mozrunner 09:26:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Running setup.py install for mozscreenshot 09:26:25 INFO - Running setup.py install for moztest 09:26:25 INFO - Running setup.py install for mozversion 09:26:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:26:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:26:25 INFO - Cleaning up... 09:26:25 INFO - Return code: 0 09:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:25 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:26:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:26 INFO - 'HOME': '/Users/cltbld', 09:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:26 INFO - 'LOGNAME': 'cltbld', 09:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:26 INFO - 'MOZ_NO_REMOTE': '1', 09:26:26 INFO - 'NO_EM_RESTART': '1', 09:26:26 INFO - 'PAGER': '/bin/cat', 09:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:26 INFO - 'PWD': '/builds/slave/test', 09:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:26 INFO - 'SHELL': '/bin/bash', 09:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:26 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:26 INFO - 'USER': 'cltbld', 09:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:26 INFO - 'XPC_FLAGS': '0x0', 09:26:26 INFO - 'XPC_SERVICE_NAME': '0', 09:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-8894V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-6KiAnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-mkGGED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-DsprQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-h82BRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-kcsbI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:26 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-P6eDiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Sfm0Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-roGMwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-o06Dnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-jWYtIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-PWDm2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-2H4dHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-j9cgL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-Ks4JJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-zurWmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:27 INFO - Running setup.py (path:/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/pip-GoDdBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:29 INFO - Downloading blessings-1.6.tar.gz 09:26:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:26:29 INFO - Installing collected packages: blessings 09:26:29 INFO - Running setup.py install for blessings 09:26:29 INFO - Successfully installed blessings 09:26:29 INFO - Cleaning up... 09:26:29 INFO - Return code: 0 09:26:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:26:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:30 INFO - Reading from file tmpfile_stdout 09:26:30 INFO - Current package versions: 09:26:30 INFO - blessings == 1.6 09:26:30 INFO - blobuploader == 1.2.4 09:26:30 INFO - docopt == 0.6.1 09:26:30 INFO - functools32 == 3.2.3-2 09:26:30 INFO - jsonschema == 2.5.1 09:26:30 INFO - manifestparser == 1.1 09:26:30 INFO - mozInstall == 1.12 09:26:30 INFO - mozcrash == 1.0 09:26:30 INFO - mozdebug == 0.1 09:26:30 INFO - mozdevice == 0.49 09:26:30 INFO - mozfile == 1.2 09:26:30 INFO - mozhttpd == 0.7 09:26:30 INFO - mozinfo == 0.9 09:26:30 INFO - mozleak == 0.1 09:26:30 INFO - mozlog == 3.4 09:26:30 INFO - moznetwork == 0.27 09:26:30 INFO - mozprocess == 0.25 09:26:30 INFO - mozprofile == 0.28 09:26:30 INFO - mozrunner == 6.13 09:26:30 INFO - mozscreenshot == 0.1 09:26:30 INFO - mozsystemmonitor == 0.3 09:26:30 INFO - moztest == 0.8 09:26:30 INFO - mozversion == 1.4 09:26:30 INFO - psutil == 3.1.1 09:26:30 INFO - requests == 1.2.3 09:26:30 INFO - wsgiref == 0.1.2 09:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:30 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:30 INFO - 'HOME': '/Users/cltbld', 09:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:30 INFO - 'LOGNAME': 'cltbld', 09:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:30 INFO - 'MOZ_NO_REMOTE': '1', 09:26:30 INFO - 'NO_EM_RESTART': '1', 09:26:30 INFO - 'PAGER': '/bin/cat', 09:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:30 INFO - 'PWD': '/builds/slave/test', 09:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:30 INFO - 'SHELL': '/bin/bash', 09:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:30 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:30 INFO - 'USER': 'cltbld', 09:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:30 INFO - 'XPC_FLAGS': '0x0', 09:26:30 INFO - 'XPC_SERVICE_NAME': '0', 09:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:26:30 INFO - Cleaning up... 09:26:30 INFO - Return code: 0 09:26:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:30 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 0x10b4d9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4dc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a10d7e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, '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.LdzViRilXI/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.pZ3bb2uLIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:30 INFO - 'HOME': '/Users/cltbld', 09:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:30 INFO - 'LOGNAME': 'cltbld', 09:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:30 INFO - 'MOZ_NO_REMOTE': '1', 09:26:30 INFO - 'NO_EM_RESTART': '1', 09:26:30 INFO - 'PAGER': '/bin/cat', 09:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:30 INFO - 'PWD': '/builds/slave/test', 09:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:30 INFO - 'SHELL': '/bin/bash', 09:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:30 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:30 INFO - 'USER': 'cltbld', 09:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:30 INFO - 'XPC_FLAGS': '0x0', 09:26:30 INFO - 'XPC_SERVICE_NAME': '0', 09:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:30 INFO - Cleaning up... 09:26:30 INFO - Return code: 0 09:26:30 INFO - Running post-action listener: _resource_record_post_action 09:26:30 INFO - Running post-action listener: _start_resource_monitoring 09:26:30 INFO - Starting resource monitoring. 09:26:30 INFO - [mozharness: 2017-05-16 16:26:30.602731Z] Finished create-virtualenv step (success) 09:26:30 INFO - [mozharness: 2017-05-16 16:26:30.603688Z] Running install step. 09:26:30 INFO - Running pre-action listener: _resource_record_pre_action 09:26:30 INFO - Running main action method: install 09:26:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:30 INFO - Reading from file tmpfile_stdout 09:26:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:30 INFO - Reading from file tmpfile_stdout 09:26:30 INFO - Output received: 09:26:30 INFO - Usage: mozinstall [options] installer 09:26:30 INFO - Options: 09:26:30 INFO - -h, --help show this help message and exit 09:26:30 INFO - -d DEST, --destination=DEST 09:26:30 INFO - Directory to install application into. [default: 09:26:30 INFO - "/builds/slave/test"] 09:26:30 INFO - --app=APP Application being installed. [default: firefox] 09:26:30 INFO - mkdir: /builds/slave/test/build/application 09:26:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:26:45 INFO - Reading from file tmpfile_stdout 09:26:45 INFO - Output received: 09:26:45 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:26:45 INFO - Running post-action listener: _resource_record_post_action 09:26:45 INFO - [mozharness: 2017-05-16 16:26:45.738596Z] Finished install step (success) 09:26:45 INFO - [mozharness: 2017-05-16 16:26:45.738727Z] Running run-tests step. 09:26:45 INFO - Running pre-action listener: _resource_record_pre_action 09:26:45 INFO - Running main action method: run_tests 09:26:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:45 INFO - 2017-05-16 09:26:45.751 screenresolution[1634:8167] starting screenresolution argv=screenresolution get 09:26:45 INFO - 2017-05-16 09:26:45.753 screenresolution[1634:8167] Display 0: 1600x1200x32@60 09:26:45 INFO - 2017-05-16 09:26:45.758 screenresolution[1635:8169] starting screenresolution argv=screenresolution list 09:26:45 INFO - Available Modes on Display 0 09:26:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:45 INFO - Intel Iris: 09:26:45 INFO - Chipset Model: Intel Iris 09:26:45 INFO - Type: GPU 09:26:45 INFO - Bus: Built-In 09:26:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:45 INFO - Vendor: Intel (0x8086) 09:26:45 INFO - Device ID: 0x0a2e 09:26:45 INFO - Revision ID: 0x0009 09:26:45 INFO - Displays: 09:26:45 INFO - Display: 09:26:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:45 INFO - Display Serial Number: 1600x1200 60 09:26:45 INFO - Main Display: Yes 09:26:45 INFO - Mirror: Off 09:26:45 INFO - Online: Yes 09:26:45 INFO - Rotation: Supported 09:26:45 INFO - Return code: 0 09:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:45 INFO - Python 2.7.3 09:26:45 INFO - Return code: 0 09:26:45 INFO - grabbing minidump binary from tooltool 09:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:45 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 0x10b2b7d30>, '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 0x10b1dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2acbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:26:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:26:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:26:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:46 INFO - Return code: 0 09:26:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:26:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:46 INFO - ENV: RUST_BACKTRACE is now 1 09:26:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:26:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:26:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0329 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:26:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LdzViRilXI/Render', 09:26:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:46 INFO - 'HOME': '/Users/cltbld', 09:26:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:46 INFO - 'LOGNAME': 'cltbld', 09:26:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:26:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:46 INFO - 'MOZ_NO_REMOTE': '1', 09:26:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:46 INFO - 'NO_EM_RESTART': '1', 09:26:46 INFO - 'PAGER': '/bin/cat', 09:26:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:46 INFO - 'PWD': '/builds/slave/test', 09:26:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:46 INFO - 'RUST_BACKTRACE': '1', 09:26:46 INFO - 'SHELL': '/bin/bash', 09:26:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pZ3bb2uLIr/Listeners', 09:26:46 INFO - 'TMPDIR': '/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/', 09:26:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:46 INFO - 'USER': 'cltbld', 09:26:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:46 INFO - 'XPC_FLAGS': '0x0', 09:26:46 INFO - 'XPC_SERVICE_NAME': '0', 09:26:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0329', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:26:46 INFO - mozversion application_buildid: 20170516074024 09:26:46 INFO - mozversion application_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 09:26:46 INFO - mozversion application_display_name: Firefox 09:26:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:46 INFO - mozversion application_name: Firefox 09:26:46 INFO - mozversion application_remotingname: firefox 09:26:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:26:46 INFO - mozversion application_vendor: Mozilla 09:26:46 INFO - mozversion application_version: 54.0 09:26:46 INFO - mozversion platform_buildid: 20170516074024 09:26:46 INFO - mozversion platform_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 09:26:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:26:46 INFO - mozversion platform_version: 54.0 09:26:46 INFO - using testdate: 1494952006 09:26:46 INFO - actual date: 1494952006 09:26:46 INFO - starting webserver on 'localhost:49225' 09:26:46 INFO - SUITE-START | Running 2 tests 09:26:46 INFO - TEST-START | dromaeo_css 09:26:46 INFO - operating with platform_type : mac_ 09:26:46 INFO - Initialising browser for dromaeo_css test... 09:26:46 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpfDMgfQ/profile http://localhost:49225/getInfo.html) 09:26:48 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 09:26:48 INFO - PROCESS | 1645 | colorDepth:24 09:26:48 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 09:26:48 INFO - PROCESS | 1645 | __metrics 09:26:48 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:26:48 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:49 INFO - TEST-INFO | 1645: exit 0 09:26:49 INFO - Browser initialized. 09:26:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:26:49 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpfDMgfQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:01 INFO - PROCESS | 1649 | RSS: Main: 165212160 09:27:01 INFO - PROCESS | 1649 | 09:30:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 09:30:07 INFO - PROCESS | 1649 | RSS: Main: 296030208 09:30:07 INFO - PROCESS | 1649 | 09:33:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 09:33:10 INFO - PROCESS | 1649 | RSS: Main: 301658112 09:33:10 INFO - PROCESS | 1649 | 09:36:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 09:36:13 INFO - PROCESS | 1649 | RSS: Main: 291164160 09:36:13 INFO - PROCESS | 1649 | 09:39:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 09:39:16 INFO - PROCESS | 1649 | RSS: Main: 285515776 09:39:16 INFO - PROCESS | 1649 | 09:42:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 09:42:20 INFO - PROCESS | 1649 | RSS: Main: 277745664 09:42:20 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 09:45:24 INFO - PROCESS | 1649 | RSS: Main: 358113280 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | __start_tp_report 09:45:24 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:45:24 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:45:24 INFO - PROCESS | 1649 | |i|pagename|runs| 09:45:24 INFO - PROCESS | 1649 | |0;dojo.html;3513.490543871139;3520.366334059869;3522.524459198009;3570.8393122309494;3586.47996040574;6241.407066328691;6249.406306400891;6254.186955695754;6286.591371560845;6326.398992095749;6256.09286653435;6277.309495955441;6297.08692239625;6298.622082675031;6307.653079080649;5517.586181036429;5569.387367389584;5587.972060139694;5588.608797384185;5660.830175094754;5427.755750991195;5434.91847622286;5480.835574932738;5641.097424412095;5649.406812284713;5512.531434828044;5531.474509921572;5538.47384498474;5543.501084902378;5552.3892371839065;5524.972375138119;5534.75093620788;5535.778568857241;5543.944560554423;5543.974364742495;4606.286025666027;5345.465453454663;5477.370102438191;5489;5490.066688662937;6194.5044396448175;6199.969000155015;6204.937950620481;6236.906446403307;6265.216847894013;6549.967250163766;6556.770513032021;6564.5404821662505;6564.6061236325495;6572.178477690289;6651.301613330627;6665.4001139897155;6680.331966803281;6689.364510371507;6703.664816759143;6130.417610327012;6141.692915354214;6185.628862268279;6329.177206963065;6367.235931688181;6719.227288861745;6768;6849.863002739918;6869.828254293583;6877.243503214642;6494.805155845333;6498.220213574401;6498.707558159894;6504.837379065467;6520.315366886503;6305.117283580303;6637.9668101660145;6661.866762664817;6664.166979127609;6670.833229169212;6343.1436756038265;6367.140436041075;6371.044343348535;6452.548321617535;6504.349565043458;6529.412352888263;6568.080468734381;6574.276829548746;6577.144971153815;6577.309382514767;5285.709285989231;5302.310699609026;5305.098133317269;5309.256704061435;5348.705821179795;4642.141203877293;4697.295405689106;4708.458527269339;4713.481517033124;4741.715497070187;5410.8637186191345;6133.671320645445;6281.120643109968;6301.086342480315;6321.715522801485;6645.435138013227;6646.335366463315;6646.734130634721;6649.700763465656;6654.467642588581;6046.939530604725;6132.172156758869;6222.719977601018;6255.155554000243;6261.311255761862;5696.715164241771;5726;5759.164921086419;5826.359100498942;5828;6678.4991125665765;6730.562513436611;6730.8653826924165;6733.461323094239;6741.224759152662;6449.774257901138;6560.475161987125;6572.112764776885;6572.605643661395;6584.407403333723;6475.967620161869;6541.149650545399;6681.966590167018;6727.865442691216;6796.966015169893;6519.837004074936;6535.052417399355;6537.444317232898;6538.476921846336;6542.967285163544;6573.737050517926;6584.572002819801;6627.569208001464;6643;6644.003399489922;6274.341194174728;6313.084602732486;6326.304106548184;6334.841628959129;6436.227652681709;5765.279340082489;5810.448007439287;6465.997770345604;6484.643344616092;6487.935120648922;6210.813675589739;6223.315435302023;6262.906056409249;6281.748730050746;6317.273513545817;6252.531060170414;6365.52258580599;6410.743570257137;6486;6505.0567667688865;6303.1175635412;6313.652749098844;6330.3669633036325;6336.112944187726;6350.587211831216 09:45:24 INFO - PROCESS | 1649 | |1;ext.html;798.888155658208;816.759056078457;832.9541875196866;907.8774365460665;917.4036876030583;29571.556426653617;40873;41370.9657258568;41383.37924931125;41487.58512414875;43750.562494375125;43995.34006989898;44034.89912752175;44050.559494405046;44077;7985.840283194332;9460.95929447762;9620.182284505825;9643.758906027335;9649.517524123801;8017.3586113111105;11497.252678575907;11586.652400427958;11620;11627.883721162807;11700.590479333225;11710.297382157098;11789.997850182745;11792.174547781658;11826.7634647307;11752.588659396923;11762.882371176263;11773;11775.411229438494;11783.293002419883;11449.313041217554;11479.827802582968;11489.368084755335;11489.770204595945;11496.137789665725;41015;41283.7935810319;41304.793476032726;41353;41353.79323103395;4002.679785617144;4058.9797051014853;4066.34938409853;4068.2880495913096;4076.266272071026;28902.71097289042;29510.8195672175;30074.097777066505;30909;31050.844745776125;3248;3270.705636492727;3313.486409606515;3433.6051354094348;3443.276911848515;332010;334225;335215.26746563753;337973;338679;53059.46940530623;53232.73383633135;53306.933861322555;53377.665558361354;53414;55154.44845551573;55156.345309640776;55275;55295.17057244144;55544.44455555393;15009.249537523081;15244.3902243909;15515.689686206213;15569.766453503207;15575.922120389552;11715.17993740447;11835.704107397212;13254.271015094262;13256.469741210436;13263.66840828987;11041.447927603587;12289.324087175291;12512.49950002008;12582.37088145589;12741.681457963625;5674.404187560329;5708.172315014383;5713.285839270091;5726.284214473191;5728.341240757283;889.2574700125339;1054.8839627640953;1064.5316060933164;1073.6510634043966;1109.4286442482128;27720;28453.288667783472;28592.571111433346;29119.272018199714;29212.707872921423;801.8917446144812;1123.8258069999;1134.2797323699344;1224.3143839449936;1248.7440074784647;11885.88114118865;12703.047271454678;12818.846303832699;12840.422181001877;12919.483220671067;503171;505390;506027;506719;506888;48936.75531622319;49022.75488622605;49194.52416427513;49221.50778492241;49256.75371623119;1461.0868207370393;1465.5090544667655;1478.2165452310294;1488.3153749275239;1498.4305963733711;26558.601620976086;27922.58116128219;28264.85867570649;28317;28345.85827070934;38083.238335232585;38263.42604860985;38323.80838095792;38339.61660383472;38349.42475862808;442153;442796;443076;443765;445467;29420.411591767617;29905.70094299029;30093.69906300909;30244.546331804602;30921.536176956914;39838.60564880215;40655.39016914808;40920.976975575846;40935;41486;28306;28639.427211455237;29129.126126216248;29474.410511790073;29493;28181.45002024908;28674.856625716737;28702;29595.260118497208;29735.553966690084;31320.530192047594;33252.836150734605;34520.82739586286;35059.47410788891;35117.64882351144;26259.474810504063;27475;29070.854645727482;29502.55746163852;29647.703522964493;19161.52096197606;22053.11787528481;23463;23500.647490287316;23880.641790373506 09:45:24 INFO - PROCESS | 1649 | |2;jquery.html;421.19551656336404;427.3162939297124;433.44806958704754;437.9948019295716;440.7465707218348;20477.590448191047;20803;22724.65913011302;22808.65787013196;22844.08623655056;22930.65604015941;23721.881390593066;24084.350721420673;24343.80905718485;24606.75393246067;6985.196702379228;7047;7060.788176354692;7135.964320178392;7177.964110179441;6728.697208625624;7030.437564994788;7045.260247673998;7068.434525237994;7091.581683663262;7061.4703897207655;7551.471397002212;7845.568493732842;8066.677332906676;8068.878966815502;7796.532208067534;7963.8805417918775;8024.117347091845;8140.674373025072;8150.429469937107;7153.463490238201;7323.560586364835;7626.428017898625;7824.100228473684;7847.215278472164;26724.599131013052;26852.79162437695;27186.592201117;27360.863195684095;27815.58276625852;2479.752024797524;3020.154356780105;3094.5512900629283;3447.3105378924315;3693.630636936312;16339.591510212222;16652.417165399274;16674.749878751827;16815.91592042044;17975.73036404455;2111.356036408895;2120.724305840231;2149.1403438624675;2175.8585691929975;2237.65316375962;175253;179124;179770;180117;181113;19774.30789922346;20171.092300846496;20361;20397.082131304127;20402.79597204009;32727.345453090806;32831.67168328334;32929.67070329314;32975.835120824246;33005;10450.372977621368;10555.683329500127;10751.763547192173;10797;10939.83590246147;7638.885416718754;9021.503817290111;9036;9044.095590440904;9221.17009469151;9202.539873006323;9254.583543740548;9389.812203755886;9397.107274808883;9407.365937537501;3369.949450758241;3927.6072392761066;3946.5264168299987;3990.4213888985932;3997.380406037069;883.3198437203317;930.8464103422859;1022.7443139215196;1046.1160319529986;1052.1062088241135;15885.602859928595;21931.917224892553;22454.43863903383;22823.087076516753;22936.885315573316;905.538175530471;931.9207867331353;953.9904600953902;957.2676902169845;962.4754508792724;11214.887851121548;11488.193300384371;11654.53381864733;11664.6500604982;11726.59604781035;780610;790261;798611;800772;809012;99509;119158;142539.28730356283;145788.27105864402;169908;1160.930344179335;1195.8325834383193;1275.6236910111538;1303.335298997518;1309.646395473217;20252.898735506227;20287.087081081092;20453.693194601794;20532.281370151974;21125.57748844984;63013;63164;64070.39824004251;64366.35633643791;64801;72220.55558888898;82716.58641706753;85120;85429.57285213534;90336;9436.575354109218;10817.864124266838;11516.481758320728;11776.998955088913;11931.105167112328;11603.853287141907;12415.875841241472;12894.742105158031;13197.076204665582;13287.335633218107;9232.907670923205;11005.17461190398;11122.833157502482;11438.199326047097;11485.540578377106;10346.068853803194;10467.220572502845;10703.464826758474;10743.623973160902;10752.784944301227;11359.602413915472;11438.485268163118;11636.88363116358;11715.355655439032;11743.236689615147;10011.299209055296;11178.496967636313;11485.827712584485;11844.70388240301;11862.762744745229;10438.582456701646;10950;11238.10095192399;11397.031252343311;11403.03074238699 09:45:24 INFO - PROCESS | 1649 | |3;mootools.html;1579.7867287916129;1604.8555629993305;1638.058116582965;1641.4829328761439;1654.1646468533393;10956.616518421846;11657.825132622997;11675.591354302602;11684.474198661059;11686.999999999989;11023.118150547956;11363.886361136387;11635.650930472098;11642.592509262178;11661.183717139804;2384.642303654453;2410.481746424527;2418.419579300964;2428.2836563213805;2429.1983645397063;2329.697139371879;2394.616861302192;2399.9880000599974;2406;2409.301302622246;2339.3683705399617;2374.323317854412;2399.1363109280733;2415.5651982643117;2418.818588605862;2301.4706617477905;2362.055177928825;2372.240882917467;2381.9285421437385;2382.1662418153683;2334.4047267946567;2371.739108698042;2373.4659701567184;2377.6076947303673;2391.366287933699;9506.239500839916;11503.884961150365;11507;11517.308961462339;11520.078393728481;5631.183478395651;5904.321003084614;5910.763569457216;5923.822285331447;5925.614835035709;3473.4616134499192;3571.4107172316535;3631.364511210528;3643.7813731175947;3687.465317528943;1247.1643998521013;1257.220523275566;1282.6216266201493;1302.4985380628432;1306.6994591243972;14093.577192684235;14368.281585920662;14766.557003289918;15878;15995.280212390471;4542.295944128666;4676.111538807616;4696.976515117471;4715.339852420664;4721.5278472153195;10375.27373083877;12021.33882636446;12033.278003319829;12041;12054.216475929037;4827.879303017453;4937.259411088366;5619.213310136584;5820.30156381237;5824.592278540546;5174.120399532088;5258.842234732964;5264.263003179558;5341.0653135701095;5351.866203344947;3837.808109594509;3850.8267127979307;3886.689064874803;3930.2336044471626;3941.1132495188353;1022.1414012229615;1024.8718910136233;1029.351508549609;1042.207921979301;1043.9321444106108;960.9855852162223;966.5457235099492;967.5114067396061;975.1662328708852;1004.1213937804421;6741.52809303344;6880.724771009103;6912.758053468205;6913.516053876281;6930.3762661360715;886.3352485635774;905.2983937448398;1008.2740426892625;1028.6554004408458;1050.3802756373775;4027.1744292419985;4090.652294554996;4172.666186705056;4188.350805625133;4224.239636865394;13811.51659691906;14448.060876043024;14462.783058254134;14493.20287384204;14523.346449409803;13962.301884905713;14049.306993786946;14112.01215914876;14361.210133442552;14368.640783980274;10460.267781255392;10554.10290125348;10561.735956601147;10670.733231669115;10693.786124277627;6637.634930078699;6757.86484270302;6788.49086318538;6829.590224586342;6831.419329357061;11457.541698331974;11516.82724759146;12817;12830.16603920742;12852;12642.873571264538;12929.870701292866;12945.935270323587;13012.28432436225;13165.868341316847;8661.740147795575;8670.0896405879;8685.739427817176;8703.564821759013;9125.726228213165;10694.465276735977;10699.304545204537;10799.622013229498;10806.513706883052;10826;8920.063393343606;9077.863832042656;9149.86275205859;9188.219001384958;9209.493477858781;8848.823023539622;8908.86636700463;8944.86582701273;8971.551422428722;8986.370954033155;7874.0157480314965;8057.5165490070785;8182.918170818454;8186.75439736785;8218.096009439076;6025.397460254115;6146.969265153645;6167.814965551041;6230.59501132425;6319.778807741707;5581.274434323512;5774.797882074107;5994.400559943971;6249.25008998923;6254.093156492374 09:45:24 INFO - PROCESS | 1649 | |4;prototype.html;376.738166973953;393.4472066746222;394.5029263128457;396.3796658229872;400.6454287955159;4541.772911354431;4659.603933665639;4761.523847615237;5047.0915235257735;5248.370195576532;4299;4549.680592628142;4873.951260487394;4885.920256585755;5111.795528178877;3907.8241479133367;3953.3872249801325;4155.792210389483;4314.67639927006;4328.242557552431;3994.820233089518;4239.300515414953;4300.075483770988;4391.5827996340295;4426.539242050132;3689.501917241165;4020.778857162855;4167.354060120671;4346.6522678185665;4451.354553589727;3851.9422208666892;4136.772477513736;4312.1806856697285;4325.978370108161;4396.912061758763;3923.293807114718;3953.743006704584;3956.2197011344724;3958.337498250713;3988.581198974124;4715.363425937523;5054.620903432257;5077.619178561623;5164.302819119446;5169.302144210521;4195.937060944088;4559.612432943204;4883.8290659826735;4939.407271127488;4943.876403089916;5135.512126347991;5264.947010597895;5444.127505882253;5512.33851937766;5515.696636684981;4213.4522512073545;4335.631471324941;4473.753943533105;4500.684952053358;4511.548845115495;4969.577585905238;5569.908700671126;5754.625949313281;5826.067829147359;5859.209006784072;4336.915771057235;4498.910021799611;4510.8421205257655;4634.467036290802;4635.791389387486;4032.5765794591734;4424.579664931826;4718.740469274223;4747.477777444478;4751.216049351819;3890.280298144852;4025.657819085352;4208.516020657664;4466.972596302803;4955.702657840541;4924.581410580069;5531.861703457365;5535.778568857281;5546.500814926676;5591.804286849941;1404.7050119475025;1451.7749748788954;1526.1224795742492;1576.5428025872368;1586.8495340878003;1041.958321667125;1090.738222826516;1098.3135540287321;1166.4575972172981;1208.5528354508888;4624.5375462453485;4688.109259240733;4850.005748821483;5145;5245.423003469616;4930.802767889244;5364.114921037985;5379.139337706027;5715.971420142956;5722;4296.2266791977745;4649.67452278344;4714.481407045222;4841.685290456109;5116.313935183984;2563.5257477366745;2971.182924695726;2986.283292009902;2999.865006074732;3023.682513336112;5179.689218646893;5695.743691533891;5919.348871624118;5925.6963468036965;5955.404459554096;5082.593392528589;5528.972355138279;5812.622179558315;5832.270966129234;5954.732037058343;5305.442928492453;5353.81261655848;5787.074068149076;5849.122631605208;5903.203067585821;4883.682560633548;5394.379646340721;5710.371859095413;5741.5119714824705;5831.171881637487;4914.950850491449;5246.97376513115;5447.972760136333;5629.8592535186945;5756.798512052058;4758.334582895959;4887.535684110004;5776.786874756348;5785.56608254391;5957.791477298447;3592.9640703593677;3880.8059597019474;4110.362893751473;4511.210538155744;4549.818007279804;5112.10851984768;5677.290338707661;5683.062294721325;5824;5839.854003649943;4586.701864378805;4807.975960120177;5493.642913210629;5579.581531385244;5654.236678048411;4360.32127630858;4976.0794253063295;5426.240326354393;5482.861683943345;5538.3661819764075;4650.581447669726;5138.8715282116755;5441.374241962225;5446.782128714965;5482.506574408322;5140.56305214046;5530.589699626569;5589.273394458694;5589.301337332833;5614.859628509156;4888.388951381077;5010.974945125251;5403.5136837684795;5468.316460442445;5511.283533140666 09:45:24 INFO - PROCESS | 1649 | |5;yui.html;752.1613401057822;779.4317723102378;782.2685788787487;801.7434420985287;907.0702529906845;15644.843551564481;17370.826291737078;17405.82594174058;17413.825861741378;17420.04189769562;15964.84035159648;15999.280032398541;16000.75998860018;16007.83992160081;16139.677206455865;973.9074787895139;985.857050727644;992.7915137821067;1001.9198464122853;1019.6278358399168;1000.3747657713928;1003.0471052500154;1003.5032658833887;1003.7741508160642;1003.996003996004;1064.6380230721554;1065.5790962569781;1067.2529229539314;1067.8878717734642;1068.3536460441437;1061.400308825511;1062.304190755049;1062.4846949229618;1062.941538215398;1063.1547919404093;782.8943092682472;784.3803395317692;785;785.9607019648995;789.834134831686;16835.242414091394;17516.38692645764;18217.089145542668;18368.724469132972;18457.077146142772;13606.727865442735;14850.1832399218;14875.033122847088;15047.097174169585;15488.767668484981;11654.993075551189;13457.192568445924;13691.83619392353;14047.929760351235;14398.136111833324;4740.407449068866;4741.250671494791;4783.354247176621;4797.472278049412;4926.088673595361;119290;121781;123078;123453;124938;11370.488328025258;11588.94205528967;11669.708257293465;11863.525458981716;12184.451699673536;19791.802081978996;23670;23701.52596948086;23934.401639959142;24099.638505422434;8104.189581041848;8116.472429292077;8165;8284.875726864102;8285.875711864206;4545.409096817459;4573.085382923428;4630.467496237909;4764.737344603683;4805.567498925114;3552.7690700104927;3652.579953305351;3656.0677027358383;3656.1225305926387;3660.066682995819;637.6333608175318;640.967951602418;649.3798422506495;668.6790340636521;668.8762578922915;720.2293545905831;739.2939742545856;753.7022875964074;771.1170709537548;777.8405426887474;10047.23228789204;10567.418791966362;10584.682459526226;10684.358938463716;10906.727331816768;594.4234092929851;674.4772801079105;692.6017539914569;693.6774399904069;695.9373656370951;5034.083908023078;5116.386033675908;5130.9230361544605;5136.791214452317;5183.922241166386;79993;82761;93622;94682.52658736662;96697;98302;99065.5140172898;101512.49243753734;101905.49047254716;103155.48422257841;62755.37244627587;64055.71888562296;72622.9106563402;75493.62253188698;88608.5569572148;11096;11400.771984560428;11416.244008888189;11511.251768635011;11515.596954106566;49532;49767.50232497774;50185.49814501954;50457.74771126268;55244.72377638086;65631;71915.64042179755;72025.6398718003;73040;73920;13126.868731312565;13404.530841420503;13472.642287599827;13544.932275338955;13718.38267277955;14712.117272963656;14749.483768068067;15000.624984375478;16019.599510012344;16106.516804495885;13793;14328.785068223777;14382.496612618508;14509.419623214953;14637.194954277616;13650.863491364958;14071.648208794453;14587.416503340171;14656.340464679328;14685.339159737623;13342;13739.175649461064;14072.648183795078;14232.573022809333;14316.141031538142;9763.267754918268;10149.289549731448;10334.379937203792;10428.217883658604;10665.200109991627;10759.677209683721;11256.549738010715;11303.265287756269;11405.250932359908;11539.538418463504 09:45:24 INFO - PROCESS | 1649 | __end_tp_report 09:45:24 INFO - PROCESS | 1649 | __start_cc_report 09:45:24 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1287 09:45:24 INFO - PROCESS | 1649 | __end_cc_report 09:45:24 INFO - PROCESS | 1649 | __startTimestamp1494953124521__endTimestamp 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:45:24 INFO - PROCESS | 1649 | Number of tests: 6 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:6104.06 Median:6313.65 stddev:683.66 (10.8%) stddev-sans-first:654.85 09:45:24 INFO - PROCESS | 1649 | Values: 3513.5 3520.4 3522.5 3570.8 3586.5 6241.4 6249.4 6254.2 6286.6 6326.4 6256.1 6277.3 6297.1 6298.6 6307.7 5517.6 5569.4 5588.0 5588.6 5660.8 5427.8 5434.9 5480.8 5641.1 5649.4 5512.5 5531.5 5538.5 5543.5 5552.4 5525.0 5534.8 5535.8 5543.9 5544.0 4606.3 5345.5 5477.4 5489.0 5490.1 6194.5 6200.0 6204.9 6236.9 6265.2 6550.0 6556.8 6564.5 6564.6 6572.2 6651.3 6665.4 6680.3 6689.4 6703.7 6130.4 6141.7 6185.6 6329.2 6367.2 6719.2 6768.0 6849.9 6869.8 6877.2 6494.8 6498.2 6498.7 6504.8 6520.3 6305.1 6638.0 6661.9 6664.2 6670.8 6343.1 6367.1 6371.0 6452.5 6504.3 6529.4 6568.1 6574.3 6577.1 6577.3 5285.7 5302.3 5305.1 5309.3 5348.7 4642.1 4697.3 4708.5 4713.5 4741.7 5410.9 6133.7 6281.1 6301.1 6321.7 6645.4 6646.3 6646.7 6649.7 6654.5 6046.9 6132.2 6222.7 6255.2 6261.3 5696.7 5726.0 5759.2 5826.4 5828.0 6678.5 6730.6 6730.9 6733.5 6741.2 6449.8 6560.5 6572.1 6572.6 6584.4 6476.0 6541.1 6682.0 6727.9 6797.0 6519.8 6535.1 6537.4 6538.5 6543.0 6573.7 6584.6 6627.6 6643.0 6644.0 6274.3 6313.1 6326.3 6334.8 6436.2 5765.3 5810.4 6466.0 6484.6 6487.9 6210.8 6223.3 6262.9 6281.7 6317.3 6252.5 6365.5 6410.7 6486.0 6505.1 6303.1 6313.7 6330.4 6336.1 6350.6 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:56406.33 Median:30915.27 stddev:115306.51 (373.0%) stddev-sans-first:115554.39 09:45:24 INFO - PROCESS | 1649 | Values: 798.9 816.8 833.0 907.9 917.4 29571.6 40873.0 41371.0 41383.4 41487.6 43750.6 43995.3 44034.9 44050.6 44077.0 7985.8 9461.0 9620.2 9643.8 9649.5 8017.4 11497.3 11586.7 11620.0 11627.9 11700.6 11710.3 11790.0 11792.2 11826.8 11752.6 11762.9 11773.0 11775.4 11783.3 11449.3 11479.8 11489.4 11489.8 11496.1 41015.0 41283.8 41304.8 41353.0 41353.8 4002.7 4059.0 4066.3 4068.3 4076.3 28902.7 29510.8 30074.1 30909.0 31050.8 3248.0 3270.7 3313.5 3433.6 3443.3 332010.0 334225.0 335215.3 337973.0 338679.0 53059.5 53232.7 53306.9 53377.7 53414.0 55154.4 55156.3 55275.0 55295.2 55544.4 15009.2 15244.4 15515.7 15569.8 15575.9 11715.2 11835.7 13254.3 13256.5 13263.7 11041.4 12289.3 12512.5 12582.4 12741.7 5674.4 5708.2 5713.3 5726.3 5728.3 889.3 1054.9 1064.5 1073.7 1109.4 27720.0 28453.3 28592.6 29119.3 29212.7 801.9 1123.8 1134.3 1224.3 1248.7 11885.9 12703.0 12818.8 12840.4 12919.5 503171.0 505390.0 506027.0 506719.0 506888.0 48936.8 49022.8 49194.5 49221.5 49256.8 1461.1 1465.5 1478.2 1488.3 1498.4 26558.6 27922.6 28264.9 28317.0 28345.9 38083.2 38263.4 38323.8 38339.6 38349.4 442153.0 442796.0 443076.0 443765.0 445467.0 29420.4 29905.7 30093.7 30244.5 30921.5 39838.6 40655.4 40921.0 40935.0 41486.0 28306.0 28639.4 29129.1 29474.4 29493.0 28181.5 28674.9 28702.0 29595.3 29735.6 31320.5 33252.8 34520.8 35059.5 35117.6 26259.5 27475.0 29070.9 29502.6 29647.7 19161.5 22053.1 23463.0 23500.6 23880.6 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:44773.07 Median:23329.38 stddev:132593.43 (568.4%) stddev-sans-first:132923.57 09:45:24 INFO - PROCESS | 1649 | Values: 421.2 427.3 433.4 438.0 440.7 20477.6 20803.0 22724.7 22808.7 22844.1 22930.7 23721.9 24084.4 24343.8 24606.8 6985.2 7047.0 7060.8 7136.0 7178.0 6728.7 7030.4 7045.3 7068.4 7091.6 7061.5 7551.5 7845.6 8066.7 8068.9 7796.5 7963.9 8024.1 8140.7 8150.4 7153.5 7323.6 7626.4 7824.1 7847.2 26724.6 26852.8 27186.6 27360.9 27815.6 2479.8 3020.2 3094.6 3447.3 3693.6 16339.6 16652.4 16674.7 16815.9 17975.7 2111.4 2120.7 2149.1 2175.9 2237.7 175253.0 179124.0 179770.0 180117.0 181113.0 19774.3 20171.1 20361.0 20397.1 20402.8 32727.3 32831.7 32929.7 32975.8 33005.0 10450.4 10555.7 10751.8 10797.0 10939.8 7638.9 9021.5 9036.0 9044.1 9221.2 9202.5 9254.6 9389.8 9397.1 9407.4 3369.9 3927.6 3946.5 3990.4 3997.4 883.3 930.8 1022.7 1046.1 1052.1 15885.6 21931.9 22454.4 22823.1 22936.9 905.5 931.9 954.0 957.3 962.5 11214.9 11488.2 11654.5 11664.7 11726.6 780610.0 790261.0 798611.0 800772.0 809012.0 99509.0 119158.0 142539.3 145788.3 169908.0 1160.9 1195.8 1275.6 1303.3 1309.6 20252.9 20287.1 20453.7 20532.3 21125.6 63013.0 63164.0 64070.4 64366.4 64801.0 72220.6 82716.6 85120.0 85429.6 90336.0 9436.6 10817.9 11516.5 11777.0 11931.1 11603.9 12415.9 12894.7 13197.1 13287.3 9232.9 11005.2 11122.8 11438.2 11485.5 10346.1 10467.2 10703.5 10743.6 10752.8 11359.6 11438.5 11636.9 11715.4 11743.2 10011.3 11178.5 11485.8 11844.7 11862.8 10438.6 10950.0 11238.1 11397.0 11403.0 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:6869.45 Median:2407.65 stddev:4339.40 (180.2%) stddev-sans-first:4333.37 09:45:24 INFO - PROCESS | 1649 | Values: 1579.8 1604.9 1638.1 1641.5 1654.2 10956.6 11657.8 11675.6 11684.5 11687.0 11023.1 11363.9 11635.7 11642.6 11661.2 2384.6 2410.5 2418.4 2428.3 2429.2 2329.7 2394.6 2400.0 2406.0 2409.3 2339.4 2374.3 2399.1 2415.6 2418.8 2301.5 2362.1 2372.2 2381.9 2382.2 2334.4 2371.7 2373.5 2377.6 2391.4 9506.2 11503.9 11507.0 11517.3 11520.1 5631.2 5904.3 5910.8 5923.8 5925.6 3473.5 3571.4 3631.4 3643.8 3687.5 1247.2 1257.2 1282.6 1302.5 1306.7 14093.6 14368.3 14766.6 15878.0 15995.3 4542.3 4676.1 4697.0 4715.3 4721.5 10375.3 12021.3 12033.3 12041.0 12054.2 4827.9 4937.3 5619.2 5820.3 5824.6 5174.1 5258.8 5264.3 5341.1 5351.9 3837.8 3850.8 3886.7 3930.2 3941.1 1022.1 1024.9 1029.4 1042.2 1043.9 961.0 966.5 967.5 975.2 1004.1 6741.5 6880.7 6912.8 6913.5 6930.4 886.3 905.3 1008.3 1028.7 1050.4 4027.2 4090.7 4172.7 4188.4 4224.2 13811.5 14448.1 14462.8 14493.2 14523.3 13962.3 14049.3 14112.0 14361.2 14368.6 10460.3 10554.1 10561.7 10670.7 10693.8 6637.6 6757.9 6788.5 6829.6 6831.4 11457.5 11516.8 12817.0 12830.2 12852.0 12642.9 12929.9 12945.9 13012.3 13165.9 8661.7 8670.1 8685.7 8703.6 9125.7 10694.5 10699.3 10799.6 10806.5 10826.0 8920.1 9077.9 9149.9 9188.2 9209.5 8848.8 8908.9 8944.9 8971.6 8986.4 7874.0 8057.5 8182.9 8186.8 8218.1 6025.4 6147.0 6167.8 6230.6 6319.8 5581.3 5774.8 5994.4 6249.3 6254.1 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4574.31 Median:4883.76 stddev:1279.86 (26.2%) stddev-sans-first:1244.06 09:45:24 INFO - PROCESS | 1649 | Values: 376.7 393.4 394.5 396.4 400.6 4541.8 4659.6 4761.5 5047.1 5248.4 4299.0 4549.7 4874.0 4885.9 5111.8 3907.8 3953.4 4155.8 4314.7 4328.2 3994.8 4239.3 4300.1 4391.6 4426.5 3689.5 4020.8 4167.4 4346.7 4451.4 3851.9 4136.8 4312.2 4326.0 4396.9 3923.3 3953.7 3956.2 3958.3 3988.6 4715.4 5054.6 5077.6 5164.3 5169.3 4195.9 4559.6 4883.8 4939.4 4943.9 5135.5 5264.9 5444.1 5512.3 5515.7 4213.5 4335.6 4473.8 4500.7 4511.5 4969.6 5569.9 5754.6 5826.1 5859.2 4336.9 4498.9 4510.8 4634.5 4635.8 4032.6 4424.6 4718.7 4747.5 4751.2 3890.3 4025.7 4208.5 4467.0 4955.7 4924.6 5531.9 5535.8 5546.5 5591.8 1404.7 1451.8 1526.1 1576.5 1586.8 1042.0 1090.7 1098.3 1166.5 1208.6 4624.5 4688.1 4850.0 5145.0 5245.4 4930.8 5364.1 5379.1 5716.0 5722.0 4296.2 4649.7 4714.5 4841.7 5116.3 2563.5 2971.2 2986.3 2999.9 3023.7 5179.7 5695.7 5919.3 5925.7 5955.4 5082.6 5529.0 5812.6 5832.3 5954.7 5305.4 5353.8 5787.1 5849.1 5903.2 4883.7 5394.4 5710.4 5741.5 5831.2 4915.0 5247.0 5448.0 5629.9 5756.8 4758.3 4887.5 5776.8 5785.6 5957.8 3593.0 3880.8 4110.4 4511.2 4549.8 5112.1 5677.3 5683.1 5824.0 5839.9 4586.7 4808.0 5493.6 5579.6 5654.2 4360.3 4976.1 5426.2 5482.9 5538.4 4650.6 5138.9 5441.4 5446.8 5482.5 5140.6 5530.6 5589.3 5589.3 5614.9 4888.4 5011.0 5403.5 5468.3 5511.3 09:45:24 INFO - PROCESS | 1649 | 09:45:24 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:21413.62 Median:17103.03 stddev:30544.55 (178.6%) stddev-sans-first:30590.83 09:45:24 INFO - PROCESS | 1649 | Values: 752.2 779.4 782.3 801.7 907.1 15644.8 17370.8 17405.8 17413.8 17420.0 15964.8 15999.3 16000.8 16007.8 16139.7 973.9 985.9 992.8 1001.9 1019.6 1000.4 1003.0 1003.5 1003.8 1004.0 1064.6 1065.6 1067.3 1067.9 1068.4 1061.4 1062.3 1062.5 1062.9 1063.2 782.9 784.4 785.0 786.0 789.8 16835.2 17516.4 18217.1 18368.7 18457.1 13606.7 14850.2 14875.0 15047.1 15488.8 11655.0 13457.2 13691.8 14047.9 14398.1 4740.4 4741.3 4783.4 4797.5 4926.1 119290.0 121781.0 123078.0 123453.0 124938.0 11370.5 11588.9 11669.7 11863.5 12184.5 19791.8 23670.0 23701.5 23934.4 24099.6 8104.2 8116.5 8165.0 8284.9 8285.9 4545.4 4573.1 4630.5 4764.7 4805.6 3552.8 3652.6 3656.1 3656.1 3660.1 637.6 641.0 649.4 668.7 668.9 720.2 739.3 753.7 771.1 777.8 10047.2 10567.4 10584.7 10684.4 10906.7 594.4 674.5 692.6 693.7 695.9 5034.1 5116.4 5130.9 5136.8 5183.9 79993.0 82761.0 93622.0 94682.5 96697.0 98302.0 99065.5 101512.5 101905.5 103155.5 62755.4 64055.7 72622.9 75493.6 88608.6 11096.0 11400.8 11416.2 11511.3 11515.6 49532.0 49767.5 50185.5 50457.7 55244.7 65631.0 71915.6 72025.6 73040.0 73920.0 13126.9 13404.5 13472.6 13544.9 13718.4 14712.1 14749.5 15000.6 16019.6 16106.5 13793.0 14328.8 14382.5 14509.4 14637.2 13650.9 14071.6 14587.4 14656.3 14685.3 13342.0 13739.2 14072.6 14232.6 14316.1 9763.3 10149.3 10334.4 10428.2 10665.2 10759.7 11256.5 11303.3 11405.3 11539.5 09:45:24 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:45:24 INFO - PROCESS | 1649 | 09:45:25 INFO - TEST-INFO | 1649: exit 0 09:45:25 INFO - TEST-OK | dromaeo_css | took 1118751ms 09:45:25 INFO - TEST-START | kraken 09:45:25 INFO - operating with platform_type : mac_ 09:45:25 INFO - Initialising browser for kraken test... 09:45:25 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpSsDRg4/profile http://localhost:49225/getInfo.html) 09:45:27 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:45:27 INFO - PROCESS | 1655 | colorDepth:24 09:45:27 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:45:27 INFO - PROCESS | 1655 | __metrics 09:45:27 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:45:27 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:45:28 INFO - TEST-INFO | 1655: exit 0 09:45:28 INFO - Browser initialized. 09:45:28 INFO - Running cycle 1/1 for kraken test... 09:45:28 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/T/tmpSsDRg4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:40 INFO - PROCESS | 1657 | RSS: Main: 165355520 09:45:40 INFO - PROCESS | 1657 | 09:45:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 09:45:43 INFO - PROCESS | 1657 | RSS: Main: 216227840 09:45:43 INFO - PROCESS | 1657 | 09:45:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 09:45:46 INFO - PROCESS | 1657 | RSS: Main: 232452096 09:45:46 INFO - PROCESS | 1657 | 09:45:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 09:45:50 INFO - PROCESS | 1657 | RSS: Main: 329744384 09:45:50 INFO - PROCESS | 1657 | 09:45:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 09:45:53 INFO - PROCESS | 1657 | RSS: Main: 230981632 09:45:53 INFO - PROCESS | 1657 | 09:45:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:45:55 INFO - PROCESS | 1657 | RSS: Main: 232321024 09:45:55 INFO - PROCESS | 1657 | 09:45:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 09:45:59 INFO - PROCESS | 1657 | RSS: Main: 273285120 09:45:59 INFO - PROCESS | 1657 | 09:46:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 09:46:02 INFO - PROCESS | 1657 | RSS: Main: 273469440 09:46:02 INFO - PROCESS | 1657 | 09:46:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 09:46:06 INFO - PROCESS | 1657 | RSS: Main: 271908864 09:46:06 INFO - PROCESS | 1657 | 09:46:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:46:08 INFO - PROCESS | 1657 | RSS: Main: 247828480 09:46:08 INFO - PROCESS | 1657 | 09:46:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:46:11 INFO - PROCESS | 1657 | RSS: Main: 251723776 09:46:11 INFO - PROCESS | 1657 | 09:46:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:46:13 INFO - PROCESS | 1657 | RSS: Main: 257884160 09:46:13 INFO - PROCESS | 1657 | 09:46:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:46:17 INFO - PROCESS | 1657 | RSS: Main: 273526784 09:46:17 INFO - PROCESS | 1657 | 09:46:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:21 INFO - PROCESS | 1657 | RSS: Main: 267378688 09:46:21 INFO - PROCESS | 1657 | 09:46:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 09:46:23 INFO - PROCESS | 1657 | RSS: Main: 312037376 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | __start_tp_report 09:46:23 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:46:23 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:46:23 INFO - PROCESS | 1657 | |i|pagename|runs| 09:46:23 INFO - PROCESS | 1657 | |0;ai-astar;105;115;117;114;116;116;114;115;115;115 09:46:23 INFO - PROCESS | 1657 | |1;audio-beat-detection;142;108;110;144;103;110;141;135;130;108 09:46:23 INFO - PROCESS | 1657 | |2;audio-dft;188;181;184;184;181;184;186;185;185;182 09:46:23 INFO - PROCESS | 1657 | |3;audio-fft;81;60;59;93;62;60;58;69;74;60 09:46:23 INFO - PROCESS | 1657 | |4;audio-oscillator;88;68;85;70;68;67;83;68;68;82 09:46:23 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;87;88;87;87;87;86;88;87;88;89 09:46:23 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;94;94;94;94;94;95;95;93;99 09:46:23 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;84;85;84;84;85;86;83;85;83 09:46:23 INFO - PROCESS | 1657 | |8;json-parse-financial;63;61;62;60;63;63;64;63;63;63 09:46:23 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;54;68;91;90;85;71;59;58;58;58 09:46:23 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;80;83;82;81;80;82;83;79;88;85 09:46:23 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;133;132;135;140;146;138;135;147;193;152 09:46:23 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;190;200;160;211;219;175;160;160;159;160 09:46:23 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;63;63;64;62;64;63;63;62;62;61 09:46:23 INFO - PROCESS | 1657 | __end_tp_report 09:46:23 INFO - PROCESS | 1657 | __start_cc_report 09:46:23 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2073 09:46:23 INFO - PROCESS | 1657 | __end_cc_report 09:46:23 INFO - PROCESS | 1657 | __startTimestamp1494953183135__endTimestamp 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:46:23 INFO - PROCESS | 1657 | Number of tests: 14 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:3.36 (2.9%) stddev-sans-first:0.97 09:46:23 INFO - PROCESS | 1657 | Values: 105.0 115.0 117.0 114.0 116.0 116.0 114.0 115.0 115.0 115.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:132.50 stddev:16.69 (12.6%) stddev-sans-first:16.24 09:46:23 INFO - PROCESS | 1657 | Values: 142.0 108.0 110.0 144.0 103.0 110.0 141.0 135.0 130.0 108.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.21 (1.2%) stddev-sans-first:1.81 09:46:23 INFO - PROCESS | 1657 | Values: 188.0 181.0 184.0 184.0 181.0 184.0 186.0 185.0 185.0 182.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:67.60 Median:65.50 stddev:11.73 (17.9%) stddev-sans-first:11.40 09:46:23 INFO - PROCESS | 1657 | Values: 81.0 60.0 59.0 93.0 62.0 60.0 58.0 69.0 74.0 60.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:76.00 stddev:8.60 (11.3%) stddev-sans-first:7.66 09:46:23 INFO - PROCESS | 1657 | Values: 88.0 68.0 85.0 70.0 68.0 67.0 83.0 68.0 68.0 82.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:87.50 stddev:0.84 (1.0%) stddev-sans-first:0.88 09:46:23 INFO - PROCESS | 1657 | Values: 87.0 88.0 87.0 87.0 87.0 86.0 88.0 87.0 88.0 89.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.73 09:46:23 INFO - PROCESS | 1657 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 95.0 95.0 93.0 99.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.50 stddev:0.95 (1.1%) stddev-sans-first:1.00 09:46:23 INFO - PROCESS | 1657 | Values: 84.0 84.0 85.0 84.0 84.0 85.0 86.0 83.0 85.0 83.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.18 (1.9%) stddev-sans-first:1.24 09:46:23 INFO - PROCESS | 1657 | Values: 63.0 61.0 62.0 60.0 63.0 63.0 64.0 63.0 63.0 63.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.50 stddev:14.43 (20.8%) stddev-sans-first:14.22 09:46:23 INFO - PROCESS | 1657 | Values: 54.0 68.0 91.0 90.0 85.0 71.0 59.0 58.0 58.0 58.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:2.67 (3.2%) stddev-sans-first:2.70 09:46:23 INFO - PROCESS | 1657 | Values: 80.0 83.0 82.0 81.0 80.0 82.0 83.0 79.0 88.0 85.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:143.00 stddev:18.09 (12.6%) stddev-sans-first:18.65 09:46:23 INFO - PROCESS | 1657 | Values: 133.0 132.0 135.0 140.0 146.0 138.0 135.0 147.0 193.0 152.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:182.50 stddev:23.67 (13.0%) stddev-sans-first:24.80 09:46:23 INFO - PROCESS | 1657 | Values: 190.0 200.0 160.0 211.0 219.0 175.0 160.0 160.0 159.0 160.0 09:46:23 INFO - PROCESS | 1657 | 09:46:23 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 09:46:23 INFO - PROCESS | 1657 | Values: 63.0 63.0 64.0 62.0 64.0 63.0 63.0 62.0 62.0 61.0 09:46:23 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:46:23 INFO - PROCESS | 1657 | 09:46:24 INFO - TEST-INFO | 1657: exit 0 09:46:24 INFO - TEST-OK | kraken | took 58964ms 09:46:24 INFO - SUITE-END | took 1177s 09:46:24 INFO - Completed test suite (00:19:37) 09:46:24 INFO - javascript benchmark 09:46:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7893.449430216544, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3513.490543871139, 3520.366334059869, 3522.524459198009, 3570.8393122309494, 3586.47996040574, 6241.407066328691, 6249.406306400891, 6254.186955695754, 6286.591371560845, 6326.398992095749, 6256.09286653435, 6277.309495955441, 6297.08692239625, 6298.622082675031, 6307.653079080649, 5517.586181036429, 5569.387367389584, 5587.972060139694, 5588.608797384185, 5660.830175094754, 5427.755750991195, 5434.91847622286, 5480.835574932738, 5641.097424412095, 5649.406812284713, 5512.531434828044, 5531.474509921572, 5538.47384498474, 5543.501084902378, 5552.3892371839065, 5524.972375138119, 5534.75093620788, 5535.778568857241, 5543.944560554423, 5543.974364742495, 4606.286025666027, 5345.465453454663, 5477.370102438191, 5489.0, 5490.066688662937, 6194.5044396448175, 6199.969000155015, 6204.937950620481, 6236.906446403307, 6265.216847894013, 6549.967250163766, 6556.770513032021, 6564.5404821662505, 6564.6061236325495, 6572.178477690289, 6651.301613330627, 6665.4001139897155, 6680.331966803281, 6689.364510371507, 6703.664816759143, 6130.417610327012, 6141.692915354214, 6185.628862268279, 6329.177206963065, 6367.235931688181, 6719.227288861745, 6768.0, 6849.863002739918, 6869.828254293583, 6877.243503214642, 6494.805155845333, 6498.220213574401, 6498.707558159894, 6504.837379065467, 6520.315366886503, 6305.117283580303, 6637.9668101660145, 6661.866762664817, 6664.166979127609, 6670.833229169212, 6343.1436756038265, 6367.140436041075, 6371.044343348535, 6452.548321617535, 6504.349565043458, 6529.412352888263, 6568.080468734381, 6574.276829548746, 6577.144971153815, 6577.309382514767, 5285.709285989231, 5302.310699609026, 5305.098133317269, 5309.256704061435, 5348.705821179795, 4642.141203877293, 4697.295405689106, 4708.458527269339, 4713.481517033124, 4741.715497070187, 5410.8637186191345, 6133.671320645445, 6281.120643109968, 6301.086342480315, 6321.715522801485, 6645.435138013227, 6646.335366463315, 6646.734130634721, 6649.700763465656, 6654.467642588581, 6046.939530604725, 6132.172156758869, 6222.719977601018, 6255.155554000243, 6261.311255761862, 5696.715164241771, 5726.0, 5759.164921086419, 5826.359100498942, 5828.0, 6678.4991125665765, 6730.562513436611, 6730.8653826924165, 6733.461323094239, 6741.224759152662, 6449.774257901138, 6560.475161987125, 6572.112764776885, 6572.605643661395, 6584.407403333723, 6475.967620161869, 6541.149650545399, 6681.966590167018, 6727.865442691216, 6796.966015169893, 6519.837004074936, 6535.052417399355, 6537.444317232898, 6538.476921846336, 6542.967285163544, 6573.737050517926, 6584.572002819801, 6627.569208001464, 6643.0, 6644.003399489922, 6274.341194174728, 6313.084602732486, 6326.304106548184, 6334.841628959129, 6436.227652681709, 5765.279340082489, 5810.448007439287, 6465.997770345604, 6484.643344616092, 6487.935120648922, 6210.813675589739, 6223.315435302023, 6262.906056409249, 6281.748730050746, 6317.273513545817, 6252.531060170414, 6365.52258580599, 6410.743570257137, 6486.0, 6505.0567667688865, 6303.1175635412, 6313.652749098844, 6330.3669633036325, 6336.112944187726, 6350.587211831216], "value": 6059.587001901869, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.888155658208, 816.759056078457, 832.9541875196866, 907.8774365460665, 917.4036876030583, 29571.556426653617, 40873.0, 41370.9657258568, 41383.37924931125, 41487.58512414875, 43750.562494375125, 43995.34006989898, 44034.89912752175, 44050.559494405046, 44077.0, 7985.840283194332, 9460.95929447762, 9620.182284505825, 9643.758906027335, 9649.517524123801, 8017.3586113111105, 11497.252678575907, 11586.652400427958, 11620.0, 11627.883721162807, 11700.590479333225, 11710.297382157098, 11789.997850182745, 11792.174547781658, 11826.7634647307, 11752.588659396923, 11762.882371176263, 11773.0, 11775.411229438494, 11783.293002419883, 11449.313041217554, 11479.827802582968, 11489.368084755335, 11489.770204595945, 11496.137789665725, 41015.0, 41283.7935810319, 41304.793476032726, 41353.0, 41353.79323103395, 4002.679785617144, 4058.9797051014853, 4066.34938409853, 4068.2880495913096, 4076.266272071026, 28902.71097289042, 29510.8195672175, 30074.097777066505, 30909.0, 31050.844745776125, 3248.0, 3270.705636492727, 3313.486409606515, 3433.6051354094348, 3443.276911848515, 332010.0, 334225.0, 335215.26746563753, 337973.0, 338679.0, 53059.46940530623, 53232.73383633135, 53306.933861322555, 53377.665558361354, 53414.0, 55154.44845551573, 55156.345309640776, 55275.0, 55295.17057244144, 55544.44455555393, 15009.249537523081, 15244.3902243909, 15515.689686206213, 15569.766453503207, 15575.922120389552, 11715.17993740447, 11835.704107397212, 13254.271015094262, 13256.469741210436, 13263.66840828987, 11041.447927603587, 12289.324087175291, 12512.49950002008, 12582.37088145589, 12741.681457963625, 5674.404187560329, 5708.172315014383, 5713.285839270091, 5726.284214473191, 5728.341240757283, 889.2574700125339, 1054.8839627640953, 1064.5316060933164, 1073.6510634043966, 1109.4286442482128, 27720.0, 28453.288667783472, 28592.571111433346, 29119.272018199714, 29212.707872921423, 801.8917446144812, 1123.8258069999, 1134.2797323699344, 1224.3143839449936, 1248.7440074784647, 11885.88114118865, 12703.047271454678, 12818.846303832699, 12840.422181001877, 12919.483220671067, 503171.0, 505390.0, 506027.0, 506719.0, 506888.0, 48936.75531622319, 49022.75488622605, 49194.52416427513, 49221.50778492241, 49256.75371623119, 1461.0868207370393, 1465.5090544667655, 1478.2165452310294, 1488.3153749275239, 1498.4305963733711, 26558.601620976086, 27922.58116128219, 28264.85867570649, 28317.0, 28345.85827070934, 38083.238335232585, 38263.42604860985, 38323.80838095792, 38339.61660383472, 38349.42475862808, 442153.0, 442796.0, 443076.0, 443765.0, 445467.0, 29420.411591767617, 29905.70094299029, 30093.69906300909, 30244.546331804602, 30921.536176956914, 39838.60564880215, 40655.39016914808, 40920.976975575846, 40935.0, 41486.0, 28306.0, 28639.427211455237, 29129.126126216248, 29474.410511790073, 29493.0, 28181.45002024908, 28674.856625716737, 28702.0, 29595.260118497208, 29735.553966690084, 31320.530192047594, 33252.836150734605, 34520.82739586286, 35059.47410788891, 35117.64882351144, 26259.474810504063, 27475.0, 29070.854645727482, 29502.55746163852, 29647.703522964493, 19161.52096197606, 22053.11787528481, 23463.0, 23500.647490287316, 23880.641790373506], "value": 18875.127052359705, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.19551656336404, 427.3162939297124, 433.44806958704754, 437.9948019295716, 440.7465707218348, 20477.590448191047, 20803.0, 22724.65913011302, 22808.65787013196, 22844.08623655056, 22930.65604015941, 23721.881390593066, 24084.350721420673, 24343.80905718485, 24606.75393246067, 6985.196702379228, 7047.0, 7060.788176354692, 7135.964320178392, 7177.964110179441, 6728.697208625624, 7030.437564994788, 7045.260247673998, 7068.434525237994, 7091.581683663262, 7061.4703897207655, 7551.471397002212, 7845.568493732842, 8066.677332906676, 8068.878966815502, 7796.532208067534, 7963.8805417918775, 8024.117347091845, 8140.674373025072, 8150.429469937107, 7153.463490238201, 7323.560586364835, 7626.428017898625, 7824.100228473684, 7847.215278472164, 26724.599131013052, 26852.79162437695, 27186.592201117, 27360.863195684095, 27815.58276625852, 2479.752024797524, 3020.154356780105, 3094.5512900629283, 3447.3105378924315, 3693.630636936312, 16339.591510212222, 16652.417165399274, 16674.749878751827, 16815.91592042044, 17975.73036404455, 2111.356036408895, 2120.724305840231, 2149.1403438624675, 2175.8585691929975, 2237.65316375962, 175253.0, 179124.0, 179770.0, 180117.0, 181113.0, 19774.30789922346, 20171.092300846496, 20361.0, 20397.082131304127, 20402.79597204009, 32727.345453090806, 32831.67168328334, 32929.67070329314, 32975.835120824246, 33005.0, 10450.372977621368, 10555.683329500127, 10751.763547192173, 10797.0, 10939.83590246147, 7638.885416718754, 9021.503817290111, 9036.0, 9044.095590440904, 9221.17009469151, 9202.539873006323, 9254.583543740548, 9389.812203755886, 9397.107274808883, 9407.365937537501, 3369.949450758241, 3927.6072392761066, 3946.5264168299987, 3990.4213888985932, 3997.380406037069, 883.3198437203317, 930.8464103422859, 1022.7443139215196, 1046.1160319529986, 1052.1062088241135, 15885.602859928595, 21931.917224892553, 22454.43863903383, 22823.087076516753, 22936.885315573316, 905.538175530471, 931.9207867331353, 953.9904600953902, 957.2676902169845, 962.4754508792724, 11214.887851121548, 11488.193300384371, 11654.53381864733, 11664.6500604982, 11726.59604781035, 780610.0, 790261.0, 798611.0, 800772.0, 809012.0, 99509.0, 119158.0, 142539.28730356283, 145788.27105864402, 169908.0, 1160.930344179335, 1195.8325834383193, 1275.6236910111538, 1303.335298997518, 1309.646395473217, 20252.898735506227, 20287.087081081092, 20453.693194601794, 20532.281370151974, 21125.57748844984, 63013.0, 63164.0, 64070.39824004251, 64366.35633643791, 64801.0, 72220.55558888898, 82716.58641706753, 85120.0, 85429.57285213534, 90336.0, 9436.575354109218, 10817.864124266838, 11516.481758320728, 11776.998955088913, 11931.105167112328, 11603.853287141907, 12415.875841241472, 12894.742105158031, 13197.076204665582, 13287.335633218107, 9232.907670923205, 11005.17461190398, 11122.833157502482, 11438.199326047097, 11485.540578377106, 10346.068853803194, 10467.220572502845, 10703.464826758474, 10743.623973160902, 10752.784944301227, 11359.602413915472, 11438.485268163118, 11636.88363116358, 11715.355655439032, 11743.236689615147, 10011.299209055296, 11178.496967636313, 11485.827712584485, 11844.70388240301, 11862.762744745229, 10438.582456701646, 10950.0, 11238.10095192399, 11397.031252343311, 11403.03074238699], "value": 11896.655904915651, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1579.7867287916129, 1604.8555629993305, 1638.058116582965, 1641.4829328761439, 1654.1646468533393, 10956.616518421846, 11657.825132622997, 11675.591354302602, 11684.474198661059, 11686.999999999989, 11023.118150547956, 11363.886361136387, 11635.650930472098, 11642.592509262178, 11661.183717139804, 2384.642303654453, 2410.481746424527, 2418.419579300964, 2428.2836563213805, 2429.1983645397063, 2329.697139371879, 2394.616861302192, 2399.9880000599974, 2406.0, 2409.301302622246, 2339.3683705399617, 2374.323317854412, 2399.1363109280733, 2415.5651982643117, 2418.818588605862, 2301.4706617477905, 2362.055177928825, 2372.240882917467, 2381.9285421437385, 2382.1662418153683, 2334.4047267946567, 2371.739108698042, 2373.4659701567184, 2377.6076947303673, 2391.366287933699, 9506.239500839916, 11503.884961150365, 11507.0, 11517.308961462339, 11520.078393728481, 5631.183478395651, 5904.321003084614, 5910.763569457216, 5923.822285331447, 5925.614835035709, 3473.4616134499192, 3571.4107172316535, 3631.364511210528, 3643.7813731175947, 3687.465317528943, 1247.1643998521013, 1257.220523275566, 1282.6216266201493, 1302.4985380628432, 1306.6994591243972, 14093.577192684235, 14368.281585920662, 14766.557003289918, 15878.0, 15995.280212390471, 4542.295944128666, 4676.111538807616, 4696.976515117471, 4715.339852420664, 4721.5278472153195, 10375.27373083877, 12021.33882636446, 12033.278003319829, 12041.0, 12054.216475929037, 4827.879303017453, 4937.259411088366, 5619.213310136584, 5820.30156381237, 5824.592278540546, 5174.120399532088, 5258.842234732964, 5264.263003179558, 5341.0653135701095, 5351.866203344947, 3837.808109594509, 3850.8267127979307, 3886.689064874803, 3930.2336044471626, 3941.1132495188353, 1022.1414012229615, 1024.8718910136233, 1029.351508549609, 1042.207921979301, 1043.9321444106108, 960.9855852162223, 966.5457235099492, 967.5114067396061, 975.1662328708852, 1004.1213937804421, 6741.52809303344, 6880.724771009103, 6912.758053468205, 6913.516053876281, 6930.3762661360715, 886.3352485635774, 905.2983937448398, 1008.2740426892625, 1028.6554004408458, 1050.3802756373775, 4027.1744292419985, 4090.652294554996, 4172.666186705056, 4188.350805625133, 4224.239636865394, 13811.51659691906, 14448.060876043024, 14462.783058254134, 14493.20287384204, 14523.346449409803, 13962.301884905713, 14049.306993786946, 14112.01215914876, 14361.210133442552, 14368.640783980274, 10460.267781255392, 10554.10290125348, 10561.735956601147, 10670.733231669115, 10693.786124277627, 6637.634930078699, 6757.86484270302, 6788.49086318538, 6829.590224586342, 6831.419329357061, 11457.541698331974, 11516.82724759146, 12817.0, 12830.16603920742, 12852.0, 12642.873571264538, 12929.870701292866, 12945.935270323587, 13012.28432436225, 13165.868341316847, 8661.740147795575, 8670.0896405879, 8685.739427817176, 8703.564821759013, 9125.726228213165, 10694.465276735977, 10699.304545204537, 10799.622013229498, 10806.513706883052, 10826.0, 8920.063393343606, 9077.863832042656, 9149.86275205859, 9188.219001384958, 9209.493477858781, 8848.823023539622, 8908.86636700463, 8944.86582701273, 8971.551422428722, 8986.370954033155, 7874.0157480314965, 8057.5165490070785, 8182.918170818454, 8186.75439736785, 8218.096009439076, 6025.397460254115, 6146.969265153645, 6167.814965551041, 6230.59501132425, 6319.778807741707, 5581.274434323512, 5774.797882074107, 5994.400559943971, 6249.25008998923, 6254.093156492374], "value": 5217.690121240268, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.738166973953, 393.4472066746222, 394.5029263128457, 396.3796658229872, 400.6454287955159, 4541.772911354431, 4659.603933665639, 4761.523847615237, 5047.0915235257735, 5248.370195576532, 4299.0, 4549.680592628142, 4873.951260487394, 4885.920256585755, 5111.795528178877, 3907.8241479133367, 3953.3872249801325, 4155.792210389483, 4314.67639927006, 4328.242557552431, 3994.820233089518, 4239.300515414953, 4300.075483770988, 4391.5827996340295, 4426.539242050132, 3689.501917241165, 4020.778857162855, 4167.354060120671, 4346.6522678185665, 4451.354553589727, 3851.9422208666892, 4136.772477513736, 4312.1806856697285, 4325.978370108161, 4396.912061758763, 3923.293807114718, 3953.743006704584, 3956.2197011344724, 3958.337498250713, 3988.581198974124, 4715.363425937523, 5054.620903432257, 5077.619178561623, 5164.302819119446, 5169.302144210521, 4195.937060944088, 4559.612432943204, 4883.8290659826735, 4939.407271127488, 4943.876403089916, 5135.512126347991, 5264.947010597895, 5444.127505882253, 5512.33851937766, 5515.696636684981, 4213.4522512073545, 4335.631471324941, 4473.753943533105, 4500.684952053358, 4511.548845115495, 4969.577585905238, 5569.908700671126, 5754.625949313281, 5826.067829147359, 5859.209006784072, 4336.915771057235, 4498.910021799611, 4510.8421205257655, 4634.467036290802, 4635.791389387486, 4032.5765794591734, 4424.579664931826, 4718.740469274223, 4747.477777444478, 4751.216049351819, 3890.280298144852, 4025.657819085352, 4208.516020657664, 4466.972596302803, 4955.702657840541, 4924.581410580069, 5531.861703457365, 5535.778568857281, 5546.500814926676, 5591.804286849941, 1404.7050119475025, 1451.7749748788954, 1526.1224795742492, 1576.5428025872368, 1586.8495340878003, 1041.958321667125, 1090.738222826516, 1098.3135540287321, 1166.4575972172981, 1208.5528354508888, 4624.5375462453485, 4688.109259240733, 4850.005748821483, 5145.0, 5245.423003469616, 4930.802767889244, 5364.114921037985, 5379.139337706027, 5715.971420142956, 5722.0, 4296.2266791977745, 4649.67452278344, 4714.481407045222, 4841.685290456109, 5116.313935183984, 2563.5257477366745, 2971.182924695726, 2986.283292009902, 2999.865006074732, 3023.682513336112, 5179.689218646893, 5695.743691533891, 5919.348871624118, 5925.6963468036965, 5955.404459554096, 5082.593392528589, 5528.972355138279, 5812.622179558315, 5832.270966129234, 5954.732037058343, 5305.442928492453, 5353.81261655848, 5787.074068149076, 5849.122631605208, 5903.203067585821, 4883.682560633548, 5394.379646340721, 5710.371859095413, 5741.5119714824705, 5831.171881637487, 4914.950850491449, 5246.97376513115, 5447.972760136333, 5629.8592535186945, 5756.798512052058, 4758.334582895959, 4887.535684110004, 5776.786874756348, 5785.56608254391, 5957.791477298447, 3592.9640703593677, 3880.8059597019474, 4110.362893751473, 4511.210538155744, 4549.818007279804, 5112.10851984768, 5677.290338707661, 5683.062294721325, 5824.0, 5839.854003649943, 4586.701864378805, 4807.975960120177, 5493.642913210629, 5579.581531385244, 5654.236678048411, 4360.32127630858, 4976.0794253063295, 5426.240326354393, 5482.861683943345, 5538.3661819764075, 4650.581447669726, 5138.8715282116755, 5441.374241962225, 5446.782128714965, 5482.506574408322, 5140.56305214046, 5530.589699626569, 5589.273394458694, 5589.301337332833, 5614.859628509156, 4888.388951381077, 5010.974945125251, 5403.5136837684795, 5468.316460442445, 5511.283533140666], "value": 4205.188297737813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.1613401057822, 779.4317723102378, 782.2685788787487, 801.7434420985287, 907.0702529906845, 15644.843551564481, 17370.826291737078, 17405.82594174058, 17413.825861741378, 17420.04189769562, 15964.84035159648, 15999.280032398541, 16000.75998860018, 16007.83992160081, 16139.677206455865, 973.9074787895139, 985.857050727644, 992.7915137821067, 1001.9198464122853, 1019.6278358399168, 1000.3747657713928, 1003.0471052500154, 1003.5032658833887, 1003.7741508160642, 1003.996003996004, 1064.6380230721554, 1065.5790962569781, 1067.2529229539314, 1067.8878717734642, 1068.3536460441437, 1061.400308825511, 1062.304190755049, 1062.4846949229618, 1062.941538215398, 1063.1547919404093, 782.8943092682472, 784.3803395317692, 785.0, 785.9607019648995, 789.834134831686, 16835.242414091394, 17516.38692645764, 18217.089145542668, 18368.724469132972, 18457.077146142772, 13606.727865442735, 14850.1832399218, 14875.033122847088, 15047.097174169585, 15488.767668484981, 11654.993075551189, 13457.192568445924, 13691.83619392353, 14047.929760351235, 14398.136111833324, 4740.407449068866, 4741.250671494791, 4783.354247176621, 4797.472278049412, 4926.088673595361, 119290.0, 121781.0, 123078.0, 123453.0, 124938.0, 11370.488328025258, 11588.94205528967, 11669.708257293465, 11863.525458981716, 12184.451699673536, 19791.802081978996, 23670.0, 23701.52596948086, 23934.401639959142, 24099.638505422434, 8104.189581041848, 8116.472429292077, 8165.0, 8284.875726864102, 8285.875711864206, 4545.409096817459, 4573.085382923428, 4630.467496237909, 4764.737344603683, 4805.567498925114, 3552.7690700104927, 3652.579953305351, 3656.0677027358383, 3656.1225305926387, 3660.066682995819, 637.6333608175318, 640.967951602418, 649.3798422506495, 668.6790340636521, 668.8762578922915, 720.2293545905831, 739.2939742545856, 753.7022875964074, 771.1170709537548, 777.8405426887474, 10047.23228789204, 10567.418791966362, 10584.682459526226, 10684.358938463716, 10906.727331816768, 594.4234092929851, 674.4772801079105, 692.6017539914569, 693.6774399904069, 695.9373656370951, 5034.083908023078, 5116.386033675908, 5130.9230361544605, 5136.791214452317, 5183.922241166386, 79993.0, 82761.0, 93622.0, 94682.52658736662, 96697.0, 98302.0, 99065.5140172898, 101512.49243753734, 101905.49047254716, 103155.48422257841, 62755.37244627587, 64055.71888562296, 72622.9106563402, 75493.62253188698, 88608.5569572148, 11096.0, 11400.771984560428, 11416.244008888189, 11511.251768635011, 11515.596954106566, 49532.0, 49767.50232497774, 50185.49814501954, 50457.74771126268, 55244.72377638086, 65631.0, 71915.64042179755, 72025.6398718003, 73040.0, 73920.0, 13126.868731312565, 13404.530841420503, 13472.642287599827, 13544.932275338955, 13718.38267277955, 14712.117272963656, 14749.483768068067, 15000.624984375478, 16019.599510012344, 16106.516804495885, 13793.0, 14328.785068223777, 14382.496612618508, 14509.419623214953, 14637.194954277616, 13650.863491364958, 14071.648208794453, 14587.416503340171, 14656.340464679328, 14685.339159737623, 13342.0, 13739.175649461064, 14072.648183795078, 14232.573022809333, 14316.141031538142, 9763.267754918268, 10149.289549731448, 10334.379937203792, 10428.217883658604, 10665.200109991627, 10759.677209683721, 11256.549738010715, 11303.265287756269, 11405.250932359908, 11539.538418463504], "value": 8100.993225373534, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 117.0, 114.0, 116.0, 116.0, 114.0, 115.0, 115.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 110.0, 144.0, 103.0, 110.0, 141.0, 135.0, 130.0, 108.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 184.0, 184.0, 181.0, 184.0, 186.0, 185.0, 185.0, 182.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 93.0, 62.0, 60.0, 58.0, 69.0, 74.0, 60.0], "value": 67.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 85.0, 70.0, 68.0, 67.0, 83.0, 68.0, 68.0, 82.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 87.0, 87.0, 86.0, 88.0, 87.0, 88.0, 89.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 93.0, 99.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 86.0, 83.0, 85.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 60.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 68.0, 91.0, 90.0, 85.0, 71.0, 59.0, 58.0, 58.0, 58.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 82.0, 81.0, 80.0, 82.0, 83.0, 79.0, 88.0, 85.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 135.0, 140.0, 146.0, 138.0, 135.0, 147.0, 193.0, 152.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 200.0, 160.0, 211.0, 219.0, 175.0, 160.0, 160.0, 159.0, 160.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 62.0, 64.0, 63.0, 63.0, 62.0, 62.0, 61.0], "value": 62.7, "unit": "score"}]}]} 09:46:24 INFO - Return code: 0 09:46:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:46:24 INFO - # TBPL SUCCESS # 09:46:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:24 INFO - 2017-05-16 09:46:24.474 screenresolution[1661:10426] starting screenresolution argv=screenresolution get 09:46:24 INFO - 2017-05-16 09:46:24.476 screenresolution[1661:10426] Display 0: 1600x1200x32@60 09:46:24 INFO - 2017-05-16 09:46:24.483 screenresolution[1662:10429] starting screenresolution argv=screenresolution list 09:46:24 INFO - Available Modes on Display 0 09:46:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:24 INFO - Intel Iris: 09:46:24 INFO - Chipset Model: Intel Iris 09:46:24 INFO - Type: GPU 09:46:24 INFO - Bus: Built-In 09:46:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:24 INFO - Vendor: Intel (0x8086) 09:46:24 INFO - Device ID: 0x0a2e 09:46:24 INFO - Revision ID: 0x0009 09:46:24 INFO - Displays: 09:46:24 INFO - Display: 09:46:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:24 INFO - Display Serial Number: 1600x1200 60 09:46:24 INFO - Main Display: Yes 09:46:24 INFO - Mirror: Off 09:46:24 INFO - Online: Yes 09:46:24 INFO - Rotation: Supported 09:46:24 INFO - Return code: 0 09:46:24 INFO - Running post-action listener: _resource_record_post_action 09:46:24 INFO - [mozharness: 2017-05-16 16:46:24.724627Z] Finished run-tests step (success) 09:46:24 INFO - Running post-run listener: _resource_record_post_run 09:46:24 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 32713728; Write bytes: 424380416; Read time: 196; Write time: 1354 09:46:24 INFO - TinderboxPrint: CPU usage
53.7% 09:46:24 INFO - TinderboxPrint: I/O read bytes / time
32,713,728 / 196 09:46:24 INFO - TinderboxPrint: I/O write bytes / time
424,380,416 / 1,354 09:46:24 INFO - TinderboxPrint: CPU idle
2,210.6 (46.3%) 09:46:24 INFO - TinderboxPrint: CPU system
950.3 (19.9%) 09:46:24 INFO - TinderboxPrint: CPU user
1,612.8 (33.8%) 09:46:24 INFO - TinderboxPrint: Swap in / out
151,035,904 / 0 09:46:24 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122966528; Write bytes: 116082176; Read time: 11131; Write time: 254 09:46:24 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 28576768; Write bytes: 298078720; Read time: 171; Write time: 1070 09:46:25 INFO - Running post-run listener: _upload_blobber_files 09:46:25 INFO - Blob upload gear active. 09:46:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:46:25 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 09:46:25 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'] 09:46:25 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 09:46:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:26 INFO - (blobuploader) - INFO - Done attempting. 09:46:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:46:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:46:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:27 INFO - (blobuploader) - INFO - Done attempting. 09:46:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:46:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:46:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:28 INFO - (blobuploader) - INFO - Done attempting. 09:46:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:46:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:30 INFO - (blobuploader) - INFO - Done attempting. 09:46:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:46:30 INFO - Return code: 0 09:46:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:46:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:46:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ad9dbbc6c822149b83867e1312f2ceb8ab79f67dd383e0bd7b1a9600fda7e6a7327eda2b11a891fde67ae442f529e6f3e1f898d8d92e3648697cee28cd6936a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cee1db6788f35a71faf5acce21ed2d9f0b0ed5c8e972b9013012d54696695be65a3c1f185dde89fe617452b5dcb978513b6c62ddceffc7d753f16213a6b10d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/028ef728f2af018dde8befc699adc98767de9a3c5271c2ad828991bc28560cbfaa192d4ad0a7f8e4d760f5935247b6c1ee6c45ceb83661d6dfc82cb1a2e7db3d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30666ef82a6a6de53ab52dc36ea5500565114366cb6ca374647a3547d93a6c7cd2ddd29c5c711f2e1d59cb2901607965c28fe32e7ab1ae7b63551ec2c913ca1d"} 09:46:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:46:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:46:30 INFO - Contents: 09:46:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ad9dbbc6c822149b83867e1312f2ceb8ab79f67dd383e0bd7b1a9600fda7e6a7327eda2b11a891fde67ae442f529e6f3e1f898d8d92e3648697cee28cd6936a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cee1db6788f35a71faf5acce21ed2d9f0b0ed5c8e972b9013012d54696695be65a3c1f185dde89fe617452b5dcb978513b6c62ddceffc7d753f16213a6b10d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/028ef728f2af018dde8befc699adc98767de9a3c5271c2ad828991bc28560cbfaa192d4ad0a7f8e4d760f5935247b6c1ee6c45ceb83661d6dfc82cb1a2e7db3d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30666ef82a6a6de53ab52dc36ea5500565114366cb6ca374647a3547d93a6c7cd2ddd29c5c711f2e1d59cb2901607965c28fe32e7ab1ae7b63551ec2c913ca1d"} 09:46:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:46:30 INFO - Copying logs to upload dir... 09:46:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:46:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.867605 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-16 09:46:30.952816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:30.956827) ========= 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.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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/1ad9dbbc6c822149b83867e1312f2ceb8ab79f67dd383e0bd7b1a9600fda7e6a7327eda2b11a891fde67ae442f529e6f3e1f898d8d92e3648697cee28cd6936a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cee1db6788f35a71faf5acce21ed2d9f0b0ed5c8e972b9013012d54696695be65a3c1f185dde89fe617452b5dcb978513b6c62ddceffc7d753f16213a6b10d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/028ef728f2af018dde8befc699adc98767de9a3c5271c2ad828991bc28560cbfaa192d4ad0a7f8e4d760f5935247b6c1ee6c45ceb83661d6dfc82cb1a2e7db3d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30666ef82a6a6de53ab52dc36ea5500565114366cb6ca374647a3547d93a6c7cd2ddd29c5c711f2e1d59cb2901607965c28fe32e7ab1ae7b63551ec2c913ca1d"} build_url:https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/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/1ad9dbbc6c822149b83867e1312f2ceb8ab79f67dd383e0bd7b1a9600fda7e6a7327eda2b11a891fde67ae442f529e6f3e1f898d8d92e3648697cee28cd6936a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cee1db6788f35a71faf5acce21ed2d9f0b0ed5c8e972b9013012d54696695be65a3c1f185dde89fe617452b5dcb978513b6c62ddceffc7d753f16213a6b10d03", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/028ef728f2af018dde8befc699adc98767de9a3c5271c2ad828991bc28560cbfaa192d4ad0a7f8e4d760f5935247b6c1ee6c45ceb83661d6dfc82cb1a2e7db3d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30666ef82a6a6de53ab52dc36ea5500565114366cb6ca374647a3547d93a6c7cd2ddd29c5c711f2e1d59cb2901607965c28fe32e7ab1ae7b63551ec2c913ca1d"}' ========= master_lag: 0.43 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:31.398371) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:31.398706) ========= 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.LdzViRilXI/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.pZ3bb2uLIr/Listeners TMPDIR=/var/folders/qj/gk52w8cx1cd0gbstqffnnfqm00000w/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.005131 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:31.447709) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 09:46:31.448022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 09:46:31.448339) ========= ========= Total master_lag: 0.58 =========