builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0168 starttime: 1473221681.57 results: success (0) buildid: 20160906192717 builduid: 49174d6ab2774806a977beca38202dd4 revision: 5f10d3f47379248797c381742d69af738cf31aad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.567887) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.568409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.568807) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.592715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.593381) ========= 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.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-06 21:14:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.07M=0.001s 2016-09-06 21:14:41 (8.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112578 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.729168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.729976) ========= 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.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.021057 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:41.782624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 21:14:41.783454) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5f10d3f47379248797c381742d69af738cf31aad --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 5f10d3f47379248797c381742d69af738cf31aad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-06 21:14:41,862 truncating revision to first 12 chars 2016-09-06 21:14:41,862 Setting DEBUG logging. 2016-09-06 21:14:41,862 attempt 1/10 2016-09-06 21:14:41,862 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5f10d3f47379?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 21:14:43,076 unpacking tar archive at: autoland-5f10d3f47379/testing/mozharness/ program finished with exit code 0 elapsedTime=1.480258 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 21:14:43.289234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:43.290069) ========= script_repo_revision: 5f10d3f47379248797c381742d69af738cf31aad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:43.291036) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:43.291631) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 21:14:43.311931) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 21:14:43.312605) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:14:43 INFO - MultiFileLogger online at 20160906 21:14:43 in /builds/slave/test 21:14:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:14:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:14:43 INFO - {'append_to_log': False, 21:14:43 INFO - 'base_work_dir': '/builds/slave/test', 21:14:43 INFO - 'blob_upload_branch': 'Autoland', 21:14:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:14:43 INFO - 'branch': 'Autoland', 21:14:43 INFO - 'buildbot_json_path': 'buildprops.json', 21:14:43 INFO - 'config_files': ('talos/mac_config.py',), 21:14:43 INFO - 'default_actions': ('clobber', 21:14:43 INFO - 'read-buildbot-config', 21:14:43 INFO - 'download-and-extract', 21:14:43 INFO - 'populate-webroot', 21:14:43 INFO - 'create-virtualenv', 21:14:43 INFO - 'install', 21:14:43 INFO - 'run-tests'), 21:14:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:14:43 INFO - 'download_minidump_stackwalk': True, 21:14:43 INFO - 'download_symbols': 'ondemand', 21:14:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:14:43 INFO - 'tooltool.py': '/tools/tooltool.py', 21:14:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:14:43 INFO - '/tools/misc-python/virtualenv.py')}, 21:14:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:14:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:14:43 INFO - 'installer_path': 'installer.exe', 21:14:43 INFO - 'log_level': 'info', 21:14:43 INFO - 'log_name': 'talos', 21:14:43 INFO - 'log_to_console': True, 21:14:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:14:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:14:43 INFO - 'opt_config_files': (), 21:14:43 INFO - 'pip_index': False, 21:14:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:14:43 INFO - 'cmd': ('bash', 21:14:43 INFO - '-c', 21:14:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:14:43 INFO - 'enabled': True, 21:14:43 INFO - 'halt_on_failure': False, 21:14:43 INFO - 'name': 'check_screen_resolution'},), 21:14:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:14:43 INFO - 'cmd': ('bash', 21:14:43 INFO - '-c', 21:14:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:14:43 INFO - 'enabled': True, 21:14:43 INFO - 'halt_on_failure': False, 21:14:43 INFO - 'name': 'check_screen_resolution'},), 21:14:43 INFO - 'run_cmd_checks_enabled': True, 21:14:43 INFO - 'sps_profile': False, 21:14:43 INFO - 'sps_profile_interval': 0, 21:14:43 INFO - 'suite': 'dromaeojs', 21:14:43 INFO - 'system_bits': '32', 21:14:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:14:43 INFO - 'title': 't-yosemite-r7-0168', 21:14:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:14:43 INFO - 'use_talos_json': True, 21:14:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:14:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:14:43 INFO - 'work_dir': 'build'} 21:14:43 INFO - [mozharness: 2016-09-07 04:14:43.505513Z] Running clobber step. 21:14:43 INFO - Running pre-action listener: _resource_record_pre_action 21:14:43 INFO - Running main action method: clobber 21:14:43 INFO - rmtree: /builds/slave/test/build 21:14:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:14:44 INFO - Running post-action listener: _resource_record_post_action 21:14:44 INFO - [mozharness: 2016-09-07 04:14:44.414091Z] Finished clobber step (success) 21:14:44 INFO - [mozharness: 2016-09-07 04:14:44.414242Z] Running read-buildbot-config step. 21:14:44 INFO - Running pre-action listener: _resource_record_pre_action 21:14:44 INFO - Running main action method: read_buildbot_config 21:14:44 INFO - Using buildbot properties: 21:14:44 INFO - { 21:14:44 INFO - "project": "", 21:14:44 INFO - "product": "firefox", 21:14:44 INFO - "script_repo_revision": "production", 21:14:44 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 21:14:44 INFO - "repository": "", 21:14:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 21:14:44 INFO - "buildid": "20160906192717", 21:14:44 INFO - "pgo_build": "False", 21:14:44 INFO - "basedir": "/builds/slave/test", 21:14:44 INFO - "buildnumber": 159, 21:14:44 INFO - "slavename": "t-yosemite-r7-0168", 21:14:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:14:44 INFO - "platform": "yosemite_r7", 21:14:44 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:14:44 INFO - "branch": "autoland", 21:14:44 INFO - "repo_path": "integration/autoland", 21:14:44 INFO - "revision": "5f10d3f47379248797c381742d69af738cf31aad", 21:14:44 INFO - "stage_platform": "macosx64", 21:14:44 INFO - "builduid": "49174d6ab2774806a977beca38202dd4", 21:14:44 INFO - "slavebuilddir": "test" 21:14:44 INFO - } 21:14:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:14:44 INFO - Running post-action listener: _resource_record_post_action 21:14:44 INFO - [mozharness: 2016-09-07 04:14:44.417231Z] Finished read-buildbot-config step (success) 21:14:44 INFO - [mozharness: 2016-09-07 04:14:44.417332Z] Running download-and-extract step. 21:14:44 INFO - Running pre-action listener: _resource_record_pre_action 21:14:44 INFO - Running main action method: download_and_extract 21:14:44 INFO - mkdir: /builds/slave/test/build/tests 21:14:44 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')]} 21:14:44 INFO - trying https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:14:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:14:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:14:45 INFO - Downloaded 1199 bytes. 21:14:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:14:45 INFO - Using the following test package requirements: 21:14:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:14:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:14:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:14:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'jsshell-mac.zip'], 21:14:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:14:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:14:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:14:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:14:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:14:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:14:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:14:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:14:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:14:53 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 21:14:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:14:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:15:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:15:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 21:15:01 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')]} 21:15:01 INFO - trying https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:15:01 INFO - Downloading https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:15:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/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 21:15:04 INFO - Downloaded 111513314 bytes. 21:15:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:15:04 INFO - mkdir: /builds/slave/test/properties 21:15:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:15:04 INFO - Writing to file /builds/slave/test/properties/build_url 21:15:04 INFO - Contents: 21:15:04 INFO - build_url:https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:15:05 INFO - Running post-action listener: _resource_record_post_action 21:15:05 INFO - Running post-action listener: set_extra_try_arguments 21:15:05 INFO - [mozharness: 2016-09-07 04:15:05.019490Z] Finished download-and-extract step (success) 21:15:05 INFO - [mozharness: 2016-09-07 04:15:05.019609Z] Running populate-webroot step. 21:15:05 INFO - Running pre-action listener: _resource_record_pre_action 21:15:05 INFO - Running main action method: populate_webroot 21:15:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:15:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:15:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:15:05 INFO - '--tppagecycles', 21:15:05 INFO - '7'], 21:15:05 INFO - 'tests': ['tsvgm']}}, 21:15:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:15:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:15:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:15:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:15:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:15:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['damp', 'tps']}, 21:15:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'tests': ['damp', 'tps']}, 21:15:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['dromaeo_dom']}, 21:15:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:15:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['basic_compositor_video']}, 21:15:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:15:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'other-e10s': {'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['a11yr', 21:15:05 INFO - 'ts_paint', 21:15:05 INFO - 'tpaint', 21:15:05 INFO - 'sessionrestore', 21:15:05 INFO - 'sessionrestore_no_auto_restore', 21:15:05 INFO - 'tabpaint']}, 21:15:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['tsvgx', 21:15:05 INFO - 'tsvgr_opacity', 21:15:05 INFO - 'tart', 21:15:05 INFO - 'tscrollx', 21:15:05 INFO - 'cart']}, 21:15:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:15:05 INFO - 'tsvgr_opacity', 21:15:05 INFO - 'tart', 21:15:05 INFO - 'tscrollx', 21:15:05 INFO - 'cart']}, 21:15:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'talos_options': ['--disable-e10s'], 21:15:05 INFO - 'tests': ['tp5o']}, 21:15:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'tests': ['tp5o']}, 21:15:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'talos_options': ['--disable-e10s', 21:15:05 INFO - '--xperf_path', 21:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:05 INFO - 'tests': ['tp5n']}, 21:15:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:15:05 INFO - 'talos_options': ['--xperf_path', 21:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:15:05 INFO - 'tests': ['tp5n']}}, 21:15:05 INFO - 'talos.zip': {'path': '', 21:15:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:15:05 INFO - Running post-action listener: _resource_record_post_action 21:15:05 INFO - [mozharness: 2016-09-07 04:15:05.038404Z] Finished populate-webroot step (success) 21:15:05 INFO - [mozharness: 2016-09-07 04:15:05.038553Z] Running create-virtualenv step. 21:15:05 INFO - Running pre-action listener: _resource_record_pre_action 21:15:05 INFO - Running main action method: create_virtualenv 21:15:05 INFO - Creating virtualenv /builds/slave/test/build/venv 21:15:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:15:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:15:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:15:05 INFO - Using real prefix '/tools/python27' 21:15:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:15:05 INFO - Installing distribute.............................................................................................................................................................................................done. 21:15:08 INFO - Installing pip.................done. 21:15:08 INFO - Return code: 0 21:15:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:15:08 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')]} 21:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:08 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')]} 21:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:08 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:08 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 21:15:08 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 21:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:08 INFO - 'HOME': '/Users/cltbld', 21:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:08 INFO - 'LOGNAME': 'cltbld', 21:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:08 INFO - 'MOZ_NO_REMOTE': '1', 21:15:08 INFO - 'NO_EM_RESTART': '1', 21:15:08 INFO - 'PAGER': '/bin/cat', 21:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:08 INFO - 'PWD': '/builds/slave/test', 21:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:08 INFO - 'SHELL': '/bin/bash', 21:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:08 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:08 INFO - 'USER': 'cltbld', 21:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:08 INFO - 'XPC_FLAGS': '0x0', 21:15:08 INFO - 'XPC_SERVICE_NAME': '0', 21:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:08 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 21:15:08 INFO - Cleaning up... 21:15:08 INFO - Return code: 0 21:15:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:15:08 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')]} 21:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:08 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')]} 21:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:08 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:08 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 21:15:09 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 21:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:09 INFO - 'HOME': '/Users/cltbld', 21:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:09 INFO - 'LOGNAME': 'cltbld', 21:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:09 INFO - 'MOZ_NO_REMOTE': '1', 21:15:09 INFO - 'NO_EM_RESTART': '1', 21:15:09 INFO - 'PAGER': '/bin/cat', 21:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:09 INFO - 'PWD': '/builds/slave/test', 21:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:09 INFO - 'SHELL': '/bin/bash', 21:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:09 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:09 INFO - 'USER': 'cltbld', 21:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:09 INFO - 'XPC_FLAGS': '0x0', 21:15:09 INFO - 'XPC_SERVICE_NAME': '0', 21:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:09 INFO - Downloading/unpacking psutil>=3.1.1 21:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:09 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 21:15:09 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 21:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:12 INFO - Installing collected packages: psutil 21:15:12 INFO - Running setup.py install for psutil 21:15:12 INFO - building 'psutil._psutil_osx' extension 21:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:15:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:15:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:15:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:15:13 INFO - building 'psutil._psutil_posix' extension 21:15:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:15:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:15:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:15:13 INFO - ^ 21:15:13 INFO - 1 warning generated. 21:15:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:15:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:15:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:15:13 INFO - Successfully installed psutil 21:15:13 INFO - Cleaning up... 21:15:13 INFO - Return code: 0 21:15:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:15:13 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')]} 21:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:13 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')]} 21:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:13 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 21:15:13 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 21:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:13 INFO - 'HOME': '/Users/cltbld', 21:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:13 INFO - 'LOGNAME': 'cltbld', 21:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:13 INFO - 'MOZ_NO_REMOTE': '1', 21:15:13 INFO - 'NO_EM_RESTART': '1', 21:15:13 INFO - 'PAGER': '/bin/cat', 21:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:13 INFO - 'PWD': '/builds/slave/test', 21:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:13 INFO - 'SHELL': '/bin/bash', 21:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:13 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:13 INFO - 'USER': 'cltbld', 21:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:13 INFO - 'XPC_FLAGS': '0x0', 21:15:13 INFO - 'XPC_SERVICE_NAME': '0', 21:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:13 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 21:15:13 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 21:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:15:16 INFO - Installing collected packages: mozsystemmonitor 21:15:16 INFO - Running setup.py install for mozsystemmonitor 21:15:16 INFO - Successfully installed mozsystemmonitor 21:15:16 INFO - Cleaning up... 21:15:16 INFO - Return code: 0 21:15:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:15:16 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')]} 21:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:16 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')]} 21:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:16 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:16 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 21:15:16 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 21:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:16 INFO - 'HOME': '/Users/cltbld', 21:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:16 INFO - 'LOGNAME': 'cltbld', 21:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:16 INFO - 'MOZ_NO_REMOTE': '1', 21:15:16 INFO - 'NO_EM_RESTART': '1', 21:15:16 INFO - 'PAGER': '/bin/cat', 21:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:16 INFO - 'PWD': '/builds/slave/test', 21:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:16 INFO - 'SHELL': '/bin/bash', 21:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:16 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:16 INFO - 'USER': 'cltbld', 21:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:16 INFO - 'XPC_FLAGS': '0x0', 21:15:16 INFO - 'XPC_SERVICE_NAME': '0', 21:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:16 INFO - Downloading/unpacking jsonschema==2.5.1 21:15:16 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 21:15:16 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 21:15:16 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 21:15:16 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 21:15:16 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 21:15:16 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 21:15:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:15:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:19 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 21:15:19 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 21:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:19 INFO - Downloading functools32-3.2.3-2.tar.gz 21:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:15:19 INFO - warning: no files found matching '*.txt' 21:15:19 INFO - no previously-included directories found matching 'build' 21:15:19 INFO - no previously-included directories found matching 'dist' 21:15:19 INFO - no previously-included directories found matching '.git*' 21:15:19 INFO - Installing collected packages: jsonschema, functools32 21:15:19 INFO - Running setup.py install for functools32 21:15:19 INFO - warning: no files found matching '*.txt' 21:15:19 INFO - no previously-included directories found matching 'build' 21:15:19 INFO - no previously-included directories found matching 'dist' 21:15:19 INFO - no previously-included directories found matching '.git*' 21:15:20 INFO - Successfully installed jsonschema functools32 21:15:20 INFO - Cleaning up... 21:15:20 INFO - Return code: 0 21:15:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:15:20 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')]} 21:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:20 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')]} 21:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:20 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:20 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 21:15:20 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 21:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:20 INFO - 'HOME': '/Users/cltbld', 21:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:20 INFO - 'LOGNAME': 'cltbld', 21:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:20 INFO - 'MOZ_NO_REMOTE': '1', 21:15:20 INFO - 'NO_EM_RESTART': '1', 21:15:20 INFO - 'PAGER': '/bin/cat', 21:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:20 INFO - 'PWD': '/builds/slave/test', 21:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:20 INFO - 'SHELL': '/bin/bash', 21:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:20 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:20 INFO - 'USER': 'cltbld', 21:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:20 INFO - 'XPC_FLAGS': '0x0', 21:15:20 INFO - 'XPC_SERVICE_NAME': '0', 21:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:15:20 INFO - Cleaning up... 21:15:20 INFO - Return code: 0 21:15:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:15:20 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')]} 21:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:20 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')]} 21:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:20 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:20 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 21:15:20 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 21:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:20 INFO - 'HOME': '/Users/cltbld', 21:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:20 INFO - 'LOGNAME': 'cltbld', 21:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:20 INFO - 'MOZ_NO_REMOTE': '1', 21:15:20 INFO - 'NO_EM_RESTART': '1', 21:15:20 INFO - 'PAGER': '/bin/cat', 21:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:20 INFO - 'PWD': '/builds/slave/test', 21:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:20 INFO - 'SHELL': '/bin/bash', 21:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:20 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:20 INFO - 'USER': 'cltbld', 21:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:20 INFO - 'XPC_FLAGS': '0x0', 21:15:20 INFO - 'XPC_SERVICE_NAME': '0', 21:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:20 INFO - Downloading/unpacking blobuploader==1.2.4 21:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:20 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 21:15:20 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 21:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:23 INFO - Downloading blobuploader-1.2.4.tar.gz 21:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:15:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:15:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:23 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 21:15:24 INFO - Downloading docopt-0.6.1.tar.gz 21:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:15:24 INFO - Installing collected packages: blobuploader, requests, docopt 21:15:24 INFO - Running setup.py install for blobuploader 21:15:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:15:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:15:24 INFO - Running setup.py install for requests 21:15:24 INFO - Running setup.py install for docopt 21:15:25 INFO - Successfully installed blobuploader requests docopt 21:15:25 INFO - Cleaning up... 21:15:25 INFO - Return code: 0 21:15:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:25 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')]} 21:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:25 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')]} 21:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:25 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:25 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 21:15:25 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 21:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:25 INFO - 'HOME': '/Users/cltbld', 21:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:25 INFO - 'LOGNAME': 'cltbld', 21:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:25 INFO - 'MOZ_NO_REMOTE': '1', 21:15:25 INFO - 'NO_EM_RESTART': '1', 21:15:25 INFO - 'PAGER': '/bin/cat', 21:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:25 INFO - 'PWD': '/builds/slave/test', 21:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:25 INFO - 'SHELL': '/bin/bash', 21:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:25 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:25 INFO - 'USER': 'cltbld', 21:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:25 INFO - 'XPC_FLAGS': '0x0', 21:15:25 INFO - 'XPC_SERVICE_NAME': '0', 21:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-L17ILH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-qRMWuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-qsAC4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-JH_L_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-NIzV41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tj7E1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Gwm_Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-FND9B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-kNWgQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-4H5gvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-nLtlgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-cUTTLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-VXBbXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-JIGu1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-2r2gCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-jTN9n2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-v3CyuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:15:27 INFO - Running setup.py install for manifestparser 21:15:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:15:27 INFO - Running setup.py install for mozcrash 21:15:27 INFO - Running setup.py install for mozdebug 21:15:27 INFO - Running setup.py install for mozdevice 21:15:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:15:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:15:27 INFO - Running setup.py install for mozfile 21:15:28 INFO - Running setup.py install for mozhttpd 21:15:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Running setup.py install for mozinfo 21:15:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Running setup.py install for mozInstall 21:15:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Running setup.py install for mozleak 21:15:28 INFO - Running setup.py install for mozlog 21:15:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Running setup.py install for moznetwork 21:15:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:15:28 INFO - Running setup.py install for mozprocess 21:15:29 INFO - Running setup.py install for mozprofile 21:15:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:15:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:15:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:15:29 INFO - Running setup.py install for mozrunner 21:15:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:15:29 INFO - Running setup.py install for mozscreenshot 21:15:29 INFO - Running setup.py install for moztest 21:15:29 INFO - Running setup.py install for mozversion 21:15:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:15:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:15:29 INFO - Cleaning up... 21:15:29 INFO - Return code: 0 21:15:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:29 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')]} 21:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:29 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')]} 21:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:29 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:29 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 21:15:29 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 21:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:29 INFO - 'HOME': '/Users/cltbld', 21:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:29 INFO - 'LOGNAME': 'cltbld', 21:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:29 INFO - 'MOZ_NO_REMOTE': '1', 21:15:29 INFO - 'NO_EM_RESTART': '1', 21:15:29 INFO - 'PAGER': '/bin/cat', 21:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:29 INFO - 'PWD': '/builds/slave/test', 21:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:29 INFO - 'SHELL': '/bin/bash', 21:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:29 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:29 INFO - 'USER': 'cltbld', 21:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:29 INFO - 'XPC_FLAGS': '0x0', 21:15:29 INFO - 'XPC_SERVICE_NAME': '0', 21:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-hq_M0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-xyi8u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-mNGN6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-np6MeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-duFxry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-VRVv_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-E7a0L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:15:30 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)) 21:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:15:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tMn7Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-dWFhqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-IBukpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-XFMYPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-5Fu0G5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-jM2_cJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-9SG5CL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Azv_Mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-XSeM4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:15:31 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)) 21:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:15:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-thL_pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:15:32 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)) 21:15:32 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)) 21:15:32 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)) 21:15:32 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)) 21:15:32 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)) 21:15:32 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)) 21:15:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:32 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 21:15:32 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 21:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:15:34 INFO - Downloading blessings-1.6.tar.gz 21:15:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:15:34 INFO - Installing collected packages: blessings 21:15:34 INFO - Running setup.py install for blessings 21:15:34 INFO - Successfully installed blessings 21:15:34 INFO - Cleaning up... 21:15:34 INFO - Return code: 0 21:15:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:15:35 INFO - Reading from file tmpfile_stdout 21:15:35 INFO - Current package versions: 21:15:35 INFO - blessings == 1.6 21:15:35 INFO - blobuploader == 1.2.4 21:15:35 INFO - docopt == 0.6.1 21:15:35 INFO - functools32 == 3.2.3-2 21:15:35 INFO - jsonschema == 2.5.1 21:15:35 INFO - manifestparser == 1.1 21:15:35 INFO - mozInstall == 1.12 21:15:35 INFO - mozcrash == 0.17 21:15:35 INFO - mozdebug == 0.1 21:15:35 INFO - mozdevice == 0.48 21:15:35 INFO - mozfile == 1.2 21:15:35 INFO - mozhttpd == 0.7 21:15:35 INFO - mozinfo == 0.9 21:15:35 INFO - mozleak == 0.1 21:15:35 INFO - mozlog == 3.3 21:15:35 INFO - moznetwork == 0.27 21:15:35 INFO - mozprocess == 0.23 21:15:35 INFO - mozprofile == 0.28 21:15:35 INFO - mozrunner == 6.12 21:15:35 INFO - mozscreenshot == 0.1 21:15:35 INFO - mozsystemmonitor == 0.3 21:15:35 INFO - moztest == 0.7 21:15:35 INFO - mozversion == 1.4 21:15:35 INFO - psutil == 3.1.1 21:15:35 INFO - requests == 1.2.3 21:15:35 INFO - wsgiref == 0.1.2 21:15:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:15:35 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')]} 21:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 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')]} 21:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:35 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 21:15:35 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 21:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:35 INFO - 'HOME': '/Users/cltbld', 21:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:35 INFO - 'LOGNAME': 'cltbld', 21:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:35 INFO - 'MOZ_NO_REMOTE': '1', 21:15:35 INFO - 'NO_EM_RESTART': '1', 21:15:35 INFO - 'PAGER': '/bin/cat', 21:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:35 INFO - 'PWD': '/builds/slave/test', 21:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:35 INFO - 'SHELL': '/bin/bash', 21:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:35 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:35 INFO - 'USER': 'cltbld', 21:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:35 INFO - 'XPC_FLAGS': '0x0', 21:15:35 INFO - 'XPC_SERVICE_NAME': '0', 21:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 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)) 21:15:35 INFO - Cleaning up... 21:15:35 INFO - Return code: 0 21:15:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:15:35 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')]} 21:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 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')]} 21:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:15:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x106beba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106c80618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa28bcd96a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, '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.KzjFfilb88/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.HgMpb3Urug/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 21:15:35 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 21:15:35 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 21:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:15:35 INFO - 'HOME': '/Users/cltbld', 21:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:15:35 INFO - 'LOGNAME': 'cltbld', 21:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:15:35 INFO - 'MOZ_NO_REMOTE': '1', 21:15:35 INFO - 'NO_EM_RESTART': '1', 21:15:35 INFO - 'PAGER': '/bin/cat', 21:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:15:35 INFO - 'PWD': '/builds/slave/test', 21:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:15:35 INFO - 'SHELL': '/bin/bash', 21:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:15:35 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:15:35 INFO - 'USER': 'cltbld', 21:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:15:35 INFO - 'XPC_FLAGS': '0x0', 21:15:35 INFO - 'XPC_SERVICE_NAME': '0', 21:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:15:35 INFO - Cleaning up... 21:15:35 INFO - Return code: 0 21:15:35 INFO - Running post-action listener: _resource_record_post_action 21:15:35 INFO - Running post-action listener: _start_resource_monitoring 21:15:35 INFO - Starting resource monitoring. 21:15:35 INFO - [mozharness: 2016-09-07 04:15:35.669853Z] Finished create-virtualenv step (success) 21:15:35 INFO - [mozharness: 2016-09-07 04:15:35.670862Z] Running install step. 21:15:35 INFO - Running pre-action listener: _resource_record_pre_action 21:15:35 INFO - Running main action method: install 21:15:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:15:35 INFO - Reading from file tmpfile_stdout 21:15:35 INFO - Detecting whether we're running mozinstall >=1.0... 21:15:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:15:36 INFO - Reading from file tmpfile_stdout 21:15:36 INFO - Output received: 21:15:36 INFO - Usage: mozinstall [options] installer 21:15:36 INFO - Options: 21:15:36 INFO - -h, --help show this help message and exit 21:15:36 INFO - -d DEST, --destination=DEST 21:15:36 INFO - Directory to install application into. [default: 21:15:36 INFO - "/builds/slave/test"] 21:15:36 INFO - --app=APP Application being installed. [default: firefox] 21:15:36 INFO - mkdir: /builds/slave/test/build/application 21:15:36 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'] 21:15:36 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 21:16:09 INFO - Reading from file tmpfile_stdout 21:16:09 INFO - Output received: 21:16:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:16:09 INFO - Running post-action listener: _resource_record_post_action 21:16:09 INFO - [mozharness: 2016-09-07 04:16:09.889259Z] Finished install step (success) 21:16:09 INFO - [mozharness: 2016-09-07 04:16:09.889459Z] Running run-tests step. 21:16:09 INFO - Running pre-action listener: _resource_record_pre_action 21:16:09 INFO - Running main action method: run_tests 21:16:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:16:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:16:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:16:09 INFO - 2016-09-06 21:16:09.904 screenresolution[2289:20044] starting screenresolution argv=screenresolution get 21:16:09 INFO - 2016-09-06 21:16:09.906 screenresolution[2289:20044] Display 0: 1600x1200x32@60 21:16:09 INFO - 2016-09-06 21:16:09.912 screenresolution[2290:20047] starting screenresolution argv=screenresolution list 21:16:09 INFO - Available Modes on Display 0 21:16:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:16:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:16:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:16:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:16:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:16:10 INFO - Intel Iris: 21:16:10 INFO - Chipset Model: Intel Iris 21:16:10 INFO - Type: GPU 21:16:10 INFO - Bus: Built-In 21:16:10 INFO - VRAM (Dynamic, Max): 1536 MB 21:16:10 INFO - Vendor: Intel (0x8086) 21:16:10 INFO - Device ID: 0x0a2e 21:16:10 INFO - Revision ID: 0x0009 21:16:10 INFO - Displays: 21:16:10 INFO - Display: 21:16:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:16:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:16:10 INFO - Display Serial Number: 1600x1200 60 21:16:10 INFO - Main Display: Yes 21:16:10 INFO - Mirror: Off 21:16:10 INFO - Online: Yes 21:16:10 INFO - Rotation: Supported 21:16:10 INFO - Return code: 0 21:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:16:10 INFO - Python 2.7.3 21:16:10 INFO - Return code: 0 21:16:10 INFO - grabbing minidump binary from tooltool 21:16:10 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')]} 21:16:10 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 0x106b4fd30>, '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 0x1068b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106b47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:16:10 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 21:16:10 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 21:16:10 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 21:16:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:16:10 INFO - Return code: 0 21:16:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:16:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:16:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:16:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:16:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 21:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 21:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KzjFfilb88/Render', 21:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:16:10 INFO - 'HOME': '/Users/cltbld', 21:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:16:10 INFO - 'LOGNAME': 'cltbld', 21:16:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:16:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:16:10 INFO - 'MOZ_NO_REMOTE': '1', 21:16:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:16:10 INFO - 'NO_EM_RESTART': '1', 21:16:10 INFO - 'PAGER': '/bin/cat', 21:16:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:16:10 INFO - 'PWD': '/builds/slave/test', 21:16:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:16:10 INFO - 'SHELL': '/bin/bash', 21:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HgMpb3Urug/Listeners', 21:16:10 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 21:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:16:10 INFO - 'USER': 'cltbld', 21:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:16:10 INFO - 'XPC_FLAGS': '0x0', 21:16:10 INFO - 'XPC_SERVICE_NAME': '0', 21:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:16:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 21:16:10 INFO - mozversion application_buildid: 20160906192717 21:16:10 INFO - mozversion application_changeset: 5f10d3f47379248797c381742d69af738cf31aad 21:16:10 INFO - mozversion application_display_name: Nightly 21:16:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:16:10 INFO - mozversion application_name: Firefox 21:16:10 INFO - mozversion application_remotingname: firefox 21:16:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:16:10 INFO - mozversion application_vendor: Mozilla 21:16:10 INFO - mozversion application_version: 51.0a1 21:16:10 INFO - mozversion platform_buildid: 20160906192717 21:16:10 INFO - mozversion platform_changeset: 5f10d3f47379248797c381742d69af738cf31aad 21:16:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:16:10 INFO - mozversion platform_version: 51.0a1 21:16:10 INFO - using testdate: 1473221770 21:16:10 INFO - actual date: 1473221770 21:16:10 INFO - starting webserver on 'localhost:53421' 21:16:10 INFO - SUITE-START | Running 2 tests 21:16:10 INFO - TEST-START | dromaeo_css 21:16:10 INFO - operating with platform_type : mac_ 21:16:10 INFO - Initialising browser for dromaeo_css test... 21:16:10 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpnkwdW0/profile http://localhost:53421/getInfo.html) 21:16:12 INFO - PROCESS | 2300 | __metrics Screen width/height:1600/1200 21:16:12 INFO - PROCESS | 2300 | colorDepth:24 21:16:12 INFO - PROCESS | 2300 | Browser inner width/height: 1024/689 21:16:12 INFO - PROCESS | 2300 | __metrics 21:16:12 INFO - PROCESS | 2300 | 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" 21:16:12 INFO - PROCESS | 2300 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:16:13 INFO - TEST-INFO | 2300: exit 0 21:16:13 INFO - Browser initialized. 21:16:13 INFO - Running cycle 1/1 for dromaeo_css test... 21:16:13 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpnkwdW0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:25 INFO - PROCESS | 2302 | RSS: Main: 232177664 21:16:25 INFO - PROCESS | 2302 | 21:17:17 INFO - PROCESS | 2302 | 1473221837342 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] 21:19:28 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53421/tests/dromaeo/cssquery-ext.html) 21:19:30 INFO - PROCESS | 2302 | RSS: Main: 383053824 21:19:30 INFO - PROCESS | 2302 | 21:22:33 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-ext.html (next: http://localhost:53421/tests/dromaeo/cssquery-jquery.html) 21:22:33 INFO - PROCESS | 2302 | RSS: Main: 372703232 21:22:33 INFO - PROCESS | 2302 | 21:25:36 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53421/tests/dromaeo/cssquery-mootools.html) 21:25:36 INFO - PROCESS | 2302 | RSS: Main: 328773632 21:25:36 INFO - PROCESS | 2302 | 21:28:39 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53421/tests/dromaeo/cssquery-prototype.html) 21:28:39 INFO - PROCESS | 2302 | RSS: Main: 347394048 21:28:39 INFO - PROCESS | 2302 | 21:31:43 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53421/tests/dromaeo/cssquery-yui.html) 21:31:43 INFO - PROCESS | 2302 | RSS: Main: 329596928 21:31:43 INFO - PROCESS | 2302 | 21:34:46 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:53421/tests/dromaeo/cssquery-yui.html (next: http://localhost:53421/tests/dromaeo/cssquery-dojo.html) 21:34:47 INFO - PROCESS | 2302 | RSS: Main: 523145216 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | __start_tp_report 21:34:47 INFO - PROCESS | 2302 | _x_x_mozilla_page_load 21:34:47 INFO - PROCESS | 2302 | _x_x_mozilla_page_load_details 21:34:47 INFO - PROCESS | 2302 | |i|pagename|runs| 21:34:47 INFO - PROCESS | 2302 | |0;dojo.html;3309.6359400465944;3327.1848397142694;3327.7005069543734;3377.1388295984552;3407.148212946763;5668.461496157869;5720;5756.251687280658;5860.5311575074;5862;5790.30516338039;5835.941640583593;5838.883222335561;5878.059510478324;5882.617629854057;5175.275461435402;5187.533122019017;5285.02227087988;5290.523852853243;5305.283786688806;4849.442314133867;5033.84898453047;5334.546563542084;5366.9463305367035;5383.327084114486;5177.249298851645;5206.193040078794;5210.76551555181;5273.683578985254;5296.470352964711;5222.373315202162;5235.424103348629;5247.10799164143;5346.732663366816;5368.70472124033;4373.2565463871215;5149.201873709581;5219.478052194788;5225.2162175673575;5269.710165940872;5847.707614619252;5883.146943693183;5886.676232807194;5896.1450589664255;5913.5957947763445;6139.938600613981;6148.600340977822;6189.659568723719;6190.938090619081;6199.938000619981;6217.813465596039;6245.906311405332;6264.937350626481;6273.309935907047;6282.811515654537;5976.671283079429;6006.759729610767;6008.399160083956;6014.8195554133445;6025.0661147522205;6154.661493617894;6175.073738939105;6213.409726076015;6218.129461875341;6261.311255761862;6126.172966649535;6127.816165515042;6132.110843927606;6154.169187159766;6163.630182189083;6144.07838824171;6193.256809182927;6198.6280823150755;6199.628022318675;6222.253329600477;6112.74665817949;6144.863200307957;6166.290876549165;6200.131981522591;6240.095186198067;6154.815355539252;6198.969005154945;6199.3490683478485;6206.627602343874;6217.191765070603;5069.340985671839;5076;5084.720340381315;5099.23511473282;5099.8725031873755;3838.980805095957;4465.687401881837;4494.77526123699;4495;4572.679912406166;5928.970355148197;5956.225690660273;5959.553033522503;5972.895014422259;5985.34161242263;6216.968915155486;6248;6255;6259.499240060784;6282.654454005074;5751.7411716472025;5757.107648314434;5788.363280039276;5906.970465147647;5938.8812223755285;5455.454454554513;5477.260569823103;5506.834794956158;5515.696636684941;5527.778888844401;6432.839179020562;6440.967795160994;6442.387973142544;6447.258565265054;6448.065030570541;6210.068489726577;6262.74949002053;6265.937340626535;6273.9372606273355;6279.30927597973;6206.813795586139;6293.716782744879;6414.326495718069;6426.4537514311805;6439.935600644121;6232.407921247474;6257.7184026719815;6289.465395441439;6293.55945083858;6299.149614802126;6205.875882482415;6240.282367527792;6242.65665388408;6262.3737626238835;6287.622742635456;6094.268687757498;6132.325444201045;6229.283632382334;6243.719032643451;6250.593711408744;6068.787592434243;6095.963686173272;6134.436587033563;6159.846003849761;6162.014077747539;6021.969890150521;6025.548083893813;6036.034234522455;6045.516358691206;6078.422549857756;6142.385761423822;6145.354737752472;6178.443940045418;6198.194234749454;6223.37766223374;5894.2926848778425;5921.911171332347;6044.335123136363;6082.118092876596;6093.177421048102 21:34:47 INFO - PROCESS | 2302 | |1;ext.html;467.29205254040124;480.6491261379193;483.63968843211813;529.3594750352074;529.757718150936;30709.84645076774;34386.8280658597;41282.587174128246;42061.15877682444;44132;43448;43688;43786.34320485187;43829.56170438295;43842.342364864555;8896.421732587361;9850.655227067055;10018.24863135264;10020.248481363891;10036.29745917786;12144.635660930186;12354.196977196498;12367.505299787998;12600;12616.306103164323;12298.262104273772;12363.938180309086;12392.132550721408;12408.379581020958;12435.813462798065;12390.628281151536;12447.875521244761;12461.501539938437;12473.93763031179;12483.625491235278;12193.024558035424;12224;12226.572069977597;12227.266364018187;12241.755164896742;40369.19261614751;40733.388999165036;40736.38895416571;40754.184916301805;40795.796021019705;3361.848716807725;3523.330567192251;3581.28374325136;3786.772793632391;3964.306246406896;30615.38769224603;30971.845140774378;31370.52944205839;31559.21101972469;32168.874089406992;3160.2415420299194;3181.3637272545584;3224.9355012899846;3310.586176727909;3312.96687033129;518235;524373;524714;524851;525036;62538;62902.68548657227;62920;62926.37073629296;63063;28897.85551072231;29654;35051;66016.68941587022;66327.66836165788;13973.441062357595;16386.508404747878;16691.24889379981;16704.582385440463;16710.248038838283;11511.19421640477;11779.293242405483;12590.874091259153;12599.433025513874;12880.484780608858;19101.426957191306;19502.43980481558;19502.512437189183;19515.902420487808;19527.902360488108;7976.7208147714555;8107.270345668918;8173.713920012771;8190.180981901762;8198.877016844752;843.1905370844058;933.3093510801957;958.678842587741;970.3353203055788;1076.0476977874637;29517.557236641467;30281.545776813364;30424.54363184554;30646.41356930993;30744.231394215323;1000.2997901469001;1029.3206483720708;1084.8264277715527;1115.893990070942;1191.1661836714334;15718.68562628741;15744.370225191093;15761.842381576267;15798.526044218692;16249.431269905497;679275;683268;685466;686069;687024;59254;59602;59782.701086494286;59885.70057149686;59921;1239.3988915376035;1359.4358341288255;1375.3192169875776;1399.1255465334166;1407.6410515318528;30688.465576720628;31731.841340793148;31828.36343273168;31902;32713.018609441755;50052.749736251084;50630.74684626701;50705.49294507155;50766.74616626893;50774.238386423494;433002;553889;559690;560945;560973;44896.55103449054;44974.77512612547;45794;46208.76895615501;47778;47038.52961470342;48474.788130295616;49275.50724492709;49675.00649987052;50330.99338013146;40778.59221407748;41361.79319103385;41505;42387.364189536565;42704.78647606742;40130;40294.39558406568;40450.39324410077;40875.59124408837;41296;39580;40121.77807994472;40327.3950890731;40583.59416405916;40706;27997.160085197476;33765.83117084399;36197.7330793421;36348.818255908554;36398;20867.478313042295;25301.240962771146;25516.234512964642;25625.487490250463;25762.227133186036 21:34:47 INFO - PROCESS | 2302 | |2;jquery.html;348.6217454062342;366.97614655047437;369.2559492622366;378.52684144818966;397.33644813162016;14750;15338.53984380469;19955.800441995576;20644.587108257827;20665.793342066558;20259.088341024653;20600.484987875272;20631.484212894647;20681.37955861323;20717.203177571024;5841.853953651172;5843.328017278003;6099.115628233903;6101.755929762804;6122.173506576622;6011.158437818709;6159.9384006160035;6171.290301615327;6171.321154672983;6173.660448675343;5917.260342457193;6112.327643959161;6139.140520327135;6166.383361663842;6200.410960958701;6175.351588083254;6192.690365481708;6195;6208.255009398901;6208.379162083801;5537;5616.494515493625;5762.020456522401;6053.545984051169;6071.392860713938;13121.47514099435;13130.803037954438;13183.340832958314;13348.933255333759;13418.26199559024;2131.3712454825863;2206.878621675807;2257.164849005862;2265.9433514162115;2348.4481146930457;10076.193904487624;10340.172786177163;10505.212109091848;13265.86734132656;14728.852711472855;1567.9529614111595;1858.5539470527112;1863.925442982279;1864.2263460663808;1914.444811004807;121017;125396;125456.37271813583;128027.35986320103;128312;10957.83563246552;11378.431078446045;11514.136439767051;11519;11670.94164529172;14134.010619256758;14153.787693184611;14165.079269847427;14181;14206.573802785933;8158.306543943712;8478.788030299293;8482.363822713321;8624.698135565224;8636.093210212963;6819.238208525555;7583.658735356922;8431.325493960469;8619.525926074;8623.439476434012;3942.4086387042175;4435.179491794029;4446.911061778746;4508.256137737237;4545.840895568639;1837.2834594508151;1885.9811401885806;1894.4316704988723;1896.4310706787908;1897.193692680633;831.9001719793664;848.1645579104577;869.8086420987372;942.2320808541017;968.4479846487575;16056;17088.487345379657;17808.46574602764;21226.575468490544;21457.03443345022;928.8792456980549;937.1425145991478;938.6339327662217;946.9195118414874;967.579103090158;10761.192910531741;11231;11319.490622921989;11359.716007099889;11365.204435689588;585877;586415;595678;595749;601157;83188;102001.2080128185;134989;141581.29209354092;143771.5622843779;890.2343984173606;980.6910823090703;1014.3609525998573;1017.1913328903539;1017.2370721958531;19430.319938802073;19696;19857.801421986172;19919;19958.600827983068;45596.54403455923;54728;58305.12542311784;58649.12026319694;59425.92009279654;85165;85422;86335;87295.56352218453;87353.56323218597;7790.415718821225;7841.5687137208;7958.204179582227;8190.0171979360475;8195.71315003972;12398.380080996094;12922.095453318554;13258.80111798343;13695.520656776966;13733.656658583215;9213;11230.887691123311;11374.772504550028;11674.124440666814;11726.648200553997;11039.172062095535;11096.88903110991;11419.657410277705;11434;11741.706457338634;10746;11744.58893938708;11911.821322679993;11958.701032474257;12093.637190884288;6288.99005226763;7138;7349.70601175947;7963.721269755531;8367.497950122768;10304.433256170983;11167.274127181707;11487.597934072268;11588;11614.70963225926 21:34:47 INFO - PROCESS | 2302 | |3;mootools.html;1540.0528674864954;1581.533447632948;1809.3305476973514;1925.9133338999743;1964.8428125749942;8530.957345213274;9072.455652660838;9078.591463384148;9078.909210907888;9141.314401419886;7959.522428654285;8128.268455838974;8650.394472386919;8828.660404992683;8834.646614135443;2357.77601127893;2399.184277345702;2401.735809061002;2404.6873906392143;2412.445137618349;2362.6456031595226;2370.6207006878903;2408.6748288981025;2408.843425177358;2411.8914648840755;2377.5601513720017;2386.2483317754936;2393.5093305872256;2397.4605713714363;2399.592069348215;2364.9408764780846;2367.0886708617136;2377.9286621401384;2377.9524409511714;2399.83201175916;2353.882305884699;2369.324742448394;2374.8456350337174;2376.7742064503846;2379.1435083369975;8892.199702026785;8966.820663586692;9054;9063.546822658907;9076.63693452261;5267;5439.102548079562;5440.319960004999;5442.972785136049;5447.046766815792;3557.8932632021083;3673.632636736305;3704.703623710124;3738.6635202831603;3763.41667041609;1098.780243951213;1101.0035917494681;1108.8835672254377;1112.7663190729957;1125.5947858770835;11759.47082381295;11782.587609433629;11868.88131118695;11890.881091189063;11907.404629768564;3900.278448487039;4518.209313370147;4703.435587729495;4709.081729062868;4714.952850471452;9214.63141474347;9237.722868313962;9239;9240.815183696288;9254.953725231331;5296.629235953524;5299.4700529946695;5300.549453296436;5302.019126461617;5321.73391330432;4411.889702757392;4550.13547425988;4772.880677983078;4888.2178851383605;4960.379952505937;3768.6231376862643;3793.2223894102194;3899.29812633723;3922.490076290121;3935.4883865097354;1183.597576823884;1185.8161984892356;1193.8567371915426;1198.2151690642643;1202.6271855724754;888.9110839221902;913.2100732866023;933.4725879877848;934.9579268932914;977.5063592885547;5561.971035358471;5678.835838653066;5947.1079338099635;6091.908621370683;6140.232470941132;435.7102526819693;451.85314772699;453.65748859610784;454.27543068805863;457.9931301030487;3924.274009308287;3949.6445319921345;4034.253663072341;4047.4131250968444;4071.9796401017807;10304.587816487407;10338.689839304832;10625.096866766411;10772.569097236179;10802.297850639683;10911.672649820519;10924.071453926505;10930.56277748897;10935.671929842118;10935.890641093647;7743.225677432211;8214.63034163464;8326.999999999878;8344;8355.248027677417;5932.555058370725;5978.133170690313;6065.5754097212775;6141.785037523665;6190.721417536131;9983.400995940267;10144.797104058025;10175.898241017496;10203.591856325664;10239.7440063999;10100.141487973604;10522.736931576466;10527.210459215436;10539.051485366354;10540.947295263475;5755.366909639853;7070.36366726997;7297.416206703557;7555.09338879338;7687.11598166218;8254.54599997006;8578.78553036179;8589.613467394107;8592.570371481526;8613.009503907131;7057.6471176442;7138.714451421885;7139.535930164523;7217;7322.048133742793;7335.706571737071;7404.555726656418;7407.962960185165;7417.517861338995;7464.253574642492;6976.651167441709;6978.581285122909;7057.2237053925055;7058.894116588152;7062.858742825218;5010.87472813168;5042.873928151826;5056.443791182894;5125.308083408692;5200.557952573922;5021.1464051112125;5046.268291097844;5176.275321455075;5199.376074871039;5304.098303288527 21:34:47 INFO - PROCESS | 2302 | |4;prototype.html;282.6890420537408;282.809103854898;290.25694222789656;292.6415141451722;295.3575972260333;5406.864558442727;5409.350877894653;5436.972815135924;5497.230387745709;5522;4087.6729861611075;4544.341070544778;5152.140561988104;5183.2743415921705;5370.838874833751;3287.8684852605866;3473.531073305097;3820.2741479119018;3977;4017.774578753479;4046.3525835866267;4047.170330082326;4071.2875246831836;4099.959000409987;4107.507099148106;3908.7068469864876;3969.0077480629843;4032.8790136295956;4046;4067.1458993611377;4019.376996565537;4026.697997650159;4032.495938007764;4050.8784736457956;4052.49343832021;3507.684308412255;3509.666581674737;3527.6594893940396;3543.2204914918934;3578.731595130376;5027.949720502821;5182.300389447414;5250.527452529291;5253.423972808142;5259.211118332242;4549.294014744471;4589.839355622537;4674.392328997209;4678.298255261704;4710.29345598163;4980.676256043345;5220.720923373759;5671.432856714337;5704.258446401983;5717.428257174291;3848.5958974307855;3852.6339997700175;3862.555806082308;3874.1089549403514;3879.3792993121247;5620.995869097369;5813.476787089182;5892.705364731746;5908.320543137508;5979.2824861016525;3894.5131858517684;3894.941575876364;3914.9804250978564;3915.786106307054;3919.647231749156;3829.617038296148;3991.960080399217;3993.4409182714444;4023.979880100639;4034.878953631337;4010.5187377514303;4470.776461176928;4476.686631935799;4575.702579332333;4702.435707715096;5611.579131565149;5620.241267428926;5620.97189514058;5626.690532020778;5651.9152212716845;1197.5269768441592;1308.234682710606;1311.567182829664;1359.0894100952385;1372.389286767399;837.4933165434946;848.5460278750838;848.6011574559947;895.8253140637513;915.1534830281962;4865.659403841768;5129.743512824344;5238.240455133984;5260.316158899395;5289.9471005290225;5559.249501317351;5760.683162426107;5769.682667453247;5786.623869448473;5798.9130163047585;5041.873953151127;5187.610929180327;5282.498162674539;5355.884425651846;5433.483819037185;2577.7422257774074;2672.759451649361;2818.718128187165;3049.9085027449214;3095.4582947984013;5559.833205003856;5642.971785141048;5988.550858685616;5989.311229208697;6060.515158787286;5243;5861.384554621789;6043.818685439356;6074.058520929175;6143.020634992025;4543.5002149763495;5052.343195384577;5379.386184144819;6122.571420000557;6179.258488981351;5653.851536416136;5822.195119512964;5860.09168578871;5916.615419997686;5937.139114828239;4893.951060489349;5841.474267315792;5877.529797616266;5901.5868889177345;5920.703964801656;5720;5770.163326317745;5867.826434712989;6057.24339941412;6141.90222115634;3734.8506059757306;3806.7144964128356;3883.6310550497656;3885.9896426928635;3914.784686842251;5759.510441612342;5781.942180578309;5826.737796799239;5961.989195456267;5995.400459954144;4294.82820687169;4707;5162.277281180563;5515.669059856422;5784.82645520635;5171.689698618095;5590.692511911884;5665.716714164193;5733.655980641175;5736.311642602915;4120.793960302033;5333.066713325075;5366.651167674088;5529.585281104014;5589.692566908697;5084.6440749147205;5128;5660.547156227574;5701.030824759718;5770.711464426678;4457.105730064774;4996.3504744383;5231.607629427732;5370.382406023357;5376.247325374373 21:34:47 INFO - PROCESS | 2302 | |5;yui.html;714.5022085968823;727.7234650832683;762.9966593928983;792.8216151365947;815.7471183932979;15419.614509637251;16119.516414507583;16180.433684821033;16201.10893900835;16204.351825926964;14412.279386030683;14437.27813609318;14522.564323070299;14581.416743330281;14659.120452772815;681.0295329155933;690.9274526174754;827.1397746343811;848.6435697007241;861.1087524412235;819.1194465949098;830.3274347778289;831.4304701279643;832.1595188859235;834.7287131682198;878.1701292278799;879.705298724928;880.3221519430035;881.2685471059037;882.6822343956171;713.8679344321265;872.7207293665998;877.7673916412156;882.0253619750212;893.9955300223457;675.4967549175847;678.281022116558;678.2877978122951;679.5956405938458;679.8198477403492;14598.562043138723;14635;14637;14637.560873173821;14675.559733208021;13403.195808251536;13620.65948351299;13745.656358591115;13750.174989500561;13927.234002129879;12335.814962775565;12416.441260143316;12456.190347627375;12910;13008.850438539806;4383.298672212462;4437.023854751949;4467.463904331501;4513.887152821173;4694.906101877943;103815.48092259566;106259;117329.41335293354;118737.40631296788;119010.21484677737;10842.783144337227;10886.61896833607;10922.344659320466;10935.56257749697;11319.886801132048;21847.781522184894;21955.231566895083;22005.449863753533;22012;22156.2245321413;7667.501612395176;7681.308682218627;7689.846203075907;7714.190010048977;7886.72396466121;4045.0089728016824;4071.4096456013713;4082.081531655413;4086.1010577672864;4132.090939993257;3321.186309354224;3424.5890493140982;3451.861925522951;3452.430348992438;3457.7233821294235;629.6127881352935;631.2404470293932;645.3546453546453;645.5352146454545;649.8407890066964;589.1604463639297;613.7146227004464;620.7423919073534;627.6861569215392;628.6825153297556;7914.802129946682;8189.262966333059;8334.374921880883;9935.391241923877;10037.629957213849;650.8531251399536;660.4155322539588;665.4842497064814;680.4930326906388;684.2712511175681;4546.318052292183;4546.818127274938;4572.9314060289125;4624.190766615829;4651.906961860744;117587.8241217594;148292.2585387066;148518.51481485047;149604;152770;125550;126059.7394026048;127020.2310549434;129678.70321296855;132349;101357.49321253346;101947.49026254969;106312.87374252471;106695.46652266689;109520.90479095267;9289.953550232205;9357.39176953496;9677.467739274125;9747.902520974983;9861;58548.121778172506;59276;59862;60235;60561;84607.57696211479;89675.70716551253;92134.07865921255;92955;98756;13013.47946082184;13112.672183195114;13252.867471325164;13399.196048237138;13644.249566273951;14077.788833167306;14584.343704533534;14742.778858317348;14873.330700118304;15074.849251507345;13298.534551290659;13924.860751392356;14105.012649114464;14132.78800818009;14400.639984000483;14099.365528551034;14175.22036288014;14370.137791732112;14374.784378234544;14682.486112985995;13603.727925441237;13633.79549306781;13744.86255137436;14346.282685865457;14471;11028.5037173329;11218.775624487627;11448.713782155179;11531.192816502764;11560.595379161688;10133.44266065344;10633.361998280128;11251.765080249053;11633.825492617449;11645.475953582278 21:34:47 INFO - PROCESS | 2302 | __end_tp_report 21:34:47 INFO - PROCESS | 2302 | __start_cc_report 21:34:47 INFO - PROCESS | 2302 | _x_x_mozilla_cycle_collect,1449 21:34:47 INFO - PROCESS | 2302 | __end_cc_report 21:34:47 INFO - PROCESS | 2302 | __startTimestamp1473222887153__endTimestamp 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | ------- Summary: start ------- 21:34:47 INFO - PROCESS | 2302 | Number of tests: 6 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#0] dojo.html Cycles:165 Average:5797.57 Median:6068.79 stddev:652.75 (10.8%) stddev-sans-first:624.89 21:34:47 INFO - PROCESS | 2302 | Values: 3309.6 3327.2 3327.7 3377.1 3407.1 5668.5 5720.0 5756.3 5860.5 5862.0 5790.3 5835.9 5838.9 5878.1 5882.6 5175.3 5187.5 5285.0 5290.5 5305.3 4849.4 5033.8 5334.5 5366.9 5383.3 5177.2 5206.2 5210.8 5273.7 5296.5 5222.4 5235.4 5247.1 5346.7 5368.7 4373.3 5149.2 5219.5 5225.2 5269.7 5847.7 5883.1 5886.7 5896.1 5913.6 6139.9 6148.6 6189.7 6190.9 6199.9 6217.8 6245.9 6264.9 6273.3 6282.8 5976.7 6006.8 6008.4 6014.8 6025.1 6154.7 6175.1 6213.4 6218.1 6261.3 6126.2 6127.8 6132.1 6154.2 6163.6 6144.1 6193.3 6198.6 6199.6 6222.3 6112.7 6144.9 6166.3 6200.1 6240.1 6154.8 6199.0 6199.3 6206.6 6217.2 5069.3 5076.0 5084.7 5099.2 5099.9 3839.0 4465.7 4494.8 4495.0 4572.7 5929.0 5956.2 5959.6 5972.9 5985.3 6217.0 6248.0 6255.0 6259.5 6282.7 5751.7 5757.1 5788.4 5907.0 5938.9 5455.5 5477.3 5506.8 5515.7 5527.8 6432.8 6441.0 6442.4 6447.3 6448.1 6210.1 6262.7 6265.9 6273.9 6279.3 6206.8 6293.7 6414.3 6426.5 6439.9 6232.4 6257.7 6289.5 6293.6 6299.1 6205.9 6240.3 6242.7 6262.4 6287.6 6094.3 6132.3 6229.3 6243.7 6250.6 6068.8 6096.0 6134.4 6159.8 6162.0 6022.0 6025.5 6036.0 6045.5 6078.4 6142.4 6145.4 6178.4 6198.2 6223.4 5894.3 5921.9 6044.3 6082.1 6093.2 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#1] ext.html Cycles:180 Average:72058.25 Median:36373.41 stddev:156441.43 (430.1%) stddev-sans-first:156787.95 21:34:47 INFO - PROCESS | 2302 | Values: 467.3 480.6 483.6 529.4 529.8 30709.8 34386.8 41282.6 42061.2 44132.0 43448.0 43688.0 43786.3 43829.6 43842.3 8896.4 9850.7 10018.2 10020.2 10036.3 12144.6 12354.2 12367.5 12600.0 12616.3 12298.3 12363.9 12392.1 12408.4 12435.8 12390.6 12447.9 12461.5 12473.9 12483.6 12193.0 12224.0 12226.6 12227.3 12241.8 40369.2 40733.4 40736.4 40754.2 40795.8 3361.8 3523.3 3581.3 3786.8 3964.3 30615.4 30971.8 31370.5 31559.2 32168.9 3160.2 3181.4 3224.9 3310.6 3313.0 518235.0 524373.0 524714.0 524851.0 525036.0 62538.0 62902.7 62920.0 62926.4 63063.0 28897.9 29654.0 35051.0 66016.7 66327.7 13973.4 16386.5 16691.2 16704.6 16710.2 11511.2 11779.3 12590.9 12599.4 12880.5 19101.4 19502.4 19502.5 19515.9 19527.9 7976.7 8107.3 8173.7 8190.2 8198.9 843.2 933.3 958.7 970.3 1076.0 29517.6 30281.5 30424.5 30646.4 30744.2 1000.3 1029.3 1084.8 1115.9 1191.2 15718.7 15744.4 15761.8 15798.5 16249.4 679275.0 683268.0 685466.0 686069.0 687024.0 59254.0 59602.0 59782.7 59885.7 59921.0 1239.4 1359.4 1375.3 1399.1 1407.6 30688.5 31731.8 31828.4 31902.0 32713.0 50052.7 50630.7 50705.5 50766.7 50774.2 433002.0 553889.0 559690.0 560945.0 560973.0 44896.6 44974.8 45794.0 46208.8 47778.0 47038.5 48474.8 49275.5 49675.0 50331.0 40778.6 41361.8 41505.0 42387.4 42704.8 40130.0 40294.4 40450.4 40875.6 41296.0 39580.0 40121.8 40327.4 40583.6 40706.0 27997.2 33765.8 36197.7 36348.8 36398.0 20867.5 25301.2 25516.2 25625.5 25762.2 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#2] jquery.html Cycles:180 Average:34853.16 Median:11794.20 stddev:99293.16 (841.9%) stddev-sans-first:99537.90 21:34:47 INFO - PROCESS | 2302 | Values: 348.6 367.0 369.3 378.5 397.3 14750.0 15338.5 19955.8 20644.6 20665.8 20259.1 20600.5 20631.5 20681.4 20717.2 5841.9 5843.3 6099.1 6101.8 6122.2 6011.2 6159.9 6171.3 6171.3 6173.7 5917.3 6112.3 6139.1 6166.4 6200.4 6175.4 6192.7 6195.0 6208.3 6208.4 5537.0 5616.5 5762.0 6053.5 6071.4 13121.5 13130.8 13183.3 13348.9 13418.3 2131.4 2206.9 2257.2 2265.9 2348.4 10076.2 10340.2 10505.2 13265.9 14728.9 1568.0 1858.6 1863.9 1864.2 1914.4 121017.0 125396.0 125456.4 128027.4 128312.0 10957.8 11378.4 11514.1 11519.0 11670.9 14134.0 14153.8 14165.1 14181.0 14206.6 8158.3 8478.8 8482.4 8624.7 8636.1 6819.2 7583.7 8431.3 8619.5 8623.4 3942.4 4435.2 4446.9 4508.3 4545.8 1837.3 1886.0 1894.4 1896.4 1897.2 831.9 848.2 869.8 942.2 968.4 16056.0 17088.5 17808.5 21226.6 21457.0 928.9 937.1 938.6 946.9 967.6 10761.2 11231.0 11319.5 11359.7 11365.2 585877.0 586415.0 595678.0 595749.0 601157.0 83188.0 102001.2 134989.0 141581.3 143771.6 890.2 980.7 1014.4 1017.2 1017.2 19430.3 19696.0 19857.8 19919.0 19958.6 45596.5 54728.0 58305.1 58649.1 59425.9 85165.0 85422.0 86335.0 87295.6 87353.6 7790.4 7841.6 7958.2 8190.0 8195.7 12398.4 12922.1 13258.8 13695.5 13733.7 9213.0 11230.9 11374.8 11674.1 11726.6 11039.2 11096.9 11419.7 11434.0 11741.7 10746.0 11744.6 11911.8 11958.7 12093.6 6289.0 7138.0 7349.7 7963.7 8367.5 10304.4 11167.3 11487.6 11588.0 11614.7 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#3] mootools.html Cycles:180 Average:5724.90 Median:4743.92 stddev:3227.69 (68.0%) stddev-sans-first:3221.42 21:34:47 INFO - PROCESS | 2302 | Values: 1540.1 1581.5 1809.3 1925.9 1964.8 8531.0 9072.5 9078.6 9078.9 9141.3 7959.5 8128.3 8650.4 8828.7 8834.6 2357.8 2399.2 2401.7 2404.7 2412.4 2362.6 2370.6 2408.7 2408.8 2411.9 2377.6 2386.2 2393.5 2397.5 2399.6 2364.9 2367.1 2377.9 2378.0 2399.8 2353.9 2369.3 2374.8 2376.8 2379.1 8892.2 8966.8 9054.0 9063.5 9076.6 5267.0 5439.1 5440.3 5443.0 5447.0 3557.9 3673.6 3704.7 3738.7 3763.4 1098.8 1101.0 1108.9 1112.8 1125.6 11759.5 11782.6 11868.9 11890.9 11907.4 3900.3 4518.2 4703.4 4709.1 4715.0 9214.6 9237.7 9239.0 9240.8 9255.0 5296.6 5299.5 5300.5 5302.0 5321.7 4411.9 4550.1 4772.9 4888.2 4960.4 3768.6 3793.2 3899.3 3922.5 3935.5 1183.6 1185.8 1193.9 1198.2 1202.6 888.9 913.2 933.5 935.0 977.5 5562.0 5678.8 5947.1 6091.9 6140.2 435.7 451.9 453.7 454.3 458.0 3924.3 3949.6 4034.3 4047.4 4072.0 10304.6 10338.7 10625.1 10772.6 10802.3 10911.7 10924.1 10930.6 10935.7 10935.9 7743.2 8214.6 8327.0 8344.0 8355.2 5932.6 5978.1 6065.6 6141.8 6190.7 9983.4 10144.8 10175.9 10203.6 10239.7 10100.1 10522.7 10527.2 10539.1 10540.9 5755.4 7070.4 7297.4 7555.1 7687.1 8254.5 8578.8 8589.6 8592.6 8613.0 7057.6 7138.7 7139.5 7217.0 7322.0 7335.7 7404.6 7408.0 7417.5 7464.3 6976.7 6978.6 7057.2 7058.9 7062.9 5010.9 5042.9 5056.4 5125.3 5200.6 5021.1 5046.3 5176.3 5199.4 5304.1 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#4] prototype.html Cycles:180 Average:4579.03 Median:5182.79 stddev:1422.79 (27.5%) stddev-sans-first:1389.76 21:34:47 INFO - PROCESS | 2302 | Values: 282.7 282.8 290.3 292.6 295.4 5406.9 5409.4 5437.0 5497.2 5522.0 4087.7 4544.3 5152.1 5183.3 5370.8 3287.9 3473.5 3820.3 3977.0 4017.8 4046.4 4047.2 4071.3 4100.0 4107.5 3908.7 3969.0 4032.9 4046.0 4067.1 4019.4 4026.7 4032.5 4050.9 4052.5 3507.7 3509.7 3527.7 3543.2 3578.7 5027.9 5182.3 5250.5 5253.4 5259.2 4549.3 4589.8 4674.4 4678.3 4710.3 4980.7 5220.7 5671.4 5704.3 5717.4 3848.6 3852.6 3862.6 3874.1 3879.4 5621.0 5813.5 5892.7 5908.3 5979.3 3894.5 3894.9 3915.0 3915.8 3919.6 3829.6 3992.0 3993.4 4024.0 4034.9 4010.5 4470.8 4476.7 4575.7 4702.4 5611.6 5620.2 5621.0 5626.7 5651.9 1197.5 1308.2 1311.6 1359.1 1372.4 837.5 848.5 848.6 895.8 915.2 4865.7 5129.7 5238.2 5260.3 5289.9 5559.2 5760.7 5769.7 5786.6 5798.9 5041.9 5187.6 5282.5 5355.9 5433.5 2577.7 2672.8 2818.7 3049.9 3095.5 5559.8 5643.0 5988.6 5989.3 6060.5 5243.0 5861.4 6043.8 6074.1 6143.0 4543.5 5052.3 5379.4 6122.6 6179.3 5653.9 5822.2 5860.1 5916.6 5937.1 4894.0 5841.5 5877.5 5901.6 5920.7 5720.0 5770.2 5867.8 6057.2 6141.9 3734.9 3806.7 3883.6 3886.0 3914.8 5759.5 5781.9 5826.7 5962.0 5995.4 4294.8 4707.0 5162.3 5515.7 5784.8 5171.7 5590.7 5665.7 5733.7 5736.3 4120.8 5333.1 5366.7 5529.6 5589.7 5084.6 5128.0 5660.5 5701.0 5770.7 4457.1 4996.4 5231.6 5370.4 5376.2 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - PROCESS | 2302 | [#5] yui.html Cycles:180 Average:24656.15 Median:12738.71 stddev:38947.99 (305.7%) stddev-sans-first:39015.76 21:34:47 INFO - PROCESS | 2302 | Values: 714.5 727.7 763.0 792.8 815.7 15419.6 16119.5 16180.4 16201.1 16204.4 14412.3 14437.3 14522.6 14581.4 14659.1 681.0 690.9 827.1 848.6 861.1 819.1 830.3 831.4 832.2 834.7 878.2 879.7 880.3 881.3 882.7 713.9 872.7 877.8 882.0 894.0 675.5 678.3 678.3 679.6 679.8 14598.6 14635.0 14637.0 14637.6 14675.6 13403.2 13620.7 13745.7 13750.2 13927.2 12335.8 12416.4 12456.2 12910.0 13008.9 4383.3 4437.0 4467.5 4513.9 4694.9 103815.5 106259.0 117329.4 118737.4 119010.2 10842.8 10886.6 10922.3 10935.6 11319.9 21847.8 21955.2 22005.4 22012.0 22156.2 7667.5 7681.3 7689.8 7714.2 7886.7 4045.0 4071.4 4082.1 4086.1 4132.1 3321.2 3424.6 3451.9 3452.4 3457.7 629.6 631.2 645.4 645.5 649.8 589.2 613.7 620.7 627.7 628.7 7914.8 8189.3 8334.4 9935.4 10037.6 650.9 660.4 665.5 680.5 684.3 4546.3 4546.8 4572.9 4624.2 4651.9 117587.8 148292.3 148518.5 149604.0 152770.0 125550.0 126059.7 127020.2 129678.7 132349.0 101357.5 101947.5 106312.9 106695.5 109520.9 9290.0 9357.4 9677.5 9747.9 9861.0 58548.1 59276.0 59862.0 60235.0 60561.0 84607.6 89675.7 92134.1 92955.0 98756.0 13013.5 13112.7 13252.9 13399.2 13644.2 14077.8 14584.3 14742.8 14873.3 15074.8 13298.5 13924.9 14105.0 14132.8 14400.6 14099.4 14175.2 14370.1 14374.8 14682.5 13603.7 13633.8 13744.9 14346.3 14471.0 11028.5 11218.8 11448.7 11531.2 11560.6 10133.4 10633.4 11251.8 11633.8 11645.5 21:34:47 INFO - PROCESS | 2302 | -------- Summary: end -------- 21:34:47 INFO - PROCESS | 2302 | 21:34:47 INFO - TEST-INFO | 2302: exit 0 21:34:47 INFO - TEST-OK | dromaeo_css | took 1117309ms 21:34:47 INFO - TEST-START | kraken 21:34:47 INFO - operating with platform_type : mac_ 21:34:47 INFO - Initialising browser for kraken test... 21:34:47 INFO - TEST-INFO | started process 2306 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpE7ZLtg/profile http://localhost:53421/getInfo.html) 21:34:50 INFO - PROCESS | 2306 | __metrics Screen width/height:1600/1200 21:34:50 INFO - PROCESS | 2306 | colorDepth:24 21:34:50 INFO - PROCESS | 2306 | Browser inner width/height: 1024/689 21:34:50 INFO - PROCESS | 2306 | __metrics 21:34:50 INFO - PROCESS | 2306 | 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" 21:34:50 INFO - PROCESS | 2306 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:34:51 INFO - TEST-INFO | 2306: exit 0 21:34:51 INFO - Browser initialized. 21:34:51 INFO - Running cycle 1/1 for kraken test... 21:34:51 INFO - TEST-INFO | started process 2307 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpE7ZLtg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:35:03 INFO - PROCESS | 2307 | RSS: Main: 232374272 21:35:03 INFO - PROCESS | 2307 | 21:35:05 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53421/tests/kraken/driver.html?testName=audio-beat-detection) 21:35:06 INFO - PROCESS | 2307 | RSS: Main: 292143104 21:35:06 INFO - PROCESS | 2307 | 21:35:09 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53421/tests/kraken/driver.html?testName=audio-dft) 21:35:10 INFO - PROCESS | 2307 | RSS: Main: 287744000 21:35:10 INFO - PROCESS | 2307 | 21:35:13 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53421/tests/kraken/driver.html?testName=audio-fft) 21:35:14 INFO - PROCESS | 2307 | RSS: Main: 376299520 21:35:14 INFO - PROCESS | 2307 | 21:35:16 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53421/tests/kraken/driver.html?testName=audio-oscillator) 21:35:17 INFO - PROCESS | 2307 | RSS: Main: 278355968 21:35:17 INFO - PROCESS | 2307 | 21:35:19 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53421/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:35:19 INFO - PROCESS | 2307 | RSS: Main: 282361856 21:35:19 INFO - PROCESS | 2307 | 21:35:22 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53421/tests/kraken/driver.html?testName=imaging-darkroom) 21:35:23 INFO - PROCESS | 2307 | RSS: Main: 322555904 21:35:23 INFO - PROCESS | 2307 | 21:35:26 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53421/tests/kraken/driver.html?testName=imaging-desaturate) 21:35:26 INFO - PROCESS | 2307 | RSS: Main: 321724416 21:35:26 INFO - PROCESS | 2307 | 21:35:29 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53421/tests/kraken/driver.html?testName=json-parse-financial) 21:35:30 INFO - PROCESS | 2307 | RSS: Main: 320184320 21:35:30 INFO - PROCESS | 2307 | 21:35:31 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53421/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:35:32 INFO - PROCESS | 2307 | RSS: Main: 297529344 21:35:32 INFO - PROCESS | 2307 | 21:35:34 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:35:34 INFO - PROCESS | 2307 | RSS: Main: 302125056 21:35:34 INFO - PROCESS | 2307 | 21:35:37 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:35:37 INFO - PROCESS | 2307 | RSS: Main: 303812608 21:35:37 INFO - PROCESS | 2307 | 21:35:40 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:35:41 INFO - PROCESS | 2307 | RSS: Main: 319664128 21:35:41 INFO - PROCESS | 2307 | 21:35:44 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:35:44 INFO - PROCESS | 2307 | RSS: Main: 315736064 21:35:44 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53421/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53421/tests/kraken/driver.html?testName=ai-astar) 21:35:46 INFO - PROCESS | 2307 | RSS: Main: 359428096 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | __start_tp_report 21:35:46 INFO - PROCESS | 2307 | _x_x_mozilla_page_load 21:35:46 INFO - PROCESS | 2307 | _x_x_mozilla_page_load_details 21:35:46 INFO - PROCESS | 2307 | |i|pagename|runs| 21:35:46 INFO - PROCESS | 2307 | |0;ai-astar;114;114;118;114;113;114;114;113;112;124 21:35:46 INFO - PROCESS | 2307 | |1;audio-beat-detection;147;116;130;163;106;130;128;105;161;108 21:35:46 INFO - PROCESS | 2307 | |2;audio-dft;185;205;199;247;266;206;185;182;184;183 21:35:46 INFO - PROCESS | 2307 | |3;audio-fft;78;72;77;157;72;83;81;93;144;58 21:35:46 INFO - PROCESS | 2307 | |4;audio-oscillator;83;82;140;70;71;73;120;68;69;119 21:35:46 INFO - PROCESS | 2307 | |5;imaging-gaussian-blur;88;87;124;71;109;118;134;85;89;84 21:35:46 INFO - PROCESS | 2307 | |6;imaging-darkroom;94;94;95;95;95;95;94;95;94;94 21:35:46 INFO - PROCESS | 2307 | |7;imaging-desaturate;85;83;84;86;84;86;86;87;85;82 21:35:46 INFO - PROCESS | 2307 | |8;json-parse-financial;54;53;55;54;53;53;55;53;54;53 21:35:46 INFO - PROCESS | 2307 | |9;json-stringify-tinderbox;67;65;80;78;72;67;70;69;71;71 21:35:46 INFO - PROCESS | 2307 | |10;stanford-crypto-aes;88;86;85;85;88;85;86;84;89;85 21:35:46 INFO - PROCESS | 2307 | |11;stanford-crypto-ccm;142;144;160;139;138;140;139;138;180;140 21:35:46 INFO - PROCESS | 2307 | |12;stanford-crypto-pbkdf2;168;170;170;170;170;170;170;170;171;169 21:35:46 INFO - PROCESS | 2307 | |13;stanford-crypto-sha256-iterative;71;66;68;68;67;68;83;87;84;85 21:35:46 INFO - PROCESS | 2307 | __end_tp_report 21:35:46 INFO - PROCESS | 2307 | __start_cc_report 21:35:46 INFO - PROCESS | 2307 | _x_x_mozilla_cycle_collect,1992 21:35:46 INFO - PROCESS | 2307 | __end_cc_report 21:35:46 INFO - PROCESS | 2307 | __startTimestamp1473222946976__endTimestamp 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | ------- Summary: start ------- 21:35:46 INFO - PROCESS | 2307 | Number of tests: 14 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#0] ai-astar Cycles:10 Average:115.00 Median:114.00 stddev:3.53 (3.1%) stddev-sans-first:3.72 21:35:46 INFO - PROCESS | 2307 | Values: 114.0 114.0 118.0 114.0 113.0 114.0 114.0 113.0 112.0 124.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:130.00 stddev:21.65 (16.7%) stddev-sans-first:22.01 21:35:46 INFO - PROCESS | 2307 | Values: 147.0 116.0 130.0 163.0 106.0 130.0 128.0 105.0 161.0 108.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#2] audio-dft Cycles:10 Average:204.20 Median:202.00 stddev:29.38 (14.5%) stddev-sans-first:30.33 21:35:46 INFO - PROCESS | 2307 | Values: 185.0 205.0 199.0 247.0 266.0 206.0 185.0 182.0 184.0 183.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#3] audio-fft Cycles:10 Average:91.50 Median:77.50 stddev:32.50 (41.9%) stddev-sans-first:34.10 21:35:46 INFO - PROCESS | 2307 | Values: 78.0 72.0 77.0 157.0 72.0 83.0 81.0 93.0 144.0 58.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:70.50 stddev:26.51 (37.6%) stddev-sans-first:28.02 21:35:46 INFO - PROCESS | 2307 | Values: 83.0 82.0 140.0 70.0 71.0 73.0 120.0 68.0 69.0 119.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#5] imaging-gaussian-blur Cycles:10 Average:98.90 Median:84.50 stddev:20.77 (24.6%) stddev-sans-first:21.65 21:35:46 INFO - PROCESS | 2307 | Values: 88.0 87.0 124.0 71.0 109.0 118.0 134.0 85.0 89.0 84.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 21:35:46 INFO - PROCESS | 2307 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.55 (1.8%) stddev-sans-first:1.64 21:35:46 INFO - PROCESS | 2307 | Values: 85.0 83.0 84.0 86.0 84.0 86.0 86.0 87.0 85.0 82.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 21:35:46 INFO - PROCESS | 2307 | Values: 54.0 53.0 55.0 54.0 53.0 53.0 55.0 53.0 54.0 53.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:4.76 (6.7%) stddev-sans-first:4.82 21:35:46 INFO - PROCESS | 2307 | Values: 67.0 65.0 80.0 78.0 72.0 67.0 70.0 69.0 71.0 71.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.62 21:35:46 INFO - PROCESS | 2307 | Values: 88.0 86.0 85.0 85.0 88.0 85.0 86.0 84.0 89.0 85.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:141.00 stddev:13.62 (9.7%) stddev-sans-first:14.37 21:35:46 INFO - PROCESS | 2307 | Values: 142.0 144.0 160.0 139.0 138.0 140.0 139.0 138.0 180.0 140.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.00 stddev:0.79 (0.5%) stddev-sans-first:0.50 21:35:46 INFO - PROCESS | 2307 | Values: 168.0 170.0 170.0 170.0 170.0 170.0 170.0 170.0 171.0 169.0 21:35:46 INFO - PROCESS | 2307 | 21:35:46 INFO - PROCESS | 2307 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:77.00 stddev:8.79 (11.4%) stddev-sans-first:9.23 21:35:46 INFO - PROCESS | 2307 | Values: 71.0 66.0 68.0 68.0 67.0 68.0 83.0 87.0 84.0 85.0 21:35:46 INFO - PROCESS | 2307 | -------- Summary: end -------- 21:35:46 INFO - PROCESS | 2307 | 21:35:47 INFO - TEST-INFO | 2307: exit 0 21:35:47 INFO - TEST-OK | kraken | took 60023ms 21:35:47 INFO - SUITE-END | took 1177s 21:35:48 INFO - Completed test suite (00:19:37) 21:35:48 INFO - javascript benchmark 21:35:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.6359400465944, 3327.1848397142694, 3327.7005069543734, 3377.1388295984552, 3407.148212946763, 5668.461496157869, 5720.0, 5756.251687280658, 5860.5311575074, 5862.0, 5790.30516338039, 5835.941640583593, 5838.883222335561, 5878.059510478324, 5882.617629854057, 5175.275461435402, 5187.533122019017, 5285.02227087988, 5290.523852853243, 5305.283786688806, 4849.442314133867, 5033.84898453047, 5334.546563542084, 5366.9463305367035, 5383.327084114486, 5177.249298851645, 5206.193040078794, 5210.76551555181, 5273.683578985254, 5296.470352964711, 5222.373315202162, 5235.424103348629, 5247.10799164143, 5346.732663366816, 5368.70472124033, 4373.2565463871215, 5149.201873709581, 5219.478052194788, 5225.2162175673575, 5269.710165940872, 5847.707614619252, 5883.146943693183, 5886.676232807194, 5896.1450589664255, 5913.5957947763445, 6139.938600613981, 6148.600340977822, 6189.659568723719, 6190.938090619081, 6199.938000619981, 6217.813465596039, 6245.906311405332, 6264.937350626481, 6273.309935907047, 6282.811515654537, 5976.671283079429, 6006.759729610767, 6008.399160083956, 6014.8195554133445, 6025.0661147522205, 6154.661493617894, 6175.073738939105, 6213.409726076015, 6218.129461875341, 6261.311255761862, 6126.172966649535, 6127.816165515042, 6132.110843927606, 6154.169187159766, 6163.630182189083, 6144.07838824171, 6193.256809182927, 6198.6280823150755, 6199.628022318675, 6222.253329600477, 6112.74665817949, 6144.863200307957, 6166.290876549165, 6200.131981522591, 6240.095186198067, 6154.815355539252, 6198.969005154945, 6199.3490683478485, 6206.627602343874, 6217.191765070603, 5069.340985671839, 5076.0, 5084.720340381315, 5099.23511473282, 5099.8725031873755, 3838.980805095957, 4465.687401881837, 4494.77526123699, 4495.0, 4572.679912406166, 5928.970355148197, 5956.225690660273, 5959.553033522503, 5972.895014422259, 5985.34161242263, 6216.968915155486, 6248.0, 6255.0, 6259.499240060784, 6282.654454005074, 5751.7411716472025, 5757.107648314434, 5788.363280039276, 5906.970465147647, 5938.8812223755285, 5455.454454554513, 5477.260569823103, 5506.834794956158, 5515.696636684941, 5527.778888844401, 6432.839179020562, 6440.967795160994, 6442.387973142544, 6447.258565265054, 6448.065030570541, 6210.068489726577, 6262.74949002053, 6265.937340626535, 6273.9372606273355, 6279.30927597973, 6206.813795586139, 6293.716782744879, 6414.326495718069, 6426.4537514311805, 6439.935600644121, 6232.407921247474, 6257.7184026719815, 6289.465395441439, 6293.55945083858, 6299.149614802126, 6205.875882482415, 6240.282367527792, 6242.65665388408, 6262.3737626238835, 6287.622742635456, 6094.268687757498, 6132.325444201045, 6229.283632382334, 6243.719032643451, 6250.593711408744, 6068.787592434243, 6095.963686173272, 6134.436587033563, 6159.846003849761, 6162.014077747539, 6021.969890150521, 6025.548083893813, 6036.034234522455, 6045.516358691206, 6078.422549857756, 6142.385761423822, 6145.354737752472, 6178.443940045418, 6198.194234749454, 6223.37766223374, 5894.2926848778425, 5921.911171332347, 6044.335123136363, 6082.118092876596, 6093.177421048102], "value": 5754.287464625153, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.29205254040124, 480.6491261379193, 483.63968843211813, 529.3594750352074, 529.757718150936, 30709.84645076774, 34386.8280658597, 41282.587174128246, 42061.15877682444, 44132.0, 43448.0, 43688.0, 43786.34320485187, 43829.56170438295, 43842.342364864555, 8896.421732587361, 9850.655227067055, 10018.24863135264, 10020.248481363891, 10036.29745917786, 12144.635660930186, 12354.196977196498, 12367.505299787998, 12600.0, 12616.306103164323, 12298.262104273772, 12363.938180309086, 12392.132550721408, 12408.379581020958, 12435.813462798065, 12390.628281151536, 12447.875521244761, 12461.501539938437, 12473.93763031179, 12483.625491235278, 12193.024558035424, 12224.0, 12226.572069977597, 12227.266364018187, 12241.755164896742, 40369.19261614751, 40733.388999165036, 40736.38895416571, 40754.184916301805, 40795.796021019705, 3361.848716807725, 3523.330567192251, 3581.28374325136, 3786.772793632391, 3964.306246406896, 30615.38769224603, 30971.845140774378, 31370.52944205839, 31559.21101972469, 32168.874089406992, 3160.2415420299194, 3181.3637272545584, 3224.9355012899846, 3310.586176727909, 3312.96687033129, 518235.0, 524373.0, 524714.0, 524851.0, 525036.0, 62538.0, 62902.68548657227, 62920.0, 62926.37073629296, 63063.0, 28897.85551072231, 29654.0, 35051.0, 66016.68941587022, 66327.66836165788, 13973.441062357595, 16386.508404747878, 16691.24889379981, 16704.582385440463, 16710.248038838283, 11511.19421640477, 11779.293242405483, 12590.874091259153, 12599.433025513874, 12880.484780608858, 19101.426957191306, 19502.43980481558, 19502.512437189183, 19515.902420487808, 19527.902360488108, 7976.7208147714555, 8107.270345668918, 8173.713920012771, 8190.180981901762, 8198.877016844752, 843.1905370844058, 933.3093510801957, 958.678842587741, 970.3353203055788, 1076.0476977874637, 29517.557236641467, 30281.545776813364, 30424.54363184554, 30646.41356930993, 30744.231394215323, 1000.2997901469001, 1029.3206483720708, 1084.8264277715527, 1115.893990070942, 1191.1661836714334, 15718.68562628741, 15744.370225191093, 15761.842381576267, 15798.526044218692, 16249.431269905497, 679275.0, 683268.0, 685466.0, 686069.0, 687024.0, 59254.0, 59602.0, 59782.701086494286, 59885.70057149686, 59921.0, 1239.3988915376035, 1359.4358341288255, 1375.3192169875776, 1399.1255465334166, 1407.6410515318528, 30688.465576720628, 31731.841340793148, 31828.36343273168, 31902.0, 32713.018609441755, 50052.749736251084, 50630.74684626701, 50705.49294507155, 50766.74616626893, 50774.238386423494, 433002.0, 553889.0, 559690.0, 560945.0, 560973.0, 44896.55103449054, 44974.77512612547, 45794.0, 46208.76895615501, 47778.0, 47038.52961470342, 48474.788130295616, 49275.50724492709, 49675.00649987052, 50330.99338013146, 40778.59221407748, 41361.79319103385, 41505.0, 42387.364189536565, 42704.78647606742, 40130.0, 40294.39558406568, 40450.39324410077, 40875.59124408837, 41296.0, 39580.0, 40121.77807994472, 40327.3950890731, 40583.59416405916, 40706.0, 27997.160085197476, 33765.83117084399, 36197.7330793421, 36348.818255908554, 36398.0, 20867.478313042295, 25301.240962771146, 25516.234512964642, 25625.487490250463, 25762.227133186036], "value": 21017.85314888264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.6217454062342, 366.97614655047437, 369.2559492622366, 378.52684144818966, 397.33644813162016, 14750.0, 15338.53984380469, 19955.800441995576, 20644.587108257827, 20665.793342066558, 20259.088341024653, 20600.484987875272, 20631.484212894647, 20681.37955861323, 20717.203177571024, 5841.853953651172, 5843.328017278003, 6099.115628233903, 6101.755929762804, 6122.173506576622, 6011.158437818709, 6159.9384006160035, 6171.290301615327, 6171.321154672983, 6173.660448675343, 5917.260342457193, 6112.327643959161, 6139.140520327135, 6166.383361663842, 6200.410960958701, 6175.351588083254, 6192.690365481708, 6195.0, 6208.255009398901, 6208.379162083801, 5537.0, 5616.494515493625, 5762.020456522401, 6053.545984051169, 6071.392860713938, 13121.47514099435, 13130.803037954438, 13183.340832958314, 13348.933255333759, 13418.26199559024, 2131.3712454825863, 2206.878621675807, 2257.164849005862, 2265.9433514162115, 2348.4481146930457, 10076.193904487624, 10340.172786177163, 10505.212109091848, 13265.86734132656, 14728.852711472855, 1567.9529614111595, 1858.5539470527112, 1863.925442982279, 1864.2263460663808, 1914.444811004807, 121017.0, 125396.0, 125456.37271813583, 128027.35986320103, 128312.0, 10957.83563246552, 11378.431078446045, 11514.136439767051, 11519.0, 11670.94164529172, 14134.010619256758, 14153.787693184611, 14165.079269847427, 14181.0, 14206.573802785933, 8158.306543943712, 8478.788030299293, 8482.363822713321, 8624.698135565224, 8636.093210212963, 6819.238208525555, 7583.658735356922, 8431.325493960469, 8619.525926074, 8623.439476434012, 3942.4086387042175, 4435.179491794029, 4446.911061778746, 4508.256137737237, 4545.840895568639, 1837.2834594508151, 1885.9811401885806, 1894.4316704988723, 1896.4310706787908, 1897.193692680633, 831.9001719793664, 848.1645579104577, 869.8086420987372, 942.2320808541017, 968.4479846487575, 16056.0, 17088.487345379657, 17808.46574602764, 21226.575468490544, 21457.03443345022, 928.8792456980549, 937.1425145991478, 938.6339327662217, 946.9195118414874, 967.579103090158, 10761.192910531741, 11231.0, 11319.490622921989, 11359.716007099889, 11365.204435689588, 585877.0, 586415.0, 595678.0, 595749.0, 601157.0, 83188.0, 102001.2080128185, 134989.0, 141581.29209354092, 143771.5622843779, 890.2343984173606, 980.6910823090703, 1014.3609525998573, 1017.1913328903539, 1017.2370721958531, 19430.319938802073, 19696.0, 19857.801421986172, 19919.0, 19958.600827983068, 45596.54403455923, 54728.0, 58305.12542311784, 58649.12026319694, 59425.92009279654, 85165.0, 85422.0, 86335.0, 87295.56352218453, 87353.56323218597, 7790.415718821225, 7841.5687137208, 7958.204179582227, 8190.0171979360475, 8195.71315003972, 12398.380080996094, 12922.095453318554, 13258.80111798343, 13695.520656776966, 13733.656658583215, 9213.0, 11230.887691123311, 11374.772504550028, 11674.124440666814, 11726.648200553997, 11039.172062095535, 11096.88903110991, 11419.657410277705, 11434.0, 11741.706457338634, 10746.0, 11744.58893938708, 11911.821322679993, 11958.701032474257, 12093.637190884288, 6288.99005226763, 7138.0, 7349.70601175947, 7963.721269755531, 8367.497950122768, 10304.433256170983, 11167.274127181707, 11487.597934072268, 11588.0, 11614.70963225926], "value": 9438.571050399134, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.0528674864954, 1581.533447632948, 1809.3305476973514, 1925.9133338999743, 1964.8428125749942, 8530.957345213274, 9072.455652660838, 9078.591463384148, 9078.909210907888, 9141.314401419886, 7959.522428654285, 8128.268455838974, 8650.394472386919, 8828.660404992683, 8834.646614135443, 2357.77601127893, 2399.184277345702, 2401.735809061002, 2404.6873906392143, 2412.445137618349, 2362.6456031595226, 2370.6207006878903, 2408.6748288981025, 2408.843425177358, 2411.8914648840755, 2377.5601513720017, 2386.2483317754936, 2393.5093305872256, 2397.4605713714363, 2399.592069348215, 2364.9408764780846, 2367.0886708617136, 2377.9286621401384, 2377.9524409511714, 2399.83201175916, 2353.882305884699, 2369.324742448394, 2374.8456350337174, 2376.7742064503846, 2379.1435083369975, 8892.199702026785, 8966.820663586692, 9054.0, 9063.546822658907, 9076.63693452261, 5267.0, 5439.102548079562, 5440.319960004999, 5442.972785136049, 5447.046766815792, 3557.8932632021083, 3673.632636736305, 3704.703623710124, 3738.6635202831603, 3763.41667041609, 1098.780243951213, 1101.0035917494681, 1108.8835672254377, 1112.7663190729957, 1125.5947858770835, 11759.47082381295, 11782.587609433629, 11868.88131118695, 11890.881091189063, 11907.404629768564, 3900.278448487039, 4518.209313370147, 4703.435587729495, 4709.081729062868, 4714.952850471452, 9214.63141474347, 9237.722868313962, 9239.0, 9240.815183696288, 9254.953725231331, 5296.629235953524, 5299.4700529946695, 5300.549453296436, 5302.019126461617, 5321.73391330432, 4411.889702757392, 4550.13547425988, 4772.880677983078, 4888.2178851383605, 4960.379952505937, 3768.6231376862643, 3793.2223894102194, 3899.29812633723, 3922.490076290121, 3935.4883865097354, 1183.597576823884, 1185.8161984892356, 1193.8567371915426, 1198.2151690642643, 1202.6271855724754, 888.9110839221902, 913.2100732866023, 933.4725879877848, 934.9579268932914, 977.5063592885547, 5561.971035358471, 5678.835838653066, 5947.1079338099635, 6091.908621370683, 6140.232470941132, 435.7102526819693, 451.85314772699, 453.65748859610784, 454.27543068805863, 457.9931301030487, 3924.274009308287, 3949.6445319921345, 4034.253663072341, 4047.4131250968444, 4071.9796401017807, 10304.587816487407, 10338.689839304832, 10625.096866766411, 10772.569097236179, 10802.297850639683, 10911.672649820519, 10924.071453926505, 10930.56277748897, 10935.671929842118, 10935.890641093647, 7743.225677432211, 8214.63034163464, 8326.999999999878, 8344.0, 8355.248027677417, 5932.555058370725, 5978.133170690313, 6065.5754097212775, 6141.785037523665, 6190.721417536131, 9983.400995940267, 10144.797104058025, 10175.898241017496, 10203.591856325664, 10239.7440063999, 10100.141487973604, 10522.736931576466, 10527.210459215436, 10539.051485366354, 10540.947295263475, 5755.366909639853, 7070.36366726997, 7297.416206703557, 7555.09338879338, 7687.11598166218, 8254.54599997006, 8578.78553036179, 8589.613467394107, 8592.570371481526, 8613.009503907131, 7057.6471176442, 7138.714451421885, 7139.535930164523, 7217.0, 7322.048133742793, 7335.706571737071, 7404.555726656418, 7407.962960185165, 7417.517861338995, 7464.253574642492, 6976.651167441709, 6978.581285122909, 7057.2237053925055, 7058.894116588152, 7062.858742825218, 5010.87472813168, 5042.873928151826, 5056.443791182894, 5125.308083408692, 5200.557952573922, 5021.1464051112125, 5046.268291097844, 5176.275321455075, 5199.376074871039, 5304.098303288527], "value": 4530.607494699809, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.6890420537408, 282.809103854898, 290.25694222789656, 292.6415141451722, 295.3575972260333, 5406.864558442727, 5409.350877894653, 5436.972815135924, 5497.230387745709, 5522.0, 4087.6729861611075, 4544.341070544778, 5152.140561988104, 5183.2743415921705, 5370.838874833751, 3287.8684852605866, 3473.531073305097, 3820.2741479119018, 3977.0, 4017.774578753479, 4046.3525835866267, 4047.170330082326, 4071.2875246831836, 4099.959000409987, 4107.507099148106, 3908.7068469864876, 3969.0077480629843, 4032.8790136295956, 4046.0, 4067.1458993611377, 4019.376996565537, 4026.697997650159, 4032.495938007764, 4050.8784736457956, 4052.49343832021, 3507.684308412255, 3509.666581674737, 3527.6594893940396, 3543.2204914918934, 3578.731595130376, 5027.949720502821, 5182.300389447414, 5250.527452529291, 5253.423972808142, 5259.211118332242, 4549.294014744471, 4589.839355622537, 4674.392328997209, 4678.298255261704, 4710.29345598163, 4980.676256043345, 5220.720923373759, 5671.432856714337, 5704.258446401983, 5717.428257174291, 3848.5958974307855, 3852.6339997700175, 3862.555806082308, 3874.1089549403514, 3879.3792993121247, 5620.995869097369, 5813.476787089182, 5892.705364731746, 5908.320543137508, 5979.2824861016525, 3894.5131858517684, 3894.941575876364, 3914.9804250978564, 3915.786106307054, 3919.647231749156, 3829.617038296148, 3991.960080399217, 3993.4409182714444, 4023.979880100639, 4034.878953631337, 4010.5187377514303, 4470.776461176928, 4476.686631935799, 4575.702579332333, 4702.435707715096, 5611.579131565149, 5620.241267428926, 5620.97189514058, 5626.690532020778, 5651.9152212716845, 1197.5269768441592, 1308.234682710606, 1311.567182829664, 1359.0894100952385, 1372.389286767399, 837.4933165434946, 848.5460278750838, 848.6011574559947, 895.8253140637513, 915.1534830281962, 4865.659403841768, 5129.743512824344, 5238.240455133984, 5260.316158899395, 5289.9471005290225, 5559.249501317351, 5760.683162426107, 5769.682667453247, 5786.623869448473, 5798.9130163047585, 5041.873953151127, 5187.610929180327, 5282.498162674539, 5355.884425651846, 5433.483819037185, 2577.7422257774074, 2672.759451649361, 2818.718128187165, 3049.9085027449214, 3095.4582947984013, 5559.833205003856, 5642.971785141048, 5988.550858685616, 5989.311229208697, 6060.515158787286, 5243.0, 5861.384554621789, 6043.818685439356, 6074.058520929175, 6143.020634992025, 4543.5002149763495, 5052.343195384577, 5379.386184144819, 6122.571420000557, 6179.258488981351, 5653.851536416136, 5822.195119512964, 5860.09168578871, 5916.615419997686, 5937.139114828239, 4893.951060489349, 5841.474267315792, 5877.529797616266, 5901.5868889177345, 5920.703964801656, 5720.0, 5770.163326317745, 5867.826434712989, 6057.24339941412, 6141.90222115634, 3734.8506059757306, 3806.7144964128356, 3883.6310550497656, 3885.9896426928635, 3914.784686842251, 5759.510441612342, 5781.942180578309, 5826.737796799239, 5961.989195456267, 5995.400459954144, 4294.82820687169, 4707.0, 5162.277281180563, 5515.669059856422, 5784.82645520635, 5171.689698618095, 5590.692511911884, 5665.716714164193, 5733.655980641175, 5736.311642602915, 4120.793960302033, 5333.066713325075, 5366.651167674088, 5529.585281104014, 5589.692566908697, 5084.6440749147205, 5128.0, 5660.547156227574, 5701.030824759718, 5770.711464426678, 4457.105730064774, 4996.3504744383, 5231.607629427732, 5370.382406023357, 5376.247325374373], "value": 4115.434966017194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5022085968823, 727.7234650832683, 762.9966593928983, 792.8216151365947, 815.7471183932979, 15419.614509637251, 16119.516414507583, 16180.433684821033, 16201.10893900835, 16204.351825926964, 14412.279386030683, 14437.27813609318, 14522.564323070299, 14581.416743330281, 14659.120452772815, 681.0295329155933, 690.9274526174754, 827.1397746343811, 848.6435697007241, 861.1087524412235, 819.1194465949098, 830.3274347778289, 831.4304701279643, 832.1595188859235, 834.7287131682198, 878.1701292278799, 879.705298724928, 880.3221519430035, 881.2685471059037, 882.6822343956171, 713.8679344321265, 872.7207293665998, 877.7673916412156, 882.0253619750212, 893.9955300223457, 675.4967549175847, 678.281022116558, 678.2877978122951, 679.5956405938458, 679.8198477403492, 14598.562043138723, 14635.0, 14637.0, 14637.560873173821, 14675.559733208021, 13403.195808251536, 13620.65948351299, 13745.656358591115, 13750.174989500561, 13927.234002129879, 12335.814962775565, 12416.441260143316, 12456.190347627375, 12910.0, 13008.850438539806, 4383.298672212462, 4437.023854751949, 4467.463904331501, 4513.887152821173, 4694.906101877943, 103815.48092259566, 106259.0, 117329.41335293354, 118737.40631296788, 119010.21484677737, 10842.783144337227, 10886.61896833607, 10922.344659320466, 10935.56257749697, 11319.886801132048, 21847.781522184894, 21955.231566895083, 22005.449863753533, 22012.0, 22156.2245321413, 7667.501612395176, 7681.308682218627, 7689.846203075907, 7714.190010048977, 7886.72396466121, 4045.0089728016824, 4071.4096456013713, 4082.081531655413, 4086.1010577672864, 4132.090939993257, 3321.186309354224, 3424.5890493140982, 3451.861925522951, 3452.430348992438, 3457.7233821294235, 629.6127881352935, 631.2404470293932, 645.3546453546453, 645.5352146454545, 649.8407890066964, 589.1604463639297, 613.7146227004464, 620.7423919073534, 627.6861569215392, 628.6825153297556, 7914.802129946682, 8189.262966333059, 8334.374921880883, 9935.391241923877, 10037.629957213849, 650.8531251399536, 660.4155322539588, 665.4842497064814, 680.4930326906388, 684.2712511175681, 4546.318052292183, 4546.818127274938, 4572.9314060289125, 4624.190766615829, 4651.906961860744, 117587.8241217594, 148292.2585387066, 148518.51481485047, 149604.0, 152770.0, 125550.0, 126059.7394026048, 127020.2310549434, 129678.70321296855, 132349.0, 101357.49321253346, 101947.49026254969, 106312.87374252471, 106695.46652266689, 109520.90479095267, 9289.953550232205, 9357.39176953496, 9677.467739274125, 9747.902520974983, 9861.0, 58548.121778172506, 59276.0, 59862.0, 60235.0, 60561.0, 84607.57696211479, 89675.70716551253, 92134.07865921255, 92955.0, 98756.0, 13013.47946082184, 13112.672183195114, 13252.867471325164, 13399.196048237138, 13644.249566273951, 14077.788833167306, 14584.343704533534, 14742.778858317348, 14873.330700118304, 15074.849251507345, 13298.534551290659, 13924.860751392356, 14105.012649114464, 14132.78800818009, 14400.639984000483, 14099.365528551034, 14175.22036288014, 14370.137791732112, 14374.784378234544, 14682.486112985995, 13603.727925441237, 13633.79549306781, 13744.86255137436, 14346.282685865457, 14471.0, 11028.5037173329, 11218.775624487627, 11448.713782155179, 11531.192816502764, 11560.595379161688, 10133.44266065344, 10633.361998280128, 11251.765080249053, 11633.825492617449, 11645.475953582278], "value": 7826.297986268852, "unit": "score"}], "name": "dromaeo_css", "value": 7417.83339393345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 114.0, 113.0, 114.0, 114.0, 113.0, 112.0, 124.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 130.0, 163.0, 106.0, 130.0, 128.0, 105.0, 161.0, 108.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 205.0, 199.0, 247.0, 266.0, 206.0, 185.0, 182.0, 184.0, 183.0], "value": 204.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 77.0, 157.0, 72.0, 83.0, 81.0, 93.0, 144.0, 58.0], "value": 91.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 140.0, 70.0, 71.0, 73.0, 120.0, 68.0, 69.0, 119.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 124.0, 71.0, 109.0, 118.0, 134.0, 85.0, 89.0, 84.0], "value": 98.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 86.0, 84.0, 86.0, 86.0, 87.0, 85.0, 82.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 54.0, 53.0, 53.0, 55.0, 53.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 80.0, 78.0, 72.0, 67.0, 70.0, 69.0, 71.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 85.0, 88.0, 85.0, 86.0, 84.0, 89.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 144.0, 160.0, 139.0, 138.0, 140.0, 139.0, 138.0, 180.0, 140.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 170.0, 170.0, 170.0, 170.0, 170.0, 170.0, 171.0, 169.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 68.0, 68.0, 67.0, 68.0, 83.0, 87.0, 84.0, 85.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1509.1, "alertThreshold": 2.0}]} 21:35:48 INFO - Return code: 0 21:35:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:35:48 INFO - # TBPL SUCCESS # 21:35:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:35:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:35:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:35:48 INFO - 2016-09-06 21:35:48.238 screenresolution[2311:22080] starting screenresolution argv=screenresolution get 21:35:48 INFO - 2016-09-06 21:35:48.240 screenresolution[2311:22080] Display 0: 1600x1200x32@60 21:35:48 INFO - 2016-09-06 21:35:48.246 screenresolution[2312:22083] starting screenresolution argv=screenresolution list 21:35:48 INFO - Available Modes on Display 0 21:35:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:35:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:35:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:35:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:35:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:35:48 INFO - Intel Iris: 21:35:48 INFO - Chipset Model: Intel Iris 21:35:48 INFO - Type: GPU 21:35:48 INFO - Bus: Built-In 21:35:48 INFO - VRAM (Dynamic, Max): 1536 MB 21:35:48 INFO - Vendor: Intel (0x8086) 21:35:48 INFO - Device ID: 0x0a2e 21:35:48 INFO - Revision ID: 0x0009 21:35:48 INFO - Displays: 21:35:48 INFO - Display: 21:35:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:35:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:35:48 INFO - Display Serial Number: 1600x1200 60 21:35:48 INFO - Main Display: Yes 21:35:48 INFO - Mirror: Off 21:35:48 INFO - Online: Yes 21:35:48 INFO - Rotation: Supported 21:35:48 INFO - Return code: 0 21:35:48 INFO - Running post-action listener: _resource_record_post_action 21:35:48 INFO - [mozharness: 2016-09-07 04:35:48.483215Z] Finished run-tests step (success) 21:35:48 INFO - Running post-run listener: _resource_record_post_run 21:35:48 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 765952; Write bytes: 702055424; Read time: 9; Write time: 1876 21:35:48 INFO - TinderboxPrint: CPU usage
53.4% 21:35:48 INFO - TinderboxPrint: I/O read bytes / time
765,952 / 9 21:35:48 INFO - TinderboxPrint: I/O write bytes / time
702,055,424 / 1,876 21:35:48 INFO - TinderboxPrint: CPU idle
2,257.6 (46.6%) 21:35:48 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 21:35:48 INFO - TinderboxPrint: CPU user
1,623.5 (33.5%) 21:35:48 INFO - TinderboxPrint: Swap in / out
406,409,216 / 0 21:35:48 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404380160; Write bytes: 398009856; Read time: 29725; Write time: 703 21:35:48 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 765952; Write bytes: 293334528; Read time: 9; Write time: 1143 21:35:48 INFO - Running post-run listener: _upload_blobber_files 21:35:48 INFO - Blob upload gear active. 21:35:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:35:48 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 21:35:48 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:35:48 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:35:49 INFO - (blobuploader) - INFO - Open directory for files ... 21:35:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:35:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:35:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:50 INFO - (blobuploader) - INFO - Done attempting. 21:35:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:35:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:35:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:51 INFO - (blobuploader) - INFO - Done attempting. 21:35:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:35:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:35:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:35:53 INFO - (blobuploader) - INFO - Done attempting. 21:35:53 INFO - (blobuploader) - INFO - Iteration through files over. 21:35:53 INFO - Return code: 0 21:35:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:35:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:35:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fee73768568fc65ddf718f9728d7a07fbe82c34289b70a8ae1bfe35d9e60846ac50fda6e4cbd7c074cba54955d9efc304235843545a37633eecede3048d6fd6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e3118c8459824c8116bfe93a41dee1aa31af61918ccb1d99bcebe3030dc9e4d4af9c0078122286d381382fc6dfb62f0ca473f8824149477dd59d11e3645810f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/df18c4adf7b3806c4cb7306de2f89a7c675a42a6f5bc6412d74047c1992f17c7cc5ef0fd5085b7cbec6a80d616a0a7fb24b10b2d62a43f7fc838945f750047e9"} 21:35:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:35:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:35:53 INFO - Contents: 21:35:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fee73768568fc65ddf718f9728d7a07fbe82c34289b70a8ae1bfe35d9e60846ac50fda6e4cbd7c074cba54955d9efc304235843545a37633eecede3048d6fd6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e3118c8459824c8116bfe93a41dee1aa31af61918ccb1d99bcebe3030dc9e4d4af9c0078122286d381382fc6dfb62f0ca473f8824149477dd59d11e3645810f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/df18c4adf7b3806c4cb7306de2f89a7c675a42a6f5bc6412d74047c1992f17c7cc5ef0fd5085b7cbec6a80d616a0a7fb24b10b2d62a43f7fc838945f750047e9"} 21:35:53 INFO - Running post-run listener: copy_logs_to_upload_dir 21:35:53 INFO - Copying logs to upload dir... 21:35:53 INFO - mkdir: /builds/slave/test/build/upload/logs 21:35:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.631560 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-06 21:35:53.985666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:35:53.989287) ========= 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.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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/Autoland/sha512/fee73768568fc65ddf718f9728d7a07fbe82c34289b70a8ae1bfe35d9e60846ac50fda6e4cbd7c074cba54955d9efc304235843545a37633eecede3048d6fd6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e3118c8459824c8116bfe93a41dee1aa31af61918ccb1d99bcebe3030dc9e4d4af9c0078122286d381382fc6dfb62f0ca473f8824149477dd59d11e3645810f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/df18c4adf7b3806c4cb7306de2f89a7c675a42a6f5bc6412d74047c1992f17c7cc5ef0fd5085b7cbec6a80d616a0a7fb24b10b2d62a43f7fc838945f750047e9"} build_url:https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011540 build_url: 'https://queue.taskcluster.net/v1/task/UQjKbFpKRKu4SHUzjl0a4w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fee73768568fc65ddf718f9728d7a07fbe82c34289b70a8ae1bfe35d9e60846ac50fda6e4cbd7c074cba54955d9efc304235843545a37633eecede3048d6fd6a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e3118c8459824c8116bfe93a41dee1aa31af61918ccb1d99bcebe3030dc9e4d4af9c0078122286d381382fc6dfb62f0ca473f8824149477dd59d11e3645810f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/df18c4adf7b3806c4cb7306de2f89a7c675a42a6f5bc6412d74047c1992f17c7cc5ef0fd5085b7cbec6a80d616a0a7fb24b10b2d62a43f7fc838945f750047e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 21:35:54.023646) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:35:54.024012) ========= 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.KzjFfilb88/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.HgMpb3Urug/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.005390 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 21:35:54.072996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:35:54.073362) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 21:35:54.073741) ========= ========= Total master_lag: 0.19 =========