builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0237 starttime: 1472523097.97 results: success (0) buildid: 20160829174530 builduid: 26758477d78e4e828c467ca6faaaccbe revision: 02a345df379c3d36556166722ca712a24fb29070 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:37.972896) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:37.973409) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:37.973755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:38.002152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:38.002490) ========= 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.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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-08-29 19:11:38-- 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% 161M=0s 2016-08-29 19:11:38 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091679 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:38.120170) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:38.120538) ========= 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.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.025860 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:38.170756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 19:11:38.171154) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 02a345df379c3d36556166722ca712a24fb29070 --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 02a345df379c3d36556166722ca712a24fb29070 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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-08-29 19:11:38,253 truncating revision to first 12 chars 2016-08-29 19:11:38,253 Setting DEBUG logging. 2016-08-29 19:11:38,253 attempt 1/10 2016-08-29 19:11:38,253 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/02a345df379c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 19:11:39,876 unpacking tar archive at: autoland-02a345df379c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.874761 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-29 19:11:40.068802) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:40.069168) ========= script_repo_revision: 02a345df379c3d36556166722ca712a24fb29070 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:40.069654) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:40.069975) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 19:11:40.088565) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-29 19:11:40.088904) ========= /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.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:11:40 INFO - MultiFileLogger online at 20160829 19:11:40 in /builds/slave/test 19:11:40 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 19:11:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:11:40 INFO - {'append_to_log': False, 19:11:40 INFO - 'base_work_dir': '/builds/slave/test', 19:11:40 INFO - 'blob_upload_branch': 'Autoland', 19:11:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:11:40 INFO - 'branch': 'Autoland', 19:11:40 INFO - 'buildbot_json_path': 'buildprops.json', 19:11:40 INFO - 'config_files': ('talos/mac_config.py',), 19:11:40 INFO - 'default_actions': ('clobber', 19:11:40 INFO - 'read-buildbot-config', 19:11:40 INFO - 'download-and-extract', 19:11:40 INFO - 'populate-webroot', 19:11:40 INFO - 'create-virtualenv', 19:11:40 INFO - 'install', 19:11:40 INFO - 'run-tests'), 19:11:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:11:40 INFO - 'download_minidump_stackwalk': True, 19:11:40 INFO - 'download_symbols': 'ondemand', 19:11:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:11:40 INFO - 'tooltool.py': '/tools/tooltool.py', 19:11:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:11:40 INFO - '/tools/misc-python/virtualenv.py')}, 19:11:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:11:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:11:40 INFO - 'installer_path': 'installer.exe', 19:11:40 INFO - 'log_level': 'info', 19:11:40 INFO - 'log_name': 'talos', 19:11:40 INFO - 'log_to_console': True, 19:11:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:11:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:11:40 INFO - 'opt_config_files': (), 19:11:40 INFO - 'pip_index': False, 19:11:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:40 INFO - 'cmd': ('bash', 19:11:40 INFO - '-c', 19:11:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:40 INFO - 'enabled': True, 19:11:40 INFO - 'halt_on_failure': False, 19:11:40 INFO - 'name': 'check_screen_resolution'},), 19:11:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:40 INFO - 'cmd': ('bash', 19:11:40 INFO - '-c', 19:11:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:40 INFO - 'enabled': True, 19:11:40 INFO - 'halt_on_failure': False, 19:11:40 INFO - 'name': 'check_screen_resolution'},), 19:11:40 INFO - 'run_cmd_checks_enabled': True, 19:11:40 INFO - 'sps_profile': False, 19:11:40 INFO - 'sps_profile_interval': 0, 19:11:40 INFO - 'suite': 'dromaeojs', 19:11:40 INFO - 'system_bits': '32', 19:11:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:11:40 INFO - 'title': 't-yosemite-r7-0237', 19:11:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:11:40 INFO - 'use_talos_json': True, 19:11:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:11:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:11:40 INFO - 'work_dir': 'build'} 19:11:40 INFO - [mozharness: 2016-08-30 02:11:40.279955Z] Running clobber step. 19:11:40 INFO - Running pre-action listener: _resource_record_pre_action 19:11:40 INFO - Running main action method: clobber 19:11:40 INFO - rmtree: /builds/slave/test/build 19:11:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:11:42 INFO - Running post-action listener: _resource_record_post_action 19:11:42 INFO - [mozharness: 2016-08-30 02:11:42.380015Z] Finished clobber step (success) 19:11:42 INFO - [mozharness: 2016-08-30 02:11:42.380217Z] Running read-buildbot-config step. 19:11:42 INFO - Running pre-action listener: _resource_record_pre_action 19:11:42 INFO - Running main action method: read_buildbot_config 19:11:42 INFO - Using buildbot properties: 19:11:42 INFO - { 19:11:42 INFO - "project": "", 19:11:42 INFO - "product": "firefox", 19:11:42 INFO - "script_repo_revision": "production", 19:11:42 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:11:42 INFO - "repository": "", 19:11:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:11:42 INFO - "buildid": "20160829174530", 19:11:42 INFO - "pgo_build": "False", 19:11:42 INFO - "basedir": "/builds/slave/test", 19:11:42 INFO - "buildnumber": 127, 19:11:42 INFO - "slavename": "t-yosemite-r7-0237", 19:11:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:11:42 INFO - "platform": "yosemite_r7", 19:11:42 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:11:42 INFO - "branch": "autoland", 19:11:42 INFO - "repo_path": "integration/autoland", 19:11:42 INFO - "revision": "02a345df379c3d36556166722ca712a24fb29070", 19:11:42 INFO - "stage_platform": "macosx64", 19:11:42 INFO - "builduid": "26758477d78e4e828c467ca6faaaccbe", 19:11:42 INFO - "slavebuilddir": "test" 19:11:42 INFO - } 19:11:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:11:42 INFO - Running post-action listener: _resource_record_post_action 19:11:42 INFO - [mozharness: 2016-08-30 02:11:42.384240Z] Finished read-buildbot-config step (success) 19:11:42 INFO - [mozharness: 2016-08-30 02:11:42.384370Z] Running download-and-extract step. 19:11:42 INFO - Running pre-action listener: _resource_record_pre_action 19:11:42 INFO - Running main action method: download_and_extract 19:11:42 INFO - mkdir: /builds/slave/test/build/tests 19:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:42 INFO - trying https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:11:42 INFO - Downloading https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/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 19:11:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/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 19:11:43 INFO - Downloaded 1199 bytes. 19:11:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:11:43 INFO - Using the following test package requirements: 19:11:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:11:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:11:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:11:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'jsshell-mac.zip'], 19:11:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:11:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:11:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:11:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:11:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:11:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:11:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:11:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:11:43 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')]} 19:11:43 INFO - trying https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:11:43 INFO - Downloading https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:11:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:11:46 INFO - Downloaded 16372570 bytes. 19:11:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:11:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:11:53 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')]} 19:11:53 INFO - trying https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:11:54 INFO - Downloaded 16372570 bytes. 19:11:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:12:00 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')]} 19:12:00 INFO - trying https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:12:00 INFO - Downloading https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:12:02 INFO - Downloaded 14413998 bytes. 19:12:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:12: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')]} 19:12:02 INFO - trying https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:12:02 INFO - Downloading https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/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 19:12:06 INFO - Downloaded 111557813 bytes. 19:12:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:12:06 INFO - mkdir: /builds/slave/test/properties 19:12:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:06 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:06 INFO - Contents: 19:12:06 INFO - build_url:https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:12:07 INFO - Running post-action listener: _resource_record_post_action 19:12:07 INFO - Running post-action listener: set_extra_try_arguments 19:12:07 INFO - [mozharness: 2016-08-30 02:12:07.070851Z] Finished download-and-extract step (success) 19:12:07 INFO - [mozharness: 2016-08-30 02:12:07.070969Z] Running populate-webroot step. 19:12:07 INFO - Running pre-action listener: _resource_record_pre_action 19:12:07 INFO - Running main action method: populate_webroot 19:12:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:07 INFO - '--tppagecycles', 19:12:07 INFO - '7'], 19:12:07 INFO - 'tests': ['tsvgm']}}, 19:12:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['damp', 'tps']}, 19:12:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'tests': ['damp', 'tps']}, 19:12:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['dromaeo_dom']}, 19:12:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['basic_compositor_video']}, 19:12:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:12:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['a11yr', 19:12:07 INFO - 'ts_paint', 19:12:07 INFO - 'tpaint', 19:12:07 INFO - 'sessionrestore', 19:12:07 INFO - 'sessionrestore_no_auto_restore', 19:12:07 INFO - 'tabpaint']}, 19:12:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['tsvgx', 19:12:07 INFO - 'tsvgr_opacity', 19:12:07 INFO - 'tart', 19:12:07 INFO - 'tscrollx', 19:12:07 INFO - 'cart']}, 19:12:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:07 INFO - 'tsvgr_opacity', 19:12:07 INFO - 'tart', 19:12:07 INFO - 'tscrollx', 19:12:07 INFO - 'cart']}, 19:12:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'talos_options': ['--disable-e10s'], 19:12:07 INFO - 'tests': ['tp5o']}, 19:12:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'tests': ['tp5o']}, 19:12:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'talos_options': ['--disable-e10s', 19:12:07 INFO - '--xperf_path', 19:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:07 INFO - 'tests': ['tp5n']}, 19:12:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:07 INFO - 'talos_options': ['--xperf_path', 19:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:07 INFO - 'tests': ['tp5n']}}, 19:12:07 INFO - 'talos.zip': {'path': '', 19:12:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:07 INFO - Running post-action listener: _resource_record_post_action 19:12:07 INFO - [mozharness: 2016-08-30 02:12:07.090181Z] Finished populate-webroot step (success) 19:12:07 INFO - [mozharness: 2016-08-30 02:12:07.090313Z] Running create-virtualenv step. 19:12:07 INFO - Running pre-action listener: _resource_record_pre_action 19:12:07 INFO - Running main action method: create_virtualenv 19:12:07 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:07 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 19:12:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:12:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:07 INFO - Using real prefix '/tools/python27' 19:12:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:07 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:10 INFO - Installing pip.................done. 19:12:10 INFO - Return code: 0 19:12:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:11 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:11 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 19:12:11 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 19:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:11 INFO - 'HOME': '/Users/cltbld', 19:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:11 INFO - 'LOGNAME': 'cltbld', 19:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:11 INFO - 'MOZ_NO_REMOTE': '1', 19:12:11 INFO - 'NO_EM_RESTART': '1', 19:12:11 INFO - 'PAGER': '/bin/cat', 19:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:11 INFO - 'PWD': '/builds/slave/test', 19:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:11 INFO - 'SHELL': '/bin/bash', 19:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:11 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:11 INFO - 'USER': 'cltbld', 19:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:11 INFO - 'XPC_FLAGS': '0x0', 19:12:11 INFO - 'XPC_SERVICE_NAME': '0', 19:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:11 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 19:12:11 INFO - Cleaning up... 19:12:11 INFO - Return code: 0 19:12:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:11 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:11 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 19:12:11 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 19:12:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:11 INFO - 'HOME': '/Users/cltbld', 19:12:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:11 INFO - 'LOGNAME': 'cltbld', 19:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:11 INFO - 'MOZ_NO_REMOTE': '1', 19:12:11 INFO - 'NO_EM_RESTART': '1', 19:12:11 INFO - 'PAGER': '/bin/cat', 19:12:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:11 INFO - 'PWD': '/builds/slave/test', 19:12:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:11 INFO - 'SHELL': '/bin/bash', 19:12:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:11 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:11 INFO - 'USER': 'cltbld', 19:12:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:11 INFO - 'XPC_FLAGS': '0x0', 19:12:11 INFO - 'XPC_SERVICE_NAME': '0', 19:12:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:12 INFO - Downloading/unpacking psutil>=3.1.1 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:15 INFO - Installing collected packages: psutil 19:12:15 INFO - Running setup.py install for psutil 19:12:15 INFO - building 'psutil._psutil_osx' extension 19:12:15 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 19:12:15 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 19:12:15 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 19:12:15 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 19:12:15 INFO - building 'psutil._psutil_posix' extension 19:12:15 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 19:12:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:12:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:12:16 INFO - ^ 19:12:16 INFO - 1 warning generated. 19:12:16 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 19:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:16 INFO - Successfully installed psutil 19:12:16 INFO - Cleaning up... 19:12:16 INFO - Return code: 0 19:12:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:16 INFO - 'HOME': '/Users/cltbld', 19:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:16 INFO - 'LOGNAME': 'cltbld', 19:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:16 INFO - 'MOZ_NO_REMOTE': '1', 19:12:16 INFO - 'NO_EM_RESTART': '1', 19:12:16 INFO - 'PAGER': '/bin/cat', 19:12:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:16 INFO - 'PWD': '/builds/slave/test', 19:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:16 INFO - 'SHELL': '/bin/bash', 19:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:16 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:16 INFO - 'USER': 'cltbld', 19:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:16 INFO - 'XPC_FLAGS': '0x0', 19:12:16 INFO - 'XPC_SERVICE_NAME': '0', 19:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:19 INFO - Installing collected packages: mozsystemmonitor 19:12:19 INFO - Running setup.py install for mozsystemmonitor 19:12:19 INFO - Successfully installed mozsystemmonitor 19:12:19 INFO - Cleaning up... 19:12:19 INFO - Return code: 0 19:12:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12:19 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')]} 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:19 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')]} 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:19 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:19 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 19:12:19 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 19:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:19 INFO - 'HOME': '/Users/cltbld', 19:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:19 INFO - 'LOGNAME': 'cltbld', 19:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:19 INFO - 'MOZ_NO_REMOTE': '1', 19:12:19 INFO - 'NO_EM_RESTART': '1', 19:12:19 INFO - 'PAGER': '/bin/cat', 19:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:19 INFO - 'PWD': '/builds/slave/test', 19:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:19 INFO - 'SHELL': '/bin/bash', 19:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:19 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:19 INFO - 'USER': 'cltbld', 19:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:19 INFO - 'XPC_FLAGS': '0x0', 19:12:19 INFO - 'XPC_SERVICE_NAME': '0', 19:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:19 INFO - Downloading/unpacking jsonschema==2.5.1 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:22 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 19:12:22 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 19:12:22 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 19:12:22 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 19:12:22 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 19:12:22 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 19:12:22 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:22 INFO - warning: no files found matching '*.txt' 19:12:22 INFO - no previously-included directories found matching 'build' 19:12:22 INFO - no previously-included directories found matching 'dist' 19:12:22 INFO - no previously-included directories found matching '.git*' 19:12:22 INFO - Installing collected packages: jsonschema, functools32 19:12:22 INFO - Running setup.py install for functools32 19:12:22 INFO - warning: no files found matching '*.txt' 19:12:22 INFO - no previously-included directories found matching 'build' 19:12:22 INFO - no previously-included directories found matching 'dist' 19:12:22 INFO - no previously-included directories found matching '.git*' 19:12:22 INFO - Successfully installed jsonschema functools32 19:12:22 INFO - Cleaning up... 19:12:22 INFO - Return code: 0 19:12:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:22 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')]} 19:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:22 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')]} 19:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:22 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:22 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 19:12:22 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 19:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:22 INFO - 'HOME': '/Users/cltbld', 19:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:22 INFO - 'LOGNAME': 'cltbld', 19:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:22 INFO - 'MOZ_NO_REMOTE': '1', 19:12:22 INFO - 'NO_EM_RESTART': '1', 19:12:22 INFO - 'PAGER': '/bin/cat', 19:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:22 INFO - 'PWD': '/builds/slave/test', 19:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:22 INFO - 'SHELL': '/bin/bash', 19:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:22 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:22 INFO - 'USER': 'cltbld', 19:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:22 INFO - 'XPC_FLAGS': '0x0', 19:12:22 INFO - 'XPC_SERVICE_NAME': '0', 19:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:23 INFO - Cleaning up... 19:12:23 INFO - Return code: 0 19:12:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:23 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:23 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 19:12:23 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 19:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:23 INFO - 'HOME': '/Users/cltbld', 19:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:23 INFO - 'LOGNAME': 'cltbld', 19:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:23 INFO - 'MOZ_NO_REMOTE': '1', 19:12:23 INFO - 'NO_EM_RESTART': '1', 19:12:23 INFO - 'PAGER': '/bin/cat', 19:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:23 INFO - 'PWD': '/builds/slave/test', 19:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:23 INFO - 'SHELL': '/bin/bash', 19:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:23 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:23 INFO - 'USER': 'cltbld', 19:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:23 INFO - 'XPC_FLAGS': '0x0', 19:12:23 INFO - 'XPC_SERVICE_NAME': '0', 19:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:23 INFO - Downloading/unpacking blobuploader==1.2.4 19:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:25 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:25 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 19:12:25 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 19:12:25 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 19:12:25 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 19:12:25 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 19:12:25 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 19:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:26 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 19:12:26 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 19:12:26 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 19:12:26 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 19:12:26 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 19:12:26 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 19:12:27 INFO - Downloading docopt-0.6.1.tar.gz 19:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:27 INFO - Running setup.py install for blobuploader 19:12:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:27 INFO - Running setup.py install for requests 19:12:27 INFO - Running setup.py install for docopt 19:12:27 INFO - Successfully installed blobuploader requests docopt 19:12:27 INFO - Cleaning up... 19:12:28 INFO - Return code: 0 19:12:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:28 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')]} 19:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:28 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')]} 19:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:28 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:28 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 19:12:28 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 19:12:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:28 INFO - 'HOME': '/Users/cltbld', 19:12:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:28 INFO - 'LOGNAME': 'cltbld', 19:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:28 INFO - 'MOZ_NO_REMOTE': '1', 19:12:28 INFO - 'NO_EM_RESTART': '1', 19:12:28 INFO - 'PAGER': '/bin/cat', 19:12:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:28 INFO - 'PWD': '/builds/slave/test', 19:12:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:28 INFO - 'SHELL': '/bin/bash', 19:12:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:28 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:28 INFO - 'USER': 'cltbld', 19:12:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:28 INFO - 'XPC_FLAGS': '0x0', 19:12:28 INFO - 'XPC_SERVICE_NAME': '0', 19:12:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-fQbj5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-pHkgPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-QMTmYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-ug3W41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-wWURIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-15x7ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:28 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-rcs5TZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-jCcPqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-ciNJXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Ymxapu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-f26N8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-wok1wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-5FQkAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-GhvcAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-cFgx8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-9Go15P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:29 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-7OCxRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:30 INFO - Running setup.py install for manifestparser 19:12:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozcrash 19:12:30 INFO - Running setup.py install for mozdebug 19:12:30 INFO - Running setup.py install for mozdevice 19:12:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozfile 19:12:30 INFO - Running setup.py install for mozhttpd 19:12:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozinfo 19:12:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Running setup.py install for mozInstall 19:12:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Running setup.py install for mozleak 19:12:31 INFO - Running setup.py install for mozlog 19:12:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Running setup.py install for moznetwork 19:12:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Running setup.py install for mozprocess 19:12:31 INFO - Running setup.py install for mozprofile 19:12:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:31 INFO - Running setup.py install for mozrunner 19:12:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:32 INFO - Running setup.py install for mozscreenshot 19:12:32 INFO - Running setup.py install for moztest 19:12:32 INFO - Running setup.py install for mozversion 19:12:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:32 INFO - Cleaning up... 19:12:32 INFO - Return code: 0 19:12:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:32 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')]} 19:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:32 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')]} 19:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:32 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:32 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 19:12:32 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 19:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:32 INFO - 'HOME': '/Users/cltbld', 19:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:32 INFO - 'LOGNAME': 'cltbld', 19:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:32 INFO - 'MOZ_NO_REMOTE': '1', 19:12:32 INFO - 'NO_EM_RESTART': '1', 19:12:32 INFO - 'PAGER': '/bin/cat', 19:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:32 INFO - 'PWD': '/builds/slave/test', 19:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:32 INFO - 'SHELL': '/bin/bash', 19:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:32 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:32 INFO - 'USER': 'cltbld', 19:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:32 INFO - 'XPC_FLAGS': '0x0', 19:12:32 INFO - 'XPC_SERVICE_NAME': '0', 19:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:32 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-XcOamg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-UsMliO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-5FXZQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-_2fe_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-zK1aPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-XAJ_ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-AIr9Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Vu7McK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-t_NUjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-xXedgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:33 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)) 19:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:33 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-YILTpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-YOikj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-wkC0NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-95_G3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-sjwxb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-uL_92T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:34 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)) 19:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:34 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-qp41Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:34 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)) 19:12:34 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)) 19:12:34 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)) 19:12:34 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)) 19:12:34 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)) 19:12:34 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)) 19:12:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:34 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 19:12:37 INFO - Downloading blessings-1.6.tar.gz 19:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:37 INFO - Installing collected packages: blessings 19:12:37 INFO - Running setup.py install for blessings 19:12:37 INFO - Successfully installed blessings 19:12:37 INFO - Cleaning up... 19:12:37 INFO - Return code: 0 19:12:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:37 INFO - Reading from file tmpfile_stdout 19:12:37 INFO - Current package versions: 19:12:37 INFO - blessings == 1.6 19:12:37 INFO - blobuploader == 1.2.4 19:12:37 INFO - docopt == 0.6.1 19:12:37 INFO - functools32 == 3.2.3-2 19:12:37 INFO - jsonschema == 2.5.1 19:12:37 INFO - manifestparser == 1.1 19:12:37 INFO - mozInstall == 1.12 19:12:37 INFO - mozcrash == 0.17 19:12:37 INFO - mozdebug == 0.1 19:12:37 INFO - mozdevice == 0.48 19:12:37 INFO - mozfile == 1.2 19:12:37 INFO - mozhttpd == 0.7 19:12:37 INFO - mozinfo == 0.9 19:12:37 INFO - mozleak == 0.1 19:12:37 INFO - mozlog == 3.3 19:12:37 INFO - moznetwork == 0.27 19:12:37 INFO - mozprocess == 0.23 19:12:37 INFO - mozprofile == 0.28 19:12:37 INFO - mozrunner == 6.12 19:12:37 INFO - mozscreenshot == 0.1 19:12:37 INFO - mozsystemmonitor == 0.3 19:12:37 INFO - moztest == 0.7 19:12:37 INFO - mozversion == 1.4 19:12:37 INFO - psutil == 3.1.1 19:12:37 INFO - requests == 1.2.3 19:12:37 INFO - wsgiref == 0.1.2 19:12:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:37 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 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:37 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 19:12:37 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 19:12:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:37 INFO - 'HOME': '/Users/cltbld', 19:12:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:37 INFO - 'LOGNAME': 'cltbld', 19:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:37 INFO - 'MOZ_NO_REMOTE': '1', 19:12:37 INFO - 'NO_EM_RESTART': '1', 19:12:37 INFO - 'PAGER': '/bin/cat', 19:12:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:37 INFO - 'PWD': '/builds/slave/test', 19:12:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:37 INFO - 'SHELL': '/bin/bash', 19:12:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:37 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:37 INFO - 'USER': 'cltbld', 19:12:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:37 INFO - 'XPC_FLAGS': '0x0', 19:12:37 INFO - 'XPC_SERVICE_NAME': '0', 19:12:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 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)) 19:12:37 INFO - Cleaning up... 19:12:37 INFO - Return code: 0 19:12:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e05da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba4aca0a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, '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.f24EMfzLV5/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.re28vynWQk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 19:12:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:12:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:12:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:37 INFO - 'HOME': '/Users/cltbld', 19:12:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:37 INFO - 'LOGNAME': 'cltbld', 19:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:37 INFO - 'MOZ_NO_REMOTE': '1', 19:12:37 INFO - 'NO_EM_RESTART': '1', 19:12:37 INFO - 'PAGER': '/bin/cat', 19:12:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:37 INFO - 'PWD': '/builds/slave/test', 19:12:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:37 INFO - 'SHELL': '/bin/bash', 19:12:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:12:37 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:12:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:37 INFO - 'USER': 'cltbld', 19:12:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:37 INFO - 'XPC_FLAGS': '0x0', 19:12:37 INFO - 'XPC_SERVICE_NAME': '0', 19:12:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:38 INFO - Cleaning up... 19:12:38 INFO - Return code: 0 19:12:38 INFO - Running post-action listener: _resource_record_post_action 19:12:38 INFO - Running post-action listener: _start_resource_monitoring 19:12:38 INFO - Starting resource monitoring. 19:12:38 INFO - [mozharness: 2016-08-30 02:12:38.250282Z] Finished create-virtualenv step (success) 19:12:38 INFO - [mozharness: 2016-08-30 02:12:38.251323Z] Running install step. 19:12:38 INFO - Running pre-action listener: _resource_record_pre_action 19:12:38 INFO - Running main action method: install 19:12:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:38 INFO - Reading from file tmpfile_stdout 19:12:38 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:38 INFO - Reading from file tmpfile_stdout 19:12:38 INFO - Output received: 19:12:38 INFO - Usage: mozinstall [options] installer 19:12:38 INFO - Options: 19:12:38 INFO - -h, --help show this help message and exit 19:12:38 INFO - -d DEST, --destination=DEST 19:12:38 INFO - Directory to install application into. [default: 19:12:38 INFO - "/builds/slave/test"] 19:12:38 INFO - --app=APP Application being installed. [default: firefox] 19:12:38 INFO - mkdir: /builds/slave/test/build/application 19:12:38 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'] 19:12:38 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 19:13:13 INFO - Reading from file tmpfile_stdout 19:13:13 INFO - Output received: 19:13:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:13:13 INFO - Running post-action listener: _resource_record_post_action 19:13:13 INFO - [mozharness: 2016-08-30 02:13:13.071857Z] Finished install step (success) 19:13:13 INFO - [mozharness: 2016-08-30 02:13:13.071986Z] Running run-tests step. 19:13:13 INFO - Running pre-action listener: _resource_record_pre_action 19:13:13 INFO - Running main action method: run_tests 19:13:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:13:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:13:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:13:13 INFO - 2016-08-29 19:13:13.086 screenresolution[1636:7472] starting screenresolution argv=screenresolution get 19:13:13 INFO - 2016-08-29 19:13:13.089 screenresolution[1636:7472] Display 0: 1600x1200x32@60 19:13:13 INFO - 2016-08-29 19:13:13.095 screenresolution[1637:7474] starting screenresolution argv=screenresolution list 19:13:13 INFO - Available Modes on Display 0 19:13:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:13:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:13:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:13:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:13:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:13:13 INFO - Intel Iris: 19:13:13 INFO - Chipset Model: Intel Iris 19:13:13 INFO - Type: GPU 19:13:13 INFO - Bus: Built-In 19:13:13 INFO - VRAM (Dynamic, Max): 1536 MB 19:13:13 INFO - Vendor: Intel (0x8086) 19:13:13 INFO - Device ID: 0x0a2e 19:13:13 INFO - Revision ID: 0x0009 19:13:13 INFO - Displays: 19:13:13 INFO - Display: 19:13:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:13:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:13:13 INFO - Display Serial Number: 1600x1200 60 19:13:13 INFO - Main Display: Yes 19:13:13 INFO - Mirror: Off 19:13:13 INFO - Online: Yes 19:13:13 INFO - Rotation: Supported 19:13:13 INFO - Return code: 0 19:13:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:13:13 INFO - Python 2.7.3 19:13:13 INFO - Return code: 0 19:13:13 INFO - grabbing minidump binary from tooltool 19:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:13 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 0x10dfc1d30>, '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 0x10dd26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfb9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:13:13 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 19:13:13 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 19:13:13 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 19:13:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:13 INFO - Return code: 0 19:13:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:13:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:13:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:13:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:13:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:13:13 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/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 19:13:13 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/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0237 --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 19:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f24EMfzLV5/Render', 19:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:13 INFO - 'HOME': '/Users/cltbld', 19:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:13 INFO - 'LOGNAME': 'cltbld', 19:13:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:13 INFO - 'MOZ_NO_REMOTE': '1', 19:13:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:13 INFO - 'NO_EM_RESTART': '1', 19:13:13 INFO - 'PAGER': '/bin/cat', 19:13:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:13 INFO - 'PWD': '/builds/slave/test', 19:13:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:13 INFO - 'SHELL': '/bin/bash', 19:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.re28vynWQk/Listeners', 19:13:13 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 19:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:13 INFO - 'USER': 'cltbld', 19:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:13 INFO - 'XPC_FLAGS': '0x0', 19:13:13 INFO - 'XPC_SERVICE_NAME': '0', 19:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:13 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/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 19:13:13 INFO - mozversion application_buildid: 20160829174530 19:13:13 INFO - mozversion application_changeset: 02a345df379c3d36556166722ca712a24fb29070 19:13:13 INFO - mozversion application_display_name: Nightly 19:13:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:13 INFO - mozversion application_name: Firefox 19:13:13 INFO - mozversion application_remotingname: firefox 19:13:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:13:13 INFO - mozversion application_vendor: Mozilla 19:13:13 INFO - mozversion application_version: 51.0a1 19:13:13 INFO - mozversion platform_buildid: 20160829174530 19:13:13 INFO - mozversion platform_changeset: 02a345df379c3d36556166722ca712a24fb29070 19:13:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:13:13 INFO - mozversion platform_version: 51.0a1 19:13:13 INFO - using testdate: 1472523193 19:13:13 INFO - actual date: 1472523193 19:13:13 INFO - starting webserver on 'localhost:49262' 19:13:13 INFO - SUITE-START | Running 2 tests 19:13:13 INFO - TEST-START | dromaeo_css 19:13:13 INFO - operating with platform_type : mac_ 19:13:13 INFO - Initialising browser for dromaeo_css test... 19:13:13 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmp0Ox5px/profile http://localhost:49262/getInfo.html) 19:13:16 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 19:13:16 INFO - PROCESS | 1647 | colorDepth:24 19:13:16 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 19:13:16 INFO - PROCESS | 1647 | __metrics 19:13:16 INFO - PROCESS | 1647 | 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" 19:13:16 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:17 INFO - TEST-INFO | 1647: exit 0 19:13:17 INFO - Browser initialized. 19:13:17 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:17 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmp0Ox5px/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:29 INFO - PROCESS | 1649 | RSS: Main: 234622976 19:13:29 INFO - PROCESS | 1649 | 19:14:20 INFO - PROCESS | 1649 | 1472523260555 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] 19:16:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 19:16:33 INFO - PROCESS | 1649 | RSS: Main: 350625792 19:16:33 INFO - PROCESS | 1649 | 19:19:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 19:19:37 INFO - PROCESS | 1649 | RSS: Main: 389935104 19:19:37 INFO - PROCESS | 1649 | 19:22:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 19:22:40 INFO - PROCESS | 1649 | RSS: Main: 342581248 19:22:40 INFO - PROCESS | 1649 | 19:25:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 19:25:43 INFO - PROCESS | 1649 | RSS: Main: 336117760 19:25:43 INFO - PROCESS | 1649 | 19:28:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 19:28:47 INFO - PROCESS | 1649 | RSS: Main: 327311360 19:28:47 INFO - PROCESS | 1649 | 19:31:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 19:31:51 INFO - PROCESS | 1649 | RSS: Main: 493936640 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | __start_tp_report 19:31:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 19:31:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 19:31:51 INFO - PROCESS | 1649 | |i|pagename|runs| 19:31:51 INFO - PROCESS | 1649 | |0;dojo.html;3282.195862013806;3284.2610412657154;3327.484239942808;3348.9497657535126;3389.5424117744096;5466.098093814526;5493.258410114633;5575.470330318618;5580.386157522679;5586.581006424519;5519.254900588419;5544.584156188282;5600.691961942091;5644.8024319148835;5676.347220069693;5019.899602007958;5084.491550844905;5090.185570308751;5100.285959965608;5244.082285600024;5182.637215394924;5185.844424667266;5193.117170081076;5195.896082078356;5208.401033881095;5024.32171656829;5076.974615126937;5082.644214904958;5142.999999999981;5189.506996835295;5090.77091530882;5105.923411148836;5114.59083273341;5118.2578526113875;5132.461091585367;5052.949470505321;5061.949380506221;5091.439941606457;5097.413797413271;5099.388073431175;5450.781968721247;5518.91721624176;5529.778808847641;5643.971780141114;5662.688552129631;5991.850203744898;6004.12940123684;6009.338972712998;6086.362298671702;6096.1770161028135;6027.849303767397;6146.631202127842;6147.451105484557;6174.289956654998;6177.598456100384;5543.445655434424;5559.499645031967;5603.663780173202;5714.885702285973;5732.283464566929;5154.561862241751;6244.781432649836;6291.213598300213;6307.873842523124;6310.242770867433;4820;5987.251593550806;5997.820065398045;6000.459958603747;6002.369751176151;5953.434423729739;6027.035674292092;6038.706090823769;6138.1099740537375;6161.137440758297;6053.031514957585;6112.898653789767;6115.970584430425;6138.785142519991;6139.369480960552;6120.690942950841;6130.509559235251;6144.446999770043;6152.630842149485;6159.753609855556;4809;4850.899349028475;4863.7324947128245;4929.457759646436;4958.305837182797;3704.3517384457614;4333.9133217335475;4342.218400687906;4421.270490369053;4494.325851122292;5590.496855283044;5591;5594.0769772987815;5602.411746766612;5609.1305847593685;5998.970005150034;6193.411625895533;6199.039148931923;6204.534659900525;6215.81352559424;5565.387807341189;5590.860228494239;5598.608097433141;5637.605367624309;5686.886262274814;4524.411826462605;5337.359516858002;5339.546138578186;5363.141897296414;5365.785368585291;6141.84645383869;6234.345393733593;6243.656598887105;6251.906221406683;6258.561900666909;5949.196858424058;6075.392460753889;6127.586602260358;6135.478484328882;6141.201643786279;6214.564980451325;6263.655498947602;6271.592346497463;6274.686265686606;6284.560080794484;6036.879262414815;6044.818655440344;6061;6064.3935606439;6065.72704228302;6031.69841507914;6071.51427885777;6102.125716101218;6130.141780150871;6165.722542485689;5975.701214939322;5982.611130276518;5985.40145985398;5988.221531200916;6034.456898879122;4971;5829.3296270927685;5879.794207202898;5983.730732117152;5989.101634754822;5669.064604340238;5707.1724599933605;5797.188393624811;5799.391063938226;5873.353931067664;5907.822765317047;5909.6158749681135;5926.614770039933;5941.287045554388;5942.168096466585;5621.494065534122;5766.596338256281;5778.653280803165;5779.653220806765;5791.565632577489 19:31:51 INFO - PROCESS | 1649 | |1;ext.html;477.52963331118855;498.4616614056819;520.497719700489;522.6106550619784;539.2450569203115;30266;30448.54327185094;39864.60473883411;41010.58989410105;41174;41984;42212.78893605528;42390.57609423905;42415;42511.149777004444;9719.951400243006;9901;9902.504874756234;9927.007299270088;9928.602855885792;8759.95620021899;8882.289416846637;9564.760880977996;12047.939760301186;12074.637760867188;10211.438370889598;11970.94014529926;12014.699632509213;12016.158868879182;12170.391480425988;8749.825003499958;12154.209976351553;12214.32821194834;12252.81620775689;12255.387230638476;11686;12029.51881924722;12126.6362009139;12289.56986505468;12295.139340246187;40245;40589.57936472239;40635;40704.79647601773;40716.38925416121;3501.2472318451655;4011.5988401159943;4034.172994536113;4106.630403263691;4167.124903770182;30064.8496757517;31198.532022019688;31335;32183.356332873445;32185.51721724176;3117.3297740985795;3230.5154226865925;3232.7090561849313;3235.644079151315;3294.8352582370785;501744;505589.47205264476;506019;507047;508004;56331.43668563344;58613;59052.40947590469;59086.704566477754;59202.70398647979;63671;63818;63826;63921;63971;14177.503787367392;14304.213268270138;14318.642033949234;14330.641733956525;16593.751093733605;11463.88536114645;11707.414629268502;12723.982081433463;12809.295488748208;12813.551525696555;15424.534669206407;18177.272909083753;18642.34751783681;18974.71537926932;19076.52308692266;6694.464442844561;6747.224069232003;7664.770056898302;7825.882611760828;7885;895.5298468304062;950.6672664567346;951.9524023798782;1065.733566608348;1070.6680928912062;29421.26446838803;29598.260043499085;29691;30142.397152057274;30367.24081897926;940.4639355567398;1015.5582321690089;1073.4418102586612;1110.2228440091967;1119.5186069989982;15266;15326.23368831554;15448;15584.081011855505;15650.530484085495;613082;620766;621572;622200;856142;55983;56797.7160114205;56828;59371.40628593745;59508;1333.113479536117;1359.8708122728326;1380.2615600653803;1382.046387992282;1384.764590019699;29984.776270882598;30726.692733072385;30755.692443075284;31383.843080785362;31662.68337316597;48870;49186.262206066225;49221.50778492169;49258.75370623124;49289.260661090826;545128;548596;549259;549452;549497;25812.741872581784;25883.96464141413;26066.218013459627;26341.604875926492;26367.868160659073;46030.07939841251;47089.764551177024;47389;47425.52574474349;47565.76217118892;31130.844345778125;37042.87217122869;38704.80647596744;39720.20559588734;40013.79993100016;28600;38085.04787380338;38439.84680459591;39190.41214381844;39223.60776392314;38257.6174238254;38262.42606360962;38373.61626383701;38591.42112868365;39102.21795564016;32312.515312270803;33505.66494335025;34475.65524344725;34649.653503464644;34887;21602.891985539973;23376.415589609715;24891;25630.693237608706;25870.870645647403 19:31:51 INFO - PROCESS | 1649 | |2;jquery.html;345.43176474699135;371.62280285510207;374.30566299514385;384.571203108534;405.97970101494917;15108.848911510882;15220.391184352626;16815.83184168158;20882.582348353022;21055.2630657927;19686.90156549219;20633.896830515863;20997.685034724454;21159.68260476094;21387.67918481224;5886.911696324559;5890.734916928748;6073.696315184223;6196.566240363177;6211.534134939875;4192.49690037196;5988;6097.237845269341;6103.05402662588;6112.205413296265;4389.297712366022;4399.38408622793;4408.382826404306;4412.051408947075;6754.121964144654;6739.764108256237;6753.797386078426;6755.1556055493065;6755.628440435774;6784.118064651589;5601.775928962837;5782.595218334718;5827.650340979555;5842.4741773240185;5844.766209351621;26051.478970420485;26346;26471.33821654474;26497.072602458822;26836.865815670797;2088;2159.470929622237;2827;3018.8490575471137;3080.86136123875;14739.852601473955;15025.474108406153;15608;15944.202789860461;16158.434454794025;1795.9371422000297;1878.2862512245397;1880.69908814589;1899.4871384726184;1909.455805095541;109370;111731.20671983184;112273;112378;112997.43501282523;8551.572421378905;10561.894381056245;10626.415547144827;10671.733206669895;11010.39442830652;13843.4462621494;13846.93076534611;13848.376823042987;13869.930650346885;13881.305934703225;6909.861802764017;8352.164783521599;8508.404411691246;8584.699535516353;8660.26387757035;6877.9656101720175;7619.199984001711;7930.731083026688;8662.653493860302;8698.304135669132;5442.782288708487;6481.578697384655;6578.934210657928;6587.769428070091;6622.10601568782;1708.4959936818668;1715.5711072231943;1852.2313240005517;1856.2389420337597;1863.3850829226324;854.7051267312892;887.6183241206344;935.780091678455;940.3323640215386;1007.8740157480315;20659.276925307542;22206.777932220793;22224.777752222595;22364.217252396415;22699.88650056739;943.5046600534774;946.6402766948515;970.5486948568808;982.1357205658975;1058.9523471443804;9649.951750241204;11101.72245693848;11607.825882611598;11639.418029098511;11668.416579171007;470941.64529177133;474214;475357;560862;563951;135091.64908350987;140616.1876762459;143388;145475;150942;899.8290324838259;990.9108180263811;1030.4023666273542;1030.7526193713604;1031.3708637730979;18370.448886533424;18642.16110275014;18819.059047047867;19002.619947601248;19064.29722791606;38263.85208443751;44994.77502612466;49285.26072108993;53650.19524707211;55061.174082389596;82357;82848.17151828404;83777;85231;85424.57287713522;5914;8094.352451803959;8215.917840821516;8279.834403312021;8348.123447037973;10915.454227288763;12365.629031129081;12965.935170324088;13325.267110309027;13363.465461381436;9849.901500984899;11128.499217535393;11410.754497537864;11444.713882153013;11599.130065244972;8889;11069.225154239126;11220.19216408616;11590.768184636092;11770.411479426166;10058.748531286776;11202.38386888729;11456.427178640868;11921.940390297994;12020.03839692805;8085.312748416451;8205.83588328242;8280.461769984931;8280.627371768405;8313.958430208053;9725.416475011521;11237.662870113909;11623.418829058683;11626.476808543466;11686.70783230426 19:31:51 INFO - PROCESS | 1649 | |3;mootools.html;1133.7392399748062;1468.5447511271502;1507.4648499782577;1688.8226736192698;1860.6278744251154;7651.655675494602;8482.321414286858;8726.738197854067;8740.51927144007;8818.338624603148;8594.871076933836;8690.608922598498;8693.7826554336;8700.869486957701;8719.258862996638;2312.4103353644796;2340.3447034830274;2347.2606129069372;2347.307544274435;2349.647552867075;2333.614953532665;2352.494213744045;2392.760723927602;2396.1733202045266;2403.098837935774;2354.6350315701006;2375.406148462884;2378.8810559472045;2391.1869964212165;2391.258709799959;2337.8947947342413;2358.811295096388;2373.276150774022;2374.4063984004;2377.215518878766;2206.6910632511463;2334.8015418689433;2361.374235827507;2362.090595120874;2368.1237941961413;8568;8699.304055675531;8715.346349023848;8722.520261385622;8792.648294068229;5206.713630750307;5360.544353729938;5370.409254981949;5377.440542242745;5383.946160538384;3574.696150827183;3584.964150358489;3636.7636103653176;3647.2705458908326;3656.597774244831;957.8563215517659;1086.9619563315325;1098.23123813331;1101.0750969185922;1115.8437818705388;8591.398602097855;11376.260543064674;11481.425928703531;11505.942470287679;11525.94237028818;4186.958130418657;4331.678837954391;4371.475422949203;4526.728396296233;4610.608098311614;8990.235829954396;8995;9000.279977601776;9010.3242256831;9017.81964360709;5207.505286997729;5211.687298762011;5222.399424066205;5236;5236.266922630834;4374.544318300194;4408.448943882015;4572.6799124061;4711.199096153662;4919.434265059564;3666.5233519642275;3703.7777333599624;3819.25524522721;3826.559945606291;3830.540335159799;1110.0398155595512;1117.932924024561;1127.3405058041137;1132.8470656461272;1133.9716507087223;893.5353616119581;911.4303560274828;922.5940586142076;935.9110884465965;943.3113826906259;5706.4864162225595;5931.940680593225;5936.990711579042;6126.663033533198;6140.969295153585;419.85934711871863;429.4009856250513;436.4936673458836;437.83362322317953;441.6533021578046;3834.8657796976972;3839.7120215983914;3989.1822176453757;4032.7983600819844;4086.959130408658;9796.26528010402;10020.048095430922;10179.898201018043;10708.08981236588;10753.731156720987;10637.149028077734;10664.253502254924;10673.412962286993;10677;10701.250912436055;7740.612969351622;8112.75661730149;8113.432059755878;8140.331232097525;8249.505029698237;5833.912491312719;5888.646681199141;5984.311804142405;6052.425019623129;6091.695415229309;9718.70843874685;9949.104580587782;9981.101700846959;9984.201263898725;10003.899961000297;9791.314607977372;10033.548490317808;10102.89897101049;10239.795204096024;10244.02681745233;6864.828379290358;7092.184398794205;7272.745453909088;7430.182679905314;7526.548407095592;7785;7867.095284042409;7871.448998570045;8063.83872322562;8331.50010999342;6664.3335666434905;7308.890366644611;7429.851402972018;7492.887606686013;7597.126330472067;7326.816829579303;7345.338919497271;7348.669309881175;7352.758895945844;7405.074365704287;6795.830104247433;6819.659017049227;6921.134858142732;6921.723131074902;6941;4996.925046124238;5000.2999580058095;5029.698218106925;5054.444011158843;5132.948670513247;4915.680480768739;4987.975060124676;5046.394432668103;5122.462141475092;5169.198774190007 19:31:51 INFO - PROCESS | 1649 | |4;prototype.html;293.8222375462844;299.8216061443441;308.59265769184685;308.82860012692953;311.1753852291429;5176.559992400646;5186.662866913648;5189.261597364878;5190.792368305263;5240.082985477546;4544.704594201375;4648.395708557883;4855.757212139397;4993.750312484371;5176.145935920578;3177.85699643516;3284.4088064148445;3767.6420740029653;3935.6654684351865;3981.9402708959387;3181.077487528614;3729.955612428511;3926.391409331544;3942.211557688474;3981.362981922893;3475.496053072303;3724.9383925581214;3796.8799204234815;3839.520059992501;3857.2063990244533;3922.431247469101;3927.528696556403;3962.167944731581;3965.325894597911;3968.206358728237;3163.889263875776;3419.820162044091;3421.4354631485776;3433.3133373325186;3468.3063387322636;4624.329472226542;4865.270209468573;4871.293662418965;4881.144530449691;4891.779869905828;3698.685611723007;4253.085586598865;4436.513767887762;4452.732836029849;4516.254817955067;5368.792021795104;5373.946260537384;5385.515303622693;5386.542143917772;5420.159875219348;3572.517710109154;3710.3692372296773;3769.830357633913;3771.1326394929047;3778.866340097987;4878.853634390974;5199.558037566811;5588.273524441796;5622.775088996435;5683.374828768873;3254.251522149919;3274.590676165479;3715.4426835974277;3729.123655940906;3736.215394767075;3768.8869333920024;3773.924341612157;3778.7354885158325;3781.1870447853844;3786.1670432504807;3882.980585097113;4273.401723758676;4436.977815110904;4437.955620443754;4448.5773851484055;5389;5394.487523685244;5401.297831281908;5418.756155972991;5467.890642187213;1135.9091272698165;1168.1472525056756;1260.714071646915;1262.987370126287;1289.3488788162106;739.0010022888075;746.895434639151;829.8630875700329;847.588919374103;883.9337049721297;4939.110960027229;4956.851294461172;4998.475160108209;5005.873678422398;5045.217991211368;5482.451754824486;5507.779688812483;5508.889822203533;5540.670239142577;5589.329280486368;4176.872244493969;4368.541303163186;4544.7500387479;4707.482176960531;4953.306537084811;2419.7901049475263;2524.270874451007;2613;2854.9000784972427;2872.2532141643323;5189.091908915924;5324.813631522956;5351.678899266057;5636.549076073904;5661.348944871393;4449.955500444954;5179.477954990567;5519.31008623922;5572.693501857356;5594.049011667945;4690.718556886598;5583.860403489945;5646.289780905188;5649.943500565024;5673.3475650299915;4908.484609115992;5317.9364127175495;5409.891802163856;5457.481539253765;5492.752826122754;4251.51107622615;5036.420811606712;5453.227700997271;5522.392536821027;5561.304836895388;5048.545630893237;5577;5796.333421656564;5801.622894511843;5804.9655489434435;3147.905562833119;3603.6396360363756;3730.6642402183934;3753.324401607737;3757.49273848027;4633.096546173464;5494.313210848644;5554.666719996651;5563;5574.331080270394;4536.637069034536;5421.349438067457;5437.374701909331;5452.563794896478;5488.368837583729;4792.424909010941;5109.9744501277255;5378.848460615629;5419.972900135632;5485.314335708036;5042.3949126103635;5311.575073993994;5317.813876514303;5347.411784703757;5377.516023557899;4479.977600111979;5320.866978325573;5347.7593508293;5360.839174824761;5371.657085728568;5119.232115182753;5274.736263186902;5323.201519772065;5329.653572517774;5356 19:31:51 INFO - PROCESS | 1649 | |5;yui.html;718.8346680263539;736.8710475666757;772.0465225446573;786.7482405630197;815.812363156474;15034.548963531099;15708.565172398268;15712.842871571294;15722.37110515578;15740.976836505633;14140.434382624682;14265.500707475241;14275.643108922282;14275.92862035691;14768.704625907501;664.9833754156136;678.043958019193;757.9962100189491;838.5974732128582;839.924406803389;847.8134810341714;850.6512329944724;855.1662129423825;855.5807654249435;858.4291446188278;910.2854259406367;910.5674804467891;911.8267529169436;916.2807196350866;917.7200953709149;705.2982282628817;888.9155530224618;908.1372695938885;910.9681161159394;912.5574096563162;675.537256978971;681.0397339750928;681.5501768832547;682.4165338635495;683.5591043776786;14782.334794934253;14785.26073696311;14805.925970370186;14808;14832.555023349316;13329.466821327136;13559.59321220355;13739.931300343535;13757.31213439334;13832.75589458398;11496.367699776509;11528.423578821026;11560.30638161713;11567.53729850805;11973.880261197364;4462.620677242438;4503.819847206108;4513.345564893072;4574.725516469022;4704.9059018819435;107467.46266268619;107815.92184078138;107864;111517.44241278822;112668;10655.89344106549;10775.568977240979;10790.568377264979;10923.617673381394;11040.613578524873;21013.15947362119;21031;21050.78949210519;21075.8946205268;21080.894595526923;7815.609219539;7939.444238903337;8002.159773223844;8096.797580060545;8102.0277566692375;4335;4345.217860785089;4431.667624928079;4610.538946105363;4612.769361531977;3339.9833000835324;3443.0359499340225;3443.724502039881;3449.8792542261403;3567.2687099144614;608.330836080317;650.7917466410797;693.2062788107689;698.1029377250209;723.280336065618;637.2957881540923;647.2071712152575;652.9085927970089;672.1631568697004;683.1563019670702;8552.230299272971;9540.713778586654;9664.71005869825;9675.806483870283;9697.563609637584;638.450932198309;639.587466084373;679.2833560593523;680.5270337115658;701.5930760159096;4467.262901621196;4761.214399624023;4767.785449654774;4788.664793464493;4854.844697438436;62002;77166;101063.97872042518;160685;171500;125578;138400;140281.59718402888;142354;142482;71335;76057.6197119022;94039;95208;98769.50615247166;8986.82026359456;9204.21764150028;9393;9748.707538773848;9749.463779491913;55387.72306138444;56761.58096047467;57254;57385;58166;81630;83679;88421.58930731493;91795.4102294896;95482;12721.056472493558;12924.870751292743;13337.866621333662;13884.514042008885;13938.860611394162;15177.772333414787;15331.463398781436;15436.22818859075;15899;15929.840701593299;13827.723445531234;14065.648358791112;14110.858891410955;14184.148951062969;14338.49815256461;13929;14372;14545.490907818175;14758.040727352687;14950.775738364151;13776.86223137796;13865.514706985206;14059.367328470447;14477.914156438099;14654.706905861609;11290.548378064785;11514.30914145154;11565.363904985306;11676.591319303783;11895.048396128463;10016.499175041074;10631.787364252827;11135.498902549243;11535.790825119662;11809.940950295193 19:31:51 INFO - PROCESS | 1649 | __end_tp_report 19:31:51 INFO - PROCESS | 1649 | __start_cc_report 19:31:51 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1228 19:31:51 INFO - PROCESS | 1649 | __end_cc_report 19:31:51 INFO - PROCESS | 1649 | __startTimestamp1472524311121__endTimestamp 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | ------- Summary: start ------- 19:31:51 INFO - PROCESS | 1649 | Number of tests: 6 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5611.03 Median:5778.65 stddev:637.03 (11.0%) stddev-sans-first:612.23 19:31:51 INFO - PROCESS | 1649 | Values: 3282.2 3284.3 3327.5 3348.9 3389.5 5466.1 5493.3 5575.5 5580.4 5586.6 5519.3 5544.6 5600.7 5644.8 5676.3 5019.9 5084.5 5090.2 5100.3 5244.1 5182.6 5185.8 5193.1 5195.9 5208.4 5024.3 5077.0 5082.6 5143.0 5189.5 5090.8 5105.9 5114.6 5118.3 5132.5 5052.9 5061.9 5091.4 5097.4 5099.4 5450.8 5518.9 5529.8 5644.0 5662.7 5991.9 6004.1 6009.3 6086.4 6096.2 6027.8 6146.6 6147.5 6174.3 6177.6 5543.4 5559.5 5603.7 5714.9 5732.3 5154.6 6244.8 6291.2 6307.9 6310.2 4820.0 5987.3 5997.8 6000.5 6002.4 5953.4 6027.0 6038.7 6138.1 6161.1 6053.0 6112.9 6116.0 6138.8 6139.4 6120.7 6130.5 6144.4 6152.6 6159.8 4809.0 4850.9 4863.7 4929.5 4958.3 3704.4 4333.9 4342.2 4421.3 4494.3 5590.5 5591.0 5594.1 5602.4 5609.1 5999.0 6193.4 6199.0 6204.5 6215.8 5565.4 5590.9 5598.6 5637.6 5686.9 4524.4 5337.4 5339.5 5363.1 5365.8 6141.8 6234.3 6243.7 6251.9 6258.6 5949.2 6075.4 6127.6 6135.5 6141.2 6214.6 6263.7 6271.6 6274.7 6284.6 6036.9 6044.8 6061.0 6064.4 6065.7 6031.7 6071.5 6102.1 6130.1 6165.7 5975.7 5982.6 5985.4 5988.2 6034.5 4971.0 5829.3 5879.8 5983.7 5989.1 5669.1 5707.2 5797.2 5799.4 5873.4 5907.8 5909.6 5926.6 5941.3 5942.2 5621.5 5766.6 5778.7 5779.7 5791.6 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:70555.49 Median:38318.02 stddev:155188.56 (405.0%) stddev-sans-first:155534.71 19:31:51 INFO - PROCESS | 1649 | Values: 477.5 498.5 520.5 522.6 539.2 30266.0 30448.5 39864.6 41010.6 41174.0 41984.0 42212.8 42390.6 42415.0 42511.1 9720.0 9901.0 9902.5 9927.0 9928.6 8760.0 8882.3 9564.8 12047.9 12074.6 10211.4 11970.9 12014.7 12016.2 12170.4 8749.8 12154.2 12214.3 12252.8 12255.4 11686.0 12029.5 12126.6 12289.6 12295.1 40245.0 40589.6 40635.0 40704.8 40716.4 3501.2 4011.6 4034.2 4106.6 4167.1 30064.8 31198.5 31335.0 32183.4 32185.5 3117.3 3230.5 3232.7 3235.6 3294.8 501744.0 505589.5 506019.0 507047.0 508004.0 56331.4 58613.0 59052.4 59086.7 59202.7 63671.0 63818.0 63826.0 63921.0 63971.0 14177.5 14304.2 14318.6 14330.6 16593.8 11463.9 11707.4 12724.0 12809.3 12813.6 15424.5 18177.3 18642.3 18974.7 19076.5 6694.5 6747.2 7664.8 7825.9 7885.0 895.5 950.7 952.0 1065.7 1070.7 29421.3 29598.3 29691.0 30142.4 30367.2 940.5 1015.6 1073.4 1110.2 1119.5 15266.0 15326.2 15448.0 15584.1 15650.5 613082.0 620766.0 621572.0 622200.0 856142.0 55983.0 56797.7 56828.0 59371.4 59508.0 1333.1 1359.9 1380.3 1382.0 1384.8 29984.8 30726.7 30755.7 31383.8 31662.7 48870.0 49186.3 49221.5 49258.8 49289.3 545128.0 548596.0 549259.0 549452.0 549497.0 25812.7 25884.0 26066.2 26341.6 26367.9 46030.1 47089.8 47389.0 47425.5 47565.8 31130.8 37042.9 38704.8 39720.2 40013.8 28600.0 38085.0 38439.8 39190.4 39223.6 38257.6 38262.4 38373.6 38591.4 39102.2 32312.5 33505.7 34475.7 34649.7 34887.0 21602.9 23376.4 24891.0 25630.7 25870.9 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:32973.75 Median:26666.97 stddev:86589.29 (324.7%) stddev-sans-first:86797.54 19:31:51 INFO - PROCESS | 1649 | Values: 345.4 371.6 374.3 384.6 406.0 15108.8 15220.4 16815.8 20882.6 21055.3 19686.9 20633.9 20997.7 21159.7 21387.7 5886.9 5890.7 6073.7 6196.6 6211.5 4192.5 5988.0 6097.2 6103.1 6112.2 4389.3 4399.4 4408.4 4412.1 6754.1 6739.8 6753.8 6755.2 6755.6 6784.1 5601.8 5782.6 5827.7 5842.5 5844.8 26051.5 26346.0 26471.3 26497.1 26836.9 2088.0 2159.5 2827.0 3018.8 3080.9 14739.9 15025.5 15608.0 15944.2 16158.4 1795.9 1878.3 1880.7 1899.5 1909.5 109370.0 111731.2 112273.0 112378.0 112997.4 8551.6 10561.9 10626.4 10671.7 11010.4 13843.4 13846.9 13848.4 13869.9 13881.3 6909.9 8352.2 8508.4 8584.7 8660.3 6878.0 7619.2 7930.7 8662.7 8698.3 5442.8 6481.6 6578.9 6587.8 6622.1 1708.5 1715.6 1852.2 1856.2 1863.4 854.7 887.6 935.8 940.3 1007.9 20659.3 22206.8 22224.8 22364.2 22699.9 943.5 946.6 970.5 982.1 1059.0 9650.0 11101.7 11607.8 11639.4 11668.4 470941.6 474214.0 475357.0 560862.0 563951.0 135091.6 140616.2 143388.0 145475.0 150942.0 899.8 990.9 1030.4 1030.8 1031.4 18370.4 18642.2 18819.1 19002.6 19064.3 38263.9 44994.8 49285.3 53650.2 55061.2 82357.0 82848.2 83777.0 85231.0 85424.6 5914.0 8094.4 8215.9 8279.8 8348.1 10915.5 12365.6 12965.9 13325.3 13363.5 9849.9 11128.5 11410.8 11444.7 11599.1 8889.0 11069.2 11220.2 11590.8 11770.4 10058.7 11202.4 11456.4 11921.9 12020.0 8085.3 8205.8 8280.5 8280.6 8314.0 9725.4 11237.7 11623.4 11626.5 11686.7 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5593.59 Median:5015.00 stddev:3124.78 (62.3%) stddev-sans-first:3115.57 19:31:51 INFO - PROCESS | 1649 | Values: 1133.7 1468.5 1507.5 1688.8 1860.6 7651.7 8482.3 8726.7 8740.5 8818.3 8594.9 8690.6 8693.8 8700.9 8719.3 2312.4 2340.3 2347.3 2347.3 2349.6 2333.6 2352.5 2392.8 2396.2 2403.1 2354.6 2375.4 2378.9 2391.2 2391.3 2337.9 2358.8 2373.3 2374.4 2377.2 2206.7 2334.8 2361.4 2362.1 2368.1 8568.0 8699.3 8715.3 8722.5 8792.6 5206.7 5360.5 5370.4 5377.4 5383.9 3574.7 3585.0 3636.8 3647.3 3656.6 957.9 1087.0 1098.2 1101.1 1115.8 8591.4 11376.3 11481.4 11505.9 11525.9 4187.0 4331.7 4371.5 4526.7 4610.6 8990.2 8995.0 9000.3 9010.3 9017.8 5207.5 5211.7 5222.4 5236.0 5236.3 4374.5 4408.4 4572.7 4711.2 4919.4 3666.5 3703.8 3819.3 3826.6 3830.5 1110.0 1117.9 1127.3 1132.8 1134.0 893.5 911.4 922.6 935.9 943.3 5706.5 5931.9 5937.0 6126.7 6141.0 419.9 429.4 436.5 437.8 441.7 3834.9 3839.7 3989.2 4032.8 4087.0 9796.3 10020.0 10179.9 10708.1 10753.7 10637.1 10664.3 10673.4 10677.0 10701.3 7740.6 8112.8 8113.4 8140.3 8249.5 5833.9 5888.6 5984.3 6052.4 6091.7 9718.7 9949.1 9981.1 9984.2 10003.9 9791.3 10033.5 10102.9 10239.8 10244.0 6864.8 7092.2 7272.7 7430.2 7526.5 7785.0 7867.1 7871.4 8063.8 8331.5 6664.3 7308.9 7429.9 7492.9 7597.1 7326.8 7345.3 7348.7 7352.8 7405.1 6795.8 6819.7 6921.1 6921.7 6941.0 4996.9 5000.3 5029.7 5054.4 5132.9 4915.7 4988.0 5046.4 5122.5 5169.2 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4350.47 Median:4923.80 stddev:1353.59 (27.5%) stddev-sans-first:1322.70 19:31:51 INFO - PROCESS | 1649 | Values: 293.8 299.8 308.6 308.8 311.2 5176.6 5186.7 5189.3 5190.8 5240.1 4544.7 4648.4 4855.8 4993.8 5176.1 3177.9 3284.4 3767.6 3935.7 3981.9 3181.1 3730.0 3926.4 3942.2 3981.4 3475.5 3724.9 3796.9 3839.5 3857.2 3922.4 3927.5 3962.2 3965.3 3968.2 3163.9 3419.8 3421.4 3433.3 3468.3 4624.3 4865.3 4871.3 4881.1 4891.8 3698.7 4253.1 4436.5 4452.7 4516.3 5368.8 5373.9 5385.5 5386.5 5420.2 3572.5 3710.4 3769.8 3771.1 3778.9 4878.9 5199.6 5588.3 5622.8 5683.4 3254.3 3274.6 3715.4 3729.1 3736.2 3768.9 3773.9 3778.7 3781.2 3786.2 3883.0 4273.4 4437.0 4438.0 4448.6 5389.0 5394.5 5401.3 5418.8 5467.9 1135.9 1168.1 1260.7 1263.0 1289.3 739.0 746.9 829.9 847.6 883.9 4939.1 4956.9 4998.5 5005.9 5045.2 5482.5 5507.8 5508.9 5540.7 5589.3 4176.9 4368.5 4544.8 4707.5 4953.3 2419.8 2524.3 2613.0 2854.9 2872.3 5189.1 5324.8 5351.7 5636.5 5661.3 4450.0 5179.5 5519.3 5572.7 5594.0 4690.7 5583.9 5646.3 5649.9 5673.3 4908.5 5317.9 5409.9 5457.5 5492.8 4251.5 5036.4 5453.2 5522.4 5561.3 5048.5 5577.0 5796.3 5801.6 5805.0 3147.9 3603.6 3730.7 3753.3 3757.5 4633.1 5494.3 5554.7 5563.0 5574.3 4536.6 5421.3 5437.4 5452.6 5488.4 4792.4 5110.0 5378.8 5420.0 5485.3 5042.4 5311.6 5317.8 5347.4 5377.5 4480.0 5320.9 5347.8 5360.8 5371.7 5119.2 5274.7 5323.2 5329.7 5356.0 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:23364.21 Median:4447.14 stddev:36745.97 (826.3%) stddev-sans-first:36809.71 19:31:51 INFO - PROCESS | 1649 | Values: 718.8 736.9 772.0 786.7 815.8 15034.5 15708.6 15712.8 15722.4 15741.0 14140.4 14265.5 14275.6 14275.9 14768.7 665.0 678.0 758.0 838.6 839.9 847.8 850.7 855.2 855.6 858.4 910.3 910.6 911.8 916.3 917.7 705.3 888.9 908.1 911.0 912.6 675.5 681.0 681.6 682.4 683.6 14782.3 14785.3 14805.9 14808.0 14832.6 13329.5 13559.6 13739.9 13757.3 13832.8 11496.4 11528.4 11560.3 11567.5 11973.9 4462.6 4503.8 4513.3 4574.7 4704.9 107467.5 107815.9 107864.0 111517.4 112668.0 10655.9 10775.6 10790.6 10923.6 11040.6 21013.2 21031.0 21050.8 21075.9 21080.9 7815.6 7939.4 8002.2 8096.8 8102.0 4335.0 4345.2 4431.7 4610.5 4612.8 3340.0 3443.0 3443.7 3449.9 3567.3 608.3 650.8 693.2 698.1 723.3 637.3 647.2 652.9 672.2 683.2 8552.2 9540.7 9664.7 9675.8 9697.6 638.5 639.6 679.3 680.5 701.6 4467.3 4761.2 4767.8 4788.7 4854.8 62002.0 77166.0 101064.0 160685.0 171500.0 125578.0 138400.0 140281.6 142354.0 142482.0 71335.0 76057.6 94039.0 95208.0 98769.5 8986.8 9204.2 9393.0 9748.7 9749.5 55387.7 56761.6 57254.0 57385.0 58166.0 81630.0 83679.0 88421.6 91795.4 95482.0 12721.1 12924.9 13337.9 13884.5 13938.9 15177.8 15331.5 15436.2 15899.0 15929.8 13827.7 14065.6 14110.9 14184.1 14338.5 13929.0 14372.0 14545.5 14758.0 14950.8 13776.9 13865.5 14059.4 14477.9 14654.7 11290.5 11514.3 11565.4 11676.6 11895.0 10016.5 10631.8 11135.5 11535.8 11809.9 19:31:51 INFO - PROCESS | 1649 | -------- Summary: end -------- 19:31:51 INFO - PROCESS | 1649 | 19:31:51 INFO - TEST-INFO | 1649: exit 0 19:31:51 INFO - TEST-OK | dromaeo_css | took 1118058ms 19:31:51 INFO - TEST-START | kraken 19:31:51 INFO - operating with platform_type : mac_ 19:31:51 INFO - Initialising browser for kraken test... 19:31:51 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmp4ZrNaC/profile http://localhost:49262/getInfo.html) 19:31:53 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 19:31:53 INFO - PROCESS | 1656 | colorDepth:24 19:31:53 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 19:31:53 INFO - PROCESS | 1656 | __metrics 19:31:53 INFO - PROCESS | 1656 | 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" 19:31:53 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:54 INFO - TEST-INFO | 1656: exit 0 19:31:54 INFO - Browser initialized. 19:31:54 INFO - Running cycle 1/1 for kraken test... 19:31:54 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmp4ZrNaC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:32:06 INFO - PROCESS | 1657 | RSS: Main: 234352640 19:32:06 INFO - PROCESS | 1657 | 19:32:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 19:32:10 INFO - PROCESS | 1657 | RSS: Main: 292683776 19:32:10 INFO - PROCESS | 1657 | 19:32:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 19:32:13 INFO - PROCESS | 1657 | RSS: Main: 289792000 19:32:13 INFO - PROCESS | 1657 | 19:32:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 19:32:17 INFO - PROCESS | 1657 | RSS: Main: 378236928 19:32:17 INFO - PROCESS | 1657 | 19:32:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 19:32:20 INFO - PROCESS | 1657 | RSS: Main: 282415104 19:32:20 INFO - PROCESS | 1657 | 19:32:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:23 INFO - PROCESS | 1657 | RSS: Main: 284766208 19:32:23 INFO - PROCESS | 1657 | 19:32:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:26 INFO - PROCESS | 1657 | RSS: Main: 322863104 19:32:26 INFO - PROCESS | 1657 | 19:32:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:30 INFO - PROCESS | 1657 | RSS: Main: 323063808 19:32:30 INFO - PROCESS | 1657 | 19:32:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 19:32:33 INFO - PROCESS | 1657 | RSS: Main: 322514944 19:32:33 INFO - PROCESS | 1657 | 19:32:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:35 INFO - PROCESS | 1657 | RSS: Main: 299245568 19:32:35 INFO - PROCESS | 1657 | 19:32:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:38 INFO - PROCESS | 1657 | RSS: Main: 304713728 19:32:38 INFO - PROCESS | 1657 | 19:32:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:41 INFO - PROCESS | 1657 | RSS: Main: 302403584 19:32:41 INFO - PROCESS | 1657 | 19:32:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:44 INFO - PROCESS | 1657 | RSS: Main: 321105920 19:32:44 INFO - PROCESS | 1657 | 19:32:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:48 INFO - PROCESS | 1657 | RSS: Main: 317284352 19:32:48 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 19:32:50 INFO - PROCESS | 1657 | RSS: Main: 360583168 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | __start_tp_report 19:32:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 19:32:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 19:32:50 INFO - PROCESS | 1657 | |i|pagename|runs| 19:32:50 INFO - PROCESS | 1657 | |0;ai-astar;113;112;132;111;112;112;111;112;112;112 19:32:50 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;107;146;200;108;131;127;108;198;107 19:32:50 INFO - PROCESS | 1657 | |2;audio-dft;184;183;183;182;184;181;183;181;182;180 19:32:50 INFO - PROCESS | 1657 | |3;audio-fft;74;73;76;156;58;59;64;87;167;49 19:32:50 INFO - PROCESS | 1657 | |4;audio-oscillator;82;82;134;69;71;76;117;68;69;117 19:32:50 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;88;85;87;84;87;84;88;84;86;84 19:32:50 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;96;123;162;94;96;95;96;95;95 19:32:50 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;85;85;84;86;83;87;85;84;113 19:32:50 INFO - PROCESS | 1657 | |8;json-parse-financial;53;53;54;54;55;54;54;53;56;55 19:32:50 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;73;69;82;72;71;71;70;71;72 19:32:50 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;88;75;99;96;105;103;83;88;86 19:32:50 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;171;158;156;144;140;135;148;154;191;140 19:32:50 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;171;169;168;184;231;235;170;169;168;170 19:32:50 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;68;65;68;68;68;67;67;67 19:32:50 INFO - PROCESS | 1657 | __end_tp_report 19:32:50 INFO - PROCESS | 1657 | __start_cc_report 19:32:50 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2074 19:32:50 INFO - PROCESS | 1657 | __end_cc_report 19:32:50 INFO - PROCESS | 1657 | __startTimestamp1472524370489__endTimestamp 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | ------- Summary: start ------- 19:32:50 INFO - PROCESS | 1657 | Number of tests: 14 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:113.90 Median:112.00 stddev:6.38 (5.7%) stddev-sans-first:6.76 19:32:50 INFO - PROCESS | 1657 | Values: 113.0 112.0 132.0 111.0 112.0 112.0 111.0 112.0 112.0 112.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:137.00 stddev:35.63 (26.0%) stddev-sans-first:37.73 19:32:50 INFO - PROCESS | 1657 | Values: 143.0 107.0 146.0 200.0 108.0 131.0 127.0 108.0 198.0 107.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:182.30 Median:183.00 stddev:1.34 (0.7%) stddev-sans-first:1.27 19:32:50 INFO - PROCESS | 1657 | Values: 184.0 183.0 183.0 182.0 184.0 181.0 183.0 181.0 182.0 180.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:86.30 Median:68.50 stddev:41.15 (60.1%) stddev-sans-first:43.41 19:32:50 INFO - PROCESS | 1657 | Values: 74.0 73.0 76.0 156.0 58.0 59.0 64.0 87.0 167.0 49.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:70.00 stddev:24.55 (35.1%) stddev-sans-first:25.92 19:32:50 INFO - PROCESS | 1657 | Values: 82.0 82.0 134.0 69.0 71.0 76.0 117.0 68.0 69.0 117.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.59 19:32:50 INFO - PROCESS | 1657 | Values: 88.0 85.0 87.0 84.0 87.0 84.0 88.0 84.0 86.0 84.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:95.50 stddev:21.86 (22.9%) stddev-sans-first:23.00 19:32:50 INFO - PROCESS | 1657 | Values: 97.0 96.0 123.0 162.0 94.0 96.0 95.0 96.0 95.0 95.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:87.80 Median:85.00 stddev:8.93 (10.5%) stddev-sans-first:9.45 19:32:50 INFO - PROCESS | 1657 | Values: 86.0 85.0 85.0 84.0 86.0 83.0 87.0 85.0 84.0 113.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 19:32:50 INFO - PROCESS | 1657 | Values: 53.0 53.0 54.0 54.0 55.0 54.0 54.0 53.0 56.0 55.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:4.11 (5.7%) stddev-sans-first:3.81 19:32:50 INFO - PROCESS | 1657 | Values: 66.0 73.0 69.0 82.0 72.0 71.0 71.0 70.0 71.0 72.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.00 stddev:9.53 (11.0%) stddev-sans-first:9.94 19:32:50 INFO - PROCESS | 1657 | Values: 86.0 88.0 75.0 99.0 96.0 105.0 103.0 83.0 88.0 86.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:155.00 stddev:16.89 (10.9%) stddev-sans-first:16.71 19:32:50 INFO - PROCESS | 1657 | Values: 171.0 158.0 156.0 144.0 140.0 135.0 148.0 154.0 191.0 140.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.50 Median:170.50 stddev:26.52 (15.6%) stddev-sans-first:27.74 19:32:50 INFO - PROCESS | 1657 | Values: 171.0 169.0 168.0 184.0 231.0 235.0 170.0 169.0 168.0 170.0 19:32:50 INFO - PROCESS | 1657 | 19:32:50 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 19:32:50 INFO - PROCESS | 1657 | Values: 67.0 68.0 68.0 65.0 68.0 68.0 68.0 67.0 67.0 67.0 19:32:50 INFO - PROCESS | 1657 | -------- Summary: end -------- 19:32:50 INFO - PROCESS | 1657 | 19:32:51 INFO - TEST-INFO | 1657: exit 0 19:32:51 INFO - TEST-OK | kraken | took 59575ms 19:32:51 INFO - SUITE-END | took 1177s 19:32:51 INFO - Completed test suite (00:19:37) 19:32:51 INFO - javascript benchmark 19:32:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.195862013806, 3284.2610412657154, 3327.484239942808, 3348.9497657535126, 3389.5424117744096, 5466.098093814526, 5493.258410114633, 5575.470330318618, 5580.386157522679, 5586.581006424519, 5519.254900588419, 5544.584156188282, 5600.691961942091, 5644.8024319148835, 5676.347220069693, 5019.899602007958, 5084.491550844905, 5090.185570308751, 5100.285959965608, 5244.082285600024, 5182.637215394924, 5185.844424667266, 5193.117170081076, 5195.896082078356, 5208.401033881095, 5024.32171656829, 5076.974615126937, 5082.644214904958, 5142.999999999981, 5189.506996835295, 5090.77091530882, 5105.923411148836, 5114.59083273341, 5118.2578526113875, 5132.461091585367, 5052.949470505321, 5061.949380506221, 5091.439941606457, 5097.413797413271, 5099.388073431175, 5450.781968721247, 5518.91721624176, 5529.778808847641, 5643.971780141114, 5662.688552129631, 5991.850203744898, 6004.12940123684, 6009.338972712998, 6086.362298671702, 6096.1770161028135, 6027.849303767397, 6146.631202127842, 6147.451105484557, 6174.289956654998, 6177.598456100384, 5543.445655434424, 5559.499645031967, 5603.663780173202, 5714.885702285973, 5732.283464566929, 5154.561862241751, 6244.781432649836, 6291.213598300213, 6307.873842523124, 6310.242770867433, 4820.0, 5987.251593550806, 5997.820065398045, 6000.459958603747, 6002.369751176151, 5953.434423729739, 6027.035674292092, 6038.706090823769, 6138.1099740537375, 6161.137440758297, 6053.031514957585, 6112.898653789767, 6115.970584430425, 6138.785142519991, 6139.369480960552, 6120.690942950841, 6130.509559235251, 6144.446999770043, 6152.630842149485, 6159.753609855556, 4809.0, 4850.899349028475, 4863.7324947128245, 4929.457759646436, 4958.305837182797, 3704.3517384457614, 4333.9133217335475, 4342.218400687906, 4421.270490369053, 4494.325851122292, 5590.496855283044, 5591.0, 5594.0769772987815, 5602.411746766612, 5609.1305847593685, 5998.970005150034, 6193.411625895533, 6199.039148931923, 6204.534659900525, 6215.81352559424, 5565.387807341189, 5590.860228494239, 5598.608097433141, 5637.605367624309, 5686.886262274814, 4524.411826462605, 5337.359516858002, 5339.546138578186, 5363.141897296414, 5365.785368585291, 6141.84645383869, 6234.345393733593, 6243.656598887105, 6251.906221406683, 6258.561900666909, 5949.196858424058, 6075.392460753889, 6127.586602260358, 6135.478484328882, 6141.201643786279, 6214.564980451325, 6263.655498947602, 6271.592346497463, 6274.686265686606, 6284.560080794484, 6036.879262414815, 6044.818655440344, 6061.0, 6064.3935606439, 6065.72704228302, 6031.69841507914, 6071.51427885777, 6102.125716101218, 6130.141780150871, 6165.722542485689, 5975.701214939322, 5982.611130276518, 5985.40145985398, 5988.221531200916, 6034.456898879122, 4971.0, 5829.3296270927685, 5879.794207202898, 5983.730732117152, 5989.101634754822, 5669.064604340238, 5707.1724599933605, 5797.188393624811, 5799.391063938226, 5873.353931067664, 5907.822765317047, 5909.6158749681135, 5926.614770039933, 5941.287045554388, 5942.168096466585, 5621.494065534122, 5766.596338256281, 5778.653280803165, 5779.653220806765, 5791.565632577489], "value": 5571.119747111259, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.52963331118855, 498.4616614056819, 520.497719700489, 522.6106550619784, 539.2450569203115, 30266.0, 30448.54327185094, 39864.60473883411, 41010.58989410105, 41174.0, 41984.0, 42212.78893605528, 42390.57609423905, 42415.0, 42511.149777004444, 9719.951400243006, 9901.0, 9902.504874756234, 9927.007299270088, 9928.602855885792, 8759.95620021899, 8882.289416846637, 9564.760880977996, 12047.939760301186, 12074.637760867188, 10211.438370889598, 11970.94014529926, 12014.699632509213, 12016.158868879182, 12170.391480425988, 8749.825003499958, 12154.209976351553, 12214.32821194834, 12252.81620775689, 12255.387230638476, 11686.0, 12029.51881924722, 12126.6362009139, 12289.56986505468, 12295.139340246187, 40245.0, 40589.57936472239, 40635.0, 40704.79647601773, 40716.38925416121, 3501.2472318451655, 4011.5988401159943, 4034.172994536113, 4106.630403263691, 4167.124903770182, 30064.8496757517, 31198.532022019688, 31335.0, 32183.356332873445, 32185.51721724176, 3117.3297740985795, 3230.5154226865925, 3232.7090561849313, 3235.644079151315, 3294.8352582370785, 501744.0, 505589.47205264476, 506019.0, 507047.0, 508004.0, 56331.43668563344, 58613.0, 59052.40947590469, 59086.704566477754, 59202.70398647979, 63671.0, 63818.0, 63826.0, 63921.0, 63971.0, 14177.503787367392, 14304.213268270138, 14318.642033949234, 14330.641733956525, 16593.751093733605, 11463.88536114645, 11707.414629268502, 12723.982081433463, 12809.295488748208, 12813.551525696555, 15424.534669206407, 18177.272909083753, 18642.34751783681, 18974.71537926932, 19076.52308692266, 6694.464442844561, 6747.224069232003, 7664.770056898302, 7825.882611760828, 7885.0, 895.5298468304062, 950.6672664567346, 951.9524023798782, 1065.733566608348, 1070.6680928912062, 29421.26446838803, 29598.260043499085, 29691.0, 30142.397152057274, 30367.24081897926, 940.4639355567398, 1015.5582321690089, 1073.4418102586612, 1110.2228440091967, 1119.5186069989982, 15266.0, 15326.23368831554, 15448.0, 15584.081011855505, 15650.530484085495, 613082.0, 620766.0, 621572.0, 622200.0, 856142.0, 55983.0, 56797.7160114205, 56828.0, 59371.40628593745, 59508.0, 1333.113479536117, 1359.8708122728326, 1380.2615600653803, 1382.046387992282, 1384.764590019699, 29984.776270882598, 30726.692733072385, 30755.692443075284, 31383.843080785362, 31662.68337316597, 48870.0, 49186.262206066225, 49221.50778492169, 49258.75370623124, 49289.260661090826, 545128.0, 548596.0, 549259.0, 549452.0, 549497.0, 25812.741872581784, 25883.96464141413, 26066.218013459627, 26341.604875926492, 26367.868160659073, 46030.07939841251, 47089.764551177024, 47389.0, 47425.52574474349, 47565.76217118892, 31130.844345778125, 37042.87217122869, 38704.80647596744, 39720.20559588734, 40013.79993100016, 28600.0, 38085.04787380338, 38439.84680459591, 39190.41214381844, 39223.60776392314, 38257.6174238254, 38262.42606360962, 38373.61626383701, 38591.42112868365, 39102.21795564016, 32312.515312270803, 33505.66494335025, 34475.65524344725, 34649.653503464644, 34887.0, 21602.891985539973, 23376.415589609715, 24891.0, 25630.693237608706, 25870.870645647403], "value": 20258.041221794134, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.43176474699135, 371.62280285510207, 374.30566299514385, 384.571203108534, 405.97970101494917, 15108.848911510882, 15220.391184352626, 16815.83184168158, 20882.582348353022, 21055.2630657927, 19686.90156549219, 20633.896830515863, 20997.685034724454, 21159.68260476094, 21387.67918481224, 5886.911696324559, 5890.734916928748, 6073.696315184223, 6196.566240363177, 6211.534134939875, 4192.49690037196, 5988.0, 6097.237845269341, 6103.05402662588, 6112.205413296265, 4389.297712366022, 4399.38408622793, 4408.382826404306, 4412.051408947075, 6754.121964144654, 6739.764108256237, 6753.797386078426, 6755.1556055493065, 6755.628440435774, 6784.118064651589, 5601.775928962837, 5782.595218334718, 5827.650340979555, 5842.4741773240185, 5844.766209351621, 26051.478970420485, 26346.0, 26471.33821654474, 26497.072602458822, 26836.865815670797, 2088.0, 2159.470929622237, 2827.0, 3018.8490575471137, 3080.86136123875, 14739.852601473955, 15025.474108406153, 15608.0, 15944.202789860461, 16158.434454794025, 1795.9371422000297, 1878.2862512245397, 1880.69908814589, 1899.4871384726184, 1909.455805095541, 109370.0, 111731.20671983184, 112273.0, 112378.0, 112997.43501282523, 8551.572421378905, 10561.894381056245, 10626.415547144827, 10671.733206669895, 11010.39442830652, 13843.4462621494, 13846.93076534611, 13848.376823042987, 13869.930650346885, 13881.305934703225, 6909.861802764017, 8352.164783521599, 8508.404411691246, 8584.699535516353, 8660.26387757035, 6877.9656101720175, 7619.199984001711, 7930.731083026688, 8662.653493860302, 8698.304135669132, 5442.782288708487, 6481.578697384655, 6578.934210657928, 6587.769428070091, 6622.10601568782, 1708.4959936818668, 1715.5711072231943, 1852.2313240005517, 1856.2389420337597, 1863.3850829226324, 854.7051267312892, 887.6183241206344, 935.780091678455, 940.3323640215386, 1007.8740157480315, 20659.276925307542, 22206.777932220793, 22224.777752222595, 22364.217252396415, 22699.88650056739, 943.5046600534774, 946.6402766948515, 970.5486948568808, 982.1357205658975, 1058.9523471443804, 9649.951750241204, 11101.72245693848, 11607.825882611598, 11639.418029098511, 11668.416579171007, 470941.64529177133, 474214.0, 475357.0, 560862.0, 563951.0, 135091.64908350987, 140616.1876762459, 143388.0, 145475.0, 150942.0, 899.8290324838259, 990.9108180263811, 1030.4023666273542, 1030.7526193713604, 1031.3708637730979, 18370.448886533424, 18642.16110275014, 18819.059047047867, 19002.619947601248, 19064.29722791606, 38263.85208443751, 44994.77502612466, 49285.26072108993, 53650.19524707211, 55061.174082389596, 82357.0, 82848.17151828404, 83777.0, 85231.0, 85424.57287713522, 5914.0, 8094.352451803959, 8215.917840821516, 8279.834403312021, 8348.123447037973, 10915.454227288763, 12365.629031129081, 12965.935170324088, 13325.267110309027, 13363.465461381436, 9849.901500984899, 11128.499217535393, 11410.754497537864, 11444.713882153013, 11599.130065244972, 8889.0, 11069.225154239126, 11220.19216408616, 11590.768184636092, 11770.411479426166, 10058.748531286776, 11202.38386888729, 11456.427178640868, 11921.940390297994, 12020.03839692805, 8085.312748416451, 8205.83588328242, 8280.461769984931, 8280.627371768405, 8313.958430208053, 9725.416475011521, 11237.662870113909, 11623.418829058683, 11626.476808543466, 11686.70783230426], "value": 9700.316204938264, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1133.7392399748062, 1468.5447511271502, 1507.4648499782577, 1688.8226736192698, 1860.6278744251154, 7651.655675494602, 8482.321414286858, 8726.738197854067, 8740.51927144007, 8818.338624603148, 8594.871076933836, 8690.608922598498, 8693.7826554336, 8700.869486957701, 8719.258862996638, 2312.4103353644796, 2340.3447034830274, 2347.2606129069372, 2347.307544274435, 2349.647552867075, 2333.614953532665, 2352.494213744045, 2392.760723927602, 2396.1733202045266, 2403.098837935774, 2354.6350315701006, 2375.406148462884, 2378.8810559472045, 2391.1869964212165, 2391.258709799959, 2337.8947947342413, 2358.811295096388, 2373.276150774022, 2374.4063984004, 2377.215518878766, 2206.6910632511463, 2334.8015418689433, 2361.374235827507, 2362.090595120874, 2368.1237941961413, 8568.0, 8699.304055675531, 8715.346349023848, 8722.520261385622, 8792.648294068229, 5206.713630750307, 5360.544353729938, 5370.409254981949, 5377.440542242745, 5383.946160538384, 3574.696150827183, 3584.964150358489, 3636.7636103653176, 3647.2705458908326, 3656.597774244831, 957.8563215517659, 1086.9619563315325, 1098.23123813331, 1101.0750969185922, 1115.8437818705388, 8591.398602097855, 11376.260543064674, 11481.425928703531, 11505.942470287679, 11525.94237028818, 4186.958130418657, 4331.678837954391, 4371.475422949203, 4526.728396296233, 4610.608098311614, 8990.235829954396, 8995.0, 9000.279977601776, 9010.3242256831, 9017.81964360709, 5207.505286997729, 5211.687298762011, 5222.399424066205, 5236.0, 5236.266922630834, 4374.544318300194, 4408.448943882015, 4572.6799124061, 4711.199096153662, 4919.434265059564, 3666.5233519642275, 3703.7777333599624, 3819.25524522721, 3826.559945606291, 3830.540335159799, 1110.0398155595512, 1117.932924024561, 1127.3405058041137, 1132.8470656461272, 1133.9716507087223, 893.5353616119581, 911.4303560274828, 922.5940586142076, 935.9110884465965, 943.3113826906259, 5706.4864162225595, 5931.940680593225, 5936.990711579042, 6126.663033533198, 6140.969295153585, 419.85934711871863, 429.4009856250513, 436.4936673458836, 437.83362322317953, 441.6533021578046, 3834.8657796976972, 3839.7120215983914, 3989.1822176453757, 4032.7983600819844, 4086.959130408658, 9796.26528010402, 10020.048095430922, 10179.898201018043, 10708.08981236588, 10753.731156720987, 10637.149028077734, 10664.253502254924, 10673.412962286993, 10677.0, 10701.250912436055, 7740.612969351622, 8112.75661730149, 8113.432059755878, 8140.331232097525, 8249.505029698237, 5833.912491312719, 5888.646681199141, 5984.311804142405, 6052.425019623129, 6091.695415229309, 9718.70843874685, 9949.104580587782, 9981.101700846959, 9984.201263898725, 10003.899961000297, 9791.314607977372, 10033.548490317808, 10102.89897101049, 10239.795204096024, 10244.02681745233, 6864.828379290358, 7092.184398794205, 7272.745453909088, 7430.182679905314, 7526.548407095592, 7785.0, 7867.095284042409, 7871.448998570045, 8063.83872322562, 8331.50010999342, 6664.3335666434905, 7308.890366644611, 7429.851402972018, 7492.887606686013, 7597.126330472067, 7326.816829579303, 7345.338919497271, 7348.669309881175, 7352.758895945844, 7405.074365704287, 6795.830104247433, 6819.659017049227, 6921.134858142732, 6921.723131074902, 6941.0, 4996.925046124238, 5000.2999580058095, 5029.698218106925, 5054.444011158843, 5132.948670513247, 4915.680480768739, 4987.975060124676, 5046.394432668103, 5122.462141475092, 5169.198774190007], "value": 4423.623039232843, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.8222375462844, 299.8216061443441, 308.59265769184685, 308.82860012692953, 311.1753852291429, 5176.559992400646, 5186.662866913648, 5189.261597364878, 5190.792368305263, 5240.082985477546, 4544.704594201375, 4648.395708557883, 4855.757212139397, 4993.750312484371, 5176.145935920578, 3177.85699643516, 3284.4088064148445, 3767.6420740029653, 3935.6654684351865, 3981.9402708959387, 3181.077487528614, 3729.955612428511, 3926.391409331544, 3942.211557688474, 3981.362981922893, 3475.496053072303, 3724.9383925581214, 3796.8799204234815, 3839.520059992501, 3857.2063990244533, 3922.431247469101, 3927.528696556403, 3962.167944731581, 3965.325894597911, 3968.206358728237, 3163.889263875776, 3419.820162044091, 3421.4354631485776, 3433.3133373325186, 3468.3063387322636, 4624.329472226542, 4865.270209468573, 4871.293662418965, 4881.144530449691, 4891.779869905828, 3698.685611723007, 4253.085586598865, 4436.513767887762, 4452.732836029849, 4516.254817955067, 5368.792021795104, 5373.946260537384, 5385.515303622693, 5386.542143917772, 5420.159875219348, 3572.517710109154, 3710.3692372296773, 3769.830357633913, 3771.1326394929047, 3778.866340097987, 4878.853634390974, 5199.558037566811, 5588.273524441796, 5622.775088996435, 5683.374828768873, 3254.251522149919, 3274.590676165479, 3715.4426835974277, 3729.123655940906, 3736.215394767075, 3768.8869333920024, 3773.924341612157, 3778.7354885158325, 3781.1870447853844, 3786.1670432504807, 3882.980585097113, 4273.401723758676, 4436.977815110904, 4437.955620443754, 4448.5773851484055, 5389.0, 5394.487523685244, 5401.297831281908, 5418.756155972991, 5467.890642187213, 1135.9091272698165, 1168.1472525056756, 1260.714071646915, 1262.987370126287, 1289.3488788162106, 739.0010022888075, 746.895434639151, 829.8630875700329, 847.588919374103, 883.9337049721297, 4939.110960027229, 4956.851294461172, 4998.475160108209, 5005.873678422398, 5045.217991211368, 5482.451754824486, 5507.779688812483, 5508.889822203533, 5540.670239142577, 5589.329280486368, 4176.872244493969, 4368.541303163186, 4544.7500387479, 4707.482176960531, 4953.306537084811, 2419.7901049475263, 2524.270874451007, 2613.0, 2854.9000784972427, 2872.2532141643323, 5189.091908915924, 5324.813631522956, 5351.678899266057, 5636.549076073904, 5661.348944871393, 4449.955500444954, 5179.477954990567, 5519.31008623922, 5572.693501857356, 5594.049011667945, 4690.718556886598, 5583.860403489945, 5646.289780905188, 5649.943500565024, 5673.3475650299915, 4908.484609115992, 5317.9364127175495, 5409.891802163856, 5457.481539253765, 5492.752826122754, 4251.51107622615, 5036.420811606712, 5453.227700997271, 5522.392536821027, 5561.304836895388, 5048.545630893237, 5577.0, 5796.333421656564, 5801.622894511843, 5804.9655489434435, 3147.905562833119, 3603.6396360363756, 3730.6642402183934, 3753.324401607737, 3757.49273848027, 4633.096546173464, 5494.313210848644, 5554.666719996651, 5563.0, 5574.331080270394, 4536.637069034536, 5421.349438067457, 5437.374701909331, 5452.563794896478, 5488.368837583729, 4792.424909010941, 5109.9744501277255, 5378.848460615629, 5419.972900135632, 5485.314335708036, 5042.3949126103635, 5311.575073993994, 5317.813876514303, 5347.411784703757, 5377.516023557899, 4479.977600111979, 5320.866978325573, 5347.7593508293, 5360.839174824761, 5371.657085728568, 5119.232115182753, 5274.736263186902, 5323.201519772065, 5329.653572517774, 5356.0], "value": 3916.3446435585056, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.8346680263539, 736.8710475666757, 772.0465225446573, 786.7482405630197, 815.812363156474, 15034.548963531099, 15708.565172398268, 15712.842871571294, 15722.37110515578, 15740.976836505633, 14140.434382624682, 14265.500707475241, 14275.643108922282, 14275.92862035691, 14768.704625907501, 664.9833754156136, 678.043958019193, 757.9962100189491, 838.5974732128582, 839.924406803389, 847.8134810341714, 850.6512329944724, 855.1662129423825, 855.5807654249435, 858.4291446188278, 910.2854259406367, 910.5674804467891, 911.8267529169436, 916.2807196350866, 917.7200953709149, 705.2982282628817, 888.9155530224618, 908.1372695938885, 910.9681161159394, 912.5574096563162, 675.537256978971, 681.0397339750928, 681.5501768832547, 682.4165338635495, 683.5591043776786, 14782.334794934253, 14785.26073696311, 14805.925970370186, 14808.0, 14832.555023349316, 13329.466821327136, 13559.59321220355, 13739.931300343535, 13757.31213439334, 13832.75589458398, 11496.367699776509, 11528.423578821026, 11560.30638161713, 11567.53729850805, 11973.880261197364, 4462.620677242438, 4503.819847206108, 4513.345564893072, 4574.725516469022, 4704.9059018819435, 107467.46266268619, 107815.92184078138, 107864.0, 111517.44241278822, 112668.0, 10655.89344106549, 10775.568977240979, 10790.568377264979, 10923.617673381394, 11040.613578524873, 21013.15947362119, 21031.0, 21050.78949210519, 21075.8946205268, 21080.894595526923, 7815.609219539, 7939.444238903337, 8002.159773223844, 8096.797580060545, 8102.0277566692375, 4335.0, 4345.217860785089, 4431.667624928079, 4610.538946105363, 4612.769361531977, 3339.9833000835324, 3443.0359499340225, 3443.724502039881, 3449.8792542261403, 3567.2687099144614, 608.330836080317, 650.7917466410797, 693.2062788107689, 698.1029377250209, 723.280336065618, 637.2957881540923, 647.2071712152575, 652.9085927970089, 672.1631568697004, 683.1563019670702, 8552.230299272971, 9540.713778586654, 9664.71005869825, 9675.806483870283, 9697.563609637584, 638.450932198309, 639.587466084373, 679.2833560593523, 680.5270337115658, 701.5930760159096, 4467.262901621196, 4761.214399624023, 4767.785449654774, 4788.664793464493, 4854.844697438436, 62002.0, 77166.0, 101063.97872042518, 160685.0, 171500.0, 125578.0, 138400.0, 140281.59718402888, 142354.0, 142482.0, 71335.0, 76057.6197119022, 94039.0, 95208.0, 98769.50615247166, 8986.82026359456, 9204.21764150028, 9393.0, 9748.707538773848, 9749.463779491913, 55387.72306138444, 56761.58096047467, 57254.0, 57385.0, 58166.0, 81630.0, 83679.0, 88421.58930731493, 91795.4102294896, 95482.0, 12721.056472493558, 12924.870751292743, 13337.866621333662, 13884.514042008885, 13938.860611394162, 15177.772333414787, 15331.463398781436, 15436.22818859075, 15899.0, 15929.840701593299, 13827.723445531234, 14065.648358791112, 14110.858891410955, 14184.148951062969, 14338.49815256461, 13929.0, 14372.0, 14545.490907818175, 14758.040727352687, 14950.775738364151, 13776.86223137796, 13865.514706985206, 14059.367328470447, 14477.914156438099, 14654.706905861609, 11290.548378064785, 11514.30914145154, 11565.363904985306, 11676.591319303783, 11895.048396128463, 10016.499175041074, 10631.787364252827, 11135.498902549243, 11535.790825119662, 11809.940950295193], "value": 7800.335145485082, "unit": "score"}], "name": "dromaeo_css", "value": 7272.629306554789, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 132.0, 111.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 146.0, 200.0, 108.0, 131.0, 127.0, 108.0, 198.0, 107.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 182.0, 184.0, 181.0, 183.0, 181.0, 182.0, 180.0], "value": 182.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 156.0, 58.0, 59.0, 64.0, 87.0, 167.0, 49.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 134.0, 69.0, 71.0, 76.0, 117.0, 68.0, 69.0, 117.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 86.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 123.0, 162.0, 94.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 86.0, 83.0, 87.0, 85.0, 84.0, 113.0], "value": 87.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 55.0, 54.0, 54.0, 53.0, 56.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 69.0, 82.0, 72.0, 71.0, 71.0, 70.0, 71.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 75.0, 99.0, 96.0, 105.0, 103.0, 83.0, 88.0, 86.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 158.0, 156.0, 144.0, 140.0, 135.0, 148.0, 154.0, 191.0, 140.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 168.0, 184.0, 231.0, 235.0, 170.0, 169.0, 168.0, 170.0], "value": 183.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 65.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1508.1000000000001, "alertThreshold": 2.0}]} 19:32:51 INFO - Return code: 0 19:32:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:32:51 INFO - # TBPL SUCCESS # 19:32:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:32:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:32:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:32:51 INFO - 2016-08-29 19:32:51.837 screenresolution[1661:9664] starting screenresolution argv=screenresolution get 19:32:51 INFO - 2016-08-29 19:32:51.840 screenresolution[1661:9664] Display 0: 1600x1200x32@60 19:32:51 INFO - 2016-08-29 19:32:51.846 screenresolution[1662:9667] starting screenresolution argv=screenresolution list 19:32:51 INFO - Available Modes on Display 0 19:32:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:32:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:32:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:32:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:32:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:32:52 INFO - Intel Iris: 19:32:52 INFO - Chipset Model: Intel Iris 19:32:52 INFO - Type: GPU 19:32:52 INFO - Bus: Built-In 19:32:52 INFO - VRAM (Dynamic, Max): 1536 MB 19:32:52 INFO - Vendor: Intel (0x8086) 19:32:52 INFO - Device ID: 0x0a2e 19:32:52 INFO - Revision ID: 0x0009 19:32:52 INFO - Displays: 19:32:52 INFO - Display: 19:32:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:32:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:32:52 INFO - Display Serial Number: 1600x1200 60 19:32:52 INFO - Main Display: Yes 19:32:52 INFO - Mirror: Off 19:32:52 INFO - Online: Yes 19:32:52 INFO - Rotation: Supported 19:32:52 INFO - Return code: 0 19:32:52 INFO - Running post-action listener: _resource_record_post_action 19:32:52 INFO - [mozharness: 2016-08-30 02:32:52.080109Z] Finished run-tests step (success) 19:32:52 INFO - Running post-run listener: _resource_record_post_run 19:32:52 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37352448; Write bytes: 706242560; Read time: 244; Write time: 2008 19:32:52 INFO - TinderboxPrint: CPU usage
53.4% 19:32:52 INFO - TinderboxPrint: I/O read bytes / time
37,352,448 / 244 19:32:52 INFO - TinderboxPrint: I/O write bytes / time
706,242,560 / 2,008 19:32:52 INFO - TinderboxPrint: CPU idle
2,262.6 (46.6%) 19:32:52 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 19:32:52 INFO - TinderboxPrint: CPU user
1,621.2 (33.4%) 19:32:52 INFO - TinderboxPrint: Swap in / out
443,310,080 / 0 19:32:52 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395813376; Write bytes: 386563584; Read time: 29662; Write time: 846 19:32:52 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 31058944; Write bytes: 295549440; Read time: 184; Write time: 1106 19:32:52 INFO - Running post-run listener: _upload_blobber_files 19:32:52 INFO - Blob upload gear active. 19:32:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:32:52 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 19:32:52 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'] 19:32:52 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 19:32:52 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:32:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:54 INFO - (blobuploader) - INFO - Done attempting. 19:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:32:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:55 INFO - (blobuploader) - INFO - Done attempting. 19:32:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:32:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:58 INFO - (blobuploader) - INFO - Done attempting. 19:32:58 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:58 INFO - Return code: 0 19:32:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:32:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:32:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44b87f69c34f97b1041052a52ffd640abc1a44cfe906870d0adf55634bbcf27d31ad2cb7e5e25596359e33b3bc25fa17c7c9bdd62d2726565e662500e11d476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a286dbae2fb1bc53059fd1a23d3185a026dc8caa4bd57f740c3a8ddfb86bc9ce954502865a53192ef29f93eb8759c0915cbeff6d71e1074b6890e312ed31f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54175be9849c48b5b2b4d0d2e8624d31fbd471316b1df7e08858a7564c6993eefd05d2155e29747616264f17276e15ab440f12edd8460f85f0b39e00d17bc399"} 19:32:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:32:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:32:58 INFO - Contents: 19:32:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44b87f69c34f97b1041052a52ffd640abc1a44cfe906870d0adf55634bbcf27d31ad2cb7e5e25596359e33b3bc25fa17c7c9bdd62d2726565e662500e11d476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a286dbae2fb1bc53059fd1a23d3185a026dc8caa4bd57f740c3a8ddfb86bc9ce954502865a53192ef29f93eb8759c0915cbeff6d71e1074b6890e312ed31f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54175be9849c48b5b2b4d0d2e8624d31fbd471316b1df7e08858a7564c6993eefd05d2155e29747616264f17276e15ab440f12edd8460f85f0b39e00d17bc399"} 19:32:58 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:58 INFO - Copying logs to upload dir... 19:32:58 INFO - mkdir: /builds/slave/test/build/upload/logs 19:32:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.535485 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-29 19:32:58.662976) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:32:58.667108) ========= 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.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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/44b87f69c34f97b1041052a52ffd640abc1a44cfe906870d0adf55634bbcf27d31ad2cb7e5e25596359e33b3bc25fa17c7c9bdd62d2726565e662500e11d476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a286dbae2fb1bc53059fd1a23d3185a026dc8caa4bd57f740c3a8ddfb86bc9ce954502865a53192ef29f93eb8759c0915cbeff6d71e1074b6890e312ed31f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54175be9849c48b5b2b4d0d2e8624d31fbd471316b1df7e08858a7564c6993eefd05d2155e29747616264f17276e15ab440f12edd8460f85f0b39e00d17bc399"} build_url:https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/IHDVe5P_Qw-ubIGreeKEsw/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/44b87f69c34f97b1041052a52ffd640abc1a44cfe906870d0adf55634bbcf27d31ad2cb7e5e25596359e33b3bc25fa17c7c9bdd62d2726565e662500e11d476c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a286dbae2fb1bc53059fd1a23d3185a026dc8caa4bd57f740c3a8ddfb86bc9ce954502865a53192ef29f93eb8759c0915cbeff6d71e1074b6890e312ed31f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/54175be9849c48b5b2b4d0d2e8624d31fbd471316b1df7e08858a7564c6993eefd05d2155e29747616264f17276e15ab440f12edd8460f85f0b39e00d17bc399"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:32:58.700748) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:32:58.701112) ========= 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.f24EMfzLV5/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.re28vynWQk/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.004996 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:32:58.752427) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:32:58.753237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:32:58.754093) ========= ========= Total master_lag: 0.18 =========