builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0112 starttime: 1473373566.86 results: success (0) buildid: 20160908134620 builduid: 5e9fb8617db2422d83ab0d523d12748b revision: a05091172da102a71a89a6602385b2114574abfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:06.857467) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:06.858203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:06.858632) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:06.895557) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:06.895966) ========= 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.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 --2016-09-08 15:26:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2016-09-08 15:26:07 (14.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154119 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:07.073573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:07.073927) ========= 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.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.028467 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:07.123171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 15:26:07.123666) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a05091172da102a71a89a6602385b2114574abfd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a05091172da102a71a89a6602385b2114574abfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 2016-09-08 15:26:07,203 truncating revision to first 12 chars 2016-09-08 15:26:07,204 Setting DEBUG logging. 2016-09-08 15:26:07,204 attempt 1/10 2016-09-08 15:26:07,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a05091172da1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 15:26:08,888 unpacking tar archive at: fx-team-a05091172da1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.935008 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 15:26:09.089953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:09.090337) ========= script_repo_revision: a05091172da102a71a89a6602385b2114574abfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:09.090789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:09.091092) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 15:26:09.110116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 15:26:09.110516) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:09 INFO - MultiFileLogger online at 20160908 15:26:09 in /builds/slave/test 15:26:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:26:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:26:09 INFO - {'append_to_log': False, 15:26:09 INFO - 'base_work_dir': '/builds/slave/test', 15:26:09 INFO - 'blob_upload_branch': 'Fx-Team', 15:26:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:26:09 INFO - 'branch': 'Fx-Team', 15:26:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:26:09 INFO - 'config_files': ('talos/mac_config.py',), 15:26:09 INFO - 'default_actions': ('clobber', 15:26:09 INFO - 'read-buildbot-config', 15:26:09 INFO - 'download-and-extract', 15:26:09 INFO - 'populate-webroot', 15:26:09 INFO - 'create-virtualenv', 15:26:09 INFO - 'install', 15:26:09 INFO - 'run-tests'), 15:26:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:26:09 INFO - 'download_minidump_stackwalk': True, 15:26:09 INFO - 'download_symbols': 'ondemand', 15:26:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:26:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:26:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:26:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:26:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:26:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:26:09 INFO - 'installer_path': 'installer.exe', 15:26:09 INFO - 'log_level': 'info', 15:26:09 INFO - 'log_name': 'talos', 15:26:09 INFO - 'log_to_console': True, 15:26:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:26:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:26:09 INFO - 'opt_config_files': (), 15:26:09 INFO - 'pip_index': False, 15:26:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:26:09 INFO - 'cmd': ('bash', 15:26:09 INFO - '-c', 15:26:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:26:09 INFO - 'enabled': True, 15:26:09 INFO - 'halt_on_failure': False, 15:26:09 INFO - 'name': 'check_screen_resolution'},), 15:26:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:26:09 INFO - 'cmd': ('bash', 15:26:09 INFO - '-c', 15:26:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:26:09 INFO - 'enabled': True, 15:26:09 INFO - 'halt_on_failure': False, 15:26:09 INFO - 'name': 'check_screen_resolution'},), 15:26:09 INFO - 'run_cmd_checks_enabled': True, 15:26:09 INFO - 'sps_profile': False, 15:26:09 INFO - 'sps_profile_interval': 0, 15:26:09 INFO - 'suite': 'dromaeojs', 15:26:09 INFO - 'system_bits': '32', 15:26:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:26:09 INFO - 'title': 't-yosemite-r7-0112', 15:26:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:26:09 INFO - 'use_talos_json': True, 15:26:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:26:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:26:09 INFO - 'work_dir': 'build'} 15:26:09 INFO - [mozharness: 2016-09-08 22:26:09.300936Z] Running clobber step. 15:26:09 INFO - Running pre-action listener: _resource_record_pre_action 15:26:09 INFO - Running main action method: clobber 15:26:09 INFO - rmtree: /builds/slave/test/build 15:26:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:26:11 INFO - Running post-action listener: _resource_record_post_action 15:26:11 INFO - [mozharness: 2016-09-08 22:26:11.510426Z] Finished clobber step (success) 15:26:11 INFO - [mozharness: 2016-09-08 22:26:11.510679Z] Running read-buildbot-config step. 15:26:11 INFO - Running pre-action listener: _resource_record_pre_action 15:26:11 INFO - Running main action method: read_buildbot_config 15:26:11 INFO - Using buildbot properties: 15:26:11 INFO - { 15:26:11 INFO - "project": "", 15:26:11 INFO - "product": "firefox", 15:26:11 INFO - "script_repo_revision": "production", 15:26:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:26:11 INFO - "repository": "", 15:26:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:26:11 INFO - "buildid": "20160908134620", 15:26:11 INFO - "pgo_build": "False", 15:26:11 INFO - "basedir": "/builds/slave/test", 15:26:11 INFO - "buildnumber": 138, 15:26:11 INFO - "slavename": "t-yosemite-r7-0112", 15:26:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:26:11 INFO - "platform": "yosemite_r7", 15:26:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:26:11 INFO - "branch": "fx-team", 15:26:11 INFO - "repo_path": "integration/fx-team", 15:26:11 INFO - "revision": "a05091172da102a71a89a6602385b2114574abfd", 15:26:11 INFO - "stage_platform": "macosx64", 15:26:11 INFO - "builduid": "5e9fb8617db2422d83ab0d523d12748b", 15:26:11 INFO - "slavebuilddir": "test" 15:26:11 INFO - } 15:26:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:26:11 INFO - Running post-action listener: _resource_record_post_action 15:26:11 INFO - [mozharness: 2016-09-08 22:26:11.516612Z] Finished read-buildbot-config step (success) 15:26:11 INFO - [mozharness: 2016-09-08 22:26:11.516853Z] Running download-and-extract step. 15:26:11 INFO - Running pre-action listener: _resource_record_pre_action 15:26:11 INFO - Running main action method: download_and_extract 15:26:11 INFO - mkdir: /builds/slave/test/build/tests 15:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:11 INFO - trying https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:26:11 INFO - Downloading https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:26:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 15:26:12 INFO - Downloaded 1199 bytes. 15:26:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:26:12 INFO - Using the following test package requirements: 15:26:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:26:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:26:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:26:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'jsshell-mac.zip'], 15:26:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:26:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:26:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:26:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:26:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:26:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:26:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:26:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:26:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:26:13 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:26:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:26:21 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:26:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 15:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:30 INFO - trying https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:30 INFO - Downloading https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:26:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 15:26:33 INFO - Downloaded 111487881 bytes. 15:26:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:33 INFO - mkdir: /builds/slave/test/properties 15:26:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:26:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:26:33 INFO - Contents: 15:26:33 INFO - build_url:https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:26:33 INFO - Running post-action listener: _resource_record_post_action 15:26:33 INFO - Running post-action listener: set_extra_try_arguments 15:26:33 INFO - [mozharness: 2016-09-08 22:26:33.685262Z] Finished download-and-extract step (success) 15:26:33 INFO - [mozharness: 2016-09-08 22:26:33.685383Z] Running populate-webroot step. 15:26:33 INFO - Running pre-action listener: _resource_record_pre_action 15:26:33 INFO - Running main action method: populate_webroot 15:26:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:26:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:26:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:26:33 INFO - '--tppagecycles', 15:26:33 INFO - '7'], 15:26:33 INFO - 'tests': ['tsvgm']}}, 15:26:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:26:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:26:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:26:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:26:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['damp', 'tps']}, 15:26:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'tests': ['damp', 'tps']}, 15:26:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['dromaeo_dom']}, 15:26:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['basic_compositor_video']}, 15:26:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:26:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['a11yr', 15:26:33 INFO - 'ts_paint', 15:26:33 INFO - 'tpaint', 15:26:33 INFO - 'sessionrestore', 15:26:33 INFO - 'sessionrestore_no_auto_restore', 15:26:33 INFO - 'tabpaint']}, 15:26:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['tsvgx', 15:26:33 INFO - 'tsvgr_opacity', 15:26:33 INFO - 'tart', 15:26:33 INFO - 'tscrollx', 15:26:33 INFO - 'cart']}, 15:26:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:26:33 INFO - 'tsvgr_opacity', 15:26:33 INFO - 'tart', 15:26:33 INFO - 'tscrollx', 15:26:33 INFO - 'cart']}, 15:26:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'talos_options': ['--disable-e10s'], 15:26:33 INFO - 'tests': ['tp5o']}, 15:26:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'tests': ['tp5o']}, 15:26:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'talos_options': ['--disable-e10s', 15:26:33 INFO - '--xperf_path', 15:26:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:33 INFO - 'tests': ['tp5n']}, 15:26:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:26:33 INFO - 'talos_options': ['--xperf_path', 15:26:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:33 INFO - 'tests': ['tp5n']}}, 15:26:33 INFO - 'talos.zip': {'path': '', 15:26:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:26:33 INFO - Running post-action listener: _resource_record_post_action 15:26:33 INFO - [mozharness: 2016-09-08 22:26:33.704312Z] Finished populate-webroot step (success) 15:26:33 INFO - [mozharness: 2016-09-08 22:26:33.704409Z] Running create-virtualenv step. 15:26:33 INFO - Running pre-action listener: _resource_record_pre_action 15:26:33 INFO - Running main action method: create_virtualenv 15:26:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:26:33 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 15:26:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:26:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:33 INFO - Using real prefix '/tools/python27' 15:26:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:26:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:37 INFO - Installing pip.................done. 15:26:37 INFO - Return code: 0 15:26:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:37 INFO - 'HOME': '/Users/cltbld', 15:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:37 INFO - 'LOGNAME': 'cltbld', 15:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:37 INFO - 'MOZ_NO_REMOTE': '1', 15:26:37 INFO - 'NO_EM_RESTART': '1', 15:26:37 INFO - 'PAGER': '/bin/cat', 15:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:37 INFO - 'PWD': '/builds/slave/test', 15:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:37 INFO - 'SHELL': '/bin/bash', 15:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:37 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:37 INFO - 'USER': 'cltbld', 15:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:37 INFO - 'XPC_FLAGS': '0x0', 15:26:37 INFO - 'XPC_SERVICE_NAME': '0', 15:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:38 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 15:26:38 INFO - Cleaning up... 15:26:38 INFO - Return code: 0 15:26:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:38 INFO - 'HOME': '/Users/cltbld', 15:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:38 INFO - 'LOGNAME': 'cltbld', 15:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:38 INFO - 'MOZ_NO_REMOTE': '1', 15:26:38 INFO - 'NO_EM_RESTART': '1', 15:26:38 INFO - 'PAGER': '/bin/cat', 15:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:38 INFO - 'PWD': '/builds/slave/test', 15:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:38 INFO - 'SHELL': '/bin/bash', 15:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:38 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:38 INFO - 'USER': 'cltbld', 15:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:38 INFO - 'XPC_FLAGS': '0x0', 15:26:38 INFO - 'XPC_SERVICE_NAME': '0', 15:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:38 INFO - Downloading/unpacking psutil>=3.1.1 15:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:41 INFO - Installing collected packages: psutil 15:26:41 INFO - Running setup.py install for psutil 15:26:41 INFO - building 'psutil._psutil_osx' extension 15:26:41 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 15:26:42 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 15:26:42 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 15:26:42 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 15:26:42 INFO - building 'psutil._psutil_posix' extension 15:26:42 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 15:26:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:26:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:26:42 INFO - ^ 15:26:42 INFO - 1 warning generated. 15:26:42 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 15:26:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:42 INFO - Successfully installed psutil 15:26:42 INFO - Cleaning up... 15:26:42 INFO - Return code: 0 15:26:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:42 INFO - 'HOME': '/Users/cltbld', 15:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:42 INFO - 'LOGNAME': 'cltbld', 15:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:42 INFO - 'MOZ_NO_REMOTE': '1', 15:26:42 INFO - 'NO_EM_RESTART': '1', 15:26:42 INFO - 'PAGER': '/bin/cat', 15:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:42 INFO - 'PWD': '/builds/slave/test', 15:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:42 INFO - 'SHELL': '/bin/bash', 15:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:42 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:42 INFO - 'USER': 'cltbld', 15:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:42 INFO - 'XPC_FLAGS': '0x0', 15:26:42 INFO - 'XPC_SERVICE_NAME': '0', 15:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:26:45 INFO - Installing collected packages: mozsystemmonitor 15:26:45 INFO - Running setup.py install for mozsystemmonitor 15:26:45 INFO - Successfully installed mozsystemmonitor 15:26:45 INFO - Cleaning up... 15:26:45 INFO - Return code: 0 15:26:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:26:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:45 INFO - 'HOME': '/Users/cltbld', 15:26:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:45 INFO - 'LOGNAME': 'cltbld', 15:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:45 INFO - 'MOZ_NO_REMOTE': '1', 15:26:45 INFO - 'NO_EM_RESTART': '1', 15:26:45 INFO - 'PAGER': '/bin/cat', 15:26:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:45 INFO - 'PWD': '/builds/slave/test', 15:26:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:45 INFO - 'SHELL': '/bin/bash', 15:26:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:45 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:45 INFO - 'USER': 'cltbld', 15:26:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:45 INFO - 'XPC_FLAGS': '0x0', 15:26:45 INFO - 'XPC_SERVICE_NAME': '0', 15:26:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:46 INFO - Downloading/unpacking jsonschema==2.5.1 15:26:46 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 15:26:46 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 15:26:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:46 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 15:26:46 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 15:26:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:26:48 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 15:26:48 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 15:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:48 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 15:26:48 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 15:26:49 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:49 INFO - warning: no files found matching '*.txt' 15:26:49 INFO - no previously-included directories found matching 'build' 15:26:49 INFO - no previously-included directories found matching 'dist' 15:26:49 INFO - no previously-included directories found matching '.git*' 15:26:49 INFO - Installing collected packages: jsonschema, functools32 15:26:49 INFO - Running setup.py install for functools32 15:26:49 INFO - warning: no files found matching '*.txt' 15:26:49 INFO - no previously-included directories found matching 'build' 15:26:49 INFO - no previously-included directories found matching 'dist' 15:26:49 INFO - no previously-included directories found matching '.git*' 15:26:49 INFO - Successfully installed jsonschema functools32 15:26:49 INFO - Cleaning up... 15:26:49 INFO - Return code: 0 15:26:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:49 INFO - 'HOME': '/Users/cltbld', 15:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:49 INFO - 'LOGNAME': 'cltbld', 15:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:49 INFO - 'MOZ_NO_REMOTE': '1', 15:26:49 INFO - 'NO_EM_RESTART': '1', 15:26:49 INFO - 'PAGER': '/bin/cat', 15:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:49 INFO - 'PWD': '/builds/slave/test', 15:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:49 INFO - 'SHELL': '/bin/bash', 15:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:49 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:49 INFO - 'USER': 'cltbld', 15:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:49 INFO - 'XPC_FLAGS': '0x0', 15:26:49 INFO - 'XPC_SERVICE_NAME': '0', 15:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:26:49 INFO - Cleaning up... 15:26:49 INFO - Return code: 0 15:26:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:49 INFO - 'HOME': '/Users/cltbld', 15:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:49 INFO - 'LOGNAME': 'cltbld', 15:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:49 INFO - 'MOZ_NO_REMOTE': '1', 15:26:49 INFO - 'NO_EM_RESTART': '1', 15:26:49 INFO - 'PAGER': '/bin/cat', 15:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:49 INFO - 'PWD': '/builds/slave/test', 15:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:49 INFO - 'SHELL': '/bin/bash', 15:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:49 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:49 INFO - 'USER': 'cltbld', 15:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:49 INFO - 'XPC_FLAGS': '0x0', 15:26:49 INFO - 'XPC_SERVICE_NAME': '0', 15:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 15:26:50 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 15:26:50 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 15:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:50 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 15:26:50 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 15:26:52 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26:52 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 15:26:52 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 15:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:52 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 15:26:52 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 15:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:26:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:53 INFO - Downloading docopt-0.6.1.tar.gz 15:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:53 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:53 INFO - Running setup.py install for blobuploader 15:26:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:26:53 INFO - Running setup.py install for requests 15:26:54 INFO - Running setup.py install for docopt 15:26:54 INFO - Successfully installed blobuploader requests docopt 15:26:54 INFO - Cleaning up... 15:26:54 INFO - Return code: 0 15:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:54 INFO - 'HOME': '/Users/cltbld', 15:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:54 INFO - 'LOGNAME': 'cltbld', 15:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:54 INFO - 'MOZ_NO_REMOTE': '1', 15:26:54 INFO - 'NO_EM_RESTART': '1', 15:26:54 INFO - 'PAGER': '/bin/cat', 15:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:54 INFO - 'PWD': '/builds/slave/test', 15:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:54 INFO - 'SHELL': '/bin/bash', 15:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:54 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:54 INFO - 'USER': 'cltbld', 15:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:54 INFO - 'XPC_FLAGS': '0x0', 15:26:54 INFO - 'XPC_SERVICE_NAME': '0', 15:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:54 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vBzkx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:54 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0gQzTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jRWgza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-G3djIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0DIk9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-OqkKWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-fIleYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-8ZJZy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JTL5V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JChgLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-WNEqEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ighix5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-teOJIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-XT_U2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-pvYiBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-RqAMQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4L0iUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:26:56 INFO - Running setup.py install for manifestparser 15:26:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:26:56 INFO - Running setup.py install for mozcrash 15:26:56 INFO - Running setup.py install for mozdebug 15:26:57 INFO - Running setup.py install for mozdevice 15:26:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Running setup.py install for mozfile 15:26:57 INFO - Running setup.py install for mozhttpd 15:26:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Running setup.py install for mozinfo 15:26:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Running setup.py install for mozInstall 15:26:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:26:57 INFO - Running setup.py install for mozleak 15:26:57 INFO - Running setup.py install for mozlog 15:26:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Running setup.py install for moznetwork 15:26:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Running setup.py install for mozprocess 15:26:58 INFO - Running setup.py install for mozprofile 15:26:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Running setup.py install for mozrunner 15:26:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:26:58 INFO - Running setup.py install for mozscreenshot 15:26:58 INFO - Running setup.py install for moztest 15:26:59 INFO - Running setup.py install for mozversion 15:26:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:26:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:26:59 INFO - Cleaning up... 15:26:59 INFO - Return code: 0 15:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:26:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:26:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:26:59 INFO - 'HOME': '/Users/cltbld', 15:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:26:59 INFO - 'LOGNAME': 'cltbld', 15:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:59 INFO - 'MOZ_NO_REMOTE': '1', 15:26:59 INFO - 'NO_EM_RESTART': '1', 15:26:59 INFO - 'PAGER': '/bin/cat', 15:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:59 INFO - 'PWD': '/builds/slave/test', 15:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:26:59 INFO - 'SHELL': '/bin/bash', 15:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:26:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:26:59 INFO - 'USER': 'cltbld', 15:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:59 INFO - 'XPC_FLAGS': '0x0', 15:26:59 INFO - 'XPC_SERVICE_NAME': '0', 15:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-YQtzi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:59 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)) 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-z2QYZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QvZfTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:59 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)) 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-P3gMLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-37DQJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:59 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)) 15:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:59 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-D0AuMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Wu61pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-CB5snp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JNeuyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-GpzB4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-9JaYea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Qd31T3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ZH6Fiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:00 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-9ZGE_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 15:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:00 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ohkQcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:01 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)) 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:01 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-L6bQtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 15:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:01 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JMre3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:01 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)) 15:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:27:01 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)) 15:27:01 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:27:01 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:27:01 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 15:27:01 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 15:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:01 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 15:27:01 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 15:27:04 INFO - Downloading blessings-1.6.tar.gz 15:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:27:04 INFO - Installing collected packages: blessings 15:27:04 INFO - Running setup.py install for blessings 15:27:05 INFO - Successfully installed blessings 15:27:05 INFO - Cleaning up... 15:27:05 INFO - Return code: 0 15:27:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:05 INFO - Reading from file tmpfile_stdout 15:27:05 INFO - Current package versions: 15:27:05 INFO - blessings == 1.6 15:27:05 INFO - blobuploader == 1.2.4 15:27:05 INFO - docopt == 0.6.1 15:27:05 INFO - functools32 == 3.2.3-2 15:27:05 INFO - jsonschema == 2.5.1 15:27:05 INFO - manifestparser == 1.1 15:27:05 INFO - mozInstall == 1.12 15:27:05 INFO - mozcrash == 0.17 15:27:05 INFO - mozdebug == 0.1 15:27:05 INFO - mozdevice == 0.48 15:27:05 INFO - mozfile == 1.2 15:27:05 INFO - mozhttpd == 0.7 15:27:05 INFO - mozinfo == 0.9 15:27:05 INFO - mozleak == 0.1 15:27:05 INFO - mozlog == 3.3 15:27:05 INFO - moznetwork == 0.27 15:27:05 INFO - mozprocess == 0.23 15:27:05 INFO - mozprofile == 0.28 15:27:05 INFO - mozrunner == 6.12 15:27:05 INFO - mozscreenshot == 0.1 15:27:05 INFO - mozsystemmonitor == 0.3 15:27:05 INFO - moztest == 0.7 15:27:05 INFO - mozversion == 1.4 15:27:05 INFO - psutil == 3.1.1 15:27:05 INFO - requests == 1.2.3 15:27:05 INFO - wsgiref == 0.1.2 15:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:27:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 15:27:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:05 INFO - 'HOME': '/Users/cltbld', 15:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:05 INFO - 'LOGNAME': 'cltbld', 15:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:05 INFO - 'MOZ_NO_REMOTE': '1', 15:27:05 INFO - 'NO_EM_RESTART': '1', 15:27:05 INFO - 'PAGER': '/bin/cat', 15:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:05 INFO - 'PWD': '/builds/slave/test', 15:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:05 INFO - 'SHELL': '/bin/bash', 15:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:27:05 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:05 INFO - 'USER': 'cltbld', 15:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:05 INFO - 'XPC_FLAGS': '0x0', 15:27:05 INFO - 'XPC_SERVICE_NAME': '0', 15:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 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)) 15:27:05 INFO - Cleaning up... 15:27:05 INFO - Return code: 0 15:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1021dda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102272618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa70248e2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, '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.tB6ov4avkP/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.xAPB42MLKQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 15:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:05 INFO - 'HOME': '/Users/cltbld', 15:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:05 INFO - 'LOGNAME': 'cltbld', 15:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:05 INFO - 'MOZ_NO_REMOTE': '1', 15:27:05 INFO - 'NO_EM_RESTART': '1', 15:27:05 INFO - 'PAGER': '/bin/cat', 15:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:05 INFO - 'PWD': '/builds/slave/test', 15:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:05 INFO - 'SHELL': '/bin/bash', 15:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:27:05 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:05 INFO - 'USER': 'cltbld', 15:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:05 INFO - 'XPC_FLAGS': '0x0', 15:27:05 INFO - 'XPC_SERVICE_NAME': '0', 15:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:27:05 INFO - Cleaning up... 15:27:05 INFO - Return code: 0 15:27:05 INFO - Running post-action listener: _resource_record_post_action 15:27:05 INFO - Running post-action listener: _start_resource_monitoring 15:27:05 INFO - Starting resource monitoring. 15:27:05 INFO - [mozharness: 2016-09-08 22:27:05.942971Z] Finished create-virtualenv step (success) 15:27:05 INFO - [mozharness: 2016-09-08 22:27:05.943975Z] Running install step. 15:27:05 INFO - Running pre-action listener: _resource_record_pre_action 15:27:05 INFO - Running main action method: install 15:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:27:06 INFO - Reading from file tmpfile_stdout 15:27:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:27:06 INFO - Reading from file tmpfile_stdout 15:27:06 INFO - Output received: 15:27:06 INFO - Usage: mozinstall [options] installer 15:27:06 INFO - Options: 15:27:06 INFO - -h, --help show this help message and exit 15:27:06 INFO - -d DEST, --destination=DEST 15:27:06 INFO - Directory to install application into. [default: 15:27:06 INFO - "/builds/slave/test"] 15:27:06 INFO - --app=APP Application being installed. [default: firefox] 15:27:06 INFO - mkdir: /builds/slave/test/build/application 15:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:27:40 INFO - Reading from file tmpfile_stdout 15:27:40 INFO - Output received: 15:27:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:27:40 INFO - Running post-action listener: _resource_record_post_action 15:27:40 INFO - [mozharness: 2016-09-08 22:27:40.857579Z] Finished install step (success) 15:27:40 INFO - [mozharness: 2016-09-08 22:27:40.857698Z] Running run-tests step. 15:27:40 INFO - Running pre-action listener: _resource_record_pre_action 15:27:40 INFO - Running main action method: run_tests 15:27:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:27:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:27:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:27:40 INFO - 2016-09-08 15:27:40.869 screenresolution[1640:7717] starting screenresolution argv=screenresolution get 15:27:40 INFO - 2016-09-08 15:27:40.872 screenresolution[1640:7717] Display 0: 1600x1200x32@60 15:27:40 INFO - 2016-09-08 15:27:40.877 screenresolution[1641:7721] starting screenresolution argv=screenresolution list 15:27:40 INFO - Available Modes on Display 0 15:27:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:27:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:27:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:27:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:27:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:27:41 INFO - Intel Iris: 15:27:41 INFO - Chipset Model: Intel Iris 15:27:41 INFO - Type: GPU 15:27:41 INFO - Bus: Built-In 15:27:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:27:41 INFO - Vendor: Intel (0x8086) 15:27:41 INFO - Device ID: 0x0a2e 15:27:41 INFO - Revision ID: 0x0009 15:27:41 INFO - Displays: 15:27:41 INFO - Display: 15:27:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:27:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:27:41 INFO - Display Serial Number: 1600x1200 60 15:27:41 INFO - Main Display: Yes 15:27:41 INFO - Mirror: Off 15:27:41 INFO - Online: Yes 15:27:41 INFO - Rotation: Supported 15:27:41 INFO - Return code: 0 15:27:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:27:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:27:41 INFO - Python 2.7.3 15:27:41 INFO - Return code: 0 15:27:41 INFO - grabbing minidump binary from tooltool 15:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 15:27:41 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 0x102141d30>, '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 0x101ea6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102139b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:27:41 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 15:27:41 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 15:27:41 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 15:27:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:27:41 INFO - Return code: 0 15:27:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:27:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:27:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:27:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:27:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:27:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 15:27:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --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 15:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tB6ov4avkP/Render', 15:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:41 INFO - 'HOME': '/Users/cltbld', 15:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:41 INFO - 'LOGNAME': 'cltbld', 15:27:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:27:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:41 INFO - 'MOZ_NO_REMOTE': '1', 15:27:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:27:41 INFO - 'NO_EM_RESTART': '1', 15:27:41 INFO - 'PAGER': '/bin/cat', 15:27:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:41 INFO - 'PWD': '/builds/slave/test', 15:27:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:41 INFO - 'SHELL': '/bin/bash', 15:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xAPB42MLKQ/Listeners', 15:27:41 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 15:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:41 INFO - 'USER': 'cltbld', 15:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:41 INFO - 'XPC_FLAGS': '0x0', 15:27:41 INFO - 'XPC_SERVICE_NAME': '0', 15:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 15:27:41 INFO - mozversion application_buildid: 20160908134620 15:27:41 INFO - mozversion application_changeset: a05091172da102a71a89a6602385b2114574abfd 15:27:41 INFO - mozversion application_display_name: Nightly 15:27:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:27:41 INFO - mozversion application_name: Firefox 15:27:41 INFO - mozversion application_remotingname: firefox 15:27:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:27:41 INFO - mozversion application_vendor: Mozilla 15:27:41 INFO - mozversion application_version: 51.0a1 15:27:41 INFO - mozversion platform_buildid: 20160908134620 15:27:41 INFO - mozversion platform_changeset: a05091172da102a71a89a6602385b2114574abfd 15:27:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:27:41 INFO - mozversion platform_version: 51.0a1 15:27:41 INFO - using testdate: 1473373661 15:27:41 INFO - actual date: 1473373661 15:27:41 INFO - starting webserver on 'localhost:49271' 15:27:41 INFO - SUITE-START | Running 2 tests 15:27:41 INFO - TEST-START | dromaeo_css 15:27:41 INFO - operating with platform_type : mac_ 15:27:41 INFO - Initialising browser for dromaeo_css test... 15:27:41 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmp6704Wl/profile http://localhost:49271/getInfo.html) 15:27:43 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 15:27:43 INFO - PROCESS | 1651 | colorDepth:24 15:27:43 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 15:27:43 INFO - PROCESS | 1651 | __metrics 15:27:43 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:27:43 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:44 INFO - TEST-INFO | 1651: exit 0 15:27:44 INFO - Browser initialized. 15:27:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:27:44 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmp6704Wl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:56 INFO - PROCESS | 1653 | RSS: Main: 232333312 15:27:56 INFO - PROCESS | 1653 | 15:28:47 INFO - PROCESS | 1653 | 1473373727669 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:30:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:31:01 INFO - PROCESS | 1653 | RSS: Main: 399704064 15:31:01 INFO - PROCESS | 1653 | 15:34:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:34:04 INFO - PROCESS | 1653 | RSS: Main: 373350400 15:34:04 INFO - PROCESS | 1653 | 15:37:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:37:07 INFO - PROCESS | 1653 | RSS: Main: 335118336 15:37:07 INFO - PROCESS | 1653 | 15:40:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:40:10 INFO - PROCESS | 1653 | RSS: Main: 343654400 15:40:10 INFO - PROCESS | 1653 | 15:43:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:43:14 INFO - PROCESS | 1653 | RSS: Main: 327888896 15:43:14 INFO - PROCESS | 1653 | 15:46:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 15:46:18 INFO - PROCESS | 1653 | RSS: Main: 450748416 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | __start_tp_report 15:46:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 15:46:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 15:46:18 INFO - PROCESS | 1653 | |i|pagename|runs| 15:46:18 INFO - PROCESS | 1653 | |0;dojo.html;3315.668433156685;3322.0200040987916;3347.8660853565857;3374.8143852088156;3404.4212483877736;5519.172124181375;5612.579056570758;5615.943840561593;5715.371309155989;5735.369109397973;5636;5660.858478538039;5677.829665110043;5680.772769089242;5693;5111.97760447911;5149.76826042829;5152.201408781645;5171.637985341028;5206.895862082756;5255.159174532076;5268.130758424855;5269.473052694719;5270.0513907496825;5272.578193744491;5167.431582525919;5177.767000484987;5189.42916279209;5208.062548741244;5250.05499010178;5261.131913234331;5294.285271488338;5296.602754793367;5305.65513241638;5337.572994160458;5034.043531728959;5038.5465308122075;5104.234364845266;5184.248283998837;5185.248139019821;5657.179708942181;5675.574331925122;5707.1153971134545;5734.283214598176;5787.971060144672;6233.719482623293;6253.280872699653;6259.87480250397;6267.05994100884;6277.183966084425;6090.96954515229;6190.752369905198;6230.657313847737;6254.499640028786;6294.685265736694;5796.188533605298;5797.681127537984;5804.88390232193;5817.942806495173;5837.67892765902;6233.781817636361;6246.063090536365;6263.279722831841;6278.215223097113;6283.465905398092;5967.283925928917;5967.671778052249;5990.760369585168;6193.6903154842075;6204.658743769136;6095;6148.016317389286;6159.753609855646;6163.630182189083;6166.568340216231;6065.3934606539;6080.361562036011;6092.208012958287;6095.44857245249;6109.266887973383;5110.2590124431745;5315.362156541162;6013.827303675741;6097.84141013206;6119.143319935212;4908.239222920453;4914.213725803854;4917.434495033045;4920.434150072716;4922.532359425849;4371.672124590668;4443.977780111079;4446.244138496463;4472.619827314649;4479.910401791946;5716.399778023331;5745.827625171251;5785.623934444332;5797.101449275369;5800.419958004166;5223.347081614798;5266.894662106736;6196.897576293602;6222.036835649845;6278.748850046039;4777.402824646919;5692.260006199251;5702.087665973424;5714.200011998323;5715.885682286331;5303.231031500488;5359.517643412034;5372.973135134299;5385.111456609694;5409.323834520685;6259.780907668301;6265.122882796503;6272.372762723782;6276.278228003746;6283.277423096402;6250.968745156427;6278.4035516625845;6280.057991301342;6281.246250449975;6293.992961126198;6161.5686901918225;6200.875982480235;6216;6239;6271.811845644638;6184.103305020837;6219.937800621936;6222.097795819672;6231.906521402273;6234.812955611339;5235.743421578717;5312.840614781563;6128.50971922254;6242.875142497034;6248.12526246335;5760.567957403127;5861.062230043172;5885.087811389241;6039.154518367344;6050.092486127116;4973.577245934136;5915.40845915405;5926.585139040226;5956.434138756811;5961.940380596138;5775.787084712089;5885.264341957255;5927.940720592739;5937;5960.791372301948;5258.789648414021;5985.102234664835;6026.427489388501;6084.269887613515;6103.877922441615;5968;5969.313528944227;5969.671668058299;5976.940230597812;5991.281046274475 15:46:18 INFO - PROCESS | 1653 | |1;ext.html;492.23457523550877;507.13786562843154;507.40126650552355;540.3434826685576;550.7301422303068;23853;30573.235669108257;31878.68121318786;32043.67956320436;32044.67955320446;32533.837330813312;33482;33535.49696754545;40077.799611001974;42349.94125146865;9807.166390856768;9963.15313198379;9966.850497242547;9968.102870741632;9980.550875210633;8456.957715211445;9739.123478886899;9837.55730992107;9847.90152098477;9849.310548261588;9795.90204097957;9830.606775728998;9832.360896541737;9839.70480885571;9843.753906152368;9736.221102311834;9795.363301385458;9796.608135674565;9797.167240784505;9807.656732014417;9744.123028927359;9829.459379734111;9832.508374581314;9832.90167098327;9873.358231714916;32848.50727239093;39817.40273895894;39830.60169398298;39835.20329593392;39846.800765996275;3418.3163367326633;3472.2708231271463;3531;3667.8716244931566;3965.662918651918;30099.398012039856;30289.242768930733;30625.693743062508;30719.846400768078;31372.372552548823;3221.7261532769744;3232.095013396253;3254.886078987248;3321.701046905766;3351.2962277921665;418801;424428;426364;428787;431961;57523.712381438665;57774;57786;57815;57941.8411631765;33721.32557348839;34229.48655770165;34544.654553454144;60125.797484050076;60449.39550604526;16014.519564412853;16463.91768041152;16472.176391180394;16481.25834337458;16506.092164931044;12626.81059784104;12743.745125097446;12756.489740410465;12760.80858787119;12776.808347874789;15399.384024639114;18755.593330500265;18805.529861753566;18883.433496995112;18916.527086822665;6643.40209381158;7643.082830060428;7749.92250077492;7785.688572457151;7810;845.6027162992862;868.8349213649386;938.8122375524922;956.4787190980932;994.4282037828277;28724;29725.91183855211;30084.398312034074;30104.84947575292;30123;881.378628067211;1004.6634377483625;1063.8563544190026;1070.4754670211696;1134.4288160897052;13905.86094139066;14322.639349261803;15074.924625376803;15212.391504339923;15516.146611936452;554450;565060;565941;567637;572553;55692.72153639206;56172;56285.718571406884;56499.435005649415;56510.717446412506;1200.6038007457519;1320.9273489958146;1339.2232505146992;1353.1678018019045;1354.7493713663005;30228.84885575558;30352;30566.694333057276;31132.533012004384;31166.532502012942;47676.523234767206;48347.758261208466;48396.75801620969;48424.515754842;48500.757496212296;381956;383329;384589;385390;386715;25761.484770304865;26631.20106396811;26635.86682066577;27584.89660413561;27677.169684909484;45876.77061614804;46160.76919615381;47214.527854722386;47469.762651186524;47610;30973.845130774203;37854.81072594619;38955;39046.219075618894;39055.2188956225;32717.672823271463;37806.054848629006;38172.618273818014;38351.65769198065;39321.60678393179;37222.06944826401;37670.246595067394;37890.43164352482;37982;37996.62003379931;31742.523862141625;32091;33752.19618420747;34166.14584635404;34245.97262082141;24259.272221833373;24928.252152435456;24960.750392495844;25265.87367063153;25689.357766055997 15:46:18 INFO - PROCESS | 1653 | |2;jquery.html;318.4093506545357;349.05058241582884;369.73933376969234;378.7140708764883;404.36110944707366;15302.234888255598;17141.057241851693;20165.890876001813;20783.480412989684;20887.895560522196;20358.898205508987;20638.277660281892;20999.160033598677;21025.36923892282;21480.785192148072;5344.385395679507;5972.820815375535;6130.662813545253;6225.035119556476;6241.65670888101;6104.389561043882;6216.254049514065;6221.906671399933;6222.191115155046;6227.283862355842;5898.41015898411;6069.696515174246;6117.44942955134;6124.448799608034;6187.90718139226;5878.676672782995;6091.878162436748;6152;6152.87694246117;6155.446009859134;4942;5256.159014557691;5261.921071183935;5263.763130659129;5272.499112584298;25654.358641033938;26230.73769262302;26253.606195907076;26334.73665263342;26629;2215.235743668432;2257.0181970842573;2285.074544809355;2457.938551536208;2922.8308676529346;15322.770158447633;15423.691526169414;15562.688746225012;15803.313436715785;15816.60458488547;1797.613513094691;1812.5649844037468;1840.4478656403026;1857.6377606366764;1875.3248830421037;112536;115515;115558;115634.42182789116;116507;10651.680449586525;10872.130229581773;11201.719957000978;11589.710257243467;11740.823887641693;13859.653508662162;13899.930500347433;14058.859411405754;14073.788893166611;14089.084209526349;8627.568621568897;8670.219680228809;8729.115543583044;8821.647134114692;8853.822923541622;8686.609102590399;8695.739127826175;8736.257418119403;8877.556122193864;8894.86657700135;6563.540552161303;6675.299093595199;6676.532642714963;6696.196456425163;6697.296783837724;1561.4691005058335;1800.1989114843768;1839.3286450445491;1840.3098837935775;1843.3363988963963;870.9592037515108;879.3185281406961;891.2157301574573;961.1397798970022;988.2242439684813;21860.45348866291;22550.872456377114;22617.434564136027;22622.701686679808;22774.8861255696;829.5188790501616;907.6006557114848;930.4789317981952;965.5799727118725;988.8912219655832;10553.683389498327;10668.093212077061;10975.83536246957;11298.378589177673;11369.260998035266;507444;524991;532807;543004;548217;98939;103750.48124759328;121668;126065;133942;880.6213328268906;982.886967998675;998.7752755630071;1016.9338992965434;1017.5980487707462;17588.560285992953;18008.729869052237;18874.811251887306;19024.19270169332;19860.40418787439;43447.78276108599;54300.728496358846;54714.72642636761;55101;56633.433665662815;59269.70365148147;78473;78596.60701696455;80333.19666803257;80709;5566.359868615161;6827.317268273133;7714.922850771645;8012.719554815553;8157.265846073882;9625.614975400906;10099.7475063124;11926.642200733992;12537.937310313755;13269.203847769164;10889.836652450062;11090.279131856403;11225.775484490101;11553.768924621629;11556.94221528887;10247.282690211614;10959.56161753521;11283;11404.94297528507;11560.248583842023;10901.509432075734;11220.719482013015;11260.09919206478;11367.886321136682;11478.713032174262;7946.7615971520945;8002.639881205243;8066.959665201636;8073.3541316693345;8155.143709910374;10693.679189624323;10961.835572466578;11162.776744465227;11315;11356.488957996744 15:46:18 INFO - PROCESS | 1653 | |3;mootools.html;1427.8357988831285;1519.5289460267318;1687.2660392729165;1847.9118595986533;1852.805455427181;7946.880796788038;8194.180581941802;8328.79178020549;8813.4711917285;8821.250193733533;7084.326988936055;7980.16208298129;8065.637046332914;8638.697645582406;8644.222020018196;2342.7891489765916;2374.620060790274;2374.8218883583713;2376.1564644551195;2376.4771750215014;2330.9650355244685;2364.408897775556;2385.976140238593;2389.187676190095;2390.8087353011715;2084.9374518764384;2151.0965394534255;2152.365052309565;2161.945951351221;2162.772908844572;2292.128990983424;2359.917402890908;2364.9408764780846;2372.3594629450126;2373;2337.1703045418803;2358.056777289081;2361.445060410802;2361.5276944611146;2380.797632201265;8757.7810554736;8807.07525709804;8814.338924580617;8816.8677469838;8838.690645827364;4830.758462076918;5091.854332775096;5189.66267192635;5336.679799212021;5338;3464;3596.1009747563107;3599.1541987633145;3636.5999740028574;3652.506911566931;1050.2858056521563;1099.648112603967;1124.4714983957526;1124.780667769777;1125.6791814332876;11486.827697584544;11517.654470365902;11519.712007199803;11537.365444900612;11552.711182220428;4417.712848664826;4560.361549383089;4580.358749775034;4740.928886066712;4753.215719406328;9812;9812.067853553901;9821.968693287245;9832.950835245778;9835.459049752333;5355.169948657965;5359.839204823861;5359.892802143935;5365.248865158881;5367;4775.355327030807;4874.829380971649;4934.432540257844;4941.332920055818;4944.208926571731;3859.208862183246;3874.791368919689;3905.843766249375;3961.950083227947;3982.880513584539;1273.2742336867896;1286.4403984266874;1290.3612711707765;1305.2755720574983;1311.3508813137366;764.5527366490554;860.7288704058202;883.271301176519;894.4677916639589;939.9718008459757;5563.443655634404;5626.662400255997;5629.324481062217;5638.182463542763;5950.96500784603;826.5660528222732;930.3394589841289;942.4439580647451;962.6245764151986;984.7882705218412;3603.94594081089;3801.4297855321925;3839.3665045267776;3886.689064874803;3892.241013002494;10360.015798499131;10559.524821383056;10662.360258384522;10674.893251067546;10758.94620526908;8954.820903581893;10093.394396336244;10550.788984220426;10563.471826408802;10606.628767993237;7851.097123830833;8110.391720620859;8126.674933002732;8129.878051829227;8130.430869839172;5821.738021789114;5826.41735826414;5849.824505264849;6021.879562408815;6160.630362178284;9724.124828765162;9918.504074796087;9940.900590994286;10031.949840250752;10073.899261007296;9736.22110231194;9974.850377244202;10020.448875311928;10180.541875615467;10269;6169.537284703575;7518.548887066793;7519.210482899217;7529.698812047676;7544.735934242275;7989.400794940519;8038.196180381915;8134.63394147253;8136.145704700921;8218.506889586644;7281.817954551178;7307.561546307238;7359.484836061424;7375.926240737524;7384.704611815468;7145.857082858418;7290.416766658548;7463.029806125169;7559.244075592397;7739.651715672696;6597.967010164918;6692.564983276072;6891.586504809728;6896.310368963063;6899.965500172467;5007.649464537593;5083.771230294572;5196.922046169235;5231.581473482189;5282.4717528246865;5031.169856973558;5123.513266239701;5157.252198431282;5163.741812909265;5319.654222475527 15:46:18 INFO - PROCESS | 1653 | |4;prototype.html;284.933040735427;296.7892796114758;302.74418116691396;309.671747947176;313.835236500837;3932.233214523169;4861.878453038671;5074.949250507494;5153.330067091273;5389.326334208224;4574.993501429689;4787.8324258650955;5223.7201885538;5292.1003429417;5378.354597448311;3175.7459403247744;3989.5412027616762;4024.4103579086245;4035.919281614366;4095.525610780117;3814.2752876953427;3964.940525892114;3991.6607088397377;3999.080441388121;4048.655864251542;3859.0545316397383;3946.6645335146404;3989.5611482736876;4007.6994225433064;4041.51501819782;3981.442598036277;3996.800159991989;4008.7594744315434;4011.5186177658575;4030.8186131624147;3097.931213731259;3284.6715328467203;3448.6723761242642;3453.205762674599;3460.636633153533;4794.784234709412;5122.359705036871;5124.103281925648;5155.267708114705;5160.56135228506;3865.4588357629955;4590.816367345302;4602.148602508513;4626.773904915201;4643.558861908114;4629.930551041737;5380.300560927094;5618.522425593829;5628.831135065954;5659.349174844904;3802.5627052888717;3817.9118951098803;3819.8854034378737;3828;3864.806759662034;5544.77820887164;5785.6528608283215;5849;5854.946109700252;5866.090755932837;3237.9190520237185;3877.495925529663;3899.883003509899;3900.921981560353;3928.705347098979;3586.318599466093;3768.9057773555332;3926.6662333701956;3937;3938;3681.631836816297;4044.3124668805785;4061.939070913939;4238.046439551076;4529.527903431398;4729.55069268419;5094.261332106824;5604.635698679573;5615.466530679579;5665;1314.2180402660401;1341.503643651855;1346.299924039494;1352.4184600621827;1353.5465619017739;765.3953376832352;769.9451751101027;804.2279411764771;811.664811385268;821.2854816309849;3843.9615603844163;4060.213506057384;4200.873973780792;4433.778311084498;4472.195004899149;4472.597466228055;4523.185826551186;4694.765261736899;4728.71627702339;4760.97639007615;3802.8591422573672;4313.6980411370905;4323.610875021201;4329.047609525899;4361.716488428242;2214.833887458447;2301.951263375597;2419.7459266777087;2531.848089114659;2549.4391233928504;3956.773400245933;4202.537720850704;4911.8280860170435;4919.810684245476;4942.654014218971;4702.836695120214;4714.95285047152;4861.927071093936;4897.510248975074;4998.325226094504;4333.696641235147;4374.58441448062;4713.528647135327;4785.545373189472;4889.242167464049;4365.856583260048;4422.977885110554;4593.035462552768;4606.239970404979;4728.810847566197;4544.909101818012;4681.267930865602;4722.5513576210205;4737.739424331695;4751.358566593465;4455.749846327584;4688.9296660549435;4898.632602554894;4956.801727930842;4977.328060711758;3003.828506882361;3287.967120328766;3319.8506067227518;3335.499675048762;3341.62993172798;4208.768517731554;4211.431456753299;4723.574878260973;4781.3422731611045;4843.989744256235;4623.468301145412;5081.491850815037;5129.563987061141;5276.973615131899;5306.310179676618;5095.496828435695;5627.409122042127;5694.971525142348;5702.887936852274;5711.7429715662065;5207.973960130175;5466.173678425039;5557.555395568425;5572.442755724395;5586.692731899784;5045.344105266292;5493.395726470165;5636.830895073154;5693.914591281217;5737.942620573741;5490.203920431435;5501.807436739695;5541.722913854372;5542.140968149944;5548.556115510669 15:46:18 INFO - PROCESS | 1653 | |5;yui.html;618.6844709198309;720.3768740039867;768.8462307538493;786.9415635969619;817.7645453417692;14343.856561434395;15632.14023228722;15648.295826687798;15673.45142919998;15702.371905123795;13820;13967.930160349184;13978.3709733062;14009.929950350233;14036;656.671664167916;670.7752902777576;814.9103598604149;818.472085504849;834.1491678487939;834.4576025583389;839.3956351426993;852.5524099847568;853.6756032707563;856.9742907712779;907.863820426938;907.8865141857268;910.622091831889;911.5168960450939;914.1544071733653;690.9067275917737;858.7552547523926;901.0944001278706;903.5617725403207;905.9547022648841;649.8148027812098;659.24845675929;659.673461636493;659.868026394723;660.4881217056771;14174.291285435791;14193.432262709479;14195.645108872257;14210.289485525785;14225.14649121056;13482.39329230187;13651.044426890121;13737.656558586015;13813.930930345385;14002.099220502792;10636.893631063667;12651.74696506074;12726.936365318206;13270.535531256457;13292;4463.24124898768;4475.709078909893;4482.186374033817;4548.226801443762;4700.4594471635855;105602.4159637606;106545;108382.45808770906;109010;109406.45296773544;9936.503174841373;10141.847872281922;10614.89385106139;10961.725956851142;10974.396408197466;20676.379708608765;20797.896010520155;20808.85551294663;20954.161833526487;21023.579528409653;7475.102987641518;7504.137024242173;7536.585487798224;7545.094588649397;7605.581693006938;3704.1665625234646;4156.750594964312;4288.4210631564965;4297.162053399619;4410.911781764411;3397.7451691123265;3515.9472607910902;3524.7004004659893;3807.8286477108595;3990.521137463523;713.6824113269545;714.0895358417955;723.0780754537901;728.1480667618898;735.1802739944923;502.74360076360585;550.4468654833044;589.3840936221659;635.5646382228189;680.8536164724509;8835.939687237444;9027;9511.143997040299;9561.521923903778;9689.612415503301;561.5114850080456;608.5253502268238;647.6632151281308;655.0283349378242;666.3103687683224;4636.072785442925;4718.245080787057;4724.314974328703;4763.142634325785;4782.641301902371;136256.6374336244;139845;142122;142358.01746938808;146171;94404.52797735967;124788;125804;125902.99999999817;128124;92258.07741922629;93016;100580;106784;109007;8276.544790036605;9906.157976571783;10080.647177348757;10103.141232994984;10119;40057.599424005384;57754;57942.42057579539;60514;60782;57494.7125264371;86963.1303686955;89573.55213223892;89776;91410;11158.386288754196;13143.14569552976;13508.797368039684;13558.79661805054;13790.103643263155;14670.71834213436;14923.57839922074;14936.029158105122;14998;15036.173010484528;12571.622851314476;12749.235045897276;13001.479940802263;13047.217166970013;13175.341232938123;13617.931910340385;13844;13848.169109853441;14023.789643155156;14093.718125637224;13131.212127272394;13154.21074735519;13244.337783110614;13267.933660331637;13483.528076517276;10919.12646988223;11432;11553.537858485566;11560.190786644855;11836;9934.261504236585;10468.057874791306;10634.73413164677;11112.555497780322;11585.82621260665 15:46:18 INFO - PROCESS | 1653 | __end_tp_report 15:46:18 INFO - PROCESS | 1653 | __start_cc_report 15:46:18 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1558 15:46:18 INFO - PROCESS | 1653 | __end_cc_report 15:46:18 INFO - PROCESS | 1653 | __startTimestamp1473374778019__endTimestamp 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | ------- Summary: start ------- 15:46:18 INFO - PROCESS | 1653 | Number of tests: 6 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5669.14 Median:5804.88 stddev:628.70 (10.8%) stddev-sans-first:602.91 15:46:18 INFO - PROCESS | 1653 | Values: 3315.7 3322.0 3347.9 3374.8 3404.4 5519.2 5612.6 5615.9 5715.4 5735.4 5636.0 5660.9 5677.8 5680.8 5693.0 5112.0 5149.8 5152.2 5171.6 5206.9 5255.2 5268.1 5269.5 5270.1 5272.6 5167.4 5177.8 5189.4 5208.1 5250.1 5261.1 5294.3 5296.6 5305.7 5337.6 5034.0 5038.5 5104.2 5184.2 5185.2 5657.2 5675.6 5707.1 5734.3 5788.0 6233.7 6253.3 6259.9 6267.1 6277.2 6091.0 6190.8 6230.7 6254.5 6294.7 5796.2 5797.7 5804.9 5817.9 5837.7 6233.8 6246.1 6263.3 6278.2 6283.5 5967.3 5967.7 5990.8 6193.7 6204.7 6095.0 6148.0 6159.8 6163.6 6166.6 6065.4 6080.4 6092.2 6095.4 6109.3 5110.3 5315.4 6013.8 6097.8 6119.1 4908.2 4914.2 4917.4 4920.4 4922.5 4371.7 4444.0 4446.2 4472.6 4479.9 5716.4 5745.8 5785.6 5797.1 5800.4 5223.3 5266.9 6196.9 6222.0 6278.7 4777.4 5692.3 5702.1 5714.2 5715.9 5303.2 5359.5 5373.0 5385.1 5409.3 6259.8 6265.1 6272.4 6276.3 6283.3 6251.0 6278.4 6280.1 6281.2 6294.0 6161.6 6200.9 6216.0 6239.0 6271.8 6184.1 6219.9 6222.1 6231.9 6234.8 5235.7 5312.8 6128.5 6242.9 6248.1 5760.6 5861.1 5885.1 6039.2 6050.1 4973.6 5915.4 5926.6 5956.4 5961.9 5775.8 5885.3 5927.9 5937.0 5960.8 5258.8 5985.1 6026.4 6084.3 6103.9 5968.0 5969.3 5969.7 5976.9 5991.3 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:59772.11 Median:382642.50 stddev:123722.47 (32.3%) stddev-sans-first:123989.49 15:46:18 INFO - PROCESS | 1653 | Values: 492.2 507.1 507.4 540.3 550.7 23853.0 30573.2 31878.7 32043.7 32044.7 32533.8 33482.0 33535.5 40077.8 42349.9 9807.2 9963.2 9966.9 9968.1 9980.6 8457.0 9739.1 9837.6 9847.9 9849.3 9795.9 9830.6 9832.4 9839.7 9843.8 9736.2 9795.4 9796.6 9797.2 9807.7 9744.1 9829.5 9832.5 9832.9 9873.4 32848.5 39817.4 39830.6 39835.2 39846.8 3418.3 3472.3 3531.0 3667.9 3965.7 30099.4 30289.2 30625.7 30719.8 31372.4 3221.7 3232.1 3254.9 3321.7 3351.3 418801.0 424428.0 426364.0 428787.0 431961.0 57523.7 57774.0 57786.0 57815.0 57941.8 33721.3 34229.5 34544.7 60125.8 60449.4 16014.5 16463.9 16472.2 16481.3 16506.1 12626.8 12743.7 12756.5 12760.8 12776.8 15399.4 18755.6 18805.5 18883.4 18916.5 6643.4 7643.1 7749.9 7785.7 7810.0 845.6 868.8 938.8 956.5 994.4 28724.0 29725.9 30084.4 30104.8 30123.0 881.4 1004.7 1063.9 1070.5 1134.4 13905.9 14322.6 15074.9 15212.4 15516.1 554450.0 565060.0 565941.0 567637.0 572553.0 55692.7 56172.0 56285.7 56499.4 56510.7 1200.6 1320.9 1339.2 1353.2 1354.7 30228.8 30352.0 30566.7 31132.5 31166.5 47676.5 48347.8 48396.8 48424.5 48500.8 381956.0 383329.0 384589.0 385390.0 386715.0 25761.5 26631.2 26635.9 27584.9 27677.2 45876.8 46160.8 47214.5 47469.8 47610.0 30973.8 37854.8 38955.0 39046.2 39055.2 32717.7 37806.1 38172.6 38351.7 39321.6 37222.1 37670.2 37890.4 37982.0 37996.6 31742.5 32091.0 33752.2 34166.1 34246.0 24259.3 24928.3 24960.8 25265.9 25689.4 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:32916.11 Median:26242.17 stddev:88960.00 (339.0%) stddev-sans-first:89175.89 15:46:18 INFO - PROCESS | 1653 | Values: 318.4 349.1 369.7 378.7 404.4 15302.2 17141.1 20165.9 20783.5 20887.9 20358.9 20638.3 20999.2 21025.4 21480.8 5344.4 5972.8 6130.7 6225.0 6241.7 6104.4 6216.3 6221.9 6222.2 6227.3 5898.4 6069.7 6117.4 6124.4 6187.9 5878.7 6091.9 6152.0 6152.9 6155.4 4942.0 5256.2 5261.9 5263.8 5272.5 25654.4 26230.7 26253.6 26334.7 26629.0 2215.2 2257.0 2285.1 2457.9 2922.8 15322.8 15423.7 15562.7 15803.3 15816.6 1797.6 1812.6 1840.4 1857.6 1875.3 112536.0 115515.0 115558.0 115634.4 116507.0 10651.7 10872.1 11201.7 11589.7 11740.8 13859.7 13899.9 14058.9 14073.8 14089.1 8627.6 8670.2 8729.1 8821.6 8853.8 8686.6 8695.7 8736.3 8877.6 8894.9 6563.5 6675.3 6676.5 6696.2 6697.3 1561.5 1800.2 1839.3 1840.3 1843.3 871.0 879.3 891.2 961.1 988.2 21860.5 22550.9 22617.4 22622.7 22774.9 829.5 907.6 930.5 965.6 988.9 10553.7 10668.1 10975.8 11298.4 11369.3 507444.0 524991.0 532807.0 543004.0 548217.0 98939.0 103750.5 121668.0 126065.0 133942.0 880.6 982.9 998.8 1016.9 1017.6 17588.6 18008.7 18874.8 19024.2 19860.4 43447.8 54300.7 54714.7 55101.0 56633.4 59269.7 78473.0 78596.6 80333.2 80709.0 5566.4 6827.3 7714.9 8012.7 8157.3 9625.6 10099.7 11926.6 12537.9 13269.2 10889.8 11090.3 11225.8 11553.8 11556.9 10247.3 10959.6 11283.0 11404.9 11560.2 10901.5 11220.7 11260.1 11367.9 11478.7 7946.8 8002.6 8067.0 8073.4 8155.1 10693.7 10961.8 11162.8 11315.0 11356.5 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5642.37 Median:5160.50 stddev:3129.16 (60.6%) stddev-sans-first:3121.91 15:46:18 INFO - PROCESS | 1653 | Values: 1427.8 1519.5 1687.3 1847.9 1852.8 7946.9 8194.2 8328.8 8813.5 8821.3 7084.3 7980.2 8065.6 8638.7 8644.2 2342.8 2374.6 2374.8 2376.2 2376.5 2331.0 2364.4 2386.0 2389.2 2390.8 2084.9 2151.1 2152.4 2161.9 2162.8 2292.1 2359.9 2364.9 2372.4 2373.0 2337.2 2358.1 2361.4 2361.5 2380.8 8757.8 8807.1 8814.3 8816.9 8838.7 4830.8 5091.9 5189.7 5336.7 5338.0 3464.0 3596.1 3599.2 3636.6 3652.5 1050.3 1099.6 1124.5 1124.8 1125.7 11486.8 11517.7 11519.7 11537.4 11552.7 4417.7 4560.4 4580.4 4740.9 4753.2 9812.0 9812.1 9822.0 9833.0 9835.5 5355.2 5359.8 5359.9 5365.2 5367.0 4775.4 4874.8 4934.4 4941.3 4944.2 3859.2 3874.8 3905.8 3962.0 3982.9 1273.3 1286.4 1290.4 1305.3 1311.4 764.6 860.7 883.3 894.5 940.0 5563.4 5626.7 5629.3 5638.2 5951.0 826.6 930.3 942.4 962.6 984.8 3603.9 3801.4 3839.4 3886.7 3892.2 10360.0 10559.5 10662.4 10674.9 10758.9 8954.8 10093.4 10550.8 10563.5 10606.6 7851.1 8110.4 8126.7 8129.9 8130.4 5821.7 5826.4 5849.8 6021.9 6160.6 9724.1 9918.5 9940.9 10031.9 10073.9 9736.2 9974.9 10020.4 10180.5 10269.0 6169.5 7518.5 7519.2 7529.7 7544.7 7989.4 8038.2 8134.6 8136.1 8218.5 7281.8 7307.6 7359.5 7375.9 7384.7 7145.9 7290.4 7463.0 7559.2 7739.7 6598.0 6692.6 6891.6 6896.3 6900.0 5007.6 5083.8 5196.9 5231.6 5282.5 5031.2 5123.5 5157.3 5163.7 5319.7 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4211.82 Median:4591.93 stddev:1284.87 (28.0%) stddev-sans-first:1254.21 15:46:18 INFO - PROCESS | 1653 | Values: 284.9 296.8 302.7 309.7 313.8 3932.2 4861.9 5074.9 5153.3 5389.3 4575.0 4787.8 5223.7 5292.1 5378.4 3175.7 3989.5 4024.4 4035.9 4095.5 3814.3 3964.9 3991.7 3999.1 4048.7 3859.1 3946.7 3989.6 4007.7 4041.5 3981.4 3996.8 4008.8 4011.5 4030.8 3097.9 3284.7 3448.7 3453.2 3460.6 4794.8 5122.4 5124.1 5155.3 5160.6 3865.5 4590.8 4602.1 4626.8 4643.6 4629.9 5380.3 5618.5 5628.8 5659.3 3802.6 3817.9 3819.9 3828.0 3864.8 5544.8 5785.7 5849.0 5854.9 5866.1 3237.9 3877.5 3899.9 3900.9 3928.7 3586.3 3768.9 3926.7 3937.0 3938.0 3681.6 4044.3 4061.9 4238.0 4529.5 4729.6 5094.3 5604.6 5615.5 5665.0 1314.2 1341.5 1346.3 1352.4 1353.5 765.4 769.9 804.2 811.7 821.3 3844.0 4060.2 4200.9 4433.8 4472.2 4472.6 4523.2 4694.8 4728.7 4761.0 3802.9 4313.7 4323.6 4329.0 4361.7 2214.8 2302.0 2419.7 2531.8 2549.4 3956.8 4202.5 4911.8 4919.8 4942.7 4702.8 4715.0 4861.9 4897.5 4998.3 4333.7 4374.6 4713.5 4785.5 4889.2 4365.9 4423.0 4593.0 4606.2 4728.8 4544.9 4681.3 4722.6 4737.7 4751.4 4455.7 4688.9 4898.6 4956.8 4977.3 3003.8 3288.0 3319.9 3335.5 3341.6 4208.8 4211.4 4723.6 4781.3 4844.0 4623.5 5081.5 5129.6 5277.0 5306.3 5095.5 5627.4 5695.0 5702.9 5711.7 5208.0 5466.2 5557.6 5572.4 5586.7 5045.3 5493.4 5636.8 5693.9 5737.9 5490.2 5501.8 5541.7 5542.1 5548.6 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:23596.33 Median:3614.43 stddev:37149.43 (1027.8%) stddev-sans-first:37213.58 15:46:18 INFO - PROCESS | 1653 | Values: 618.7 720.4 768.8 786.9 817.8 14343.9 15632.1 15648.3 15673.5 15702.4 13820.0 13967.9 13978.4 14009.9 14036.0 656.7 670.8 814.9 818.5 834.1 834.5 839.4 852.6 853.7 857.0 907.9 907.9 910.6 911.5 914.2 690.9 858.8 901.1 903.6 906.0 649.8 659.2 659.7 659.9 660.5 14174.3 14193.4 14195.6 14210.3 14225.1 13482.4 13651.0 13737.7 13813.9 14002.1 10636.9 12651.7 12726.9 13270.5 13292.0 4463.2 4475.7 4482.2 4548.2 4700.5 105602.4 106545.0 108382.5 109010.0 109406.5 9936.5 10141.8 10614.9 10961.7 10974.4 20676.4 20797.9 20808.9 20954.2 21023.6 7475.1 7504.1 7536.6 7545.1 7605.6 3704.2 4156.8 4288.4 4297.2 4410.9 3397.7 3515.9 3524.7 3807.8 3990.5 713.7 714.1 723.1 728.1 735.2 502.7 550.4 589.4 635.6 680.9 8835.9 9027.0 9511.1 9561.5 9689.6 561.5 608.5 647.7 655.0 666.3 4636.1 4718.2 4724.3 4763.1 4782.6 136256.6 139845.0 142122.0 142358.0 146171.0 94404.5 124788.0 125804.0 125903.0 128124.0 92258.1 93016.0 100580.0 106784.0 109007.0 8276.5 9906.2 10080.6 10103.1 10119.0 40057.6 57754.0 57942.4 60514.0 60782.0 57494.7 86963.1 89573.6 89776.0 91410.0 11158.4 13143.1 13508.8 13558.8 13790.1 14670.7 14923.6 14936.0 14998.0 15036.2 12571.6 12749.2 13001.5 13047.2 13175.3 13617.9 13844.0 13848.2 14023.8 14093.7 13131.2 13154.2 13244.3 13267.9 13483.5 10919.1 11432.0 11553.5 11560.2 11836.0 9934.3 10468.1 10634.7 11112.6 11585.8 15:46:18 INFO - PROCESS | 1653 | -------- Summary: end -------- 15:46:18 INFO - PROCESS | 1653 | 15:46:18 INFO - TEST-INFO | 1653: exit 0 15:46:18 INFO - TEST-OK | dromaeo_css | took 1117277ms 15:46:18 INFO - TEST-START | kraken 15:46:18 INFO - operating with platform_type : mac_ 15:46:18 INFO - Initialising browser for kraken test... 15:46:18 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpazDIfT/profile http://localhost:49271/getInfo.html) 15:46:21 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 15:46:21 INFO - PROCESS | 1661 | colorDepth:24 15:46:21 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 15:46:21 INFO - PROCESS | 1661 | __metrics 15:46:21 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:46:21 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:46:21 INFO - TEST-INFO | 1661: exit 0 15:46:21 INFO - Browser initialized. 15:46:21 INFO - Running cycle 1/1 for kraken test... 15:46:21 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpazDIfT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:33 INFO - PROCESS | 1662 | RSS: Main: 232337408 15:46:33 INFO - PROCESS | 1662 | 15:46:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 15:46:37 INFO - PROCESS | 1662 | RSS: Main: 292159488 15:46:37 INFO - PROCESS | 1662 | 15:46:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 15:46:40 INFO - PROCESS | 1662 | RSS: Main: 287358976 15:46:40 INFO - PROCESS | 1662 | 15:46:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 15:46:44 INFO - PROCESS | 1662 | RSS: Main: 375414784 15:46:44 INFO - PROCESS | 1662 | 15:46:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 15:46:47 INFO - PROCESS | 1662 | RSS: Main: 278667264 15:46:47 INFO - PROCESS | 1662 | 15:46:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:46:49 INFO - PROCESS | 1662 | RSS: Main: 282587136 15:46:49 INFO - PROCESS | 1662 | 15:46:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 15:46:53 INFO - PROCESS | 1662 | RSS: Main: 324091904 15:46:53 INFO - PROCESS | 1662 | 15:46:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 15:46:56 INFO - PROCESS | 1662 | RSS: Main: 322977792 15:46:56 INFO - PROCESS | 1662 | 15:46:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 15:47:00 INFO - PROCESS | 1662 | RSS: Main: 322539520 15:47:00 INFO - PROCESS | 1662 | 15:47:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:47:02 INFO - PROCESS | 1662 | RSS: Main: 297652224 15:47:02 INFO - PROCESS | 1662 | 15:47:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:47:05 INFO - PROCESS | 1662 | RSS: Main: 303906816 15:47:05 INFO - PROCESS | 1662 | 15:47:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:47:08 INFO - PROCESS | 1662 | RSS: Main: 298749952 15:47:08 INFO - PROCESS | 1662 | 15:47:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:47:11 INFO - PROCESS | 1662 | RSS: Main: 319848448 15:47:11 INFO - PROCESS | 1662 | 15:47:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:47:15 INFO - PROCESS | 1662 | RSS: Main: 315637760 15:47:15 INFO - PROCESS | 1662 | 15:47:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 15:47:17 INFO - PROCESS | 1662 | RSS: Main: 359444480 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | __start_tp_report 15:47:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 15:47:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 15:47:17 INFO - PROCESS | 1662 | |i|pagename|runs| 15:47:17 INFO - PROCESS | 1662 | |0;ai-astar;112;111;115;111;112;110;110;109;111;124 15:47:17 INFO - PROCESS | 1662 | |1;audio-beat-detection;170;144;129;165;106;129;127;108;157;106 15:47:17 INFO - PROCESS | 1662 | |2;audio-dft;185;185;186;181;184;184;185;183;181;181 15:47:17 INFO - PROCESS | 1662 | |3;audio-fft;76;77;100;92;58;62;64;60;138;57 15:47:17 INFO - PROCESS | 1662 | |4;audio-oscillator;82;81;131;70;76;75;112;68;70;111 15:47:17 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;88;135;120;88;85;88;84;88;85 15:47:17 INFO - PROCESS | 1662 | |6;imaging-darkroom;94;93;93;93;94;94;93;93;100;149 15:47:17 INFO - PROCESS | 1662 | |7;imaging-desaturate;70;133;134;111;83;85;83;84;84;85 15:47:17 INFO - PROCESS | 1662 | |8;json-parse-financial;55;55;59;54;52;55;55;54;54;54 15:47:17 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;64;66;72;74;75;70;68;68;69;69 15:47:17 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;82;103;99;103;84;84;86;97;105;110 15:47:17 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;138;137;136;128;168;140;139;155;179;149 15:47:17 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;209;197;169;168;169;170;165;170;167;166 15:47:17 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;65;64;67;67;67;66;66;66;67;67 15:47:17 INFO - PROCESS | 1662 | __end_tp_report 15:47:17 INFO - PROCESS | 1662 | __start_cc_report 15:47:17 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2018 15:47:17 INFO - PROCESS | 1662 | __end_cc_report 15:47:17 INFO - PROCESS | 1662 | __startTimestamp1473374837118__endTimestamp 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | ------- Summary: start ------- 15:47:17 INFO - PROCESS | 1662 | Number of tests: 14 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.50 Median:111.50 stddev:4.35 (3.9%) stddev-sans-first:4.61 15:47:17 INFO - PROCESS | 1662 | Values: 112.0 111.0 115.0 111.0 112.0 110.0 110.0 109.0 111.0 124.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:136.50 stddev:24.10 (17.7%) stddev-sans-first:21.79 15:47:17 INFO - PROCESS | 1662 | Values: 170.0 144.0 129.0 165.0 106.0 129.0 127.0 108.0 157.0 106.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.50 stddev:1.90 (1.0%) stddev-sans-first:1.94 15:47:17 INFO - PROCESS | 1662 | Values: 185.0 185.0 186.0 181.0 184.0 184.0 185.0 183.0 181.0 181.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:78.40 Median:63.00 stddev:25.60 (40.6%) stddev-sans-first:27.14 15:47:17 INFO - PROCESS | 1662 | Values: 76.0 77.0 100.0 92.0 58.0 62.0 64.0 60.0 138.0 57.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:72.50 stddev:22.11 (30.5%) stddev-sans-first:23.35 15:47:17 INFO - PROCESS | 1662 | Values: 82.0 81.0 131.0 70.0 76.0 75.0 112.0 68.0 70.0 111.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:87.50 stddev:17.66 (20.2%) stddev-sans-first:18.50 15:47:17 INFO - PROCESS | 1662 | Values: 87.0 88.0 135.0 120.0 88.0 85.0 88.0 84.0 88.0 85.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:93.00 stddev:17.49 (18.8%) stddev-sans-first:18.43 15:47:17 INFO - PROCESS | 1662 | Values: 94.0 93.0 93.0 93.0 94.0 94.0 93.0 93.0 100.0 149.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:83.50 stddev:22.55 (27.0%) stddev-sans-first:21.99 15:47:17 INFO - PROCESS | 1662 | Values: 70.0 133.0 134.0 111.0 83.0 85.0 83.0 84.0 84.0 85.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.77 (3.2%) stddev-sans-first:1.87 15:47:17 INFO - PROCESS | 1662 | Values: 55.0 55.0 59.0 54.0 52.0 55.0 55.0 54.0 54.0 54.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:3.41 (4.9%) stddev-sans-first:2.98 15:47:17 INFO - PROCESS | 1662 | Values: 64.0 66.0 72.0 74.0 75.0 70.0 68.0 68.0 69.0 69.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:84.00 stddev:10.35 (12.3%) stddev-sans-first:9.80 15:47:17 INFO - PROCESS | 1662 | Values: 82.0 103.0 99.0 103.0 84.0 84.0 86.0 97.0 105.0 110.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:144.50 stddev:16.02 (11.1%) stddev-sans-first:16.66 15:47:17 INFO - PROCESS | 1662 | Values: 138.0 137.0 136.0 128.0 168.0 140.0 139.0 155.0 179.0 149.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:169.50 stddev:15.11 (8.9%) stddev-sans-first:9.82 15:47:17 INFO - PROCESS | 1662 | Values: 209.0 197.0 169.0 168.0 169.0 170.0 165.0 170.0 167.0 166.0 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.03 (1.5%) stddev-sans-first:1.00 15:47:17 INFO - PROCESS | 1662 | Values: 65.0 64.0 67.0 67.0 67.0 66.0 66.0 66.0 67.0 67.0 15:47:17 INFO - PROCESS | 1662 | -------- Summary: end -------- 15:47:17 INFO - PROCESS | 1662 | 15:47:17 INFO - TEST-INFO | 1662: exit 0 15:47:17 INFO - TEST-OK | kraken | took 59211ms 15:47:17 INFO - SUITE-END | took 1176s 15:47:18 INFO - Completed test suite (00:19:36) 15:47:18 INFO - javascript benchmark 15:47:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.668433156685, 3322.0200040987916, 3347.8660853565857, 3374.8143852088156, 3404.4212483877736, 5519.172124181375, 5612.579056570758, 5615.943840561593, 5715.371309155989, 5735.369109397973, 5636.0, 5660.858478538039, 5677.829665110043, 5680.772769089242, 5693.0, 5111.97760447911, 5149.76826042829, 5152.201408781645, 5171.637985341028, 5206.895862082756, 5255.159174532076, 5268.130758424855, 5269.473052694719, 5270.0513907496825, 5272.578193744491, 5167.431582525919, 5177.767000484987, 5189.42916279209, 5208.062548741244, 5250.05499010178, 5261.131913234331, 5294.285271488338, 5296.602754793367, 5305.65513241638, 5337.572994160458, 5034.043531728959, 5038.5465308122075, 5104.234364845266, 5184.248283998837, 5185.248139019821, 5657.179708942181, 5675.574331925122, 5707.1153971134545, 5734.283214598176, 5787.971060144672, 6233.719482623293, 6253.280872699653, 6259.87480250397, 6267.05994100884, 6277.183966084425, 6090.96954515229, 6190.752369905198, 6230.657313847737, 6254.499640028786, 6294.685265736694, 5796.188533605298, 5797.681127537984, 5804.88390232193, 5817.942806495173, 5837.67892765902, 6233.781817636361, 6246.063090536365, 6263.279722831841, 6278.215223097113, 6283.465905398092, 5967.283925928917, 5967.671778052249, 5990.760369585168, 6193.6903154842075, 6204.658743769136, 6095.0, 6148.016317389286, 6159.753609855646, 6163.630182189083, 6166.568340216231, 6065.3934606539, 6080.361562036011, 6092.208012958287, 6095.44857245249, 6109.266887973383, 5110.2590124431745, 5315.362156541162, 6013.827303675741, 6097.84141013206, 6119.143319935212, 4908.239222920453, 4914.213725803854, 4917.434495033045, 4920.434150072716, 4922.532359425849, 4371.672124590668, 4443.977780111079, 4446.244138496463, 4472.619827314649, 4479.910401791946, 5716.399778023331, 5745.827625171251, 5785.623934444332, 5797.101449275369, 5800.419958004166, 5223.347081614798, 5266.894662106736, 6196.897576293602, 6222.036835649845, 6278.748850046039, 4777.402824646919, 5692.260006199251, 5702.087665973424, 5714.200011998323, 5715.885682286331, 5303.231031500488, 5359.517643412034, 5372.973135134299, 5385.111456609694, 5409.323834520685, 6259.780907668301, 6265.122882796503, 6272.372762723782, 6276.278228003746, 6283.277423096402, 6250.968745156427, 6278.4035516625845, 6280.057991301342, 6281.246250449975, 6293.992961126198, 6161.5686901918225, 6200.875982480235, 6216.0, 6239.0, 6271.811845644638, 6184.103305020837, 6219.937800621936, 6222.097795819672, 6231.906521402273, 6234.812955611339, 5235.743421578717, 5312.840614781563, 6128.50971922254, 6242.875142497034, 6248.12526246335, 5760.567957403127, 5861.062230043172, 5885.087811389241, 6039.154518367344, 6050.092486127116, 4973.577245934136, 5915.40845915405, 5926.585139040226, 5956.434138756811, 5961.940380596138, 5775.787084712089, 5885.264341957255, 5927.940720592739, 5937.0, 5960.791372301948, 5258.789648414021, 5985.102234664835, 6026.427489388501, 6084.269887613515, 6103.877922441615, 5968.0, 5969.313528944227, 5969.671668058299, 5976.940230597812, 5991.281046274475], "value": 5631.271030554909, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.23457523550877, 507.13786562843154, 507.40126650552355, 540.3434826685576, 550.7301422303068, 23853.0, 30573.235669108257, 31878.68121318786, 32043.67956320436, 32044.67955320446, 32533.837330813312, 33482.0, 33535.49696754545, 40077.799611001974, 42349.94125146865, 9807.166390856768, 9963.15313198379, 9966.850497242547, 9968.102870741632, 9980.550875210633, 8456.957715211445, 9739.123478886899, 9837.55730992107, 9847.90152098477, 9849.310548261588, 9795.90204097957, 9830.606775728998, 9832.360896541737, 9839.70480885571, 9843.753906152368, 9736.221102311834, 9795.363301385458, 9796.608135674565, 9797.167240784505, 9807.656732014417, 9744.123028927359, 9829.459379734111, 9832.508374581314, 9832.90167098327, 9873.358231714916, 32848.50727239093, 39817.40273895894, 39830.60169398298, 39835.20329593392, 39846.800765996275, 3418.3163367326633, 3472.2708231271463, 3531.0, 3667.8716244931566, 3965.662918651918, 30099.398012039856, 30289.242768930733, 30625.693743062508, 30719.846400768078, 31372.372552548823, 3221.7261532769744, 3232.095013396253, 3254.886078987248, 3321.701046905766, 3351.2962277921665, 418801.0, 424428.0, 426364.0, 428787.0, 431961.0, 57523.712381438665, 57774.0, 57786.0, 57815.0, 57941.8411631765, 33721.32557348839, 34229.48655770165, 34544.654553454144, 60125.797484050076, 60449.39550604526, 16014.519564412853, 16463.91768041152, 16472.176391180394, 16481.25834337458, 16506.092164931044, 12626.81059784104, 12743.745125097446, 12756.489740410465, 12760.80858787119, 12776.808347874789, 15399.384024639114, 18755.593330500265, 18805.529861753566, 18883.433496995112, 18916.527086822665, 6643.40209381158, 7643.082830060428, 7749.92250077492, 7785.688572457151, 7810.0, 845.6027162992862, 868.8349213649386, 938.8122375524922, 956.4787190980932, 994.4282037828277, 28724.0, 29725.91183855211, 30084.398312034074, 30104.84947575292, 30123.0, 881.378628067211, 1004.6634377483625, 1063.8563544190026, 1070.4754670211696, 1134.4288160897052, 13905.86094139066, 14322.639349261803, 15074.924625376803, 15212.391504339923, 15516.146611936452, 554450.0, 565060.0, 565941.0, 567637.0, 572553.0, 55692.72153639206, 56172.0, 56285.718571406884, 56499.435005649415, 56510.717446412506, 1200.6038007457519, 1320.9273489958146, 1339.2232505146992, 1353.1678018019045, 1354.7493713663005, 30228.84885575558, 30352.0, 30566.694333057276, 31132.533012004384, 31166.532502012942, 47676.523234767206, 48347.758261208466, 48396.75801620969, 48424.515754842, 48500.757496212296, 381956.0, 383329.0, 384589.0, 385390.0, 386715.0, 25761.484770304865, 26631.20106396811, 26635.86682066577, 27584.89660413561, 27677.169684909484, 45876.77061614804, 46160.76919615381, 47214.527854722386, 47469.762651186524, 47610.0, 30973.845130774203, 37854.81072594619, 38955.0, 39046.219075618894, 39055.2188956225, 32717.672823271463, 37806.054848629006, 38172.618273818014, 38351.65769198065, 39321.60678393179, 37222.06944826401, 37670.246595067394, 37890.43164352482, 37982.0, 37996.62003379931, 31742.523862141625, 32091.0, 33752.19618420747, 34166.14584635404, 34245.97262082141, 24259.272221833373, 24928.252152435456, 24960.750392495844, 25265.87367063153, 25689.357766055997], "value": 19059.8648303115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.4093506545357, 349.05058241582884, 369.73933376969234, 378.7140708764883, 404.36110944707366, 15302.234888255598, 17141.057241851693, 20165.890876001813, 20783.480412989684, 20887.895560522196, 20358.898205508987, 20638.277660281892, 20999.160033598677, 21025.36923892282, 21480.785192148072, 5344.385395679507, 5972.820815375535, 6130.662813545253, 6225.035119556476, 6241.65670888101, 6104.389561043882, 6216.254049514065, 6221.906671399933, 6222.191115155046, 6227.283862355842, 5898.41015898411, 6069.696515174246, 6117.44942955134, 6124.448799608034, 6187.90718139226, 5878.676672782995, 6091.878162436748, 6152.0, 6152.87694246117, 6155.446009859134, 4942.0, 5256.159014557691, 5261.921071183935, 5263.763130659129, 5272.499112584298, 25654.358641033938, 26230.73769262302, 26253.606195907076, 26334.73665263342, 26629.0, 2215.235743668432, 2257.0181970842573, 2285.074544809355, 2457.938551536208, 2922.8308676529346, 15322.770158447633, 15423.691526169414, 15562.688746225012, 15803.313436715785, 15816.60458488547, 1797.613513094691, 1812.5649844037468, 1840.4478656403026, 1857.6377606366764, 1875.3248830421037, 112536.0, 115515.0, 115558.0, 115634.42182789116, 116507.0, 10651.680449586525, 10872.130229581773, 11201.719957000978, 11589.710257243467, 11740.823887641693, 13859.653508662162, 13899.930500347433, 14058.859411405754, 14073.788893166611, 14089.084209526349, 8627.568621568897, 8670.219680228809, 8729.115543583044, 8821.647134114692, 8853.822923541622, 8686.609102590399, 8695.739127826175, 8736.257418119403, 8877.556122193864, 8894.86657700135, 6563.540552161303, 6675.299093595199, 6676.532642714963, 6696.196456425163, 6697.296783837724, 1561.4691005058335, 1800.1989114843768, 1839.3286450445491, 1840.3098837935775, 1843.3363988963963, 870.9592037515108, 879.3185281406961, 891.2157301574573, 961.1397798970022, 988.2242439684813, 21860.45348866291, 22550.872456377114, 22617.434564136027, 22622.701686679808, 22774.8861255696, 829.5188790501616, 907.6006557114848, 930.4789317981952, 965.5799727118725, 988.8912219655832, 10553.683389498327, 10668.093212077061, 10975.83536246957, 11298.378589177673, 11369.260998035266, 507444.0, 524991.0, 532807.0, 543004.0, 548217.0, 98939.0, 103750.48124759328, 121668.0, 126065.0, 133942.0, 880.6213328268906, 982.886967998675, 998.7752755630071, 1016.9338992965434, 1017.5980487707462, 17588.560285992953, 18008.729869052237, 18874.811251887306, 19024.19270169332, 19860.40418787439, 43447.78276108599, 54300.728496358846, 54714.72642636761, 55101.0, 56633.433665662815, 59269.70365148147, 78473.0, 78596.60701696455, 80333.19666803257, 80709.0, 5566.359868615161, 6827.317268273133, 7714.922850771645, 8012.719554815553, 8157.265846073882, 9625.614975400906, 10099.7475063124, 11926.642200733992, 12537.937310313755, 13269.203847769164, 10889.836652450062, 11090.279131856403, 11225.775484490101, 11553.768924621629, 11556.94221528887, 10247.282690211614, 10959.56161753521, 11283.0, 11404.94297528507, 11560.248583842023, 10901.509432075734, 11220.719482013015, 11260.09919206478, 11367.886321136682, 11478.713032174262, 7946.7615971520945, 8002.639881205243, 8066.959665201636, 8073.3541316693345, 8155.143709910374, 10693.679189624323, 10961.835572466578, 11162.776744465227, 11315.0, 11356.488957996744], "value": 9676.09676472458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.8357988831285, 1519.5289460267318, 1687.2660392729165, 1847.9118595986533, 1852.805455427181, 7946.880796788038, 8194.180581941802, 8328.79178020549, 8813.4711917285, 8821.250193733533, 7084.326988936055, 7980.16208298129, 8065.637046332914, 8638.697645582406, 8644.222020018196, 2342.7891489765916, 2374.620060790274, 2374.8218883583713, 2376.1564644551195, 2376.4771750215014, 2330.9650355244685, 2364.408897775556, 2385.976140238593, 2389.187676190095, 2390.8087353011715, 2084.9374518764384, 2151.0965394534255, 2152.365052309565, 2161.945951351221, 2162.772908844572, 2292.128990983424, 2359.917402890908, 2364.9408764780846, 2372.3594629450126, 2373.0, 2337.1703045418803, 2358.056777289081, 2361.445060410802, 2361.5276944611146, 2380.797632201265, 8757.7810554736, 8807.07525709804, 8814.338924580617, 8816.8677469838, 8838.690645827364, 4830.758462076918, 5091.854332775096, 5189.66267192635, 5336.679799212021, 5338.0, 3464.0, 3596.1009747563107, 3599.1541987633145, 3636.5999740028574, 3652.506911566931, 1050.2858056521563, 1099.648112603967, 1124.4714983957526, 1124.780667769777, 1125.6791814332876, 11486.827697584544, 11517.654470365902, 11519.712007199803, 11537.365444900612, 11552.711182220428, 4417.712848664826, 4560.361549383089, 4580.358749775034, 4740.928886066712, 4753.215719406328, 9812.0, 9812.067853553901, 9821.968693287245, 9832.950835245778, 9835.459049752333, 5355.169948657965, 5359.839204823861, 5359.892802143935, 5365.248865158881, 5367.0, 4775.355327030807, 4874.829380971649, 4934.432540257844, 4941.332920055818, 4944.208926571731, 3859.208862183246, 3874.791368919689, 3905.843766249375, 3961.950083227947, 3982.880513584539, 1273.2742336867896, 1286.4403984266874, 1290.3612711707765, 1305.2755720574983, 1311.3508813137366, 764.5527366490554, 860.7288704058202, 883.271301176519, 894.4677916639589, 939.9718008459757, 5563.443655634404, 5626.662400255997, 5629.324481062217, 5638.182463542763, 5950.96500784603, 826.5660528222732, 930.3394589841289, 942.4439580647451, 962.6245764151986, 984.7882705218412, 3603.94594081089, 3801.4297855321925, 3839.3665045267776, 3886.689064874803, 3892.241013002494, 10360.015798499131, 10559.524821383056, 10662.360258384522, 10674.893251067546, 10758.94620526908, 8954.820903581893, 10093.394396336244, 10550.788984220426, 10563.471826408802, 10606.628767993237, 7851.097123830833, 8110.391720620859, 8126.674933002732, 8129.878051829227, 8130.430869839172, 5821.738021789114, 5826.41735826414, 5849.824505264849, 6021.879562408815, 6160.630362178284, 9724.124828765162, 9918.504074796087, 9940.900590994286, 10031.949840250752, 10073.899261007296, 9736.22110231194, 9974.850377244202, 10020.448875311928, 10180.541875615467, 10269.0, 6169.537284703575, 7518.548887066793, 7519.210482899217, 7529.698812047676, 7544.735934242275, 7989.400794940519, 8038.196180381915, 8134.63394147253, 8136.145704700921, 8218.506889586644, 7281.817954551178, 7307.561546307238, 7359.484836061424, 7375.926240737524, 7384.704611815468, 7145.857082858418, 7290.416766658548, 7463.029806125169, 7559.244075592397, 7739.651715672696, 6597.967010164918, 6692.564983276072, 6891.586504809728, 6896.310368963063, 6899.965500172467, 5007.649464537593, 5083.771230294572, 5196.922046169235, 5231.581473482189, 5282.4717528246865, 5031.169856973558, 5123.513266239701, 5157.252198431282, 5163.741812909265, 5319.654222475527], "value": 4552.007150453126, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.933040735427, 296.7892796114758, 302.74418116691396, 309.671747947176, 313.835236500837, 3932.233214523169, 4861.878453038671, 5074.949250507494, 5153.330067091273, 5389.326334208224, 4574.993501429689, 4787.8324258650955, 5223.7201885538, 5292.1003429417, 5378.354597448311, 3175.7459403247744, 3989.5412027616762, 4024.4103579086245, 4035.919281614366, 4095.525610780117, 3814.2752876953427, 3964.940525892114, 3991.6607088397377, 3999.080441388121, 4048.655864251542, 3859.0545316397383, 3946.6645335146404, 3989.5611482736876, 4007.6994225433064, 4041.51501819782, 3981.442598036277, 3996.800159991989, 4008.7594744315434, 4011.5186177658575, 4030.8186131624147, 3097.931213731259, 3284.6715328467203, 3448.6723761242642, 3453.205762674599, 3460.636633153533, 4794.784234709412, 5122.359705036871, 5124.103281925648, 5155.267708114705, 5160.56135228506, 3865.4588357629955, 4590.816367345302, 4602.148602508513, 4626.773904915201, 4643.558861908114, 4629.930551041737, 5380.300560927094, 5618.522425593829, 5628.831135065954, 5659.349174844904, 3802.5627052888717, 3817.9118951098803, 3819.8854034378737, 3828.0, 3864.806759662034, 5544.77820887164, 5785.6528608283215, 5849.0, 5854.946109700252, 5866.090755932837, 3237.9190520237185, 3877.495925529663, 3899.883003509899, 3900.921981560353, 3928.705347098979, 3586.318599466093, 3768.9057773555332, 3926.6662333701956, 3937.0, 3938.0, 3681.631836816297, 4044.3124668805785, 4061.939070913939, 4238.046439551076, 4529.527903431398, 4729.55069268419, 5094.261332106824, 5604.635698679573, 5615.466530679579, 5665.0, 1314.2180402660401, 1341.503643651855, 1346.299924039494, 1352.4184600621827, 1353.5465619017739, 765.3953376832352, 769.9451751101027, 804.2279411764771, 811.664811385268, 821.2854816309849, 3843.9615603844163, 4060.213506057384, 4200.873973780792, 4433.778311084498, 4472.195004899149, 4472.597466228055, 4523.185826551186, 4694.765261736899, 4728.71627702339, 4760.97639007615, 3802.8591422573672, 4313.6980411370905, 4323.610875021201, 4329.047609525899, 4361.716488428242, 2214.833887458447, 2301.951263375597, 2419.7459266777087, 2531.848089114659, 2549.4391233928504, 3956.773400245933, 4202.537720850704, 4911.8280860170435, 4919.810684245476, 4942.654014218971, 4702.836695120214, 4714.95285047152, 4861.927071093936, 4897.510248975074, 4998.325226094504, 4333.696641235147, 4374.58441448062, 4713.528647135327, 4785.545373189472, 4889.242167464049, 4365.856583260048, 4422.977885110554, 4593.035462552768, 4606.239970404979, 4728.810847566197, 4544.909101818012, 4681.267930865602, 4722.5513576210205, 4737.739424331695, 4751.358566593465, 4455.749846327584, 4688.9296660549435, 4898.632602554894, 4956.801727930842, 4977.328060711758, 3003.828506882361, 3287.967120328766, 3319.8506067227518, 3335.499675048762, 3341.62993172798, 4208.768517731554, 4211.431456753299, 4723.574878260973, 4781.3422731611045, 4843.989744256235, 4623.468301145412, 5081.491850815037, 5129.563987061141, 5276.973615131899, 5306.310179676618, 5095.496828435695, 5627.409122042127, 5694.971525142348, 5702.887936852274, 5711.7429715662065, 5207.973960130175, 5466.173678425039, 5557.555395568425, 5572.442755724395, 5586.692731899784, 5045.344105266292, 5493.395726470165, 5636.830895073154, 5693.914591281217, 5737.942620573741, 5490.203920431435, 5501.807436739695, 5541.722913854372, 5542.140968149944, 5548.556115510669], "value": 3808.2954170729154, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.6844709198309, 720.3768740039867, 768.8462307538493, 786.9415635969619, 817.7645453417692, 14343.856561434395, 15632.14023228722, 15648.295826687798, 15673.45142919998, 15702.371905123795, 13820.0, 13967.930160349184, 13978.3709733062, 14009.929950350233, 14036.0, 656.671664167916, 670.7752902777576, 814.9103598604149, 818.472085504849, 834.1491678487939, 834.4576025583389, 839.3956351426993, 852.5524099847568, 853.6756032707563, 856.9742907712779, 907.863820426938, 907.8865141857268, 910.622091831889, 911.5168960450939, 914.1544071733653, 690.9067275917737, 858.7552547523926, 901.0944001278706, 903.5617725403207, 905.9547022648841, 649.8148027812098, 659.24845675929, 659.673461636493, 659.868026394723, 660.4881217056771, 14174.291285435791, 14193.432262709479, 14195.645108872257, 14210.289485525785, 14225.14649121056, 13482.39329230187, 13651.044426890121, 13737.656558586015, 13813.930930345385, 14002.099220502792, 10636.893631063667, 12651.74696506074, 12726.936365318206, 13270.535531256457, 13292.0, 4463.24124898768, 4475.709078909893, 4482.186374033817, 4548.226801443762, 4700.4594471635855, 105602.4159637606, 106545.0, 108382.45808770906, 109010.0, 109406.45296773544, 9936.503174841373, 10141.847872281922, 10614.89385106139, 10961.725956851142, 10974.396408197466, 20676.379708608765, 20797.896010520155, 20808.85551294663, 20954.161833526487, 21023.579528409653, 7475.102987641518, 7504.137024242173, 7536.585487798224, 7545.094588649397, 7605.581693006938, 3704.1665625234646, 4156.750594964312, 4288.4210631564965, 4297.162053399619, 4410.911781764411, 3397.7451691123265, 3515.9472607910902, 3524.7004004659893, 3807.8286477108595, 3990.521137463523, 713.6824113269545, 714.0895358417955, 723.0780754537901, 728.1480667618898, 735.1802739944923, 502.74360076360585, 550.4468654833044, 589.3840936221659, 635.5646382228189, 680.8536164724509, 8835.939687237444, 9027.0, 9511.143997040299, 9561.521923903778, 9689.612415503301, 561.5114850080456, 608.5253502268238, 647.6632151281308, 655.0283349378242, 666.3103687683224, 4636.072785442925, 4718.245080787057, 4724.314974328703, 4763.142634325785, 4782.641301902371, 136256.6374336244, 139845.0, 142122.0, 142358.01746938808, 146171.0, 94404.52797735967, 124788.0, 125804.0, 125902.99999999817, 128124.0, 92258.07741922629, 93016.0, 100580.0, 106784.0, 109007.0, 8276.544790036605, 9906.157976571783, 10080.647177348757, 10103.141232994984, 10119.0, 40057.599424005384, 57754.0, 57942.42057579539, 60514.0, 60782.0, 57494.7125264371, 86963.1303686955, 89573.55213223892, 89776.0, 91410.0, 11158.386288754196, 13143.14569552976, 13508.797368039684, 13558.79661805054, 13790.103643263155, 14670.71834213436, 14923.57839922074, 14936.029158105122, 14998.0, 15036.173010484528, 12571.622851314476, 12749.235045897276, 13001.479940802263, 13047.217166970013, 13175.341232938123, 13617.931910340385, 13844.0, 13848.169109853441, 14023.789643155156, 14093.718125637224, 13131.212127272394, 13154.21074735519, 13244.337783110614, 13267.933660331637, 13483.528076517276, 10919.12646988223, 11432.0, 11553.537858485566, 11560.190786644855, 11836.0, 9934.261504236585, 10468.057874791306, 10634.73413164677, 11112.555497780322, 11585.82621260665], "value": 7697.595927689727, "unit": "score"}], "name": "dromaeo_css", "value": 7193.836962837114, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 115.0, 111.0, 112.0, 110.0, 110.0, 109.0, 111.0, 124.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 144.0, 129.0, 165.0, 106.0, 129.0, 127.0, 108.0, 157.0, 106.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 181.0, 184.0, 184.0, 185.0, 183.0, 181.0, 181.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 100.0, 92.0, 58.0, 62.0, 64.0, 60.0, 138.0, 57.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 131.0, 70.0, 76.0, 75.0, 112.0, 68.0, 70.0, 111.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 135.0, 120.0, 88.0, 85.0, 88.0, 84.0, 88.0, 85.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 100.0, 149.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 133.0, 134.0, 111.0, 83.0, 85.0, 83.0, 84.0, 84.0, 85.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 59.0, 54.0, 52.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 72.0, 74.0, 75.0, 70.0, 68.0, 68.0, 69.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 103.0, 99.0, 103.0, 84.0, 84.0, 86.0, 97.0, 105.0, 110.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 128.0, 168.0, 140.0, 139.0, 155.0, 179.0, 149.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 197.0, 169.0, 168.0, 169.0, 170.0, 165.0, 170.0, 167.0, 166.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1493.3000000000002, "alertThreshold": 2.0}]} 15:47:18 INFO - Return code: 0 15:47:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:47:18 INFO - # TBPL SUCCESS # 15:47:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:47:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:47:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:47:18 INFO - 2016-09-08 15:47:18.454 screenresolution[1666:9973] starting screenresolution argv=screenresolution get 15:47:18 INFO - 2016-09-08 15:47:18.456 screenresolution[1666:9973] Display 0: 1600x1200x32@60 15:47:18 INFO - 2016-09-08 15:47:18.462 screenresolution[1667:9976] starting screenresolution argv=screenresolution list 15:47:18 INFO - Available Modes on Display 0 15:47:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:47:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:47:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:47:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:47:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:47:18 INFO - Intel Iris: 15:47:18 INFO - Chipset Model: Intel Iris 15:47:18 INFO - Type: GPU 15:47:18 INFO - Bus: Built-In 15:47:18 INFO - VRAM (Dynamic, Max): 1536 MB 15:47:18 INFO - Vendor: Intel (0x8086) 15:47:18 INFO - Device ID: 0x0a2e 15:47:18 INFO - Revision ID: 0x0009 15:47:18 INFO - Displays: 15:47:18 INFO - Display: 15:47:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:47:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:47:18 INFO - Display Serial Number: 1600x1200 60 15:47:18 INFO - Main Display: Yes 15:47:18 INFO - Mirror: Off 15:47:18 INFO - Online: Yes 15:47:18 INFO - Rotation: Supported 15:47:18 INFO - Return code: 0 15:47:18 INFO - Running post-action listener: _resource_record_post_action 15:47:18 INFO - [mozharness: 2016-09-08 22:47:18.702698Z] Finished run-tests step (success) 15:47:18 INFO - Running post-run listener: _resource_record_post_run 15:47:18 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38499328; Write bytes: 706399744; Read time: 373; Write time: 1917 15:47:18 INFO - TinderboxPrint: CPU usage
53.7% 15:47:18 INFO - TinderboxPrint: I/O read bytes / time
38,499,328 / 373 15:47:18 INFO - TinderboxPrint: I/O write bytes / time
706,399,744 / 1,917 15:47:18 INFO - TinderboxPrint: CPU idle
2,243.6 (46.3%) 15:47:18 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 15:47:18 INFO - TinderboxPrint: CPU user
1,635.9 (33.7%) 15:47:18 INFO - TinderboxPrint: Swap in / out
444,149,760 / 0 15:47:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391080448; Write bytes: 381659136; Read time: 29369; Write time: 715 15:47:18 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 32246784; Write bytes: 296420864; Read time: 294; Write time: 1149 15:47:19 INFO - Running post-run listener: _upload_blobber_files 15:47:19 INFO - Blob upload gear active. 15:47:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:47:19 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 15:47:19 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:47:19 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:47:19 INFO - (blobuploader) - INFO - Open directory for files ... 15:47:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:47:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:47:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:20 INFO - (blobuploader) - INFO - Done attempting. 15:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:47:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:21 INFO - (blobuploader) - INFO - Done attempting. 15:47:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:47:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:47:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:47:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:47:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:47:23 INFO - (blobuploader) - INFO - Done attempting. 15:47:23 INFO - (blobuploader) - INFO - Iteration through files over. 15:47:23 INFO - Return code: 0 15:47:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:47:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:47:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e945cf315a448f967dad162f4fae5b1d2718dda0c301abb2f37dc7fca3f1e096cc7fa300a9eb0f80bf87817476a057783ad11a6c4cfeb42bfe80c648f258c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f99a3af79034df2441309aaed3296b84c0800d5614c3f874d67c18b6958bb6e4c678d90389121cbdadd71791a42082482f03afced4ba397d3806827ba7f57a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c9e4ff0de917732cfe817086d9f1a84c9d0be436e481d6a9615d258d0c44912bd88439269cd7a1035b061d1d90ecbb72ecac3ab8bf624e98a42c84f0aff834"} 15:47:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:47:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:47:23 INFO - Contents: 15:47:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e945cf315a448f967dad162f4fae5b1d2718dda0c301abb2f37dc7fca3f1e096cc7fa300a9eb0f80bf87817476a057783ad11a6c4cfeb42bfe80c648f258c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f99a3af79034df2441309aaed3296b84c0800d5614c3f874d67c18b6958bb6e4c678d90389121cbdadd71791a42082482f03afced4ba397d3806827ba7f57a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c9e4ff0de917732cfe817086d9f1a84c9d0be436e481d6a9615d258d0c44912bd88439269cd7a1035b061d1d90ecbb72ecac3ab8bf624e98a42c84f0aff834"} 15:47:23 INFO - Running post-run listener: copy_logs_to_upload_dir 15:47:23 INFO - Copying logs to upload dir... 15:47:23 INFO - mkdir: /builds/slave/test/build/upload/logs 15:47:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.785125 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-08 15:47:23.940372) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 15:47:23.943693) ========= 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.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/Fx-Team/sha512/0e945cf315a448f967dad162f4fae5b1d2718dda0c301abb2f37dc7fca3f1e096cc7fa300a9eb0f80bf87817476a057783ad11a6c4cfeb42bfe80c648f258c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f99a3af79034df2441309aaed3296b84c0800d5614c3f874d67c18b6958bb6e4c678d90389121cbdadd71791a42082482f03afced4ba397d3806827ba7f57a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c9e4ff0de917732cfe817086d9f1a84c9d0be436e481d6a9615d258d0c44912bd88439269cd7a1035b061d1d90ecbb72ecac3ab8bf624e98a42c84f0aff834"} build_url:https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/X2K1XxxWSjKw2zQ18tBgQg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e945cf315a448f967dad162f4fae5b1d2718dda0c301abb2f37dc7fca3f1e096cc7fa300a9eb0f80bf87817476a057783ad11a6c4cfeb42bfe80c648f258c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f99a3af79034df2441309aaed3296b84c0800d5614c3f874d67c18b6958bb6e4c678d90389121cbdadd71791a42082482f03afced4ba397d3806827ba7f57a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c9e4ff0de917732cfe817086d9f1a84c9d0be436e481d6a9615d258d0c44912bd88439269cd7a1035b061d1d90ecbb72ecac3ab8bf624e98a42c84f0aff834"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 15:47:23.980025) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:47:23.980720) ========= 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.tB6ov4avkP/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.xAPB42MLKQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005195 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 15:47:24.031517) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 15:47:24.031882) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 15:47:24.032552) ========= ========= Total master_lag: 0.19 =========