builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0350 starttime: 1473414268.38 results: success (0) buildid: 20160909012519 builduid: f02b6e4478244e84a78787e56788637f revision: 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:28.375534) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:28.376513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:28.376879) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:28.530750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:28.531100) ========= 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.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-09 02:44:28-- 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% 18.5M=0.001s 2016-09-09 02:44:29 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.610330 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:29.164806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:29.165345) ========= 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.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.030085 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:29.219190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 02:44:29.219544) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec --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 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-09 02:44:29,310 truncating revision to first 12 chars 2016-09-09 02:44:29,310 Setting DEBUG logging. 2016-09-09 02:44:29,310 attempt 1/10 2016-09-09 02:44:29,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/50d2423ae823?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 02:44:30,559 unpacking tar archive at: autoland-50d2423ae823/testing/mozharness/ program finished with exit code 0 elapsedTime=1.515534 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 02:44:30.760259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:30.760802) ========= script_repo_revision: 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:30.761736) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:30.762078) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 02:44:30.781347) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 02:44:30.781949) ========= /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.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:44:30 INFO - MultiFileLogger online at 20160909 02:44:30 in /builds/slave/test 02:44:30 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 02:44:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:30 INFO - {'append_to_log': False, 02:44:30 INFO - 'base_work_dir': '/builds/slave/test', 02:44:30 INFO - 'blob_upload_branch': 'Autoland', 02:44:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:30 INFO - 'branch': 'Autoland', 02:44:30 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:30 INFO - 'config_files': ('talos/mac_config.py',), 02:44:30 INFO - 'default_actions': ('clobber', 02:44:30 INFO - 'read-buildbot-config', 02:44:30 INFO - 'download-and-extract', 02:44:30 INFO - 'populate-webroot', 02:44:30 INFO - 'create-virtualenv', 02:44:30 INFO - 'install', 02:44:30 INFO - 'run-tests'), 02:44:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:30 INFO - 'download_minidump_stackwalk': True, 02:44:30 INFO - 'download_symbols': 'ondemand', 02:44:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:44:30 INFO - 'tooltool.py': '/tools/tooltool.py', 02:44:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:44:30 INFO - '/tools/misc-python/virtualenv.py')}, 02:44:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:30 INFO - 'installer_path': 'installer.exe', 02:44:30 INFO - 'log_level': 'info', 02:44:30 INFO - 'log_name': 'talos', 02:44:30 INFO - 'log_to_console': True, 02:44:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:44:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:44:30 INFO - 'opt_config_files': (), 02:44:30 INFO - 'pip_index': False, 02:44:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:30 INFO - 'cmd': ('bash', 02:44:30 INFO - '-c', 02:44:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:30 INFO - 'enabled': True, 02:44:30 INFO - 'halt_on_failure': False, 02:44:30 INFO - 'name': 'check_screen_resolution'},), 02:44:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:30 INFO - 'cmd': ('bash', 02:44:30 INFO - '-c', 02:44:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:30 INFO - 'enabled': True, 02:44:30 INFO - 'halt_on_failure': False, 02:44:30 INFO - 'name': 'check_screen_resolution'},), 02:44:30 INFO - 'run_cmd_checks_enabled': True, 02:44:30 INFO - 'sps_profile': False, 02:44:30 INFO - 'sps_profile_interval': 0, 02:44:30 INFO - 'suite': 'dromaeojs', 02:44:30 INFO - 'system_bits': '32', 02:44:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:30 INFO - 'title': 't-yosemite-r7-0350', 02:44:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:30 INFO - 'use_talos_json': True, 02:44:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:30 INFO - 'work_dir': 'build'} 02:44:30 INFO - [mozharness: 2016-09-09 09:44:30.992875Z] Running clobber step. 02:44:30 INFO - Running pre-action listener: _resource_record_pre_action 02:44:30 INFO - Running main action method: clobber 02:44:30 INFO - rmtree: /builds/slave/test/build 02:44:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:33 INFO - Running post-action listener: _resource_record_post_action 02:44:33 INFO - [mozharness: 2016-09-09 09:44:33.123519Z] Finished clobber step (success) 02:44:33 INFO - [mozharness: 2016-09-09 09:44:33.123642Z] Running read-buildbot-config step. 02:44:33 INFO - Running pre-action listener: _resource_record_pre_action 02:44:33 INFO - Running main action method: read_buildbot_config 02:44:33 INFO - Using buildbot properties: 02:44:33 INFO - { 02:44:33 INFO - "project": "", 02:44:33 INFO - "product": "firefox", 02:44:33 INFO - "script_repo_revision": "production", 02:44:33 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 02:44:33 INFO - "repository": "", 02:44:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 02:44:33 INFO - "buildid": "20160909012519", 02:44:33 INFO - "pgo_build": "False", 02:44:33 INFO - "basedir": "/builds/slave/test", 02:44:33 INFO - "buildnumber": 147, 02:44:33 INFO - "slavename": "t-yosemite-r7-0350", 02:44:33 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:44:33 INFO - "platform": "yosemite_r7", 02:44:33 INFO - "scheduler": "tests-autoland-macosx64-talos", 02:44:33 INFO - "branch": "autoland", 02:44:33 INFO - "repo_path": "integration/autoland", 02:44:33 INFO - "revision": "50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec", 02:44:33 INFO - "stage_platform": "macosx64", 02:44:33 INFO - "builduid": "f02b6e4478244e84a78787e56788637f", 02:44:33 INFO - "slavebuilddir": "test" 02:44:33 INFO - } 02:44:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:44:33 INFO - Running post-action listener: _resource_record_post_action 02:44:33 INFO - [mozharness: 2016-09-09 09:44:33.126793Z] Finished read-buildbot-config step (success) 02:44:33 INFO - [mozharness: 2016-09-09 09:44:33.126940Z] Running download-and-extract step. 02:44:33 INFO - Running pre-action listener: _resource_record_pre_action 02:44:33 INFO - Running main action method: download_and_extract 02:44:33 INFO - mkdir: /builds/slave/test/build/tests 02:44:33 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')]} 02:44:33 INFO - trying https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:33 INFO - Downloading https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/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 02:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/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 02:44:34 INFO - Downloaded 1199 bytes. 02:44:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:34 INFO - Using the following test package requirements: 02:44:34 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:34 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:44:34 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:44:34 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'jsshell-mac.zip'], 02:44:34 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:44:34 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:34 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:44:34 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:44:34 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:44:34 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:34 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:44:34 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:44:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:34 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:41 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 02:44:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:41 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:44:49 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:49 INFO - trying https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/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 02:44:52 INFO - Downloaded 111537618 bytes. 02:44:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:52 INFO - mkdir: /builds/slave/test/properties 02:44:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:52 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:52 INFO - Contents: 02:44:52 INFO - build_url:https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:53 INFO - Running post-action listener: _resource_record_post_action 02:44:53 INFO - Running post-action listener: set_extra_try_arguments 02:44:53 INFO - [mozharness: 2016-09-09 09:44:53.261690Z] Finished download-and-extract step (success) 02:44:53 INFO - [mozharness: 2016-09-09 09:44:53.261828Z] Running populate-webroot step. 02:44:53 INFO - Running pre-action listener: _resource_record_pre_action 02:44:53 INFO - Running main action method: populate_webroot 02:44:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:44:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:44:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:44:53 INFO - '--tppagecycles', 02:44:53 INFO - '7'], 02:44:53 INFO - 'tests': ['tsvgm']}}, 02:44:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['damp', 'tps']}, 02:44:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'tests': ['damp', 'tps']}, 02:44:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['dromaeo_dom']}, 02:44:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['basic_compositor_video']}, 02:44:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:44:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['a11yr', 02:44:53 INFO - 'ts_paint', 02:44:53 INFO - 'tpaint', 02:44:53 INFO - 'sessionrestore', 02:44:53 INFO - 'sessionrestore_no_auto_restore', 02:44:53 INFO - 'tabpaint']}, 02:44:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['tsvgx', 02:44:53 INFO - 'tsvgr_opacity', 02:44:53 INFO - 'tart', 02:44:53 INFO - 'tscrollx', 02:44:53 INFO - 'cart']}, 02:44:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:53 INFO - 'tsvgr_opacity', 02:44:53 INFO - 'tart', 02:44:53 INFO - 'tscrollx', 02:44:53 INFO - 'cart']}, 02:44:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'talos_options': ['--disable-e10s'], 02:44:53 INFO - 'tests': ['tp5o']}, 02:44:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'tests': ['tp5o']}, 02:44:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'talos_options': ['--disable-e10s', 02:44:53 INFO - '--xperf_path', 02:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:53 INFO - 'tests': ['tp5n']}, 02:44:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:53 INFO - 'talos_options': ['--xperf_path', 02:44:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:53 INFO - 'tests': ['tp5n']}}, 02:44:53 INFO - 'talos.zip': {'path': '', 02:44:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:44:53 INFO - Running post-action listener: _resource_record_post_action 02:44:53 INFO - [mozharness: 2016-09-09 09:44:53.280706Z] Finished populate-webroot step (success) 02:44:53 INFO - [mozharness: 2016-09-09 09:44:53.280838Z] Running create-virtualenv step. 02:44:53 INFO - Running pre-action listener: _resource_record_pre_action 02:44:53 INFO - Running main action method: create_virtualenv 02:44:53 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:53 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 02:44:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:44:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:53 INFO - Using real prefix '/tools/python27' 02:44:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:54 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:57 INFO - Installing pip.................done. 02:44:57 INFO - Return code: 0 02:44:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:57 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')]} 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:57 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')]} 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:57 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:44:57 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 02:44:57 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 02:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:57 INFO - 'HOME': '/Users/cltbld', 02:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:57 INFO - 'LOGNAME': 'cltbld', 02:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:57 INFO - 'MOZ_NO_REMOTE': '1', 02:44:57 INFO - 'NO_EM_RESTART': '1', 02:44:57 INFO - 'PAGER': '/bin/cat', 02:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:57 INFO - 'PWD': '/builds/slave/test', 02:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:57 INFO - 'SHELL': '/bin/bash', 02:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:44:57 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:57 INFO - 'USER': 'cltbld', 02:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:57 INFO - 'XPC_FLAGS': '0x0', 02:44:57 INFO - 'XPC_SERVICE_NAME': '0', 02:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:57 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 02:44:57 INFO - Cleaning up... 02:44:57 INFO - Return code: 0 02:44:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:57 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')]} 02:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:57 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')]} 02:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:57 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:44:57 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 02:44:57 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 02:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:57 INFO - 'HOME': '/Users/cltbld', 02:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:57 INFO - 'LOGNAME': 'cltbld', 02:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:57 INFO - 'MOZ_NO_REMOTE': '1', 02:44:57 INFO - 'NO_EM_RESTART': '1', 02:44:57 INFO - 'PAGER': '/bin/cat', 02:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:57 INFO - 'PWD': '/builds/slave/test', 02:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:57 INFO - 'SHELL': '/bin/bash', 02:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:44:57 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:57 INFO - 'USER': 'cltbld', 02:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:57 INFO - 'XPC_FLAGS': '0x0', 02:44:57 INFO - 'XPC_SERVICE_NAME': '0', 02:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:57 INFO - Downloading/unpacking psutil>=3.1.1 02:44:57 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 02:44:57 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 02:44:57 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 02:44:57 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 02:44:57 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 02:44:57 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 02:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:45:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:01 INFO - Installing collected packages: psutil 02:45:01 INFO - Running setup.py install for psutil 02:45:01 INFO - building 'psutil._psutil_osx' extension 02:45:01 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 02:45:01 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 02:45:01 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 02:45:02 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 02:45:02 INFO - building 'psutil._psutil_posix' extension 02:45:02 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 02:45:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:45:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:45:02 INFO - ^ 02:45:02 INFO - 1 warning generated. 02:45:02 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 02:45:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:02 INFO - Successfully installed psutil 02:45:02 INFO - Cleaning up... 02:45:02 INFO - Return code: 0 02:45:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:45:02 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')]} 02:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:02 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')]} 02:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:02 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:02 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 02:45:02 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 02:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:02 INFO - 'HOME': '/Users/cltbld', 02:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:02 INFO - 'LOGNAME': 'cltbld', 02:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:02 INFO - 'MOZ_NO_REMOTE': '1', 02:45:02 INFO - 'NO_EM_RESTART': '1', 02:45:02 INFO - 'PAGER': '/bin/cat', 02:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:02 INFO - 'PWD': '/builds/slave/test', 02:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:02 INFO - 'SHELL': '/bin/bash', 02:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:02 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:02 INFO - 'USER': 'cltbld', 02:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:02 INFO - 'XPC_FLAGS': '0x0', 02:45:02 INFO - 'XPC_SERVICE_NAME': '0', 02:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:45:02 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 02:45:02 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 02:45:02 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 02:45:02 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 02:45:02 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 02:45:02 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 02:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:45:05 INFO - Installing collected packages: mozsystemmonitor 02:45:05 INFO - Running setup.py install for mozsystemmonitor 02:45:05 INFO - Successfully installed mozsystemmonitor 02:45:05 INFO - Cleaning up... 02:45:05 INFO - Return code: 0 02:45:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:05 INFO - 'HOME': '/Users/cltbld', 02:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:05 INFO - 'LOGNAME': 'cltbld', 02:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:05 INFO - 'MOZ_NO_REMOTE': '1', 02:45:05 INFO - 'NO_EM_RESTART': '1', 02:45:05 INFO - 'PAGER': '/bin/cat', 02:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:05 INFO - 'PWD': '/builds/slave/test', 02:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:05 INFO - 'SHELL': '/bin/bash', 02:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:05 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:05 INFO - 'USER': 'cltbld', 02:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:05 INFO - 'XPC_FLAGS': '0x0', 02:45:05 INFO - 'XPC_SERVICE_NAME': '0', 02:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:05 INFO - Downloading/unpacking jsonschema==2.5.1 02:45:05 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 02:45:05 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 02:45:05 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 02:45:05 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 02:45:05 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 02:45:05 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 02:45:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:45:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:45:08 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 02:45:08 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 02:45:08 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 02:45:08 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 02:45:08 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 02:45:08 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 02:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 02:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:45:08 INFO - warning: no files found matching '*.txt' 02:45:08 INFO - no previously-included directories found matching 'build' 02:45:08 INFO - no previously-included directories found matching 'dist' 02:45:08 INFO - no previously-included directories found matching '.git*' 02:45:08 INFO - Installing collected packages: jsonschema, functools32 02:45:08 INFO - Running setup.py install for functools32 02:45:08 INFO - warning: no files found matching '*.txt' 02:45:08 INFO - no previously-included directories found matching 'build' 02:45:08 INFO - no previously-included directories found matching 'dist' 02:45:08 INFO - no previously-included directories found matching '.git*' 02:45:08 INFO - Successfully installed jsonschema functools32 02:45:08 INFO - Cleaning up... 02:45:09 INFO - Return code: 0 02:45:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:45:09 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')]} 02:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:09 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')]} 02:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:09 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 02:45: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 functools32==3.2.3-2 02:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:09 INFO - 'HOME': '/Users/cltbld', 02:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:09 INFO - 'LOGNAME': 'cltbld', 02:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:09 INFO - 'MOZ_NO_REMOTE': '1', 02:45:09 INFO - 'NO_EM_RESTART': '1', 02:45:09 INFO - 'PAGER': '/bin/cat', 02:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:09 INFO - 'PWD': '/builds/slave/test', 02:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:09 INFO - 'SHELL': '/bin/bash', 02:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:09 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:09 INFO - 'USER': 'cltbld', 02:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:09 INFO - 'XPC_FLAGS': '0x0', 02:45:09 INFO - 'XPC_SERVICE_NAME': '0', 02:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:45:09 INFO - Cleaning up... 02:45:09 INFO - Return code: 0 02:45:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:45:09 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')]} 02:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:09 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')]} 02:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:09 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 02:45: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 blobuploader==1.2.4 02:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:09 INFO - 'HOME': '/Users/cltbld', 02:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:09 INFO - 'LOGNAME': 'cltbld', 02:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:09 INFO - 'MOZ_NO_REMOTE': '1', 02:45:09 INFO - 'NO_EM_RESTART': '1', 02:45:09 INFO - 'PAGER': '/bin/cat', 02:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:09 INFO - 'PWD': '/builds/slave/test', 02:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:09 INFO - 'SHELL': '/bin/bash', 02:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:09 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:09 INFO - 'USER': 'cltbld', 02:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:09 INFO - 'XPC_FLAGS': '0x0', 02:45:09 INFO - 'XPC_SERVICE_NAME': '0', 02:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 02:45: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 02:45: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 02:45: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 02:45: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 02:45: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 02:45: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 02:45:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:45:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:45:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:12 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 02:45:13 INFO - Downloading docopt-0.6.1.tar.gz 02:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:45:13 INFO - Installing collected packages: blobuploader, requests, docopt 02:45:13 INFO - Running setup.py install for blobuploader 02:45:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:45:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:45:13 INFO - Running setup.py install for requests 02:45:13 INFO - Running setup.py install for docopt 02:45:14 INFO - Successfully installed blobuploader requests docopt 02:45:14 INFO - Cleaning up... 02:45:14 INFO - Return code: 0 02:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:14 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')]} 02:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:14 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')]} 02:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:14 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:14 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 02:45:14 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 02:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:14 INFO - 'HOME': '/Users/cltbld', 02:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:14 INFO - 'LOGNAME': 'cltbld', 02:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:14 INFO - 'MOZ_NO_REMOTE': '1', 02:45:14 INFO - 'NO_EM_RESTART': '1', 02:45:14 INFO - 'PAGER': '/bin/cat', 02:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:14 INFO - 'PWD': '/builds/slave/test', 02:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:14 INFO - 'SHELL': '/bin/bash', 02:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:14 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:14 INFO - 'USER': 'cltbld', 02:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:14 INFO - 'XPC_FLAGS': '0x0', 02:45:14 INFO - 'XPC_SERVICE_NAME': '0', 02:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-3VnTUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ve9izJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-YSOG5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mpPSdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DW7Vj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:14 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-vQG6KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hSL9MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1GZd_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-jko9Fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-5l9L7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-OcmY13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-qs7p3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-9zZqRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-9izeTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Ewpxok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:15 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hiDflH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:16 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-dBO4KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:45:16 INFO - Running setup.py install for manifestparser 02:45:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:45:16 INFO - Running setup.py install for mozcrash 02:45:16 INFO - Running setup.py install for mozdebug 02:45:16 INFO - Running setup.py install for mozdevice 02:45:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:45:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:45:16 INFO - Running setup.py install for mozfile 02:45:16 INFO - Running setup.py install for mozhttpd 02:45:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Running setup.py install for mozinfo 02:45:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Running setup.py install for mozInstall 02:45:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Running setup.py install for mozleak 02:45:17 INFO - Running setup.py install for mozlog 02:45:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Running setup.py install for moznetwork 02:45:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:45:17 INFO - Running setup.py install for mozprocess 02:45:17 INFO - Running setup.py install for mozprofile 02:45:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:45:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:45:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:45:18 INFO - Running setup.py install for mozrunner 02:45:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:45:18 INFO - Running setup.py install for mozscreenshot 02:45:18 INFO - Running setup.py install for moztest 02:45:18 INFO - Running setup.py install for mozversion 02:45:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:18 INFO - Cleaning up... 02:45:18 INFO - Return code: 0 02:45:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:18 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')]} 02:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:18 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')]} 02:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:18 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:18 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 02:45:18 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 02:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:18 INFO - 'HOME': '/Users/cltbld', 02:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:18 INFO - 'LOGNAME': 'cltbld', 02:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:18 INFO - 'MOZ_NO_REMOTE': '1', 02:45:18 INFO - 'NO_EM_RESTART': '1', 02:45:18 INFO - 'PAGER': '/bin/cat', 02:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:18 INFO - 'PWD': '/builds/slave/test', 02:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:18 INFO - 'SHELL': '/bin/bash', 02:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:18 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:18 INFO - 'USER': 'cltbld', 02:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:18 INFO - 'XPC_FLAGS': '0x0', 02:45:18 INFO - 'XPC_SERVICE_NAME': '0', 02:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-WllInT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-jvtmHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-25Y9VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-rpjFV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-CRbgxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-YpIz4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ziQ1p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-iLYuLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0_8VX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:19 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)) 02:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-3WhDGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-992Odq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-xroXll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-uXML2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-8WnKem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hfHkoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-O1ZBpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:20 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)) 02:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:20 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_U093u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:20 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)) 02:45:20 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)) 02:45:20 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)) 02:45:20 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)) 02:45:20 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)) 02:45:20 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)) 02:45:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45: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 02:45: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 02:45: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 02:45: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 02:45: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 02:45: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 02:45:23 INFO - Downloading blessings-1.6.tar.gz 02:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:45:23 INFO - Installing collected packages: blessings 02:45:23 INFO - Running setup.py install for blessings 02:45:23 INFO - Successfully installed blessings 02:45:23 INFO - Cleaning up... 02:45:23 INFO - Return code: 0 02:45:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:45:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:23 INFO - Reading from file tmpfile_stdout 02:45:23 INFO - Current package versions: 02:45:23 INFO - blessings == 1.6 02:45:23 INFO - blobuploader == 1.2.4 02:45:23 INFO - docopt == 0.6.1 02:45:23 INFO - functools32 == 3.2.3-2 02:45:23 INFO - jsonschema == 2.5.1 02:45:23 INFO - manifestparser == 1.1 02:45:23 INFO - mozInstall == 1.12 02:45:23 INFO - mozcrash == 0.17 02:45:23 INFO - mozdebug == 0.1 02:45:23 INFO - mozdevice == 0.48 02:45:23 INFO - mozfile == 1.2 02:45:23 INFO - mozhttpd == 0.7 02:45:23 INFO - mozinfo == 0.9 02:45:23 INFO - mozleak == 0.1 02:45:23 INFO - mozlog == 3.3 02:45:23 INFO - moznetwork == 0.27 02:45:23 INFO - mozprocess == 0.23 02:45:23 INFO - mozprofile == 0.28 02:45:23 INFO - mozrunner == 6.12 02:45:23 INFO - mozscreenshot == 0.1 02:45:23 INFO - mozsystemmonitor == 0.3 02:45:23 INFO - moztest == 0.7 02:45:23 INFO - mozversion == 1.4 02:45:23 INFO - psutil == 3.1.1 02:45:23 INFO - requests == 1.2.3 02:45:23 INFO - wsgiref == 0.1.2 02:45:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:23 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')]} 02:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:23 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')]} 02:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:23 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:23 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 02:45:23 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 02:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:23 INFO - 'HOME': '/Users/cltbld', 02:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:23 INFO - 'LOGNAME': 'cltbld', 02:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:23 INFO - 'MOZ_NO_REMOTE': '1', 02:45:23 INFO - 'NO_EM_RESTART': '1', 02:45:23 INFO - 'PAGER': '/bin/cat', 02:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:23 INFO - 'PWD': '/builds/slave/test', 02:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:23 INFO - 'SHELL': '/bin/bash', 02:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:23 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:23 INFO - 'USER': 'cltbld', 02:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:23 INFO - 'XPC_FLAGS': '0x0', 02:45:23 INFO - 'XPC_SERVICE_NAME': '0', 02:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 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)) 02:45:24 INFO - Cleaning up... 02:45:24 INFO - Return code: 0 02:45:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:45:24 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')]} 02:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:24 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')]} 02:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:24 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 0x107c6ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d01618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8abac99070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, '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.YDDO60ucIw/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.8Ks5knO5bv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 02:45:24 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 02:45:24 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 02:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:24 INFO - 'HOME': '/Users/cltbld', 02:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:24 INFO - 'LOGNAME': 'cltbld', 02:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:24 INFO - 'MOZ_NO_REMOTE': '1', 02:45:24 INFO - 'NO_EM_RESTART': '1', 02:45:24 INFO - 'PAGER': '/bin/cat', 02:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:24 INFO - 'PWD': '/builds/slave/test', 02:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:24 INFO - 'SHELL': '/bin/bash', 02:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:24 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:24 INFO - 'USER': 'cltbld', 02:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:24 INFO - 'XPC_FLAGS': '0x0', 02:45:24 INFO - 'XPC_SERVICE_NAME': '0', 02:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:45:24 INFO - Cleaning up... 02:45:24 INFO - Return code: 0 02:45:24 INFO - Running post-action listener: _resource_record_post_action 02:45:24 INFO - Running post-action listener: _start_resource_monitoring 02:45:24 INFO - Starting resource monitoring. 02:45:24 INFO - [mozharness: 2016-09-09 09:45:24.489756Z] Finished create-virtualenv step (success) 02:45:24 INFO - [mozharness: 2016-09-09 09:45:24.490825Z] Running install step. 02:45:24 INFO - Running pre-action listener: _resource_record_pre_action 02:45:24 INFO - Running main action method: install 02:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:24 INFO - Reading from file tmpfile_stdout 02:45:24 INFO - Detecting whether we're running mozinstall >=1.0... 02:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:45:24 INFO - Reading from file tmpfile_stdout 02:45:24 INFO - Output received: 02:45:24 INFO - Usage: mozinstall [options] installer 02:45:24 INFO - Options: 02:45:24 INFO - -h, --help show this help message and exit 02:45:24 INFO - -d DEST, --destination=DEST 02:45:24 INFO - Directory to install application into. [default: 02:45:24 INFO - "/builds/slave/test"] 02:45:24 INFO - --app=APP Application being installed. [default: firefox] 02:45:24 INFO - mkdir: /builds/slave/test/build/application 02:45:24 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'] 02:45:24 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 02:45:58 INFO - Reading from file tmpfile_stdout 02:45:58 INFO - Output received: 02:45:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:45:58 INFO - Running post-action listener: _resource_record_post_action 02:45:58 INFO - [mozharness: 2016-09-09 09:45:58.896595Z] Finished install step (success) 02:45:58 INFO - [mozharness: 2016-09-09 09:45:58.896725Z] Running run-tests step. 02:45:58 INFO - Running pre-action listener: _resource_record_pre_action 02:45:58 INFO - Running main action method: run_tests 02:45:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:58 INFO - 2016-09-09 02:45:58.911 screenresolution[1650:9000] starting screenresolution argv=screenresolution get 02:45:58 INFO - 2016-09-09 02:45:58.914 screenresolution[1650:9000] Display 0: 1600x1200x32@60 02:45:58 INFO - 2016-09-09 02:45:58.920 screenresolution[1651:9002] starting screenresolution argv=screenresolution list 02:45:58 INFO - Available Modes on Display 0 02:45:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:59 INFO - Intel Iris: 02:45:59 INFO - Chipset Model: Intel Iris 02:45:59 INFO - Type: GPU 02:45:59 INFO - Bus: Built-In 02:45:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:59 INFO - Vendor: Intel (0x8086) 02:45:59 INFO - Device ID: 0x0a2e 02:45:59 INFO - Revision ID: 0x0009 02:45:59 INFO - Displays: 02:45:59 INFO - Display: 02:45:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:59 INFO - Display Serial Number: 1600x1200 60 02:45:59 INFO - Main Display: Yes 02:45:59 INFO - Mirror: Off 02:45:59 INFO - Online: Yes 02:45:59 INFO - Rotation: Supported 02:45:59 INFO - Return code: 0 02:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:59 INFO - Python 2.7.3 02:45:59 INFO - Return code: 0 02:45:59 INFO - grabbing minidump binary from tooltool 02:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:59 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 0x107bd0d30>, '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 0x107935db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bc8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:59 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 02:45:59 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 02:45:59 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 02:45:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:59 INFO - Return code: 0 02:45:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:59 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/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 02:45:59 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/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --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 02:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDDO60ucIw/Render', 02:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:59 INFO - 'HOME': '/Users/cltbld', 02:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:59 INFO - 'LOGNAME': 'cltbld', 02:45:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:59 INFO - 'MOZ_NO_REMOTE': '1', 02:45:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:59 INFO - 'NO_EM_RESTART': '1', 02:45:59 INFO - 'PAGER': '/bin/cat', 02:45:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:59 INFO - 'PWD': '/builds/slave/test', 02:45:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:59 INFO - 'SHELL': '/bin/bash', 02:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8Ks5knO5bv/Listeners', 02:45:59 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:59 INFO - 'USER': 'cltbld', 02:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:59 INFO - 'XPC_FLAGS': '0x0', 02:45:59 INFO - 'XPC_SERVICE_NAME': '0', 02:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:59 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/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 02:45:59 INFO - mozversion application_buildid: 20160909012519 02:45:59 INFO - mozversion application_changeset: 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec 02:45:59 INFO - mozversion application_display_name: Nightly 02:45:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:59 INFO - mozversion application_name: Firefox 02:45:59 INFO - mozversion application_remotingname: firefox 02:45:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:45:59 INFO - mozversion application_vendor: Mozilla 02:45:59 INFO - mozversion application_version: 51.0a1 02:45:59 INFO - mozversion platform_buildid: 20160909012519 02:45:59 INFO - mozversion platform_changeset: 50d2423ae823c406a8b0cc5fe57bfc8e6966a4ec 02:45:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:45:59 INFO - mozversion platform_version: 51.0a1 02:45:59 INFO - using testdate: 1473414359 02:45:59 INFO - actual date: 1473414359 02:45:59 INFO - starting webserver on 'localhost:49266' 02:45:59 INFO - SUITE-START | Running 2 tests 02:45:59 INFO - TEST-START | dromaeo_css 02:45:59 INFO - operating with platform_type : mac_ 02:45:59 INFO - Initialising browser for dromaeo_css test... 02:45:59 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmp33ToS1/profile http://localhost:49266/getInfo.html) 02:46:01 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:46:01 INFO - PROCESS | 1661 | colorDepth:24 02:46:01 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:46:01 INFO - PROCESS | 1661 | __metrics 02:46:01 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:46:01 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:46:02 INFO - TEST-INFO | 1661: exit 0 02:46:02 INFO - Browser initialized. 02:46:02 INFO - Running cycle 1/1 for dromaeo_css test... 02:46:02 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmp33ToS1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:46:14 INFO - PROCESS | 1664 | RSS: Main: 232321024 02:46:14 INFO - PROCESS | 1664 | 02:47:06 INFO - PROCESS | 1664 | 1473414426032 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] 02:49:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 02:49:18 INFO - PROCESS | 1664 | RSS: Main: 351924224 02:49:18 INFO - PROCESS | 1664 | 02:52:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 02:52:22 INFO - PROCESS | 1664 | RSS: Main: 380223488 02:52:22 INFO - PROCESS | 1664 | 02:55:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 02:55:25 INFO - PROCESS | 1664 | RSS: Main: 337682432 02:55:25 INFO - PROCESS | 1664 | 02:58:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 02:58:28 INFO - PROCESS | 1664 | RSS: Main: 356458496 02:58:28 INFO - PROCESS | 1664 | 03:01:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 03:01:32 INFO - PROCESS | 1664 | RSS: Main: 342847488 03:01:32 INFO - PROCESS | 1664 | 03:04:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 03:04:36 INFO - PROCESS | 1664 | RSS: Main: 427761664 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | __start_tp_report 03:04:36 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:04:36 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:04:36 INFO - PROCESS | 1664 | |i|pagename|runs| 03:04:36 INFO - PROCESS | 1664 | |0;dojo.html;3340.1816554944035;3366.973073212671;3374.2407958209405;3387.2548039431317;3446.5864096308433;5602.299712535933;5712.542996560275;5755.942440575593;5762.3949485304065;5780.595358324919;5745.540356771458;5765.942340576593;5812.447817457345;5850;5883.441073098049;5156.458571849958;5192.740362981855;5237.86905327368;5262.631615786897;5347.7058761768085;5361.892762144755;5364.061289274381;5366.356037275513;5369.140937450009;5419.0245755764145;5141.562967147759;5220.92168617471;5226.137687281594;5246.9212961805415;5260.6580572263065;5198.506141916512;5212.192110222922;5219.530242278213;5220.843374698765;5282.947170528284;5165.1219292720325;5245.763940622681;5251.4485978972425;5253.212018197301;5257.290265814105;5681.9431805681825;5720.485156335908;5815.970920145414;5826.563007774392;5828.2131912191735;5178.922316165261;6170.84572885677;6178.783742568989;6212.285587157489;6286.591371560834;6236.656983865885;6264.3422440643535;6272.905906411407;6287.434130928193;6304.716287767062;5686.2323586315315;5714.514266287412;5773.480386765212;5776.740046697867;5784.797532086399;6267.373262673696;6286.119943207955;6322.178116844781;6324.3043265240785;6334.208223972004;6240.500759939194;6240.843978900473;6249.218847644044;6252.499800015988;6254.030625253093;5774.913376299359;6193.1329613854095;6257.217847769029;6351.142595749607;6364.268109167412;6162.969185154046;6204.5967012144065;6241.18864547615;6259.280182779039;6280.434760871543;6300.338464461257;6304.338044505353;6315.747370105236;6330.746770129235;6334.809955701337;4863.465018847924;4969.875753106129;4976.178930476431;4977.651564390458;5009.073321435546;4360.65114790816;4431.933520997188;4503.054358584668;4516.8193272268745;4580.519045500241;5745.023346031185;5750.856228594319;5755.597108202385;5760.625559338713;5763.366029736726;6298.244210694655;6332.081848131995;6363.7772677956045;6375.968120159462;6391.424771770563;5570.5265052470995;5594.384617692051;5688.23208866806;5716.228309178207;5728.79949201776;5430.728463576806;5434.864128396743;5441.075017247077;5442.346918369821;5470.452954704498;6299.653519056313;6322.715477803418;6334.049892516159;6336.366363363627;6363.300036995926;6173.6913154343;6229.4393504584805;6276.027215781562;6302.5588208826775;6329.968350158219;6292.622442653455;6334.049892515975;6337.4613157882095;6342.175517182921;6346.650934198663;6242.937570624235;6260.530460215411;6263.248410190807;6284.4344009039405;6286.685665716787;5068.59451243907;6228.0969259456215;6263.937360626518;6265.937340626535;6315.715792789244;6181.5363847710705;6198.132261483486;6198.411150940834;6217.720202590985;6235.501159907106;6153.292371377349;6180.103884936569;6181.196444462191;6194.907076393948;6218.440340369389;5847.44449277318;5883.235179426647;5897.764089436375;5923.970380148244;5990.341062483036;6112.349665590355;6157.415045570664;6167.198264225622;6173.043178307369;6173.259208894961;6010.278766548042;6022.8795424090395;6024.277086749618;6029;6074.969625151846 03:04:36 INFO - PROCESS | 1664 | |1;ext.html;459.84365315792644;489.5080444153625;503.4914736116521;519.8518422249659;568.4372471253461;29949.401011979775;39578.2084358313;40752.99999999996;40923.59076409235;43167.35248971268;41877;42147.367789483185;42284.15431691372;42392.576074239245;42506.14987700244;9689.273304502172;9873.605055797796;9880.851787223162;9883.555240014217;9902.059304366074;8698.173673501007;11809.173357864953;12039.337836418994;12048;12293.69265768354;12059.698507537294;12076.03391728664;12114.394280285995;12121.696957576087;12136.93931530341;11011.944940275287;11215;12084.939575302154;12094.758104837942;12097.576584819577;11840.822387664191;11916.285022898654;11919.225250358699;11924.523019079226;11925.284482930965;33737.48181331846;39896.40155397671;40027;40170;40206.99482512961;3266.3140740444537;3288.651652822355;3302.9669703302898;3347.41420251455;3422.3839708852397;30169.547456788165;31116;31130.844345778354;31205;31268.530972035438;3164.5095010273444;3219.9356012879844;3372.19067423819;3422.6235114137426;3423.3324501722172;462019;465341.9999999966;469508.6524567389;469531;471386;59346;60169;60280;60338.69830650907;60404.69797650984;63289.05066424007;63420.68289658522;63458.73082538323;63546.682266588374;63671;16148.27332770028;16462.670746585;16470.75293870593;16482.34070637185;16529.917350413172;11410.600628977945;11693.941530292464;12732.618021459371;12793.552225672056;12803.935980320224;16300.347986080424;16401.343946242017;16421.835781642032;18312;18771.96754178506;6921.377076063179;7779.805504862311;7794.532328060334;7821.726239581587;7991.961045064105;730.561663002205;932.8787257656461;955.5699935029126;960.9039096090335;1042.817506936283;27843.860780695966;28243.71756282452;28912.855435722686;29499.705002950126;30197.339146347163;907.3829795738961;1001.7946321004176;1057.3444464432102;1065.6909496246158;1130.728625129963;12563.623091307276;14368.353424095942;15357.692846143014;15556.13016150841;15567.844321556866;629596;632705;633206;634381;650248;57290;57781.711091444275;57978;57985.71007144937;58043.419565804645;1252.8621851596317;1323.2722002898367;1345.8183145275264;1367.3710093357167;1367.9794803078162;29796.67948147463;30156.547651784804;30179.396412071197;30285.545716814708;30596.541051884684;48862.02275954532;49455.50544494509;49496.75251623719;49527.50472495229;49558.00883982228;571715;575315;575782;577036;577302;26308.342291442867;26601.600975984988;26654.466910662068;26699.065532706263;26761.464770704086;44952.77523612361;45284;45600.7719961398;46117.76941115273;46158.30762538632;37611;38145;38386.0403489915;38518.39350332812;38620.80689596534;37308.440373393874;37362.62637373591;37633;38707.61292387153;38708;36653;36948.815255923546;37287.067823304635;37336.6266337344;37348.06629834167;32152;32961.505577416836;33530.497042543895;33704.66295337113;33709.30325573997;21116.68324975096;21300.573988519835;23669.33125365571;23938.162164324163;25136.37159071038 03:04:36 INFO - PROCESS | 1664 | |2;jquery.html;349.44263899080954;359.74278390950474;369.9408094704846;370.2003672068332;401.1695789715289;14898.404063837446;16229.83770162298;19725.238466614686;19963.60072798543;20149.697754533692;19435.31976380827;19583.412497625057;19699.31052413166;19907;20419.897900510514;5156.226566015091;5781.421857814206;6035.456808887199;6045.395460453963;6064.696765161724;5860.326062502802;5939.703014849262;5985.4912332451795;6025.096235564679;6031.547633927473;5657.603967722261;5970.940290597104;5972.940270597282;5974.074018527135;5974.253218347707;5962.701864906759;6009.278886533622;6013;6019.909701354505;6025.517958563349;4631.698939568917;5071.264666623356;5331.600129990266;5440.510354068113;5801.941980580183;12820.423080961378;12860.485580576766;12901.612951611467;13072.215667060007;13086.607401777961;2132;2203.4175253393055;2320.6170981787986;2625.698044724862;2843.6018957345987;9510.9524452378;9710.514474276259;9745.756356091084;10165.949170254175;10251.179905607609;1777.6889044417176;1813.8458231050377;1822.298415110179;1830.066666000336;1844.5204246895769;91895;100039;116483;116951;117134;11125;11228.775424491629;11506.597269095542;11545.670373609897;11584.189106762615;14187.290635468391;14209.431622734975;14217.004809663431;14222.146671199554;14239.78640320396;7117.6085315308155;8235.21765432283;8370.748877533684;8422.073571907085;8540.743777686555;8338.541380224025;8350.290225330915;8358;8553.101924297984;8566.48601083937;4413.271810151353;4460.197164510354;4468.50846406895;4560.726356418625;4611.792469338888;1879.0927208882995;1900.6768849295654;1902.5243689077731;1904.5906029538041;1953.7069439583893;765.2882818978293;779.9805004874923;843.1568431568431;926.2266007883358;927.8700981862545;20057.799422005886;20529.741728409863;20804.063817128266;20817.375478735663;20927.895360523406;883.3992884838372;898.442965361467;930.5486838883136;958.6596758150756;1012.9898701012895;10617.416042117758;11007.394593297286;11122.388268645302;11126.276792008493;11185.552577896793;527870;541677;550917;557912;558394;97593;111559;119793;131315;131696;903.0969030969031;913.899471058183;950.8858936927613;956.4022485946283;981.2787601113176;17369.826301737325;18103.547411314823;18435;18505.72241416407;18863.24547018104;37889.86330410092;44282.77858610686;44510.3323450155;54004;55012.44987550233;79576.60211698904;80354.7946780787;82040.58979705063;83060;83565;9195.218406435528;11194.888051119384;11660.475278612314;11771.705707357385;12189.51241950312;8541.402101852811;8788.648454061766;9001.63993440255;9060.592273347584;13341.266230357423;7810;7938.285554300141;7948.205179482005;7968.0039995000625;8193.877091843508;4545.749983750926;7811.179826118403;7962.482438641469;8068.354531637572;8084.919150808417;6760.763373281911;7757.418193635613;7823.295903368724;8242.587870606565;8256.545889975869;10180.287379883337;11743.534066064896;11809.645710628694;12095;12178.756424871275;8262.752117436487;11569.59506417305;11718.941405292919;11823;11833.940830295793 03:04:36 INFO - PROCESS | 1664 | |3;mootools.html;1355.4984655677397;1501.7672260799577;1651.6366399392136;1827.3786912449764;1866.738656588077;8467.322614190858;9013;9029.322800789942;9052.275817934575;9058.184763371295;7790.454668173216;8046.114927357986;8477.194666506688;8721.389502734812;8779.122087791218;2351.941201469964;2381.249906279525;2393.1982785766704;2395.5807733646543;2399.292208798409;2358.6933698619155;2374.905003799846;2395.7125144982538;2397.1729753235104;2397.472556037678;2027.1789925080293;2354.75275096115;2366.503034362786;2391.0435825669783;2401.591729406005;2338.695969523968;2356.929292121222;2358.3160883343808;2359.846609970355;2388.9880550597136;2338.3218866528687;2350.7884290413945;2356.422676444265;2361.3034154924167;2367.455485238405;8677.435966662148;8867.556622168866;8870.866936995886;8882.378233523656;8908.198262156437;5144.408393034774;5296.993571221494;5299.523042926116;5303.787848486056;5308.867278318035;3608.9278214435826;3624.1845584743487;3687.815609219528;3694.3165514379657;3702.5371828521434;819.3608984991716;969.7914948286152;979.5738853598707;997.0129571724013;998.5107297424282;11588;11753.706157346134;11762.177077501337;11771.999380052706;11794.882051179464;4557;4642.396488456479;4802.135615589157;4812.879678008007;4814.085323788469;8430.789230269293;9879.604815807288;9909.405435673883;9922.603095876084;9953.004699529989;5327.547158491494;5328.4671532846405;5332.68003919766;5334;5340.225667278223;4398.142362239332;4543.227651299287;4733.290006499053;4894.26586012094;4911;3793.639604237593;3840.30874442603;3949.3483575210344;3970.82131304092;3979.900502487403;1270.7903195972747;1293.4438191577524;1298.1237664576374;1309.9803502947464;1310.1811367895066;818.3721057420298;830.8795224692507;847.9406441549032;853.9359548033798;867.8437881181321;5384.6499977501335;5609.186667933127;5714.571407144481;5930.673812940244;5958.225430693982;411.1304590790457;422.46558103998467;425.8424382978256;432.675493379965;440.9250427427345;3783.9621603784162;3832.2335532893535;3893.0851249956236;3910.237503686754;3968.8015599219925;10432.68701938943;10705.464726763787;10811.972862578043;10833.29583577065;10861.370794380906;9477.715668529956;9647.938726740193;10767.246292759432;10803.567857285778;10839.50691340996;8197.34421246299;8277.172282771795;8284.875726864102;8286.378521610903;8294.460860044199;5569.415211402745;5654.434556544313;5656.9717151415625;5730.140478928194;5767.336756273083;9974.950125249326;10049.748756280858;10084.798304034024;10089.646862359783;10102;9753.219742420448;10340.793184136426;10376.740581485523;10389.480525973822;10460.320079194827;6789.864202715819;7004.544704594185;7405.740799072007;7474.925250747423;7475.36459400957;8054.597270136587;8278.875816862632;8309.792255193426;8376.958115209385;8433.367497437594;7405.851882962418;7475;7511.887321690288;7561.09266887977;7643.388528917784;7295.817604559928;7298.96350518244;7305.1233851938105;7339.339459448675;7417.777466676009;5676.17695434168;6902.654867256718;6960.060391847036;6960.547564408297;6964.686589103401;4964.975175124101;4967.528084831935;4978.352814134139;5030.346055012825;5092;4885.047415753894;4916.70499770015;5024.42219144803;5171.224316352426;5180.844574662766 03:04:36 INFO - PROCESS | 1664 | |4;prototype.html;288.5498622149447;299.2145617753397;306.7469337796317;307.7983920532051;311.6602902835908;5240.449752775951;5339;5435.4564543545675;5454.59090568208;5467.644603100801;4651.4650815156265;4856.660033797635;5321.414644389124;5353.250544923714;5378.542823859977;4131.896702582437;4155.792210389483;4164.084521120289;4166.625003749662;4168.270552653289;3575.410057340536;4116.34138537834;4154.916901661965;4178.665706743468;4190.371444283352;3843.3082045231845;3877.3408520551575;4093.950007244607;4139.275626765319;4149.605787450188;3461.809600471973;4122.546519882811;4130.752154870717;4159.9792001040105;4173.412484259748;3059.6175478065243;3088.6911308868935;3531.9580723686317;3576.445650924111;3599.478075679012;5089.898202035939;5210.713410762407;5274.894502109936;5283.9471605284225;5289.947100528984;3768.7550309229814;3983.7134364149692;4206.453161089069;4680.906381872343;4730.739809310487;4803.3035209894715;5560.2493663355735;5606.607537472379;5674.319081710221;5797.536197104221;3790.6209379062147;3943.7042221833476;3961.7124434558596;3965.980170099131;3970.3845903884944;5539.673337198346;5562.833115006556;5705.291560485676;5747.942520574825;5945.970270148665;3609.097725568608;3894.902627434337;3903.765774053566;3905.1994341159993;3905.9804700976315;3321.368939901411;3546.6630670086815;3863.8022213114023;3927.3912543555793;4086.3053280942313;4050.440580376517;4228;4467.329900514884;4659.836905708284;4683.946112124813;5385.272988146628;5391.487808658171;5601.103823388238;5688.914666280009;5707.343655479673;1373.8214032175754;1396.694091024895;1398.76221042421;1406.423366419763;1408.6760045189665;790.8690572481407;795.7466989491573;810.785141937387;838.8783626374263;848.3382961290204;4028.9194216115516;4283.87148385549;4389.846355377546;4446.911061778746;4458.955410445854;4422.668299877522;4456.844010459618;4720.669553131316;4875.439324477659;4898.456986049383;4215.59951804578;4320.243957307522;4368.497622773358;4375.912481750347;4406.581374769392;1964.2339487599847;2573.4081161332624;2585.190366743287;2588.915922682221;2622.4230669252734;4474.88127968008;4576.382188404589;4803.663743537919;4830.468648448667;5029.479364444903;4285;4603.769811509411;4620.775494493962;4988.152014157602;5069.732566858285;4678.578927896438;4966.503349665077;4989.55094041538;4997.950020499894;5032.829317530006;4009.619086186924;4243.978780106079;4291.605228300752;4847.75761211931;4856.150173719656;4126.75092074814;4235.364695295731;4245.919499445311;4475.455967691141;4849.757512124309;4352.713701155607;4384.167008268419;4455.68810183284;4774.570288674036;4857.304196171159;3416.094734895205;3433.399155147889;3438.5873695156743;3462.2902305026905;3469.913252168716;4342.56695290547;4410.536893626251;4629.916537558045;4773.618110551217;4890.584300334369;4178;4336.826526938887;4584.129015487047;4768;4800.807967681254;3952.8616498422416;4403.82384704621;4731.752417601611;4786.353842231254;4802.615790736662;4304.106193274949;4343.762962074597;4376.846810361622;4658.136745301907;4692.835750748707;3937.251922134785;4255.680823938155;4268.81655977138;4449.466064072332;4496.695958172093;4032.2540330039037;4425.402570652921;4516.548345165457;4597.448306203277;4606.239970404846 03:04:36 INFO - PROCESS | 1664 | |5;yui.html;709.7409445552373;730.0691618186814;735.8381156145646;778.4084096086975;809.7206463770004;15517.301721422535;16384.918075409638;16386.344546218137;16434.58913527161;16464.423745168922;14707.779383309233;14826.703465930676;14839.406423743065;14866.405343786235;14873.553793386189;686.4336922039313;705.4849959529547;841.8568843296624;845.3110714767461;861.8664107063383;825.3644693585935;838.9077201507829;844.1811442900407;845.5687599324327;848.0713618587637;896.5382827843666;899.0604817965209;900.4237288135598;901.5987885391004;901.6438506789814;720.7441358317826;746.6005686957452;749.4304328710165;893.5978809535702;896.8789213456198;551.6111141645131;659.6306068601599;677.3192941094218;677.8983152527111;678.8676208139415;14804.555863324009;14806.111633302036;14835.48075817352;14854.1830199339;14863.479778207819;13681;13862.792058119136;13866.098703584335;13924.51264205758;14011.859881401157;12404.379781010914;12443.253404795742;12619.369031548478;12725.936370318183;12754.225436528526;4446.266366049598;4554.2485489894125;4596.080783843211;4598.356230127785;4604.2633178691585;112364.75270494627;113777;115878;116204.41897790541;116433;10672.679819605424;10873.347599144077;11123.165762567678;11172.162087843282;11339.262947908359;21970;21979.560408792055;21989.450263743536;22015.669764953538;22016.669749953762;7743.070831500256;7874.212578742195;7970.123286438487;8042.919570804334;8053.395995300259;3937.5078115235597;3941.4876066111224;3959.465472161279;4028.597140285948;4341.404910347018;3345.8996230113135;3469.635688252697;3470.410030294856;3473.1838018065732;3476.0440878758545;593.8099808061379;626.37675512865;626.5175814622806;626.5551458464541;628.023423576334;617.2532726351006;619.1610367951452;620.633826042628;646.5538778242998;658.7628453756644;8485.703000394957;8920.24177944869;9020.368574199743;9052.411593246417;9235.433746234983;531.2350215689395;603.8828167889458;608.6774009774826;641.9614823110628;646.0793369448517;3471.8090505022465;4398.274284743045;4546.318052292183;4552.726836389827;4561.840335588238;87611;108503.45748271208;109953.80092398108;133470;155487;131115.99999999808;141350.90280138352;145273;148697;148839;114077;115937.4203128979;116373;117059.41470292595;119154;8536.359773016924;8592.441491303045;8594.269487093667;8767.167119123673;8855.64577416922;51574.96850063053;60249;61040.08439873494;62161.06758398537;62889.68555157195;88962.55518722365;91607.08392916252;92823.53588232015;93728;93793;11318.377489238172;11591.304521728724;11687.655919569359;11930.403479826147;12175.025997920322;12089.879101208875;12108.576199832964;12355.75288494243;12584.874151258737;12604.684882877635;11806.173567850168;11810.586629467927;11971.880281197426;12076.75846483083;12385.814212786636;10430.68707938763;11415.486303116546;11865.5847045353;11967.222130561486;12191.57329493499;11282.717932051764;11793.823092653445;11966.521339146337;12059.939700301793;12232.204906681038;9612.375195612263;9771.462569558742;10035.096841284027;10307.433091180057;10390.116840068678;7986.440949133505;9166.495842728715;10052.597896084075;10156.593736250466;10165.695029149138 03:04:36 INFO - PROCESS | 1664 | __end_tp_report 03:04:36 INFO - PROCESS | 1664 | __start_cc_report 03:04:36 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1169 03:04:36 INFO - PROCESS | 1664 | __end_cc_report 03:04:36 INFO - PROCESS | 1664 | __startTimestamp1473415476179__endTimestamp 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:04:36 INFO - PROCESS | 1664 | Number of tests: 6 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5777.62 Median:5990.34 stddev:643.89 (10.7%) stddev-sans-first:616.82 03:04:36 INFO - PROCESS | 1664 | Values: 3340.2 3367.0 3374.2 3387.3 3446.6 5602.3 5712.5 5755.9 5762.4 5780.6 5745.5 5765.9 5812.4 5850.0 5883.4 5156.5 5192.7 5237.9 5262.6 5347.7 5361.9 5364.1 5366.4 5369.1 5419.0 5141.6 5220.9 5226.1 5246.9 5260.7 5198.5 5212.2 5219.5 5220.8 5282.9 5165.1 5245.8 5251.4 5253.2 5257.3 5681.9 5720.5 5816.0 5826.6 5828.2 5178.9 6170.8 6178.8 6212.3 6286.6 6236.7 6264.3 6272.9 6287.4 6304.7 5686.2 5714.5 5773.5 5776.7 5784.8 6267.4 6286.1 6322.2 6324.3 6334.2 6240.5 6240.8 6249.2 6252.5 6254.0 5774.9 6193.1 6257.2 6351.1 6364.3 6163.0 6204.6 6241.2 6259.3 6280.4 6300.3 6304.3 6315.7 6330.7 6334.8 4863.5 4969.9 4976.2 4977.7 5009.1 4360.7 4431.9 4503.1 4516.8 4580.5 5745.0 5750.9 5755.6 5760.6 5763.4 6298.2 6332.1 6363.8 6376.0 6391.4 5570.5 5594.4 5688.2 5716.2 5728.8 5430.7 5434.9 5441.1 5442.3 5470.5 6299.7 6322.7 6334.0 6336.4 6363.3 6173.7 6229.4 6276.0 6302.6 6330.0 6292.6 6334.0 6337.5 6342.2 6346.7 6242.9 6260.5 6263.2 6284.4 6286.7 5068.6 6228.1 6263.9 6265.9 6315.7 6181.5 6198.1 6198.4 6217.7 6235.5 6153.3 6180.1 6181.2 6194.9 6218.4 5847.4 5883.2 5897.8 5924.0 5990.3 6112.3 6157.4 6167.2 6173.0 6173.3 6010.3 6022.9 6024.3 6029.0 6075.0 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:69369.21 Median:20038.17 stddev:150644.69 (751.8%) stddev-sans-first:150978.44 03:04:36 INFO - PROCESS | 1664 | Values: 459.8 489.5 503.5 519.9 568.4 29949.4 39578.2 40753.0 40923.6 43167.4 41877.0 42147.4 42284.2 42392.6 42506.1 9689.3 9873.6 9880.9 9883.6 9902.1 8698.2 11809.2 12039.3 12048.0 12293.7 12059.7 12076.0 12114.4 12121.7 12136.9 11011.9 11215.0 12084.9 12094.8 12097.6 11840.8 11916.3 11919.2 11924.5 11925.3 33737.5 39896.4 40027.0 40170.0 40207.0 3266.3 3288.7 3303.0 3347.4 3422.4 30169.5 31116.0 31130.8 31205.0 31268.5 3164.5 3219.9 3372.2 3422.6 3423.3 462019.0 465342.0 469508.7 469531.0 471386.0 59346.0 60169.0 60280.0 60338.7 60404.7 63289.1 63420.7 63458.7 63546.7 63671.0 16148.3 16462.7 16470.8 16482.3 16529.9 11410.6 11693.9 12732.6 12793.6 12803.9 16300.3 16401.3 16421.8 18312.0 18772.0 6921.4 7779.8 7794.5 7821.7 7992.0 730.6 932.9 955.6 960.9 1042.8 27843.9 28243.7 28912.9 29499.7 30197.3 907.4 1001.8 1057.3 1065.7 1130.7 12563.6 14368.4 15357.7 15556.1 15567.8 629596.0 632705.0 633206.0 634381.0 650248.0 57290.0 57781.7 57978.0 57985.7 58043.4 1252.9 1323.3 1345.8 1367.4 1368.0 29796.7 30156.5 30179.4 30285.5 30596.5 48862.0 49455.5 49496.8 49527.5 49558.0 571715.0 575315.0 575782.0 577036.0 577302.0 26308.3 26601.6 26654.5 26699.1 26761.5 44952.8 45284.0 45600.8 46117.8 46158.3 37611.0 38145.0 38386.0 38518.4 38620.8 37308.4 37362.6 37633.0 38707.6 38708.0 36653.0 36948.8 37287.1 37336.6 37348.1 32152.0 32961.5 33530.5 33704.7 33709.3 21116.7 21300.6 23669.3 23938.2 25136.4 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:32370.67 Median:4507.13 stddev:91576.17 (2031.8%) stddev-sans-first:91801.51 03:04:36 INFO - PROCESS | 1664 | Values: 349.4 359.7 369.9 370.2 401.2 14898.4 16229.8 19725.2 19963.6 20149.7 19435.3 19583.4 19699.3 19907.0 20419.9 5156.2 5781.4 6035.5 6045.4 6064.7 5860.3 5939.7 5985.5 6025.1 6031.5 5657.6 5970.9 5972.9 5974.1 5974.3 5962.7 6009.3 6013.0 6019.9 6025.5 4631.7 5071.3 5331.6 5440.5 5801.9 12820.4 12860.5 12901.6 13072.2 13086.6 2132.0 2203.4 2320.6 2625.7 2843.6 9511.0 9710.5 9745.8 10165.9 10251.2 1777.7 1813.8 1822.3 1830.1 1844.5 91895.0 100039.0 116483.0 116951.0 117134.0 11125.0 11228.8 11506.6 11545.7 11584.2 14187.3 14209.4 14217.0 14222.1 14239.8 7117.6 8235.2 8370.7 8422.1 8540.7 8338.5 8350.3 8358.0 8553.1 8566.5 4413.3 4460.2 4468.5 4560.7 4611.8 1879.1 1900.7 1902.5 1904.6 1953.7 765.3 780.0 843.2 926.2 927.9 20057.8 20529.7 20804.1 20817.4 20927.9 883.4 898.4 930.5 958.7 1013.0 10617.4 11007.4 11122.4 11126.3 11185.6 527870.0 541677.0 550917.0 557912.0 558394.0 97593.0 111559.0 119793.0 131315.0 131696.0 903.1 913.9 950.9 956.4 981.3 17369.8 18103.5 18435.0 18505.7 18863.2 37889.9 44282.8 44510.3 54004.0 55012.4 79576.6 80354.8 82040.6 83060.0 83565.0 9195.2 11194.9 11660.5 11771.7 12189.5 8541.4 8788.6 9001.6 9060.6 13341.3 7810.0 7938.3 7948.2 7968.0 8193.9 4545.7 7811.2 7962.5 8068.4 8084.9 6760.8 7757.4 7823.3 8242.6 8256.5 10180.3 11743.5 11809.6 12095.0 12178.8 8262.8 11569.6 11718.9 11823.0 11833.9 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5667.72 Median:5001.39 stddev:3211.17 (64.2%) stddev-sans-first:3203.83 03:04:36 INFO - PROCESS | 1664 | Values: 1355.5 1501.8 1651.6 1827.4 1866.7 8467.3 9013.0 9029.3 9052.3 9058.2 7790.5 8046.1 8477.2 8721.4 8779.1 2351.9 2381.2 2393.2 2395.6 2399.3 2358.7 2374.9 2395.7 2397.2 2397.5 2027.2 2354.8 2366.5 2391.0 2401.6 2338.7 2356.9 2358.3 2359.8 2389.0 2338.3 2350.8 2356.4 2361.3 2367.5 8677.4 8867.6 8870.9 8882.4 8908.2 5144.4 5297.0 5299.5 5303.8 5308.9 3608.9 3624.2 3687.8 3694.3 3702.5 819.4 969.8 979.6 997.0 998.5 11588.0 11753.7 11762.2 11772.0 11794.9 4557.0 4642.4 4802.1 4812.9 4814.1 8430.8 9879.6 9909.4 9922.6 9953.0 5327.5 5328.5 5332.7 5334.0 5340.2 4398.1 4543.2 4733.3 4894.3 4911.0 3793.6 3840.3 3949.3 3970.8 3979.9 1270.8 1293.4 1298.1 1310.0 1310.2 818.4 830.9 847.9 853.9 867.8 5384.6 5609.2 5714.6 5930.7 5958.2 411.1 422.5 425.8 432.7 440.9 3784.0 3832.2 3893.1 3910.2 3968.8 10432.7 10705.5 10812.0 10833.3 10861.4 9477.7 9647.9 10767.2 10803.6 10839.5 8197.3 8277.2 8284.9 8286.4 8294.5 5569.4 5654.4 5657.0 5730.1 5767.3 9975.0 10049.7 10084.8 10089.6 10102.0 9753.2 10340.8 10376.7 10389.5 10460.3 6789.9 7004.5 7405.7 7474.9 7475.4 8054.6 8278.9 8309.8 8377.0 8433.4 7405.9 7475.0 7511.9 7561.1 7643.4 7295.8 7299.0 7305.1 7339.3 7417.8 5676.2 6902.7 6960.1 6960.5 6964.7 4965.0 4967.5 4978.4 5030.3 5092.0 4885.0 4916.7 5024.4 5171.2 5180.8 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4130.83 Median:4387.01 stddev:1203.94 (27.4%) stddev-sans-first:1172.27 03:04:36 INFO - PROCESS | 1664 | Values: 288.5 299.2 306.7 307.8 311.7 5240.4 5339.0 5435.5 5454.6 5467.6 4651.5 4856.7 5321.4 5353.3 5378.5 4131.9 4155.8 4164.1 4166.6 4168.3 3575.4 4116.3 4154.9 4178.7 4190.4 3843.3 3877.3 4094.0 4139.3 4149.6 3461.8 4122.5 4130.8 4160.0 4173.4 3059.6 3088.7 3532.0 3576.4 3599.5 5089.9 5210.7 5274.9 5283.9 5289.9 3768.8 3983.7 4206.5 4680.9 4730.7 4803.3 5560.2 5606.6 5674.3 5797.5 3790.6 3943.7 3961.7 3966.0 3970.4 5539.7 5562.8 5705.3 5747.9 5946.0 3609.1 3894.9 3903.8 3905.2 3906.0 3321.4 3546.7 3863.8 3927.4 4086.3 4050.4 4228.0 4467.3 4659.8 4683.9 5385.3 5391.5 5601.1 5688.9 5707.3 1373.8 1396.7 1398.8 1406.4 1408.7 790.9 795.7 810.8 838.9 848.3 4028.9 4283.9 4389.8 4446.9 4459.0 4422.7 4456.8 4720.7 4875.4 4898.5 4215.6 4320.2 4368.5 4375.9 4406.6 1964.2 2573.4 2585.2 2588.9 2622.4 4474.9 4576.4 4803.7 4830.5 5029.5 4285.0 4603.8 4620.8 4988.2 5069.7 4678.6 4966.5 4989.6 4998.0 5032.8 4009.6 4244.0 4291.6 4847.8 4856.2 4126.8 4235.4 4245.9 4475.5 4849.8 4352.7 4384.2 4455.7 4774.6 4857.3 3416.1 3433.4 3438.6 3462.3 3469.9 4342.6 4410.5 4629.9 4773.6 4890.6 4178.0 4336.8 4584.1 4768.0 4800.8 3952.9 4403.8 4731.8 4786.4 4802.6 4304.1 4343.8 4376.8 4658.1 4692.8 3937.3 4255.7 4268.8 4449.5 4496.7 4032.3 4425.4 4516.5 4597.4 4606.2 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24395.10 Median:3706.78 stddev:39374.03 (1062.2%) stddev-sans-first:39444.32 03:04:36 INFO - PROCESS | 1664 | Values: 709.7 730.1 735.8 778.4 809.7 15517.3 16384.9 16386.3 16434.6 16464.4 14707.8 14826.7 14839.4 14866.4 14873.6 686.4 705.5 841.9 845.3 861.9 825.4 838.9 844.2 845.6 848.1 896.5 899.1 900.4 901.6 901.6 720.7 746.6 749.4 893.6 896.9 551.6 659.6 677.3 677.9 678.9 14804.6 14806.1 14835.5 14854.2 14863.5 13681.0 13862.8 13866.1 13924.5 14011.9 12404.4 12443.3 12619.4 12725.9 12754.2 4446.3 4554.2 4596.1 4598.4 4604.3 112364.8 113777.0 115878.0 116204.4 116433.0 10672.7 10873.3 11123.2 11172.2 11339.3 21970.0 21979.6 21989.5 22015.7 22016.7 7743.1 7874.2 7970.1 8042.9 8053.4 3937.5 3941.5 3959.5 4028.6 4341.4 3345.9 3469.6 3470.4 3473.2 3476.0 593.8 626.4 626.5 626.6 628.0 617.3 619.2 620.6 646.6 658.8 8485.7 8920.2 9020.4 9052.4 9235.4 531.2 603.9 608.7 642.0 646.1 3471.8 4398.3 4546.3 4552.7 4561.8 87611.0 108503.5 109953.8 133470.0 155487.0 131116.0 141350.9 145273.0 148697.0 148839.0 114077.0 115937.4 116373.0 117059.4 119154.0 8536.4 8592.4 8594.3 8767.2 8855.6 51575.0 60249.0 61040.1 62161.1 62889.7 88962.6 91607.1 92823.5 93728.0 93793.0 11318.4 11591.3 11687.7 11930.4 12175.0 12089.9 12108.6 12355.8 12584.9 12604.7 11806.2 11810.6 11971.9 12076.8 12385.8 10430.7 11415.5 11865.6 11967.2 12191.6 11282.7 11793.8 11966.5 12059.9 12232.2 9612.4 9771.5 10035.1 10307.4 10390.1 7986.4 9166.5 10052.6 10156.6 10165.7 03:04:36 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:04:36 INFO - PROCESS | 1664 | 03:04:36 INFO - TEST-INFO | 1664: exit 0 03:04:36 INFO - TEST-OK | dromaeo_css | took 1117249ms 03:04:36 INFO - TEST-START | kraken 03:04:36 INFO - operating with platform_type : mac_ 03:04:36 INFO - Initialising browser for kraken test... 03:04:36 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpBBTrgs/profile http://localhost:49266/getInfo.html) 03:04:38 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 03:04:38 INFO - PROCESS | 1671 | colorDepth:24 03:04:38 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 03:04:38 INFO - PROCESS | 1671 | __metrics 03:04:39 INFO - PROCESS | 1671 | 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" 03:04:39 INFO - PROCESS | 1671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:04:39 INFO - TEST-INFO | 1671: exit 0 03:04:39 INFO - Browser initialized. 03:04:39 INFO - Running cycle 1/1 for kraken test... 03:04:39 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpBBTrgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:51 INFO - PROCESS | 1672 | RSS: Main: 232271872 03:04:51 INFO - PROCESS | 1672 | 03:04:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:55 INFO - PROCESS | 1672 | RSS: Main: 292401152 03:04:55 INFO - PROCESS | 1672 | 03:04:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 03:04:58 INFO - PROCESS | 1672 | RSS: Main: 288727040 03:04:58 INFO - PROCESS | 1672 | 03:05:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 03:05:02 INFO - PROCESS | 1672 | RSS: Main: 374554624 03:05:02 INFO - PROCESS | 1672 | 03:05:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 03:05:04 INFO - PROCESS | 1672 | RSS: Main: 278622208 03:05:04 INFO - PROCESS | 1672 | 03:05:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:05:07 INFO - PROCESS | 1672 | RSS: Main: 283582464 03:05:07 INFO - PROCESS | 1672 | 03:05:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 03:05:11 INFO - PROCESS | 1672 | RSS: Main: 322101248 03:05:11 INFO - PROCESS | 1672 | 03:05:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 03:05:14 INFO - PROCESS | 1672 | RSS: Main: 322244608 03:05:14 INFO - PROCESS | 1672 | 03:05:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 03:05:17 INFO - PROCESS | 1672 | RSS: Main: 320827392 03:05:17 INFO - PROCESS | 1672 | 03:05:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:05:20 INFO - PROCESS | 1672 | RSS: Main: 297902080 03:05:20 INFO - PROCESS | 1672 | 03:05:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:05:22 INFO - PROCESS | 1672 | RSS: Main: 305328128 03:05:22 INFO - PROCESS | 1672 | 03:05:24 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:05:25 INFO - PROCESS | 1672 | RSS: Main: 304287744 03:05:25 INFO - PROCESS | 1672 | 03:05:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:05:28 INFO - PROCESS | 1672 | RSS: Main: 320057344 03:05:28 INFO - PROCESS | 1672 | 03:05:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:05:32 INFO - PROCESS | 1672 | RSS: Main: 316211200 03:05:32 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 03:05:34 INFO - PROCESS | 1672 | RSS: Main: 359981056 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | __start_tp_report 03:05:34 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 03:05:34 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 03:05:34 INFO - PROCESS | 1672 | |i|pagename|runs| 03:05:34 INFO - PROCESS | 1672 | |0;ai-astar;113;112;118;114;114;113;114;112;112;125 03:05:34 INFO - PROCESS | 1672 | |1;audio-beat-detection;143;109;130;160;109;128;128;105;155;107 03:05:34 INFO - PROCESS | 1672 | |2;audio-dft;184;183;182;185;183;183;184;180;181;183 03:05:34 INFO - PROCESS | 1672 | |3;audio-fft;71;78;74;156;58;57;57;62;137;57 03:05:34 INFO - PROCESS | 1672 | |4;audio-oscillator;81;83;134;70;70;82;120;68;69;118 03:05:34 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;119;127;89;86;89;85;90;84;90;86 03:05:34 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;94;94;94;93;94;94;103;149;116 03:05:34 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;84;85;85;84;84;84;86;85;84 03:05:34 INFO - PROCESS | 1672 | |8;json-parse-financial;54;55;55;52;54;54;53;54;53;52 03:05:34 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;65;75;74;71;69;69;68;69;70 03:05:34 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;85;86;87;85;89;85;85;83;86;86 03:05:34 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;147;142;138;137;136;137;138;133;181;141 03:05:34 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;167;164;167;165;168;168;167;167;166;166 03:05:34 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;67;69;67;67;67;66;65;68;80 03:05:34 INFO - PROCESS | 1672 | __end_tp_report 03:05:34 INFO - PROCESS | 1672 | __start_cc_report 03:05:34 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2054 03:05:34 INFO - PROCESS | 1672 | __end_cc_report 03:05:34 INFO - PROCESS | 1672 | __startTimestamp1473415534444__endTimestamp 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | ------- Summary: start ------- 03:05:34 INFO - PROCESS | 1672 | Number of tests: 14 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:114.70 Median:114.00 stddev:4.03 (3.5%) stddev-sans-first:4.23 03:05:34 INFO - PROCESS | 1672 | Values: 113.0 112.0 118.0 114.0 114.0 113.0 114.0 112.0 112.0 125.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:129.00 stddev:20.19 (15.7%) stddev-sans-first:20.62 03:05:34 INFO - PROCESS | 1672 | Values: 143.0 109.0 130.0 160.0 109.0 128.0 128.0 105.0 155.0 107.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.48 (0.8%) stddev-sans-first:1.50 03:05:34 INFO - PROCESS | 1672 | Values: 184.0 183.0 182.0 185.0 183.0 183.0 184.0 180.0 181.0 183.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:80.70 Median:60.00 stddev:35.80 (59.7%) stddev-sans-first:37.80 03:05:34 INFO - PROCESS | 1672 | Values: 71.0 78.0 74.0 156.0 58.0 57.0 57.0 62.0 137.0 57.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:70.00 stddev:24.80 (35.4%) stddev-sans-first:26.12 03:05:34 INFO - PROCESS | 1672 | Values: 81.0 83.0 134.0 70.0 70.0 82.0 120.0 68.0 69.0 118.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:87.50 stddev:15.28 (17.5%) stddev-sans-first:13.40 03:05:34 INFO - PROCESS | 1672 | Values: 119.0 127.0 89.0 86.0 89.0 85.0 90.0 84.0 90.0 86.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:94.00 stddev:17.85 (19.0%) stddev-sans-first:18.67 03:05:34 INFO - PROCESS | 1672 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 103.0 149.0 116.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 03:05:34 INFO - PROCESS | 1672 | Values: 84.0 84.0 85.0 85.0 84.0 84.0 84.0 86.0 85.0 84.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.07 (2.0%) stddev-sans-first:1.13 03:05:34 INFO - PROCESS | 1672 | Values: 54.0 55.0 55.0 52.0 54.0 54.0 53.0 54.0 53.0 52.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:3.44 (4.9%) stddev-sans-first:3.04 03:05:34 INFO - PROCESS | 1672 | Values: 64.0 65.0 75.0 74.0 71.0 69.0 69.0 68.0 69.0 70.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 03:05:34 INFO - PROCESS | 1672 | Values: 85.0 86.0 87.0 85.0 89.0 85.0 85.0 83.0 86.0 86.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:139.50 stddev:13.89 (10.0%) stddev-sans-first:14.66 03:05:34 INFO - PROCESS | 1672 | Values: 147.0 142.0 138.0 137.0 136.0 137.0 138.0 133.0 181.0 141.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 03:05:34 INFO - PROCESS | 1672 | Values: 167.0 164.0 167.0 165.0 168.0 168.0 167.0 167.0 166.0 166.0 03:05:34 INFO - PROCESS | 1672 | 03:05:34 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.00 stddev:4.24 (6.3%) stddev-sans-first:4.48 03:05:34 INFO - PROCESS | 1672 | Values: 67.0 67.0 69.0 67.0 67.0 67.0 66.0 65.0 68.0 80.0 03:05:34 INFO - PROCESS | 1672 | -------- Summary: end -------- 03:05:34 INFO - PROCESS | 1672 | 03:05:35 INFO - TEST-INFO | 1672: exit 0 03:05:35 INFO - TEST-OK | kraken | took 58498ms 03:05:35 INFO - SUITE-END | took 1175s 03:05:35 INFO - Completed test suite (00:19:36) 03:05:35 INFO - javascript benchmark 03:05:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.1816554944035, 3366.973073212671, 3374.2407958209405, 3387.2548039431317, 3446.5864096308433, 5602.299712535933, 5712.542996560275, 5755.942440575593, 5762.3949485304065, 5780.595358324919, 5745.540356771458, 5765.942340576593, 5812.447817457345, 5850.0, 5883.441073098049, 5156.458571849958, 5192.740362981855, 5237.86905327368, 5262.631615786897, 5347.7058761768085, 5361.892762144755, 5364.061289274381, 5366.356037275513, 5369.140937450009, 5419.0245755764145, 5141.562967147759, 5220.92168617471, 5226.137687281594, 5246.9212961805415, 5260.6580572263065, 5198.506141916512, 5212.192110222922, 5219.530242278213, 5220.843374698765, 5282.947170528284, 5165.1219292720325, 5245.763940622681, 5251.4485978972425, 5253.212018197301, 5257.290265814105, 5681.9431805681825, 5720.485156335908, 5815.970920145414, 5826.563007774392, 5828.2131912191735, 5178.922316165261, 6170.84572885677, 6178.783742568989, 6212.285587157489, 6286.591371560834, 6236.656983865885, 6264.3422440643535, 6272.905906411407, 6287.434130928193, 6304.716287767062, 5686.2323586315315, 5714.514266287412, 5773.480386765212, 5776.740046697867, 5784.797532086399, 6267.373262673696, 6286.119943207955, 6322.178116844781, 6324.3043265240785, 6334.208223972004, 6240.500759939194, 6240.843978900473, 6249.218847644044, 6252.499800015988, 6254.030625253093, 5774.913376299359, 6193.1329613854095, 6257.217847769029, 6351.142595749607, 6364.268109167412, 6162.969185154046, 6204.5967012144065, 6241.18864547615, 6259.280182779039, 6280.434760871543, 6300.338464461257, 6304.338044505353, 6315.747370105236, 6330.746770129235, 6334.809955701337, 4863.465018847924, 4969.875753106129, 4976.178930476431, 4977.651564390458, 5009.073321435546, 4360.65114790816, 4431.933520997188, 4503.054358584668, 4516.8193272268745, 4580.519045500241, 5745.023346031185, 5750.856228594319, 5755.597108202385, 5760.625559338713, 5763.366029736726, 6298.244210694655, 6332.081848131995, 6363.7772677956045, 6375.968120159462, 6391.424771770563, 5570.5265052470995, 5594.384617692051, 5688.23208866806, 5716.228309178207, 5728.79949201776, 5430.728463576806, 5434.864128396743, 5441.075017247077, 5442.346918369821, 5470.452954704498, 6299.653519056313, 6322.715477803418, 6334.049892516159, 6336.366363363627, 6363.300036995926, 6173.6913154343, 6229.4393504584805, 6276.027215781562, 6302.5588208826775, 6329.968350158219, 6292.622442653455, 6334.049892515975, 6337.4613157882095, 6342.175517182921, 6346.650934198663, 6242.937570624235, 6260.530460215411, 6263.248410190807, 6284.4344009039405, 6286.685665716787, 5068.59451243907, 6228.0969259456215, 6263.937360626518, 6265.937340626535, 6315.715792789244, 6181.5363847710705, 6198.132261483486, 6198.411150940834, 6217.720202590985, 6235.501159907106, 6153.292371377349, 6180.103884936569, 6181.196444462191, 6194.907076393948, 6218.440340369389, 5847.44449277318, 5883.235179426647, 5897.764089436375, 5923.970380148244, 5990.341062483036, 6112.349665590355, 6157.415045570664, 6167.198264225622, 6173.043178307369, 6173.259208894961, 6010.278766548042, 6022.8795424090395, 6024.277086749618, 6029.0, 6074.969625151846], "value": 5736.563586260029, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.84365315792644, 489.5080444153625, 503.4914736116521, 519.8518422249659, 568.4372471253461, 29949.401011979775, 39578.2084358313, 40752.99999999996, 40923.59076409235, 43167.35248971268, 41877.0, 42147.367789483185, 42284.15431691372, 42392.576074239245, 42506.14987700244, 9689.273304502172, 9873.605055797796, 9880.851787223162, 9883.555240014217, 9902.059304366074, 8698.173673501007, 11809.173357864953, 12039.337836418994, 12048.0, 12293.69265768354, 12059.698507537294, 12076.03391728664, 12114.394280285995, 12121.696957576087, 12136.93931530341, 11011.944940275287, 11215.0, 12084.939575302154, 12094.758104837942, 12097.576584819577, 11840.822387664191, 11916.285022898654, 11919.225250358699, 11924.523019079226, 11925.284482930965, 33737.48181331846, 39896.40155397671, 40027.0, 40170.0, 40206.99482512961, 3266.3140740444537, 3288.651652822355, 3302.9669703302898, 3347.41420251455, 3422.3839708852397, 30169.547456788165, 31116.0, 31130.844345778354, 31205.0, 31268.530972035438, 3164.5095010273444, 3219.9356012879844, 3372.19067423819, 3422.6235114137426, 3423.3324501722172, 462019.0, 465341.9999999966, 469508.6524567389, 469531.0, 471386.0, 59346.0, 60169.0, 60280.0, 60338.69830650907, 60404.69797650984, 63289.05066424007, 63420.68289658522, 63458.73082538323, 63546.682266588374, 63671.0, 16148.27332770028, 16462.670746585, 16470.75293870593, 16482.34070637185, 16529.917350413172, 11410.600628977945, 11693.941530292464, 12732.618021459371, 12793.552225672056, 12803.935980320224, 16300.347986080424, 16401.343946242017, 16421.835781642032, 18312.0, 18771.96754178506, 6921.377076063179, 7779.805504862311, 7794.532328060334, 7821.726239581587, 7991.961045064105, 730.561663002205, 932.8787257656461, 955.5699935029126, 960.9039096090335, 1042.817506936283, 27843.860780695966, 28243.71756282452, 28912.855435722686, 29499.705002950126, 30197.339146347163, 907.3829795738961, 1001.7946321004176, 1057.3444464432102, 1065.6909496246158, 1130.728625129963, 12563.623091307276, 14368.353424095942, 15357.692846143014, 15556.13016150841, 15567.844321556866, 629596.0, 632705.0, 633206.0, 634381.0, 650248.0, 57290.0, 57781.711091444275, 57978.0, 57985.71007144937, 58043.419565804645, 1252.8621851596317, 1323.2722002898367, 1345.8183145275264, 1367.3710093357167, 1367.9794803078162, 29796.67948147463, 30156.547651784804, 30179.396412071197, 30285.545716814708, 30596.541051884684, 48862.02275954532, 49455.50544494509, 49496.75251623719, 49527.50472495229, 49558.00883982228, 571715.0, 575315.0, 575782.0, 577036.0, 577302.0, 26308.342291442867, 26601.600975984988, 26654.466910662068, 26699.065532706263, 26761.464770704086, 44952.77523612361, 45284.0, 45600.7719961398, 46117.76941115273, 46158.30762538632, 37611.0, 38145.0, 38386.0403489915, 38518.39350332812, 38620.80689596534, 37308.440373393874, 37362.62637373591, 37633.0, 38707.61292387153, 38708.0, 36653.0, 36948.815255923546, 37287.067823304635, 37336.6266337344, 37348.06629834167, 32152.0, 32961.505577416836, 33530.497042543895, 33704.66295337113, 33709.30325573997, 21116.68324975096, 21300.573988519835, 23669.33125365571, 23938.162164324163, 25136.37159071038], "value": 20119.9431713708, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.44263899080954, 359.74278390950474, 369.9408094704846, 370.2003672068332, 401.1695789715289, 14898.404063837446, 16229.83770162298, 19725.238466614686, 19963.60072798543, 20149.697754533692, 19435.31976380827, 19583.412497625057, 19699.31052413166, 19907.0, 20419.897900510514, 5156.226566015091, 5781.421857814206, 6035.456808887199, 6045.395460453963, 6064.696765161724, 5860.326062502802, 5939.703014849262, 5985.4912332451795, 6025.096235564679, 6031.547633927473, 5657.603967722261, 5970.940290597104, 5972.940270597282, 5974.074018527135, 5974.253218347707, 5962.701864906759, 6009.278886533622, 6013.0, 6019.909701354505, 6025.517958563349, 4631.698939568917, 5071.264666623356, 5331.600129990266, 5440.510354068113, 5801.941980580183, 12820.423080961378, 12860.485580576766, 12901.612951611467, 13072.215667060007, 13086.607401777961, 2132.0, 2203.4175253393055, 2320.6170981787986, 2625.698044724862, 2843.6018957345987, 9510.9524452378, 9710.514474276259, 9745.756356091084, 10165.949170254175, 10251.179905607609, 1777.6889044417176, 1813.8458231050377, 1822.298415110179, 1830.066666000336, 1844.5204246895769, 91895.0, 100039.0, 116483.0, 116951.0, 117134.0, 11125.0, 11228.775424491629, 11506.597269095542, 11545.670373609897, 11584.189106762615, 14187.290635468391, 14209.431622734975, 14217.004809663431, 14222.146671199554, 14239.78640320396, 7117.6085315308155, 8235.21765432283, 8370.748877533684, 8422.073571907085, 8540.743777686555, 8338.541380224025, 8350.290225330915, 8358.0, 8553.101924297984, 8566.48601083937, 4413.271810151353, 4460.197164510354, 4468.50846406895, 4560.726356418625, 4611.792469338888, 1879.0927208882995, 1900.6768849295654, 1902.5243689077731, 1904.5906029538041, 1953.7069439583893, 765.2882818978293, 779.9805004874923, 843.1568431568431, 926.2266007883358, 927.8700981862545, 20057.799422005886, 20529.741728409863, 20804.063817128266, 20817.375478735663, 20927.895360523406, 883.3992884838372, 898.442965361467, 930.5486838883136, 958.6596758150756, 1012.9898701012895, 10617.416042117758, 11007.394593297286, 11122.388268645302, 11126.276792008493, 11185.552577896793, 527870.0, 541677.0, 550917.0, 557912.0, 558394.0, 97593.0, 111559.0, 119793.0, 131315.0, 131696.0, 903.0969030969031, 913.899471058183, 950.8858936927613, 956.4022485946283, 981.2787601113176, 17369.826301737325, 18103.547411314823, 18435.0, 18505.72241416407, 18863.24547018104, 37889.86330410092, 44282.77858610686, 44510.3323450155, 54004.0, 55012.44987550233, 79576.60211698904, 80354.7946780787, 82040.58979705063, 83060.0, 83565.0, 9195.218406435528, 11194.888051119384, 11660.475278612314, 11771.705707357385, 12189.51241950312, 8541.402101852811, 8788.648454061766, 9001.63993440255, 9060.592273347584, 13341.266230357423, 7810.0, 7938.285554300141, 7948.205179482005, 7968.0039995000625, 8193.877091843508, 4545.749983750926, 7811.179826118403, 7962.482438641469, 8068.354531637572, 8084.919150808417, 6760.763373281911, 7757.418193635613, 7823.295903368724, 8242.587870606565, 8256.545889975869, 10180.287379883337, 11743.534066064896, 11809.645710628694, 12095.0, 12178.756424871275, 8262.752117436487, 11569.59506417305, 11718.941405292919, 11823.0, 11833.940830295793], "value": 9024.546821395705, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.4984655677397, 1501.7672260799577, 1651.6366399392136, 1827.3786912449764, 1866.738656588077, 8467.322614190858, 9013.0, 9029.322800789942, 9052.275817934575, 9058.184763371295, 7790.454668173216, 8046.114927357986, 8477.194666506688, 8721.389502734812, 8779.122087791218, 2351.941201469964, 2381.249906279525, 2393.1982785766704, 2395.5807733646543, 2399.292208798409, 2358.6933698619155, 2374.905003799846, 2395.7125144982538, 2397.1729753235104, 2397.472556037678, 2027.1789925080293, 2354.75275096115, 2366.503034362786, 2391.0435825669783, 2401.591729406005, 2338.695969523968, 2356.929292121222, 2358.3160883343808, 2359.846609970355, 2388.9880550597136, 2338.3218866528687, 2350.7884290413945, 2356.422676444265, 2361.3034154924167, 2367.455485238405, 8677.435966662148, 8867.556622168866, 8870.866936995886, 8882.378233523656, 8908.198262156437, 5144.408393034774, 5296.993571221494, 5299.523042926116, 5303.787848486056, 5308.867278318035, 3608.9278214435826, 3624.1845584743487, 3687.815609219528, 3694.3165514379657, 3702.5371828521434, 819.3608984991716, 969.7914948286152, 979.5738853598707, 997.0129571724013, 998.5107297424282, 11588.0, 11753.706157346134, 11762.177077501337, 11771.999380052706, 11794.882051179464, 4557.0, 4642.396488456479, 4802.135615589157, 4812.879678008007, 4814.085323788469, 8430.789230269293, 9879.604815807288, 9909.405435673883, 9922.603095876084, 9953.004699529989, 5327.547158491494, 5328.4671532846405, 5332.68003919766, 5334.0, 5340.225667278223, 4398.142362239332, 4543.227651299287, 4733.290006499053, 4894.26586012094, 4911.0, 3793.639604237593, 3840.30874442603, 3949.3483575210344, 3970.82131304092, 3979.900502487403, 1270.7903195972747, 1293.4438191577524, 1298.1237664576374, 1309.9803502947464, 1310.1811367895066, 818.3721057420298, 830.8795224692507, 847.9406441549032, 853.9359548033798, 867.8437881181321, 5384.6499977501335, 5609.186667933127, 5714.571407144481, 5930.673812940244, 5958.225430693982, 411.1304590790457, 422.46558103998467, 425.8424382978256, 432.675493379965, 440.9250427427345, 3783.9621603784162, 3832.2335532893535, 3893.0851249956236, 3910.237503686754, 3968.8015599219925, 10432.68701938943, 10705.464726763787, 10811.972862578043, 10833.29583577065, 10861.370794380906, 9477.715668529956, 9647.938726740193, 10767.246292759432, 10803.567857285778, 10839.50691340996, 8197.34421246299, 8277.172282771795, 8284.875726864102, 8286.378521610903, 8294.460860044199, 5569.415211402745, 5654.434556544313, 5656.9717151415625, 5730.140478928194, 5767.336756273083, 9974.950125249326, 10049.748756280858, 10084.798304034024, 10089.646862359783, 10102.0, 9753.219742420448, 10340.793184136426, 10376.740581485523, 10389.480525973822, 10460.320079194827, 6789.864202715819, 7004.544704594185, 7405.740799072007, 7474.925250747423, 7475.36459400957, 8054.597270136587, 8278.875816862632, 8309.792255193426, 8376.958115209385, 8433.367497437594, 7405.851882962418, 7475.0, 7511.887321690288, 7561.09266887977, 7643.388528917784, 7295.817604559928, 7298.96350518244, 7305.1233851938105, 7339.339459448675, 7417.777466676009, 5676.17695434168, 6902.654867256718, 6960.060391847036, 6960.547564408297, 6964.686589103401, 4964.975175124101, 4967.528084831935, 4978.352814134139, 5030.346055012825, 5092.0, 4885.047415753894, 4916.70499770015, 5024.42219144803, 5171.224316352426, 5180.844574662766], "value": 4458.8207301441325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.5498622149447, 299.2145617753397, 306.7469337796317, 307.7983920532051, 311.6602902835908, 5240.449752775951, 5339.0, 5435.4564543545675, 5454.59090568208, 5467.644603100801, 4651.4650815156265, 4856.660033797635, 5321.414644389124, 5353.250544923714, 5378.542823859977, 4131.896702582437, 4155.792210389483, 4164.084521120289, 4166.625003749662, 4168.270552653289, 3575.410057340536, 4116.34138537834, 4154.916901661965, 4178.665706743468, 4190.371444283352, 3843.3082045231845, 3877.3408520551575, 4093.950007244607, 4139.275626765319, 4149.605787450188, 3461.809600471973, 4122.546519882811, 4130.752154870717, 4159.9792001040105, 4173.412484259748, 3059.6175478065243, 3088.6911308868935, 3531.9580723686317, 3576.445650924111, 3599.478075679012, 5089.898202035939, 5210.713410762407, 5274.894502109936, 5283.9471605284225, 5289.947100528984, 3768.7550309229814, 3983.7134364149692, 4206.453161089069, 4680.906381872343, 4730.739809310487, 4803.3035209894715, 5560.2493663355735, 5606.607537472379, 5674.319081710221, 5797.536197104221, 3790.6209379062147, 3943.7042221833476, 3961.7124434558596, 3965.980170099131, 3970.3845903884944, 5539.673337198346, 5562.833115006556, 5705.291560485676, 5747.942520574825, 5945.970270148665, 3609.097725568608, 3894.902627434337, 3903.765774053566, 3905.1994341159993, 3905.9804700976315, 3321.368939901411, 3546.6630670086815, 3863.8022213114023, 3927.3912543555793, 4086.3053280942313, 4050.440580376517, 4228.0, 4467.329900514884, 4659.836905708284, 4683.946112124813, 5385.272988146628, 5391.487808658171, 5601.103823388238, 5688.914666280009, 5707.343655479673, 1373.8214032175754, 1396.694091024895, 1398.76221042421, 1406.423366419763, 1408.6760045189665, 790.8690572481407, 795.7466989491573, 810.785141937387, 838.8783626374263, 848.3382961290204, 4028.9194216115516, 4283.87148385549, 4389.846355377546, 4446.911061778746, 4458.955410445854, 4422.668299877522, 4456.844010459618, 4720.669553131316, 4875.439324477659, 4898.456986049383, 4215.59951804578, 4320.243957307522, 4368.497622773358, 4375.912481750347, 4406.581374769392, 1964.2339487599847, 2573.4081161332624, 2585.190366743287, 2588.915922682221, 2622.4230669252734, 4474.88127968008, 4576.382188404589, 4803.663743537919, 4830.468648448667, 5029.479364444903, 4285.0, 4603.769811509411, 4620.775494493962, 4988.152014157602, 5069.732566858285, 4678.578927896438, 4966.503349665077, 4989.55094041538, 4997.950020499894, 5032.829317530006, 4009.619086186924, 4243.978780106079, 4291.605228300752, 4847.75761211931, 4856.150173719656, 4126.75092074814, 4235.364695295731, 4245.919499445311, 4475.455967691141, 4849.757512124309, 4352.713701155607, 4384.167008268419, 4455.68810183284, 4774.570288674036, 4857.304196171159, 3416.094734895205, 3433.399155147889, 3438.5873695156743, 3462.2902305026905, 3469.913252168716, 4342.56695290547, 4410.536893626251, 4629.916537558045, 4773.618110551217, 4890.584300334369, 4178.0, 4336.826526938887, 4584.129015487047, 4768.0, 4800.807967681254, 3952.8616498422416, 4403.82384704621, 4731.752417601611, 4786.353842231254, 4802.615790736662, 4304.106193274949, 4343.762962074597, 4376.846810361622, 4658.136745301907, 4692.835750748707, 3937.251922134785, 4255.680823938155, 4268.81655977138, 4449.466064072332, 4496.695958172093, 4032.2540330039037, 4425.402570652921, 4516.548345165457, 4597.448306203277, 4606.239970404846], "value": 3757.9554396272665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7409445552373, 730.0691618186814, 735.8381156145646, 778.4084096086975, 809.7206463770004, 15517.301721422535, 16384.918075409638, 16386.344546218137, 16434.58913527161, 16464.423745168922, 14707.779383309233, 14826.703465930676, 14839.406423743065, 14866.405343786235, 14873.553793386189, 686.4336922039313, 705.4849959529547, 841.8568843296624, 845.3110714767461, 861.8664107063383, 825.3644693585935, 838.9077201507829, 844.1811442900407, 845.5687599324327, 848.0713618587637, 896.5382827843666, 899.0604817965209, 900.4237288135598, 901.5987885391004, 901.6438506789814, 720.7441358317826, 746.6005686957452, 749.4304328710165, 893.5978809535702, 896.8789213456198, 551.6111141645131, 659.6306068601599, 677.3192941094218, 677.8983152527111, 678.8676208139415, 14804.555863324009, 14806.111633302036, 14835.48075817352, 14854.1830199339, 14863.479778207819, 13681.0, 13862.792058119136, 13866.098703584335, 13924.51264205758, 14011.859881401157, 12404.379781010914, 12443.253404795742, 12619.369031548478, 12725.936370318183, 12754.225436528526, 4446.266366049598, 4554.2485489894125, 4596.080783843211, 4598.356230127785, 4604.2633178691585, 112364.75270494627, 113777.0, 115878.0, 116204.41897790541, 116433.0, 10672.679819605424, 10873.347599144077, 11123.165762567678, 11172.162087843282, 11339.262947908359, 21970.0, 21979.560408792055, 21989.450263743536, 22015.669764953538, 22016.669749953762, 7743.070831500256, 7874.212578742195, 7970.123286438487, 8042.919570804334, 8053.395995300259, 3937.5078115235597, 3941.4876066111224, 3959.465472161279, 4028.597140285948, 4341.404910347018, 3345.8996230113135, 3469.635688252697, 3470.410030294856, 3473.1838018065732, 3476.0440878758545, 593.8099808061379, 626.37675512865, 626.5175814622806, 626.5551458464541, 628.023423576334, 617.2532726351006, 619.1610367951452, 620.633826042628, 646.5538778242998, 658.7628453756644, 8485.703000394957, 8920.24177944869, 9020.368574199743, 9052.411593246417, 9235.433746234983, 531.2350215689395, 603.8828167889458, 608.6774009774826, 641.9614823110628, 646.0793369448517, 3471.8090505022465, 4398.274284743045, 4546.318052292183, 4552.726836389827, 4561.840335588238, 87611.0, 108503.45748271208, 109953.80092398108, 133470.0, 155487.0, 131115.99999999808, 141350.90280138352, 145273.0, 148697.0, 148839.0, 114077.0, 115937.4203128979, 116373.0, 117059.41470292595, 119154.0, 8536.359773016924, 8592.441491303045, 8594.269487093667, 8767.167119123673, 8855.64577416922, 51574.96850063053, 60249.0, 61040.08439873494, 62161.06758398537, 62889.68555157195, 88962.55518722365, 91607.08392916252, 92823.53588232015, 93728.0, 93793.0, 11318.377489238172, 11591.304521728724, 11687.655919569359, 11930.403479826147, 12175.025997920322, 12089.879101208875, 12108.576199832964, 12355.75288494243, 12584.874151258737, 12604.684882877635, 11806.173567850168, 11810.586629467927, 11971.880281197426, 12076.75846483083, 12385.814212786636, 10430.68707938763, 11415.486303116546, 11865.5847045353, 11967.222130561486, 12191.57329493499, 11282.717932051764, 11793.823092653445, 11966.521339146337, 12059.939700301793, 12232.204906681038, 9612.375195612263, 9771.462569558742, 10035.096841284027, 10307.433091180057, 10390.116840068678, 7986.440949133505, 9166.495842728715, 10052.597896084075, 10156.593736250466, 10165.695029149138], "value": 7552.029251771785, "unit": "score"}], "name": "dromaeo_css", "value": 7133.976043506414, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 118.0, 114.0, 114.0, 113.0, 114.0, 112.0, 112.0, 125.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 130.0, 160.0, 109.0, 128.0, 128.0, 105.0, 155.0, 107.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 185.0, 183.0, 183.0, 184.0, 180.0, 181.0, 183.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 74.0, 156.0, 58.0, 57.0, 57.0, 62.0, 137.0, 57.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 134.0, 70.0, 70.0, 82.0, 120.0, 68.0, 69.0, 118.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 89.0, 86.0, 89.0, 85.0, 90.0, 84.0, 90.0, 86.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 103.0, 149.0, 116.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 84.0, 86.0, 85.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 52.0, 54.0, 54.0, 53.0, 54.0, 53.0, 52.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 75.0, 74.0, 71.0, 69.0, 69.0, 68.0, 69.0, 70.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 85.0, 89.0, 85.0, 85.0, 83.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 142.0, 138.0, 137.0, 136.0, 137.0, 138.0, 133.0, 181.0, 141.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 167.0, 165.0, 168.0, 168.0, 167.0, 167.0, 166.0, 166.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 67.0, 67.0, 66.0, 65.0, 68.0, 80.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1463.1, "alertThreshold": 2.0}]} 03:05:35 INFO - Return code: 0 03:05:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:35 INFO - # TBPL SUCCESS # 03:05:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:05:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:05:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:05:35 INFO - 2016-09-09 03:05:35.742 screenresolution[1676:11188] starting screenresolution argv=screenresolution get 03:05:35 INFO - 2016-09-09 03:05:35.745 screenresolution[1676:11188] Display 0: 1600x1200x32@60 03:05:35 INFO - 2016-09-09 03:05:35.751 screenresolution[1677:11191] starting screenresolution argv=screenresolution list 03:05:35 INFO - Available Modes on Display 0 03:05:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:05:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:05:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:05:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:05:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:05:35 INFO - Intel Iris: 03:05:35 INFO - Chipset Model: Intel Iris 03:05:35 INFO - Type: GPU 03:05:35 INFO - Bus: Built-In 03:05:35 INFO - VRAM (Dynamic, Max): 1536 MB 03:05:35 INFO - Vendor: Intel (0x8086) 03:05:35 INFO - Device ID: 0x0a2e 03:05:35 INFO - Revision ID: 0x0009 03:05:35 INFO - Displays: 03:05:35 INFO - Display: 03:05:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:05:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:05:35 INFO - Display Serial Number: 1600x1200 60 03:05:35 INFO - Main Display: Yes 03:05:35 INFO - Mirror: Off 03:05:35 INFO - Online: Yes 03:05:35 INFO - Rotation: Supported 03:05:35 INFO - Return code: 0 03:05:35 INFO - Running post-action listener: _resource_record_post_action 03:05:35 INFO - [mozharness: 2016-09-09 10:05:35.987140Z] Finished run-tests step (success) 03:05:35 INFO - Running post-run listener: _resource_record_post_run 03:05:36 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32013312; Write bytes: 707265024; Read time: 198; Write time: 1958 03:05:36 INFO - TinderboxPrint: CPU usage
53.8% 03:05:36 INFO - TinderboxPrint: I/O read bytes / time
32,013,312 / 198 03:05:36 INFO - TinderboxPrint: I/O write bytes / time
707,265,024 / 1,958 03:05:36 INFO - TinderboxPrint: CPU idle
2,236.4 (46.2%) 03:05:36 INFO - TinderboxPrint: CPU system
973.2 (20.1%) 03:05:36 INFO - TinderboxPrint: CPU user
1,636.2 (33.8%) 03:05:36 INFO - TinderboxPrint: Swap in / out
434,204,672 / 0 03:05:36 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400585216; Write bytes: 395998720; Read time: 29538; Write time: 807 03:05:36 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29981696; Write bytes: 296582144; Read time: 180; Write time: 1111 03:05:36 INFO - Running post-run listener: _upload_blobber_files 03:05:36 INFO - Blob upload gear active. 03:05:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:36 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 03:05:36 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'] 03:05:36 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 03:05:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:38 INFO - (blobuploader) - INFO - Done attempting. 03:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:05:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:39 INFO - (blobuploader) - INFO - Done attempting. 03:05:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:41 INFO - (blobuploader) - INFO - Done attempting. 03:05:41 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:41 INFO - Return code: 0 03:05:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9506dedbe18d308604a4318cf8aee17518ebfbb5782433b5bb5c520f28c3608942685585894c9eb45d47e3d0f99864351f57504a0fe1a5aede409e63cc40718b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4495ca8f47297a6153452b3ee8cf38386c4af2a2fcdc429467377d87391ab605489a1801224f09a921e593f5d8fa66fd03b8b01eb5b32126e501475ef47e4620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b55385918733bcb7cad0fa85451603629eeef4c26748e4640bd0f068ff937bcfa4694b444a5b65dbcdbd16313a67691e073d8be11215895e5db06a29fec4b62a"} 03:05:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:41 INFO - Contents: 03:05:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9506dedbe18d308604a4318cf8aee17518ebfbb5782433b5bb5c520f28c3608942685585894c9eb45d47e3d0f99864351f57504a0fe1a5aede409e63cc40718b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4495ca8f47297a6153452b3ee8cf38386c4af2a2fcdc429467377d87391ab605489a1801224f09a921e593f5d8fa66fd03b8b01eb5b32126e501475ef47e4620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b55385918733bcb7cad0fa85451603629eeef4c26748e4640bd0f068ff937bcfa4694b444a5b65dbcdbd16313a67691e073d8be11215895e5db06a29fec4b62a"} 03:05:41 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:41 INFO - Copying logs to upload dir... 03:05:41 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.673183 ========= master_lag: 1.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 03:05:42.653817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 03:05:42.659070) ========= 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.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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/9506dedbe18d308604a4318cf8aee17518ebfbb5782433b5bb5c520f28c3608942685585894c9eb45d47e3d0f99864351f57504a0fe1a5aede409e63cc40718b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4495ca8f47297a6153452b3ee8cf38386c4af2a2fcdc429467377d87391ab605489a1801224f09a921e593f5d8fa66fd03b8b01eb5b32126e501475ef47e4620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b55385918733bcb7cad0fa85451603629eeef4c26748e4640bd0f068ff937bcfa4694b444a5b65dbcdbd16313a67691e073d8be11215895e5db06a29fec4b62a"} build_url:https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/DpfI2C6nRvuA2aAxptO2wQ/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/9506dedbe18d308604a4318cf8aee17518ebfbb5782433b5bb5c520f28c3608942685585894c9eb45d47e3d0f99864351f57504a0fe1a5aede409e63cc40718b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4495ca8f47297a6153452b3ee8cf38386c4af2a2fcdc429467377d87391ab605489a1801224f09a921e593f5d8fa66fd03b8b01eb5b32126e501475ef47e4620", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b55385918733bcb7cad0fa85451603629eeef4c26748e4640bd0f068ff937bcfa4694b444a5b65dbcdbd16313a67691e073d8be11215895e5db06a29fec4b62a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 03:05:42.692418) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 03:05:42.692752) ========= 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.YDDO60ucIw/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.8Ks5knO5bv/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005180 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 03:05:42.741518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 03:05:42.741843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 03:05:42.742153) ========= ========= Total master_lag: 1.34 =========