builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0140 starttime: 1468241878.23 results: success (0) buildid: 20160711025318 builduid: 047713848aea45189e5b63ccbabe0230 revision: 786eb9c467bc80a9c7ae254142da8bc5a3927a8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.227591) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.228034) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.228317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.251137) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.251407) ========= 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.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-07-11 05:57:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.8M=0.001s 2016-07-11 05:57:58 (21.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117891 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.391573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.391883) ========= 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.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.026556 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:58.440586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 05:57:58.440910) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 786eb9c467bc80a9c7ae254142da8bc5a3927a8d --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 786eb9c467bc80a9c7ae254142da8bc5a3927a8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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-07-11 05:57:58,514 truncating revision to first 12 chars 2016-07-11 05:57:58,514 Setting DEBUG logging. 2016-07-11 05:57:58,514 attempt 1/10 2016-07-11 05:57:58,515 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/786eb9c467bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 05:57:59,531 unpacking tar archive at: autoland-786eb9c467bc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 05:57:59.740348) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:59.740677) ========= script_repo_revision: 786eb9c467bc80a9c7ae254142da8bc5a3927a8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:59.741135) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:59.741411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 05:57:59.759784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-11 05:57:59.760056) ========= /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.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:57:59 INFO - MultiFileLogger online at 20160711 05:57:59 in /builds/slave/test 05:57:59 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 05:57:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:59 INFO - {'append_to_log': False, 05:57:59 INFO - 'base_work_dir': '/builds/slave/test', 05:57:59 INFO - 'blob_upload_branch': 'Autoland', 05:57:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:59 INFO - 'branch': 'Autoland', 05:57:59 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:59 INFO - 'config_files': ('talos/mac_config.py',), 05:57:59 INFO - 'default_actions': ('clobber', 05:57:59 INFO - 'read-buildbot-config', 05:57:59 INFO - 'download-and-extract', 05:57:59 INFO - 'populate-webroot', 05:57:59 INFO - 'create-virtualenv', 05:57:59 INFO - 'install', 05:57:59 INFO - 'run-tests'), 05:57:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:59 INFO - 'download_minidump_stackwalk': True, 05:57:59 INFO - 'download_symbols': 'ondemand', 05:57:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:59 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:59 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:59 INFO - 'installer_path': 'installer.exe', 05:57:59 INFO - 'log_level': 'info', 05:57:59 INFO - 'log_name': 'talos', 05:57:59 INFO - 'log_to_console': True, 05:57:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:57:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:57:59 INFO - 'opt_config_files': (), 05:57:59 INFO - 'pip_index': False, 05:57:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:59 INFO - 'cmd': ('bash', 05:57:59 INFO - '-c', 05:57:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:59 INFO - 'enabled': True, 05:57:59 INFO - 'halt_on_failure': False, 05:57:59 INFO - 'name': 'check_screen_resolution'},), 05:57:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:59 INFO - 'cmd': ('bash', 05:57:59 INFO - '-c', 05:57:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:59 INFO - 'enabled': True, 05:57:59 INFO - 'halt_on_failure': False, 05:57:59 INFO - 'name': 'check_screen_resolution'},), 05:57:59 INFO - 'run_cmd_checks_enabled': True, 05:57:59 INFO - 'sps_profile': False, 05:57:59 INFO - 'sps_profile_interval': 0, 05:57:59 INFO - 'suite': 'dromaeojs', 05:57:59 INFO - 'system_bits': '32', 05:57:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:59 INFO - 'title': 't-yosemite-r7-0140', 05:57:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:59 INFO - 'use_talos_json': True, 05:57:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:59 INFO - 'work_dir': 'build'} 05:57:59 INFO - ##### 05:57:59 INFO - ##### Running clobber step. 05:57:59 INFO - ##### 05:57:59 INFO - Running pre-action listener: _resource_record_pre_action 05:57:59 INFO - Running main action method: clobber 05:57:59 INFO - rmtree: /builds/slave/test/build 05:57:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:01 INFO - Running post-action listener: _resource_record_post_action 05:58:01 INFO - ##### 05:58:01 INFO - ##### Finished clobber step (success) 05:58:01 INFO - ##### 05:58:01 INFO - ##### 05:58:01 INFO - ##### Running read-buildbot-config step. 05:58:01 INFO - ##### 05:58:01 INFO - Running pre-action listener: _resource_record_pre_action 05:58:01 INFO - Running main action method: read_buildbot_config 05:58:01 INFO - Using buildbot properties: 05:58:01 INFO - { 05:58:01 INFO - "project": "", 05:58:01 INFO - "product": "firefox", 05:58:01 INFO - "script_repo_revision": "production", 05:58:01 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:58:01 INFO - "repository": "", 05:58:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:58:01 INFO - "buildid": "20160711025318", 05:58:01 INFO - "pgo_build": "False", 05:58:01 INFO - "basedir": "/builds/slave/test", 05:58:01 INFO - "buildnumber": 44, 05:58:01 INFO - "slavename": "t-yosemite-r7-0140", 05:58:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:58:01 INFO - "platform": "yosemite_r7", 05:58:01 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:58:01 INFO - "branch": "autoland", 05:58:01 INFO - "repo_path": "integration/autoland", 05:58:01 INFO - "revision": "786eb9c467bc80a9c7ae254142da8bc5a3927a8d", 05:58:01 INFO - "stage_platform": "macosx64", 05:58:01 INFO - "builduid": "047713848aea45189e5b63ccbabe0230", 05:58:01 INFO - "slavebuilddir": "test" 05:58:01 INFO - } 05:58:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:58:01 INFO - Running post-action listener: _resource_record_post_action 05:58:01 INFO - ##### 05:58:01 INFO - ##### Finished read-buildbot-config step (success) 05:58:01 INFO - ##### 05:58:01 INFO - ##### 05:58:01 INFO - ##### Running download-and-extract step. 05:58:01 INFO - ##### 05:58:01 INFO - Running pre-action listener: _resource_record_pre_action 05:58:01 INFO - Running main action method: download_and_extract 05:58:01 INFO - mkdir: /builds/slave/test/build/tests 05:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:01 INFO - https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:58:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:58:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:58:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:58:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 05:58:03 INFO - Downloaded 1199 bytes. 05:58:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:58:03 INFO - Using the following test package requirements: 05:58:03 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:58:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:58:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:58:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'jsshell-mac.zip'], 05:58:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:58:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:58:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:58:03 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:58:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:58:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:58:03 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:58:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:58:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:03 INFO - https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:58:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:58:04 INFO - Downloaded 18806918 bytes. 05:58:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:58:07 INFO - Return code: 0 05:58:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:58:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:07 INFO - https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:58:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:58:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:58:08 INFO - Downloaded 18806918 bytes. 05:58:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:58:12 INFO - Return code: 0 05:58:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:12 INFO - https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:58:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:58:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:58:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:58:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:58:13 INFO - Downloaded 14403333 bytes. 05:58:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:58:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:58:13 INFO - Return code: 0 05:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:13 INFO - https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:58:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:58:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:58:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:58:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 05:58:16 INFO - Downloaded 112374673 bytes. 05:58:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:58:16 INFO - mkdir: /builds/slave/test/properties 05:58:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:58:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:58:16 INFO - Contents: 05:58:16 INFO - build_url:https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:58:17 INFO - Running post-action listener: _resource_record_post_action 05:58:17 INFO - Running post-action listener: set_extra_try_arguments 05:58:17 INFO - ##### 05:58:17 INFO - ##### Finished download-and-extract step (success) 05:58:17 INFO - ##### 05:58:17 INFO - ##### 05:58:17 INFO - ##### Running populate-webroot step. 05:58:17 INFO - ##### 05:58:17 INFO - Running pre-action listener: _resource_record_pre_action 05:58:17 INFO - Running main action method: populate_webroot 05:58:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:58:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:58:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:58:17 INFO - '--tppagecycles', 05:58:17 INFO - '7'], 05:58:17 INFO - 'tests': ['tsvgm']}}, 05:58:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:58:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:58:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:58:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:58:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:58:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['damp', 'tps']}, 05:58:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'tests': ['damp', 'tps']}, 05:58:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['dromaeo_dom']}, 05:58:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:58:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['basic_compositor_video']}, 05:58:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:58:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['a11yr', 05:58:17 INFO - 'ts_paint', 05:58:17 INFO - 'tpaint', 05:58:17 INFO - 'sessionrestore', 05:58:17 INFO - 'sessionrestore_no_auto_restore', 05:58:17 INFO - 'tabpaint']}, 05:58:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['tsvgx', 05:58:17 INFO - 'tsvgr_opacity', 05:58:17 INFO - 'tart', 05:58:17 INFO - 'tscrollx', 05:58:17 INFO - 'cart']}, 05:58:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:58:17 INFO - 'tsvgr_opacity', 05:58:17 INFO - 'tart', 05:58:17 INFO - 'tscrollx', 05:58:17 INFO - 'cart']}, 05:58:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'talos_options': ['--disable-e10s'], 05:58:17 INFO - 'tests': ['tp5o']}, 05:58:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'tests': ['tp5o']}, 05:58:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'talos_options': ['--disable-e10s', 05:58:17 INFO - '--xperf_path', 05:58:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:17 INFO - 'tests': ['tp5n']}, 05:58:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:58:17 INFO - 'talos_options': ['--xperf_path', 05:58:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:58:17 INFO - 'tests': ['tp5n']}}, 05:58:17 INFO - 'talos.zip': {'path': '', 05:58:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:58:17 INFO - Running post-action listener: _resource_record_post_action 05:58:17 INFO - ##### 05:58:17 INFO - ##### Finished populate-webroot step (success) 05:58:17 INFO - ##### 05:58:17 INFO - ##### 05:58:17 INFO - ##### Running create-virtualenv step. 05:58:17 INFO - ##### 05:58:17 INFO - Running pre-action listener: _resource_record_pre_action 05:58:17 INFO - Running main action method: create_virtualenv 05:58:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:58:17 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 05:58:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:58:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:58:18 INFO - Using real prefix '/tools/python27' 05:58:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:58:18 INFO - Installing distribute.............................................................................................................................................................................................done. 05:58:21 INFO - Installing pip.................done. 05:58:21 INFO - Return code: 0 05:58:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:58:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:21 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:21 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 05:58:21 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 05:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:21 INFO - 'HOME': '/Users/cltbld', 05:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:21 INFO - 'LOGNAME': 'cltbld', 05:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:21 INFO - 'MOZ_NO_REMOTE': '1', 05:58:21 INFO - 'NO_EM_RESTART': '1', 05:58:21 INFO - 'PAGER': '/bin/cat', 05:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:21 INFO - 'PWD': '/builds/slave/test', 05:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:21 INFO - 'SHELL': '/bin/bash', 05:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:21 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:21 INFO - 'USER': 'cltbld', 05:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:21 INFO - 'XPC_FLAGS': '0x0', 05:58:21 INFO - 'XPC_SERVICE_NAME': '0', 05:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:21 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 05:58:21 INFO - Cleaning up... 05:58:21 INFO - Return code: 0 05:58:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:58:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:21 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:21 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 05:58:21 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 05:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:21 INFO - 'HOME': '/Users/cltbld', 05:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:21 INFO - 'LOGNAME': 'cltbld', 05:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:21 INFO - 'MOZ_NO_REMOTE': '1', 05:58:21 INFO - 'NO_EM_RESTART': '1', 05:58:21 INFO - 'PAGER': '/bin/cat', 05:58:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:21 INFO - 'PWD': '/builds/slave/test', 05:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:21 INFO - 'SHELL': '/bin/bash', 05:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:21 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:21 INFO - 'USER': 'cltbld', 05:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:21 INFO - 'XPC_FLAGS': '0x0', 05:58:21 INFO - 'XPC_SERVICE_NAME': '0', 05:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:22 INFO - Downloading/unpacking psutil>=3.1.1 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:58:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:24 INFO - Installing collected packages: psutil 05:58:24 INFO - Running setup.py install for psutil 05:58:24 INFO - building 'psutil._psutil_osx' extension 05:58:24 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 05:58:25 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 05:58:25 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 05:58:25 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 05:58:25 INFO - building 'psutil._psutil_posix' extension 05:58:25 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 05:58:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:58:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:58:25 INFO - ^ 05:58:25 INFO - 1 warning generated. 05:58:25 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 05:58:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:58:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:58:25 INFO - Successfully installed psutil 05:58:25 INFO - Cleaning up... 05:58:26 INFO - Return code: 0 05:58:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:58:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:26 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:26 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 05:58:26 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 05:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:26 INFO - 'HOME': '/Users/cltbld', 05:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:26 INFO - 'LOGNAME': 'cltbld', 05:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:26 INFO - 'MOZ_NO_REMOTE': '1', 05:58:26 INFO - 'NO_EM_RESTART': '1', 05:58:26 INFO - 'PAGER': '/bin/cat', 05:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:26 INFO - 'PWD': '/builds/slave/test', 05:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:26 INFO - 'SHELL': '/bin/bash', 05:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:26 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:26 INFO - 'USER': 'cltbld', 05:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:26 INFO - 'XPC_FLAGS': '0x0', 05:58:26 INFO - 'XPC_SERVICE_NAME': '0', 05:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58: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 05:58:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:58:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:58:28 INFO - Installing collected packages: mozsystemmonitor 05:58:28 INFO - Running setup.py install for mozsystemmonitor 05:58:28 INFO - Successfully installed mozsystemmonitor 05:58:28 INFO - Cleaning up... 05:58:29 INFO - Return code: 0 05:58:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:29 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:29 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 05:58:29 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 05:58:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:29 INFO - 'HOME': '/Users/cltbld', 05:58:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:29 INFO - 'LOGNAME': 'cltbld', 05:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:29 INFO - 'MOZ_NO_REMOTE': '1', 05:58:29 INFO - 'NO_EM_RESTART': '1', 05:58:29 INFO - 'PAGER': '/bin/cat', 05:58:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:29 INFO - 'PWD': '/builds/slave/test', 05:58:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:29 INFO - 'SHELL': '/bin/bash', 05:58:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:29 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:29 INFO - 'USER': 'cltbld', 05:58:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:29 INFO - 'XPC_FLAGS': '0x0', 05:58:29 INFO - 'XPC_SERVICE_NAME': '0', 05:58:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:29 INFO - Downloading/unpacking jsonschema==2.5.1 05:58:29 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 05:58:29 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 05:58:29 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 05:58:29 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 05:58:29 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 05:58:29 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 05:58:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:58:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:58:31 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 05:58:31 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 05:58:31 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 05:58:31 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 05:58:31 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 05:58:31 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 05:58:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:58:32 INFO - warning: no files found matching '*.txt' 05:58:32 INFO - no previously-included directories found matching 'build' 05:58:32 INFO - no previously-included directories found matching 'dist' 05:58:32 INFO - no previously-included directories found matching '.git*' 05:58:32 INFO - Installing collected packages: jsonschema, functools32 05:58:32 INFO - Running setup.py install for functools32 05:58:32 INFO - warning: no files found matching '*.txt' 05:58:32 INFO - no previously-included directories found matching 'build' 05:58:32 INFO - no previously-included directories found matching 'dist' 05:58:32 INFO - no previously-included directories found matching '.git*' 05:58:32 INFO - Successfully installed jsonschema functools32 05:58:32 INFO - Cleaning up... 05:58:32 INFO - Return code: 0 05:58:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:32 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:32 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 05:58:32 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 05:58:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:32 INFO - 'HOME': '/Users/cltbld', 05:58:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:32 INFO - 'LOGNAME': 'cltbld', 05:58:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:32 INFO - 'MOZ_NO_REMOTE': '1', 05:58:32 INFO - 'NO_EM_RESTART': '1', 05:58:32 INFO - 'PAGER': '/bin/cat', 05:58:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:32 INFO - 'PWD': '/builds/slave/test', 05:58:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:32 INFO - 'SHELL': '/bin/bash', 05:58:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:32 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:32 INFO - 'USER': 'cltbld', 05:58:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:32 INFO - 'XPC_FLAGS': '0x0', 05:58:32 INFO - 'XPC_SERVICE_NAME': '0', 05:58:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:58:35 INFO - Downloading blobuploader-1.2.4.tar.gz 05:58:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:58:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:58:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:35 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 05:58:36 INFO - Downloading docopt-0.6.1.tar.gz 05:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:58:36 INFO - Installing collected packages: blobuploader, requests, docopt 05:58:36 INFO - Running setup.py install for blobuploader 05:58:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:58:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:58:36 INFO - Running setup.py install for requests 05:58:37 INFO - Running setup.py install for docopt 05:58:37 INFO - Successfully installed blobuploader requests docopt 05:58:37 INFO - Cleaning up... 05:58:37 INFO - Return code: 0 05:58:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:37 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:37 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 05:58:37 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 05:58:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:37 INFO - 'HOME': '/Users/cltbld', 05:58:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:37 INFO - 'LOGNAME': 'cltbld', 05:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:37 INFO - 'MOZ_NO_REMOTE': '1', 05:58:37 INFO - 'NO_EM_RESTART': '1', 05:58:37 INFO - 'PAGER': '/bin/cat', 05:58:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:37 INFO - 'PWD': '/builds/slave/test', 05:58:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:37 INFO - 'SHELL': '/bin/bash', 05:58:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:37 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:37 INFO - 'USER': 'cltbld', 05:58:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:37 INFO - 'XPC_FLAGS': '0x0', 05:58:37 INFO - 'XPC_SERVICE_NAME': '0', 05:58:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-8b8gXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-FPVR77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-crsEp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-NKfZ18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:37 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-2BiGhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-jfWkMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-1gVmda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-3HFhRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-K8U_lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-8HeyTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-8LBgJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-q7MQ0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-9vBCKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:38 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-0LX1fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:39 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-pBj9GA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:39 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6HEG_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:39 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ekpiTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:58:39 INFO - Running setup.py install for manifestparser 05:58:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:58:39 INFO - Running setup.py install for mozcrash 05:58:39 INFO - Running setup.py install for mozdebug 05:58:39 INFO - Running setup.py install for mozdevice 05:58:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:58:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:58:39 INFO - Running setup.py install for mozfile 05:58:40 INFO - Running setup.py install for mozhttpd 05:58:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozinfo 05:58:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozInstall 05:58:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozleak 05:58:40 INFO - Running setup.py install for mozlog 05:58:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for moznetwork 05:58:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:58:40 INFO - Running setup.py install for mozprocess 05:58:41 INFO - Running setup.py install for mozprofile 05:58:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Running setup.py install for mozrunner 05:58:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Running setup.py install for mozscreenshot 05:58:41 INFO - Running setup.py install for moztest 05:58:41 INFO - Running setup.py install for mozversion 05:58:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:58:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:58:41 INFO - Cleaning up... 05:58:41 INFO - Return code: 0 05:58:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:41 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:41 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 05:58:41 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 05:58:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:41 INFO - 'HOME': '/Users/cltbld', 05:58:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:41 INFO - 'LOGNAME': 'cltbld', 05:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:41 INFO - 'MOZ_NO_REMOTE': '1', 05:58:41 INFO - 'NO_EM_RESTART': '1', 05:58:41 INFO - 'PAGER': '/bin/cat', 05:58:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:41 INFO - 'PWD': '/builds/slave/test', 05:58:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:41 INFO - 'SHELL': '/bin/bash', 05:58:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:41 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:41 INFO - 'USER': 'cltbld', 05:58:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:41 INFO - 'XPC_FLAGS': '0x0', 05:58:41 INFO - 'XPC_SERVICE_NAME': '0', 05:58:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-GiHuTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-xEOROv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-p0hZ0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-DoJ7LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-8OSmqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-xleYga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-TJTpuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:58:42 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)) 05:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:58:42 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-2IBpwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-asI41X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-c1l_Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-SH_Hex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-yyqJDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Fra8VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-sukr7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-kD6yaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Bh7Vw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:58:43 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)) 05:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:58:43 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-E63Z4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:58:44 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)) 05:58:44 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)) 05:58:44 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)) 05:58:44 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)) 05:58:44 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)) 05:58:44 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)) 05:58:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:58:44 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 05:58:44 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 05:58:44 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 05:58:44 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 05:58:44 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 05:58:44 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 05:58:46 INFO - Downloading blessings-1.6.tar.gz 05:58:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:58:46 INFO - Installing collected packages: blessings 05:58:46 INFO - Running setup.py install for blessings 05:58:46 INFO - Successfully installed blessings 05:58:46 INFO - Cleaning up... 05:58:46 INFO - Return code: 0 05:58:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:47 INFO - Reading from file tmpfile_stdout 05:58:47 INFO - Current package versions: 05:58:47 INFO - blessings == 1.6 05:58:47 INFO - blobuploader == 1.2.4 05:58:47 INFO - docopt == 0.6.1 05:58:47 INFO - functools32 == 3.2.3-2 05:58:47 INFO - jsonschema == 2.5.1 05:58:47 INFO - manifestparser == 1.1 05:58:47 INFO - mozInstall == 1.12 05:58:47 INFO - mozcrash == 0.17 05:58:47 INFO - mozdebug == 0.1 05:58:47 INFO - mozdevice == 0.48 05:58:47 INFO - mozfile == 1.2 05:58:47 INFO - mozhttpd == 0.7 05:58:47 INFO - mozinfo == 0.9 05:58:47 INFO - mozleak == 0.1 05:58:47 INFO - mozlog == 3.2 05:58:47 INFO - moznetwork == 0.27 05:58:47 INFO - mozprocess == 0.23 05:58:47 INFO - mozprofile == 0.28 05:58:47 INFO - mozrunner == 6.12 05:58:47 INFO - mozscreenshot == 0.1 05:58:47 INFO - mozsystemmonitor == 0.3 05:58:47 INFO - moztest == 0.7 05:58:47 INFO - mozversion == 1.4 05:58:47 INFO - psutil == 3.1.1 05:58:47 INFO - requests == 1.2.3 05:58:47 INFO - wsgiref == 0.1.2 05:58:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:58:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:47 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:47 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 05:58:47 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 05:58:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:47 INFO - 'HOME': '/Users/cltbld', 05:58:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:47 INFO - 'LOGNAME': 'cltbld', 05:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:47 INFO - 'MOZ_NO_REMOTE': '1', 05:58:47 INFO - 'NO_EM_RESTART': '1', 05:58:47 INFO - 'PAGER': '/bin/cat', 05:58:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:47 INFO - 'PWD': '/builds/slave/test', 05:58:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:47 INFO - 'SHELL': '/bin/bash', 05:58:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:47 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:47 INFO - 'USER': 'cltbld', 05:58:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:47 INFO - 'XPC_FLAGS': '0x0', 05:58:47 INFO - 'XPC_SERVICE_NAME': '0', 05:58:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 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)) 05:58:47 INFO - Cleaning up... 05:58:47 INFO - Return code: 0 05:58:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:58:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:58:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:58:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:58:47 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 0x10eae4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb79618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3294a32e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, '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.LHxOAeyosi/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.zCwC2HYoxf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 05:58:47 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 05:58:47 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 05:58:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:58:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:47 INFO - 'HOME': '/Users/cltbld', 05:58:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:47 INFO - 'LOGNAME': 'cltbld', 05:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:47 INFO - 'MOZ_NO_REMOTE': '1', 05:58:47 INFO - 'NO_EM_RESTART': '1', 05:58:47 INFO - 'PAGER': '/bin/cat', 05:58:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:47 INFO - 'PWD': '/builds/slave/test', 05:58:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:47 INFO - 'SHELL': '/bin/bash', 05:58:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:58:47 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:58:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:47 INFO - 'USER': 'cltbld', 05:58:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:47 INFO - 'XPC_FLAGS': '0x0', 05:58:47 INFO - 'XPC_SERVICE_NAME': '0', 05:58:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:58:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:58:47 INFO - Cleaning up... 05:58:47 INFO - Return code: 0 05:58:47 INFO - Running post-action listener: _resource_record_post_action 05:58:47 INFO - Running post-action listener: _start_resource_monitoring 05:58:47 INFO - Starting resource monitoring. 05:58:47 INFO - ##### 05:58:47 INFO - ##### Finished create-virtualenv step (success) 05:58:47 INFO - ##### 05:58:47 INFO - ##### 05:58:47 INFO - ##### Running install step. 05:58:47 INFO - ##### 05:58:47 INFO - Running pre-action listener: _resource_record_pre_action 05:58:47 INFO - Running main action method: install 05:58:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:58:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:58:47 INFO - Reading from file tmpfile_stdout 05:58:47 INFO - Detecting whether we're running mozinstall >=1.0... 05:58:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:58:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:58:48 INFO - Reading from file tmpfile_stdout 05:58:48 INFO - Output received: 05:58:48 INFO - Usage: mozinstall [options] installer 05:58:48 INFO - Options: 05:58:48 INFO - -h, --help show this help message and exit 05:58:48 INFO - -d DEST, --destination=DEST 05:58:48 INFO - Directory to install application into. [default: 05:58:48 INFO - "/builds/slave/test"] 05:58:48 INFO - --app=APP Application being installed. [default: firefox] 05:58:48 INFO - mkdir: /builds/slave/test/build/application 05:58:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:59:22 INFO - Reading from file tmpfile_stdout 05:59:22 INFO - Output received: 05:59:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:59:22 INFO - Running post-action listener: _resource_record_post_action 05:59:22 INFO - ##### 05:59:22 INFO - ##### Finished install step (success) 05:59:22 INFO - ##### 05:59:22 INFO - ##### 05:59:22 INFO - ##### Running run-tests step. 05:59:22 INFO - ##### 05:59:22 INFO - Running pre-action listener: _resource_record_pre_action 05:59:22 INFO - Running main action method: run_tests 05:59:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:59:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:59:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:59:22 INFO - 2016-07-11 05:59:22.544 screenresolution[1642:7536] starting screenresolution argv=screenresolution get 05:59:22 INFO - 2016-07-11 05:59:22.546 screenresolution[1642:7536] Display 0: 1600x1200x32@60 05:59:22 INFO - 2016-07-11 05:59:22.552 screenresolution[1643:7538] starting screenresolution argv=screenresolution list 05:59:22 INFO - Available Modes on Display 0 05:59:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:59:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:59:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:59:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:59:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:59:22 INFO - Intel Iris: 05:59:22 INFO - Chipset Model: Intel Iris 05:59:22 INFO - Type: GPU 05:59:22 INFO - Bus: Built-In 05:59:22 INFO - VRAM (Dynamic, Max): 1536 MB 05:59:22 INFO - Vendor: Intel (0x8086) 05:59:22 INFO - Device ID: 0x0a2e 05:59:22 INFO - Revision ID: 0x0009 05:59:22 INFO - Displays: 05:59:22 INFO - Display: 05:59:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:59:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:59:22 INFO - Display Serial Number: 1600x1200 60 05:59:22 INFO - Main Display: Yes 05:59:22 INFO - Mirror: Off 05:59:22 INFO - Online: Yes 05:59:22 INFO - Rotation: Supported 05:59:22 INFO - Return code: 0 05:59:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:59:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:59:22 INFO - Python 2.7.3 05:59:22 INFO - Return code: 0 05:59:22 INFO - grabbing minidump binary from tooltool 05:59: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:22 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 0x10ea48d30>, '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 0x10e7addb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea40b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:59:22 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 05:59:22 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 05:59:22 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 05:59:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:59:22 INFO - Return code: 0 05:59:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:59:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:59:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:59:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:59:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:59:22 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/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 05:59:22 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/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0140 --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 05:59:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LHxOAeyosi/Render', 05:59:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:22 INFO - 'HOME': '/Users/cltbld', 05:59:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:22 INFO - 'LOGNAME': 'cltbld', 05:59:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:59:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:22 INFO - 'MOZ_NO_REMOTE': '1', 05:59:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:59:22 INFO - 'NO_EM_RESTART': '1', 05:59:22 INFO - 'PAGER': '/bin/cat', 05:59:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:22 INFO - 'PWD': '/builds/slave/test', 05:59:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:59:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:22 INFO - 'SHELL': '/bin/bash', 05:59:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zCwC2HYoxf/Listeners', 05:59:22 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 05:59:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:22 INFO - 'USER': 'cltbld', 05:59:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:22 INFO - 'XPC_FLAGS': '0x0', 05:59:22 INFO - 'XPC_SERVICE_NAME': '0', 05:59:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:22 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/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 05:59:23 INFO - mozversion application_buildid: 20160711025318 05:59:23 INFO - mozversion application_changeset: 786eb9c467bc80a9c7ae254142da8bc5a3927a8d 05:59:23 INFO - mozversion application_display_name: Nightly 05:59:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:59:23 INFO - mozversion application_name: Firefox 05:59:23 INFO - mozversion application_remotingname: firefox 05:59:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:59:23 INFO - mozversion application_vendor: Mozilla 05:59:23 INFO - mozversion application_version: 50.0a1 05:59:23 INFO - mozversion platform_buildid: 20160711025318 05:59:23 INFO - mozversion platform_changeset: 786eb9c467bc80a9c7ae254142da8bc5a3927a8d 05:59:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:59:23 INFO - mozversion platform_version: 50.0a1 05:59:23 INFO - using testdate: 1468241963 05:59:23 INFO - actual date: 1468241963 05:59:23 INFO - starting webserver on 'localhost:49280' 05:59:23 INFO - SUITE-START | Running 2 tests 05:59:23 INFO - TEST-START | dromaeo_css 05:59:23 INFO - operating with platform_type : mac_ 05:59:23 INFO - Initialising browser for dromaeo_css test... 05:59:23 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpgKhFY4/profile http://localhost:49280/getInfo.html) 05:59:25 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:59:25 INFO - PROCESS | 1653 | colorDepth:24 05:59:25 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:59:25 INFO - PROCESS | 1653 | __metrics 05:59:25 INFO - PROCESS | 1653 | 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" 05:59:25 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:59:26 INFO - TEST-INFO | 1653: exit 0 05:59:26 INFO - Browser initialized. 05:59:26 INFO - Running cycle 1/1 for dromaeo_css test... 05:59:26 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpgKhFY4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:38 INFO - PROCESS | 1655 | RSS: Main: 243675136 05:59:38 INFO - PROCESS | 1655 | 06:00:30 INFO - PROCESS | 1655 | 1468242030053 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] 06:02:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 06:02:42 INFO - PROCESS | 1655 | RSS: Main: 391090176 06:02:42 INFO - PROCESS | 1655 | 06:05:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 06:05:46 INFO - PROCESS | 1655 | RSS: Main: 383340544 06:05:46 INFO - PROCESS | 1655 | 06:08:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 06:08:48 INFO - PROCESS | 1655 | RSS: Main: 375418880 06:08:48 INFO - PROCESS | 1655 | 06:11:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 06:11:51 INFO - PROCESS | 1655 | RSS: Main: 375492608 06:11:51 INFO - PROCESS | 1655 | 06:14:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 06:14:55 INFO - PROCESS | 1655 | RSS: Main: 348332032 06:14:55 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 06:17:59 INFO - PROCESS | 1655 | RSS: Main: 414654464 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | __start_tp_report 06:17:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 06:17:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 06:17:59 INFO - PROCESS | 1655 | |i|pagename|runs| 06:17:59 INFO - PROCESS | 1655 | |0;dojo.html;3283.4910588858725;3341.331733653269;3347.0628224097263;3365.209175843675;3411;5441.183822426638;5501.119820828658;5542.196381524676;5654.095344744842;5662.5186859116975;4672.322513235587;5591.133374326976;5631.859203519914;5637.3235211774645;5656.717164141797;5107.310513080743;5125.333706618135;5168.638195326329;5209.453007434222;5223.8432847014465;4311.611954924057;5154.4072431670465;5220.373555173384;5222;5227.607929405291;5091.6690415123085;5100.438951715309;5110.693358398508;5120;5211;5197.1424714922;5201.453847346031;5223.71269580173;5224.294720212761;5276.577873770089;4986.87532811679;5048.242763585455;5058.4688607696025;5147.613928955306;5154.871128221787;4610.76946152691;5516.255305533782;5540.667559946376;5544.251526044013;5624.9875022495935;6146.016637338004;6178.320384757673;6190.164327815777;6191.845203869894;6200.007998720228;5868.9413105868825;6112;6151.077338399231;6228.22147231596;6273.090401891745;5682.801101961494;5690.573207009491;5692.743826527816;5706.201131841587;5709.457601527848;6238.469730072995;6290.528210384148;6294.874102517924;6303.243610766737;6322;5792.218050563204;5853.326867410217;5856.150858125633;5918.408159184047;6024.548158888101;6144.832481828438;6159.538034647419;6161.045038019114;6172.321044685081;6174.44430001293;6031.418187595589;6053.303870054912;6061.636301821904;6061.909071363933;6108.969455152695;5303.390110137307;5459.317585301837;6103.267607887082;6105.572609917263;6118.908216376758;4807.038592281558;4827.758612069452;4849.854504364875;4850.781714822841;4859.246816743411;4377.693561450732;4399.84600538986;4401.735895846259;4424.80088396023;4431.357453169337;5537.723113844372;5565.499105080562;5596.356419011784;5606.467385598362;5660.094384898396;6111.816645500642;6155.661438620829;6156.815295541141;6166.229221347332;6198;5614.494695477427;5617.943820561742;5638.859028524238;5644.153376993484;5648;5189.169732842727;5226.425093239741;5253.842384728464;5290.047791397586;5321.600879934021;6307.684615769285;6308.148399965946;6321.494280457552;6326.272478665012;6333.36666333363;6121.602095863755;6124.418180272867;6132.110843927695;6136.877262454636;6146.323904370426;6119.143319935301;6157.4458298753325;6223.190985171898;6295.433410993032;6300.779472718433;6080.087986802015;6085.056816193497;6107.4197951194565;6110.602810817282;6137.754489820536;6141.539384546088;6156.5998210116195;6161.568690191643;6164.291106522628;6171.59884607499;5701.344345400332;5771.509421699203;5822.82531524055;5863;5944.910826337522;5853.0635098384055;5854;5904.996150654313;5906.40935906406;5941.346451890202;5752.367239603556;5761.596688231783;5762.164486149569;5766.365699772938;5773.797917072882;5304.23088652151;5680.147977803363;5737.454941780524;5913.05391137416;5977.043673012297;5816.76732930678;5825.2135961644635;5825.242718446575;5832.708364581669;5857.619254748428 06:17:59 INFO - PROCESS | 1655 | |1;ext.html;516.8940367224719;523.6125267302195;525.062762968102;544.1891581543499;547.991780123298;27766.167014989533;29358.559621605666;29695.257618559546;31117.688823111763;31226.219344516398;29228;32434;42557.57442425575;42809;42843.57156428435;9571.808563828738;9690.66082687106;9697;9701.175400090966;9733.707988760349;9632.133108020276;9730.124288814006;9738.1235688788;9740.269479789009;9742.123208911196;8184.263416292532;8787.736367908941;11638.767224655501;11826.40867956603;11893.762124757499;11802.350870702108;11895.46470408825;11896.345700986441;11902.226355286964;11902.583409580624;8063.677452901877;11779;11830;11832.704182395422;11847.526098956032;39146.804265978775;39480.210395791924;39539.40690889639;41688.1662366751;41777;3437.553118094657;3458.291050334675;3557.5375201223674;3718.7954662493553;3929.5284565851994;31315.53026704601;32270.838645806856;32387.676123238703;32428.18929526757;32555.860544880816;3193.7924034937887;3266.4283750343834;3269.3134441767256;3287.7862938908893;3406.5230867678547;542660;545339;545451;546134;548390;61870.38129618736;61922;62304.75390492165;62339.68830155911;62428.68785656043;65403.67298163574;65509.344906551276;65635.67182164059;65692.67153664297;65799;16464.259108340153;17154.228059737343;17176.22706978189;17185.484435466708;17191.82808171927;13119.278439685719;13127.934360328138;13141.080124391201;13156.671083222996;13157.342132893316;16492.67014659724;16844.74732879008;18520.904818272327;19114;19312.32406865781;6751.696173672197;7775.961120194363;7949.920500794918;7959.179593795011;8039.678412863537;890.8930928288647;891.2023738753779;990.6329265613407;1009.5658866687395;1086.6848613902039;25788;27137.32156696059;30212;30859.69140308613;30962.916297929463;1118.8769235384102;1125.6510481750684;1152.6714886257375;1168.1881092640533;1213.1447329632588;15067.397304107713;15467.458638947583;15602.531924042298;15679.921600391925;16097.275622597012;698953;700443;706897;709281;710236;50822.729431763764;51851.22223166656;59064.11403828946;59658.10512842311;59777;1231.5012419969926;1422.07565082695;1427.964300892486;1447.2980604406644;1454.185656032625;30808.38383232278;31568.842155789072;31745.523817143225;32205.194870128435;32327;49694.006119878126;50421.74789126177;50716.492835072655;50737.23894141517;50746;676175;685106;687239;698624;930897;25880.741192587833;26214.73785262123;26258.21225363242;26265.868670656524;26326.473470530866;46260.30609540792;46843.76578117224;47212.7639361801;47832.5216747842;47998;33050.504242435905;38574.03564910787;38807.61192388153;38971.22057558889;39271;28137.296567585156;32789.85235516745;34479.13802154966;38360;39153.41269881011;37598.67615224175;37864.81067594644;38259.61740382561;38341.23317533689;38387.80806095952;25032.998680052595;30478.62846171972;32442.351152976335;34340.65659343375;34434.65565344415;24654;24890.004399824527;25116.748832511443;25293;25431.36421589475 06:17:59 INFO - PROCESS | 1655 | |2;jquery.html;357.0822984928734;378.92042671039087;386.9903252418691;388.1751278533116;410.6632561299734;15449.768253476206;15637.687246255067;20818.89590552049;20847.687284690743;21068.683969740465;20996.95015249235;21592.24427145046;21680.674789878165;21725.23961661342;21905.561888762255;5107.412647545542;5712.000399930016;5897.233359663238;5898.705064746767;5966.67183304918;5804.361520232771;5875.353711091777;5909.2022576951995;5921.615095018831;5930.347661757203;5548.55611551075;5771.769129234825;5777.913331300034;5780.219670344512;5857.4435428634215;5638.407967163471;5710.257666503369;5769.932562475956;5782.335031471351;5793.507551858076;4939.851804445872;4999.725015124167;5007.649464537484;5035.571976382012;5042.067217564762;26056.34859128518;26515.734842651713;26531.73468265312;26931.865340673172;26968.190954271402;2069.772325044244;2310.480141968044;2460.876956152203;2572.65269188659;2914.9360483423397;10270.537825797857;10360.171186305077;10663.253572249869;10711.625093121704;11256.662300131009;1600.6238533944513;1718.6219031812982;1804.6029873427824;1926.1043614719083;1965.6756635155182;121415;127018.36490817487;128616;129589;130314;11463.54145834174;11573.363465009505;11644.53421863133;11650.184487085822;11806.291622502677;14605.269736513132;14764.261786910827;14765.926170369079;14778.113313201242;14798.63003424923;8654.48073115615;8765.60554775024;8777.078406767325;8781.297496200288;8799.956000220085;8839.15496758912;8868.8669669955;8870.467771933705;8963.910360896307;8981.697653840156;3732.7760334380023;4585.266357382803;4588.839390621312;4593.690798122586;4645.907081858344;1570.5131409263165;1922.2983610981892;1927.296536764071;1927.681932481125;1927.7301177835113;881.5548148185126;887.316766090107;890.6081324217324;936.447495977363;966.1111777165025;19517.414477565697;21404.571908561742;21413.250536231157;21434.24980125688;21582.460438489165;919.5999740113073;945.2626950978248;947.1522986926662;968.4141094637783;1029.3669393323053;10519.94800519942;10953.671389858317;11438.256513326774;11450.427478626036;11452.3701196435;544847;548024;548493;582001.0899945473;587288.0169586423;90515;106907;129577;135588;136217;941.4445477168504;952.3809523809441;962.2927148545813;963.1331801378815;996.7707427291618;18797.812021879607;18936.242550298386;18954.147063381904;19035.52411189676;19134.712979305044;52420.689482763235;53994.460055398944;54559.72720136374;55215;55369.72315138399;61722.14833555;69759.30240697731;74576;78865.60567197128;83324.58337708273;7983.081945576101;8150.26647601695;8167.754967350989;8356.749297521084;8450.830983380421;9382.155605995493;12885.226886386847;13234.27211503338;13477;13532.255725935169;5425.376081750492;5550.7779688812;11323.830142548033;11420.200585958903;11635.709107272385;11129.220954533104;11153.386563739072;11317.151213658844;11510.884891151316;11635.767284654428;11712.590059347882;11740.354280514654;11910.761784764083;11945.82081268799;11969.521219151486;8122.390820688353;8238.83522329538;8290.37822163328;8341.290990265896;8429.704960326359;10264;11466;11496.655100347003;11607;11610.187286889837 06:17:59 INFO - PROCESS | 1655 | |3;mootools.html;1393.1919486697716;1548.628329200992;1556.3152213026267;1782.304901088575;1925.4608709561328;7418.035655364802;8177.918220817797;8177.959110204455;8178.345732341406;8178.4683995540245;8322.084570697218;8611.784705382353;8624.65501379944;8734.781630459242;8759.211670949613;2310.861348319102;2393.640953856918;2393.7007874015662;2394.616861302192;2395.796357309631;2326.9534609307802;2347.9060837566562;2355.8233132515043;2356.234223877238;2364.917227897024;2339.5671800716837;2353.105819788478;2362.9645555316683;2366.3492539551676;2375.3824005758543;2178.16140785797;2319.501307218939;2349.835511514195;2354.5761762882844;2364.9408764780846;2345.413646588353;2347.3427440316736;2347.835651504395;2349.494858605391;2352.3531028967;8724.607392667345;8737.21365077146;8798.956005219998;8803.559822008874;8804;4677.4620918594455;4831.9275210871865;5058.165402708549;5334.093204155303;5334.839954801362;3478;3480.425729754613;3579.8031108289033;3588.2105936693883;3609.5488063992;1002.0630710285906;1025.943573103479;1083.664064140119;1084.360227465791;1093.5079214353493;11162.497687604076;11413.258138221072;11421.914918082768;11616.825747613708;11621.825672614917;4324.632406245441;4404.207242696345;4425.269830478;4601.930971035437;4602.815887364535;7689.154193038761;8897.73306800797;8912.287017038621;8915.55422228886;8929.508877011827;5188.662736922088;5195.896082078413;5196.8960620787375;5199.168133098686;5199.896002079937;4812.735299558558;4874.293227481995;4878.195097808822;4882;4931.654784165145;3526.4886591444097;3555.6977656899453;3579.409397449444;3615.7468977171347;3640.8543658253902;1063.1123012284675;1068.9893101069044;1070.983935240972;1079.881213066562;1080.6163811846839;905.171767832426;915.1351972386032;924.7734305095295;949.710338346797;963.4412041016194;5674.546036317085;5858.707064646751;5990.131430942489;6076.240469941257;6092.908606370908;429.98925026874576;434.4330648503733;442.1334184997441;444.3002271422458;453.9977300113478;3868.787216703052;3955.782431966212;3967.305721498726;4029.1941611677194;4055.047063939972;10208.693739187836;10543.103836174012;10598.788024239471;10615.84076238857;10690.946545267223;10447.895521044693;10485.737856553495;10520.736981575368;10568.735781605368;10597.20520960931;7783.571903545359;8210.384221183434;8211.34309255258;8218.547979861285;8222;5660.235868157911;5764.106563482667;5797.884042319046;5871.970640146772;5873.706314684334;9824.852627210455;9870.654527091518;9874.358166719141;9897.406155630686;9899.307048506535;9781.559829807818;9902.306838521234;9992.050755178538;10136.645217417356;10163.796724065329;6817.5568588041615;6903.965480172567;6915.51591388598;6917.446604271546;6928.792136235921;7669.463137580539;8215.958920205361;8292.958535207526;8293.792655183668;8305.460145090792;7425.146108197627;7428.777136685908;7439;7573.2805383488485;7597.772066838003;7306.634668266672;7347.008153899155;7393.9260607393235;7445.255474452511;7454.552726836408;5933.199018132497;6891.896621550781;6951.478639101986;7022.052022977037;7039.753608623673;4859.1496488113735;5022.84931452057;5083.974580127076;5084.745762711924;5151.098557752453;4234.78826058702;4985.700857948535;5129.281900533853;5205.609579281493;5209.088409528242 06:17:59 INFO - PROCESS | 1655 | |4;prototype.html;252.5807160114211;253.06870715765984;285.2000139608398;310.43810702628235;317.07414350097713;5132.384113906336;5144.742762861856;5210.921836172461;5230.6338556301025;5234.528892399688;4111.876643700686;4388.758618275993;4457.398251236082;5178.679436743634;5373.328333958255;3912.3153448146604;4139.855105071323;4149.958500414988;4156.480439945007;4158.106007208449;3554.8222588870453;4102.774347410891;4167.729097608646;4208.473940757405;4236.9576304237025;3605.7836529808164;3859.2667393195343;3909.7263191576603;3941.960580394188;4125.236831186226;3528.1356067763304;3943.949146443862;4017.2568074905944;4096.8566100186645;4131.648809851166;3209.502527108302;3381.289929114864;3426.7601267911255;3428.8285585720614;3619.511365965587;5093.286939828427;5211.374635043781;5212.609054320941;5225.973870130663;5265.736713164365;4496.662750293741;4560.749158796265;4702.600278976257;4729.243321068647;4781.187198176319;5079.74601269935;5355.919661205046;5657.773689052433;5699.943000569982;5708.115242137475;3475;3701.5373078365205;3933.0757272041046;3940.2316548273097;3953.743006704584;5059.822906198302;5916.97041514794;5919.141724449931;5938.673372964528;5979.219662856705;3540.5574303212097;3897.61023897608;4065.6137666921595;4073.409355643415;4080.6123418275215;3392.5420068290464;4077.9805048737817;4106.240345536085;4107.712460127822;4117.8970525736495;3896.5518965318784;4366.825326986885;4677.375034348339;4706.576408123286;4708.623310135181;5425.9186112208345;5534.584906132056;5550.555955523549;5714;5758.683272420057;1139.868915074777;1334.4261967353937;1388.8333399992066;1395.2674845705883;1412.1668215752754;760.9391248700091;811.1077814404226;841.9705310314109;872.3500991761051;885.7776268749085;3998.9208309601445;4365.759883206454;4389.275769498061;4415.814171984888;4417.734935903856;4092.836286548564;4310.370673888925;4320.796393839102;4845.5881250093435;4849.951500485021;3515.34966031285;4029.9597004029583;4121.072758629284;4142.482189726285;4414.381986521889;2118.8981729500883;2416.2509622017233;2571.8199726019375;2577.329894227514;2612.2555071804823;4581.5647513486165;5045.142325804622;5047.41954675217;5078;5127.717975511308;4333;4417.889552761142;4891.192953162759;4903.803847846118;4910.979747455361;3957.8614748483665;4102.692298077596;4441.378207050951;4816.735079570657;5115.411727651293;4059.0571835419087;4101.589841015874;4200.306949353322;4777.235642297354;4847.781849816696;4381.912361752683;4449.28811390176;4485.215087359764;4514.977425112854;4769.446762162212;4481.439820022497;4611.0547337795;4637.25803871379;4639.257718764981;5008.899822003466;2821.8024738268946;3100.705432983863;3423.828808559612;3456.8962931112105;3495.563054618173;4108.096218831853;4314.956850431456;4872.781804548863;5199;5536.7819079802375;4395.450568678915;5387.595930305321;5605.383407825055;5605.439456054362;5608.747606357805;4814.903701926012;4910.379574740398;4957.479464656303;5617.494425501724;5629.493345598916;5038.647294689336;5301.734913254245;5486.753096110764;5514.443184146123;5542.3626282977975;4752.667313288037;5267.9464107177955;5604.187392828098;5612.382637909908;5629.157275575808;5264.842054738364;5489.204065410573;5497.257870187634;5507.834764957058;5531.778728850801 06:17:59 INFO - PROCESS | 1655 | |5;yui.html;713.9357457828794;728.1407938632411;734.9338559529641;760.6957217113155;901.8962819275783;15105.395784168633;16512.33950641973;16516.504504864843;16523.91738041311;16524;14668.11991280524;14979.400823967055;15137.848621513782;15148.394064237445;15153.469628563002;871.9912800872005;900.2887718702217;901.8421776189141;903.2503022491317;905.5472263868066;589.9203607512995;733.6405161470867;885.7077164535688;908.550267617527;911.7629416351763;846.2003406780594;961.1301771896445;963.5326866469757;963.6627180486844;963.9180669643089;742.3726950726681;956.9425834449955;958.1281034258826;958.9904100959005;960.865478832964;679.0357692077262;696.6203419136583;698.5110422704076;700.2367419512705;701.7368486817444;15509.379624814994;15582.688346233012;15620.765688514568;15639.296231669488;15641.452549160726;10372.585096596127;14616.12303261797;14620.853791462161;14623.268836558129;14632.853671463363;11322.60370887015;11747.412629368582;11756.353400562966;12661.36693165338;13186.604401867864;4799.976000120012;4818.566329030404;4950.059488697136;4950.8762280943265;4976.7013979161;99283.06674295395;99400.50299748527;100604.99999999927;104165.4791726044;107338;11520.53917843277;11543.826842597367;11590.478428470738;11620.186586939002;11946.107635415254;20270.49323766888;21160.365189044354;22631.43421414445;22672.43318917007;22692.432689182904;7883.329916957008;7995.200479951958;8057.315128214167;8076;8177.9591102044105;4421.115776844644;4503.166914120898;4523.819047238074;4554.203014472454;4598.149342371672;3511.9297614047578;3611.2416392557334;3623.1847834237615;3623.836927338276;3627.1657518770303;581.9185314056035;660.2110477978865;665.7270519087248;665.747016133866;680.7991642465485;394.9466821979054;552.8396764938122;567.9545636349083;593.7476572456688;625.3184029407967;8346.958265208636;8639.136086391436;9166.404183728037;9235.353525253167;9296.442213467213;601.4586871815401;606.5117580347774;628.0767272109991;634.7714822663837;641.9614823110535;4263.957360426418;4496.055828276033;4496.752678602708;4529.592336689648;4578.130155270488;143715;147234;149226.25386872995;152346.7147992781;155538.22230888772;133081.3345933264;134043.3297833524;135458;135610;140151.29924350313;113617;115469;117540.8245917547;118434.22348664777;118924.81075189137;8686.565671716515;8757.824843502967;8795.868061979203;8817.482375438853;8919.464832110094;63835;64566.67716661387;64974.35025649683;65663.6716816432;66211.66894165498;89599.55200223956;95749;97059.51470242604;97959.53060703953;99296.50351748195;12357.25856448616;13223.801642975555;13543.458261669422;13742.725145497234;14062.859371406155;15176.696466070396;15190;15277.847221527643;15787.763183552026;15897.284622191797;14135.222562758736;14379.28103594837;14420.495282665057;14544.345504452114;14858.851411485746;13785.310734463437;14295.213763243122;14477.855221448071;14768.113913165245;14891.553253402415;13767.173969561858;13928.72142557123;14109.929450353093;14307.64230894236;14675.92662036683;11895.405229738306;11917.404129793302;11934.641960740844;12052.819207711716;12162.452689629165;11356.318620882774;11492.94253528727;11497.677767056895;11641.5343386267;11958.402079896145 06:17:59 INFO - PROCESS | 1655 | __end_tp_report 06:17:59 INFO - PROCESS | 1655 | __start_cc_report 06:17:59 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1403 06:17:59 INFO - PROCESS | 1655 | __end_cc_report 06:17:59 INFO - PROCESS | 1655 | __startTimestamp1468243079321__endTimestamp 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | ------- Summary: start ------- 06:17:59 INFO - PROCESS | 1655 | Number of tests: 6 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5618.69 Median:5761.60 stddev:632.76 (11.0%) stddev-sans-first:607.61 06:17:59 INFO - PROCESS | 1655 | Values: 3283.5 3341.3 3347.1 3365.2 3411.0 5441.2 5501.1 5542.2 5654.1 5662.5 4672.3 5591.1 5631.9 5637.3 5656.7 5107.3 5125.3 5168.6 5209.5 5223.8 4311.6 5154.4 5220.4 5222.0 5227.6 5091.7 5100.4 5110.7 5120.0 5211.0 5197.1 5201.5 5223.7 5224.3 5276.6 4986.9 5048.2 5058.5 5147.6 5154.9 4610.8 5516.3 5540.7 5544.3 5625.0 6146.0 6178.3 6190.2 6191.8 6200.0 5868.9 6112.0 6151.1 6228.2 6273.1 5682.8 5690.6 5692.7 5706.2 5709.5 6238.5 6290.5 6294.9 6303.2 6322.0 5792.2 5853.3 5856.2 5918.4 6024.5 6144.8 6159.5 6161.0 6172.3 6174.4 6031.4 6053.3 6061.6 6061.9 6109.0 5303.4 5459.3 6103.3 6105.6 6118.9 4807.0 4827.8 4849.9 4850.8 4859.2 4377.7 4399.8 4401.7 4424.8 4431.4 5537.7 5565.5 5596.4 5606.5 5660.1 6111.8 6155.7 6156.8 6166.2 6198.0 5614.5 5617.9 5638.9 5644.2 5648.0 5189.2 5226.4 5253.8 5290.0 5321.6 6307.7 6308.1 6321.5 6326.3 6333.4 6121.6 6124.4 6132.1 6136.9 6146.3 6119.1 6157.4 6223.2 6295.4 6300.8 6080.1 6085.1 6107.4 6110.6 6137.8 6141.5 6156.6 6161.6 6164.3 6171.6 5701.3 5771.5 5822.8 5863.0 5944.9 5853.1 5854.0 5905.0 5906.4 5941.3 5752.4 5761.6 5762.2 5766.4 5773.8 5304.2 5680.1 5737.5 5913.1 5977.0 5816.8 5825.2 5825.2 5832.7 5857.6 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:77706.32 Median:18968.71 stddev:179958.06 (948.7%) stddev-sans-first:180369.57 06:17:59 INFO - PROCESS | 1655 | Values: 516.9 523.6 525.1 544.2 548.0 27766.2 29358.6 29695.3 31117.7 31226.2 29228.0 32434.0 42557.6 42809.0 42843.6 9571.8 9690.7 9697.0 9701.2 9733.7 9632.1 9730.1 9738.1 9740.3 9742.1 8184.3 8787.7 11638.8 11826.4 11893.8 11802.4 11895.5 11896.3 11902.2 11902.6 8063.7 11779.0 11830.0 11832.7 11847.5 39146.8 39480.2 39539.4 41688.2 41777.0 3437.6 3458.3 3557.5 3718.8 3929.5 31315.5 32270.8 32387.7 32428.2 32555.9 3193.8 3266.4 3269.3 3287.8 3406.5 542660.0 545339.0 545451.0 546134.0 548390.0 61870.4 61922.0 62304.8 62339.7 62428.7 65403.7 65509.3 65635.7 65692.7 65799.0 16464.3 17154.2 17176.2 17185.5 17191.8 13119.3 13127.9 13141.1 13156.7 13157.3 16492.7 16844.7 18520.9 19114.0 19312.3 6751.7 7776.0 7949.9 7959.2 8039.7 890.9 891.2 990.6 1009.6 1086.7 25788.0 27137.3 30212.0 30859.7 30962.9 1118.9 1125.7 1152.7 1168.2 1213.1 15067.4 15467.5 15602.5 15679.9 16097.3 698953.0 700443.0 706897.0 709281.0 710236.0 50822.7 51851.2 59064.1 59658.1 59777.0 1231.5 1422.1 1428.0 1447.3 1454.2 30808.4 31568.8 31745.5 32205.2 32327.0 49694.0 50421.7 50716.5 50737.2 50746.0 676175.0 685106.0 687239.0 698624.0 930897.0 25880.7 26214.7 26258.2 26265.9 26326.5 46260.3 46843.8 47212.8 47832.5 47998.0 33050.5 38574.0 38807.6 38971.2 39271.0 28137.3 32789.9 34479.1 38360.0 39153.4 37598.7 37864.8 38259.6 38341.2 38387.8 25033.0 30478.6 32442.4 34340.7 34434.7 24654.0 24890.0 25116.7 25293.0 25431.4 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34014.47 Median:14941.56 stddev:94260.18 (630.9%) stddev-sans-first:94490.73 06:17:59 INFO - PROCESS | 1655 | Values: 357.1 378.9 387.0 388.2 410.7 15449.8 15637.7 20818.9 20847.7 21068.7 20997.0 21592.2 21680.7 21725.2 21905.6 5107.4 5712.0 5897.2 5898.7 5966.7 5804.4 5875.4 5909.2 5921.6 5930.3 5548.6 5771.8 5777.9 5780.2 5857.4 5638.4 5710.3 5769.9 5782.3 5793.5 4939.9 4999.7 5007.6 5035.6 5042.1 26056.3 26515.7 26531.7 26931.9 26968.2 2069.8 2310.5 2460.9 2572.7 2914.9 10270.5 10360.2 10663.3 10711.6 11256.7 1600.6 1718.6 1804.6 1926.1 1965.7 121415.0 127018.4 128616.0 129589.0 130314.0 11463.5 11573.4 11644.5 11650.2 11806.3 14605.3 14764.3 14765.9 14778.1 14798.6 8654.5 8765.6 8777.1 8781.3 8800.0 8839.2 8868.9 8870.5 8963.9 8981.7 3732.8 4585.3 4588.8 4593.7 4645.9 1570.5 1922.3 1927.3 1927.7 1927.7 881.6 887.3 890.6 936.4 966.1 19517.4 21404.6 21413.3 21434.2 21582.5 919.6 945.3 947.2 968.4 1029.4 10519.9 10953.7 11438.3 11450.4 11452.4 544847.0 548024.0 548493.0 582001.1 587288.0 90515.0 106907.0 129577.0 135588.0 136217.0 941.4 952.4 962.3 963.1 996.8 18797.8 18936.2 18954.1 19035.5 19134.7 52420.7 53994.5 54559.7 55215.0 55369.7 61722.1 69759.3 74576.0 78865.6 83324.6 7983.1 8150.3 8167.8 8356.7 8450.8 9382.2 12885.2 13234.3 13477.0 13532.3 5425.4 5550.8 11323.8 11420.2 11635.7 11129.2 11153.4 11317.2 11510.9 11635.8 11712.6 11740.4 11910.8 11945.8 11969.5 8122.4 8238.8 8290.4 8341.3 8429.7 10264.0 11466.0 11496.7 11607.0 11610.2 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5572.17 Median:4958.68 stddev:3129.55 (63.1%) stddev-sans-first:3122.58 06:17:59 INFO - PROCESS | 1655 | Values: 1393.2 1548.6 1556.3 1782.3 1925.5 7418.0 8177.9 8178.0 8178.3 8178.5 8322.1 8611.8 8624.7 8734.8 8759.2 2310.9 2393.6 2393.7 2394.6 2395.8 2327.0 2347.9 2355.8 2356.2 2364.9 2339.6 2353.1 2363.0 2366.3 2375.4 2178.2 2319.5 2349.8 2354.6 2364.9 2345.4 2347.3 2347.8 2349.5 2352.4 8724.6 8737.2 8799.0 8803.6 8804.0 4677.5 4831.9 5058.2 5334.1 5334.8 3478.0 3480.4 3579.8 3588.2 3609.5 1002.1 1025.9 1083.7 1084.4 1093.5 11162.5 11413.3 11421.9 11616.8 11621.8 4324.6 4404.2 4425.3 4601.9 4602.8 7689.2 8897.7 8912.3 8915.6 8929.5 5188.7 5195.9 5196.9 5199.2 5199.9 4812.7 4874.3 4878.2 4882.0 4931.7 3526.5 3555.7 3579.4 3615.7 3640.9 1063.1 1069.0 1071.0 1079.9 1080.6 905.2 915.1 924.8 949.7 963.4 5674.5 5858.7 5990.1 6076.2 6092.9 430.0 434.4 442.1 444.3 454.0 3868.8 3955.8 3967.3 4029.2 4055.0 10208.7 10543.1 10598.8 10615.8 10690.9 10447.9 10485.7 10520.7 10568.7 10597.2 7783.6 8210.4 8211.3 8218.5 8222.0 5660.2 5764.1 5797.9 5872.0 5873.7 9824.9 9870.7 9874.4 9897.4 9899.3 9781.6 9902.3 9992.1 10136.6 10163.8 6817.6 6904.0 6915.5 6917.4 6928.8 7669.5 8216.0 8293.0 8293.8 8305.5 7425.1 7428.8 7439.0 7573.3 7597.8 7306.6 7347.0 7393.9 7445.3 7454.6 5933.2 6891.9 6951.5 7022.1 7039.8 4859.1 5022.8 5084.0 5084.7 5151.1 4234.8 4985.7 5129.3 5205.6 5209.1 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4235.01 Median:4469.42 stddev:1286.54 (28.8%) stddev-sans-first:1254.94 06:17:59 INFO - PROCESS | 1655 | Values: 252.6 253.1 285.2 310.4 317.1 5132.4 5144.7 5210.9 5230.6 5234.5 4111.9 4388.8 4457.4 5178.7 5373.3 3912.3 4139.9 4150.0 4156.5 4158.1 3554.8 4102.8 4167.7 4208.5 4237.0 3605.8 3859.3 3909.7 3942.0 4125.2 3528.1 3943.9 4017.3 4096.9 4131.6 3209.5 3381.3 3426.8 3428.8 3619.5 5093.3 5211.4 5212.6 5226.0 5265.7 4496.7 4560.7 4702.6 4729.2 4781.2 5079.7 5355.9 5657.8 5699.9 5708.1 3475.0 3701.5 3933.1 3940.2 3953.7 5059.8 5917.0 5919.1 5938.7 5979.2 3540.6 3897.6 4065.6 4073.4 4080.6 3392.5 4078.0 4106.2 4107.7 4117.9 3896.6 4366.8 4677.4 4706.6 4708.6 5425.9 5534.6 5550.6 5714.0 5758.7 1139.9 1334.4 1388.8 1395.3 1412.2 760.9 811.1 842.0 872.4 885.8 3998.9 4365.8 4389.3 4415.8 4417.7 4092.8 4310.4 4320.8 4845.6 4850.0 3515.3 4030.0 4121.1 4142.5 4414.4 2118.9 2416.3 2571.8 2577.3 2612.3 4581.6 5045.1 5047.4 5078.0 5127.7 4333.0 4417.9 4891.2 4903.8 4911.0 3957.9 4102.7 4441.4 4816.7 5115.4 4059.1 4101.6 4200.3 4777.2 4847.8 4381.9 4449.3 4485.2 4515.0 4769.4 4481.4 4611.1 4637.3 4639.3 5008.9 2821.8 3100.7 3423.8 3456.9 3495.6 4108.1 4315.0 4872.8 5199.0 5536.8 4395.5 5387.6 5605.4 5605.4 5608.7 4814.9 4910.4 4957.5 5617.5 5629.5 5038.6 5301.7 5486.8 5514.4 5542.4 4752.7 5267.9 5604.2 5612.4 5629.2 5264.8 5489.2 5497.3 5507.8 5531.8 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25475.44 Median:3623.51 stddev:40353.18 (1113.6%) stddev-sans-first:40423.55 06:17:59 INFO - PROCESS | 1655 | Values: 713.9 728.1 734.9 760.7 901.9 15105.4 16512.3 16516.5 16523.9 16524.0 14668.1 14979.4 15137.8 15148.4 15153.5 872.0 900.3 901.8 903.3 905.5 589.9 733.6 885.7 908.6 911.8 846.2 961.1 963.5 963.7 963.9 742.4 956.9 958.1 959.0 960.9 679.0 696.6 698.5 700.2 701.7 15509.4 15582.7 15620.8 15639.3 15641.5 10372.6 14616.1 14620.9 14623.3 14632.9 11322.6 11747.4 11756.4 12661.4 13186.6 4800.0 4818.6 4950.1 4950.9 4976.7 99283.1 99400.5 100605.0 104165.5 107338.0 11520.5 11543.8 11590.5 11620.2 11946.1 20270.5 21160.4 22631.4 22672.4 22692.4 7883.3 7995.2 8057.3 8076.0 8178.0 4421.1 4503.2 4523.8 4554.2 4598.1 3511.9 3611.2 3623.2 3623.8 3627.2 581.9 660.2 665.7 665.7 680.8 394.9 552.8 568.0 593.7 625.3 8347.0 8639.1 9166.4 9235.4 9296.4 601.5 606.5 628.1 634.8 642.0 4264.0 4496.1 4496.8 4529.6 4578.1 143715.0 147234.0 149226.3 152346.7 155538.2 133081.3 134043.3 135458.0 135610.0 140151.3 113617.0 115469.0 117540.8 118434.2 118924.8 8686.6 8757.8 8795.9 8817.5 8919.5 63835.0 64566.7 64974.4 65663.7 66211.7 89599.6 95749.0 97059.5 97959.5 99296.5 12357.3 13223.8 13543.5 13742.7 14062.9 15176.7 15190.0 15277.8 15787.8 15897.3 14135.2 14379.3 14420.5 14544.3 14858.9 13785.3 14295.2 14477.9 14768.1 14891.6 13767.2 13928.7 14109.9 14307.6 14675.9 11895.4 11917.4 11934.6 12052.8 12162.5 11356.3 11492.9 11497.7 11641.5 11958.4 06:17:59 INFO - PROCESS | 1655 | -------- Summary: end -------- 06:17:59 INFO - PROCESS | 1655 | 06:17:59 INFO - TEST-INFO | 1655: exit 0 06:17:59 INFO - TEST-OK | dromaeo_css | took 1116791ms 06:17:59 INFO - TEST-START | kraken 06:17:59 INFO - operating with platform_type : mac_ 06:17:59 INFO - Initialising browser for kraken test... 06:17:59 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpvWWRnR/profile http://localhost:49280/getInfo.html) 06:18:02 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:18:02 INFO - PROCESS | 1664 | colorDepth:24 06:18:02 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:18:02 INFO - PROCESS | 1664 | __metrics 06:18:02 INFO - PROCESS | 1664 | 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" 06:18:02 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:18:03 INFO - TEST-INFO | 1664: exit 0 06:18:03 INFO - Browser initialized. 06:18:03 INFO - Running cycle 1/1 for kraken test... 06:18:03 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpvWWRnR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:18:15 INFO - PROCESS | 1665 | RSS: Main: 243863552 06:18:15 INFO - PROCESS | 1665 | 06:18:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 06:18:18 INFO - PROCESS | 1665 | RSS: Main: 293888000 06:18:18 INFO - PROCESS | 1665 | 06:18:21 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 06:18:21 INFO - PROCESS | 1665 | RSS: Main: 304443392 06:18:21 INFO - PROCESS | 1665 | 06:18:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 06:18:26 INFO - PROCESS | 1665 | RSS: Main: 389218304 06:18:26 INFO - PROCESS | 1665 | 06:18:28 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 06:18:28 INFO - PROCESS | 1665 | RSS: Main: 290594816 06:18:28 INFO - PROCESS | 1665 | 06:18:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:18:31 INFO - PROCESS | 1665 | RSS: Main: 294653952 06:18:31 INFO - PROCESS | 1665 | 06:18:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 06:18:34 INFO - PROCESS | 1665 | RSS: Main: 333377536 06:18:34 INFO - PROCESS | 1665 | 06:18:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 06:18:38 INFO - PROCESS | 1665 | RSS: Main: 333819904 06:18:38 INFO - PROCESS | 1665 | 06:18:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 06:18:41 INFO - PROCESS | 1665 | RSS: Main: 332144640 06:18:41 INFO - PROCESS | 1665 | 06:18:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:18:43 INFO - PROCESS | 1665 | RSS: Main: 313114624 06:18:43 INFO - PROCESS | 1665 | 06:18:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:18:46 INFO - PROCESS | 1665 | RSS: Main: 319336448 06:18:46 INFO - PROCESS | 1665 | 06:18:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:18:49 INFO - PROCESS | 1665 | RSS: Main: 318087168 06:18:49 INFO - PROCESS | 1665 | 06:18:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:18:52 INFO - PROCESS | 1665 | RSS: Main: 326336512 06:18:52 INFO - PROCESS | 1665 | 06:18:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:18:56 INFO - PROCESS | 1665 | RSS: Main: 328638464 06:18:56 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 06:18:58 INFO - PROCESS | 1665 | RSS: Main: 372379648 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | __start_tp_report 06:18:58 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 06:18:58 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 06:18:58 INFO - PROCESS | 1665 | |i|pagename|runs| 06:18:58 INFO - PROCESS | 1665 | |0;ai-astar;101;113;114;112;112;112;114;113;112;114 06:18:58 INFO - PROCESS | 1665 | |1;audio-beat-detection;143;115;110;156;109;106;128;106;140;106 06:18:58 INFO - PROCESS | 1665 | |2;audio-dft;183;185;186;184;183;181;183;186;181;183 06:18:58 INFO - PROCESS | 1665 | |3;audio-fft;80;74;58;107;56;57;63;61;96;57 06:18:58 INFO - PROCESS | 1665 | |4;audio-oscillator;85;87;91;71;70;69;123;79;57;123 06:18:58 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;84;87;84;86;84;88;84;87;84 06:18:58 INFO - PROCESS | 1665 | |6;imaging-darkroom;94;95;94;94;94;94;93;95;93;93 06:18:58 INFO - PROCESS | 1665 | |7;imaging-desaturate;86;85;84;83;83;85;86;85;86;85 06:18:58 INFO - PROCESS | 1665 | |8;json-parse-financial;54;55;55;56;53;55;55;54;55;55 06:18:58 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;66;78;77;72;71;71;71;72;74 06:18:58 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;85;84;86;82;82;82;78;82;75;96 06:18:58 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;137;139;130;134;136;139;154;145;227;138 06:18:58 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;161;163;161;163;164;166;163;160;161;161 06:18:58 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;65;66;67;65;68;67;81;85;84;85 06:18:58 INFO - PROCESS | 1665 | __end_tp_report 06:18:58 INFO - PROCESS | 1665 | __start_cc_report 06:18:58 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2093 06:18:58 INFO - PROCESS | 1665 | __end_cc_report 06:18:58 INFO - PROCESS | 1665 | __startTimestamp1468243138270__endTimestamp 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | ------- Summary: start ------- 06:18:58 INFO - PROCESS | 1665 | Number of tests: 14 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.86 (3.4%) stddev-sans-first:0.93 06:18:58 INFO - PROCESS | 1665 | Values: 101.0 113.0 114.0 112.0 112.0 112.0 114.0 113.0 112.0 114.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:121.50 stddev:18.52 (15.2%) stddev-sans-first:18.00 06:18:58 INFO - PROCESS | 1665 | Values: 143.0 115.0 110.0 156.0 109.0 106.0 128.0 106.0 140.0 106.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.78 (1.0%) stddev-sans-first:1.88 06:18:58 INFO - PROCESS | 1665 | Values: 183.0 185.0 186.0 184.0 183.0 181.0 183.0 186.0 181.0 183.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:70.90 Median:62.00 stddev:18.14 (29.3%) stddev-sans-first:18.94 06:18:58 INFO - PROCESS | 1665 | Values: 80.0 74.0 58.0 107.0 56.0 57.0 63.0 61.0 96.0 57.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:75.00 stddev:22.17 (29.6%) stddev-sans-first:23.51 06:18:58 INFO - PROCESS | 1665 | Values: 85.0 87.0 91.0 71.0 70.0 69.0 123.0 79.0 57.0 123.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.78 (2.1%) stddev-sans-first:1.66 06:18:58 INFO - PROCESS | 1665 | Values: 88.0 84.0 87.0 84.0 86.0 84.0 88.0 84.0 87.0 84.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 06:18:58 INFO - PROCESS | 1665 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 93.0 95.0 93.0 93.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 06:18:58 INFO - PROCESS | 1665 | Values: 86.0 85.0 84.0 83.0 83.0 85.0 86.0 85.0 86.0 85.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 06:18:58 INFO - PROCESS | 1665 | Values: 54.0 55.0 55.0 56.0 53.0 55.0 55.0 54.0 55.0 55.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:3.94 (5.5%) stddev-sans-first:3.57 06:18:58 INFO - PROCESS | 1665 | Values: 66.0 66.0 78.0 77.0 72.0 71.0 71.0 71.0 72.0 74.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:5.53 (6.7%) stddev-sans-first:5.83 06:18:58 INFO - PROCESS | 1665 | Values: 85.0 84.0 86.0 82.0 82.0 82.0 78.0 82.0 75.0 96.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:139.00 stddev:28.54 (20.5%) stddev-sans-first:30.00 06:18:58 INFO - PROCESS | 1665 | Values: 137.0 139.0 130.0 134.0 136.0 139.0 154.0 145.0 227.0 138.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:1.83 (1.1%) stddev-sans-first:1.88 06:18:58 INFO - PROCESS | 1665 | Values: 161.0 163.0 161.0 163.0 164.0 166.0 163.0 160.0 161.0 161.0 06:18:58 INFO - PROCESS | 1665 | 06:18:58 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:74.50 stddev:9.10 (12.2%) stddev-sans-first:9.15 06:18:58 INFO - PROCESS | 1665 | Values: 65.0 66.0 67.0 65.0 68.0 67.0 81.0 85.0 84.0 85.0 06:18:58 INFO - PROCESS | 1665 | -------- Summary: end -------- 06:18:58 INFO - PROCESS | 1665 | 06:18:59 INFO - TEST-INFO | 1665: exit 0 06:18:59 INFO - TEST-OK | kraken | took 59204ms 06:18:59 INFO - SUITE-END | took 1175s 06:18:59 INFO - Completed test suite (00:19:36) 06:18:59 INFO - javascript benchmark 06:18:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.4910588858725, 3341.331733653269, 3347.0628224097263, 3365.209175843675, 3411.0, 5441.183822426638, 5501.119820828658, 5542.196381524676, 5654.095344744842, 5662.5186859116975, 4672.322513235587, 5591.133374326976, 5631.859203519914, 5637.3235211774645, 5656.717164141797, 5107.310513080743, 5125.333706618135, 5168.638195326329, 5209.453007434222, 5223.8432847014465, 4311.611954924057, 5154.4072431670465, 5220.373555173384, 5222.0, 5227.607929405291, 5091.6690415123085, 5100.438951715309, 5110.693358398508, 5120.0, 5211.0, 5197.1424714922, 5201.453847346031, 5223.71269580173, 5224.294720212761, 5276.577873770089, 4986.87532811679, 5048.242763585455, 5058.4688607696025, 5147.613928955306, 5154.871128221787, 4610.76946152691, 5516.255305533782, 5540.667559946376, 5544.251526044013, 5624.9875022495935, 6146.016637338004, 6178.320384757673, 6190.164327815777, 6191.845203869894, 6200.007998720228, 5868.9413105868825, 6112.0, 6151.077338399231, 6228.22147231596, 6273.090401891745, 5682.801101961494, 5690.573207009491, 5692.743826527816, 5706.201131841587, 5709.457601527848, 6238.469730072995, 6290.528210384148, 6294.874102517924, 6303.243610766737, 6322.0, 5792.218050563204, 5853.326867410217, 5856.150858125633, 5918.408159184047, 6024.548158888101, 6144.832481828438, 6159.538034647419, 6161.045038019114, 6172.321044685081, 6174.44430001293, 6031.418187595589, 6053.303870054912, 6061.636301821904, 6061.909071363933, 6108.969455152695, 5303.390110137307, 5459.317585301837, 6103.267607887082, 6105.572609917263, 6118.908216376758, 4807.038592281558, 4827.758612069452, 4849.854504364875, 4850.781714822841, 4859.246816743411, 4377.693561450732, 4399.84600538986, 4401.735895846259, 4424.80088396023, 4431.357453169337, 5537.723113844372, 5565.499105080562, 5596.356419011784, 5606.467385598362, 5660.094384898396, 6111.816645500642, 6155.661438620829, 6156.815295541141, 6166.229221347332, 6198.0, 5614.494695477427, 5617.943820561742, 5638.859028524238, 5644.153376993484, 5648.0, 5189.169732842727, 5226.425093239741, 5253.842384728464, 5290.047791397586, 5321.600879934021, 6307.684615769285, 6308.148399965946, 6321.494280457552, 6326.272478665012, 6333.36666333363, 6121.602095863755, 6124.418180272867, 6132.110843927695, 6136.877262454636, 6146.323904370426, 6119.143319935301, 6157.4458298753325, 6223.190985171898, 6295.433410993032, 6300.779472718433, 6080.087986802015, 6085.056816193497, 6107.4197951194565, 6110.602810817282, 6137.754489820536, 6141.539384546088, 6156.5998210116195, 6161.568690191643, 6164.291106522628, 6171.59884607499, 5701.344345400332, 5771.509421699203, 5822.82531524055, 5863.0, 5944.910826337522, 5853.0635098384055, 5854.0, 5904.996150654313, 5906.40935906406, 5941.346451890202, 5752.367239603556, 5761.596688231783, 5762.164486149569, 5766.365699772938, 5773.797917072882, 5304.23088652151, 5680.147977803363, 5737.454941780524, 5913.05391137416, 5977.043673012297, 5816.76732930678, 5825.2135961644635, 5825.242718446575, 5832.708364581669, 5857.619254748428], "value": 5579.324245743627, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.8940367224719, 523.6125267302195, 525.062762968102, 544.1891581543499, 547.991780123298, 27766.167014989533, 29358.559621605666, 29695.257618559546, 31117.688823111763, 31226.219344516398, 29228.0, 32434.0, 42557.57442425575, 42809.0, 42843.57156428435, 9571.808563828738, 9690.66082687106, 9697.0, 9701.175400090966, 9733.707988760349, 9632.133108020276, 9730.124288814006, 9738.1235688788, 9740.269479789009, 9742.123208911196, 8184.263416292532, 8787.736367908941, 11638.767224655501, 11826.40867956603, 11893.762124757499, 11802.350870702108, 11895.46470408825, 11896.345700986441, 11902.226355286964, 11902.583409580624, 8063.677452901877, 11779.0, 11830.0, 11832.704182395422, 11847.526098956032, 39146.804265978775, 39480.210395791924, 39539.40690889639, 41688.1662366751, 41777.0, 3437.553118094657, 3458.291050334675, 3557.5375201223674, 3718.7954662493553, 3929.5284565851994, 31315.53026704601, 32270.838645806856, 32387.676123238703, 32428.18929526757, 32555.860544880816, 3193.7924034937887, 3266.4283750343834, 3269.3134441767256, 3287.7862938908893, 3406.5230867678547, 542660.0, 545339.0, 545451.0, 546134.0, 548390.0, 61870.38129618736, 61922.0, 62304.75390492165, 62339.68830155911, 62428.68785656043, 65403.67298163574, 65509.344906551276, 65635.67182164059, 65692.67153664297, 65799.0, 16464.259108340153, 17154.228059737343, 17176.22706978189, 17185.484435466708, 17191.82808171927, 13119.278439685719, 13127.934360328138, 13141.080124391201, 13156.671083222996, 13157.342132893316, 16492.67014659724, 16844.74732879008, 18520.904818272327, 19114.0, 19312.32406865781, 6751.696173672197, 7775.961120194363, 7949.920500794918, 7959.179593795011, 8039.678412863537, 890.8930928288647, 891.2023738753779, 990.6329265613407, 1009.5658866687395, 1086.6848613902039, 25788.0, 27137.32156696059, 30212.0, 30859.69140308613, 30962.916297929463, 1118.8769235384102, 1125.6510481750684, 1152.6714886257375, 1168.1881092640533, 1213.1447329632588, 15067.397304107713, 15467.458638947583, 15602.531924042298, 15679.921600391925, 16097.275622597012, 698953.0, 700443.0, 706897.0, 709281.0, 710236.0, 50822.729431763764, 51851.22223166656, 59064.11403828946, 59658.10512842311, 59777.0, 1231.5012419969926, 1422.07565082695, 1427.964300892486, 1447.2980604406644, 1454.185656032625, 30808.38383232278, 31568.842155789072, 31745.523817143225, 32205.194870128435, 32327.0, 49694.006119878126, 50421.74789126177, 50716.492835072655, 50737.23894141517, 50746.0, 676175.0, 685106.0, 687239.0, 698624.0, 930897.0, 25880.741192587833, 26214.73785262123, 26258.21225363242, 26265.868670656524, 26326.473470530866, 46260.30609540792, 46843.76578117224, 47212.7639361801, 47832.5216747842, 47998.0, 33050.504242435905, 38574.03564910787, 38807.61192388153, 38971.22057558889, 39271.0, 28137.296567585156, 32789.85235516745, 34479.13802154966, 38360.0, 39153.41269881011, 37598.67615224175, 37864.81067594644, 38259.61740382561, 38341.23317533689, 38387.80806095952, 25032.998680052595, 30478.62846171972, 32442.351152976335, 34340.65659343375, 34434.65565344415, 24654.0, 24890.004399824527, 25116.748832511443, 25293.0, 25431.36421589475], "value": 20375.11666239859, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.0822984928734, 378.92042671039087, 386.9903252418691, 388.1751278533116, 410.6632561299734, 15449.768253476206, 15637.687246255067, 20818.89590552049, 20847.687284690743, 21068.683969740465, 20996.95015249235, 21592.24427145046, 21680.674789878165, 21725.23961661342, 21905.561888762255, 5107.412647545542, 5712.000399930016, 5897.233359663238, 5898.705064746767, 5966.67183304918, 5804.361520232771, 5875.353711091777, 5909.2022576951995, 5921.615095018831, 5930.347661757203, 5548.55611551075, 5771.769129234825, 5777.913331300034, 5780.219670344512, 5857.4435428634215, 5638.407967163471, 5710.257666503369, 5769.932562475956, 5782.335031471351, 5793.507551858076, 4939.851804445872, 4999.725015124167, 5007.649464537484, 5035.571976382012, 5042.067217564762, 26056.34859128518, 26515.734842651713, 26531.73468265312, 26931.865340673172, 26968.190954271402, 2069.772325044244, 2310.480141968044, 2460.876956152203, 2572.65269188659, 2914.9360483423397, 10270.537825797857, 10360.171186305077, 10663.253572249869, 10711.625093121704, 11256.662300131009, 1600.6238533944513, 1718.6219031812982, 1804.6029873427824, 1926.1043614719083, 1965.6756635155182, 121415.0, 127018.36490817487, 128616.0, 129589.0, 130314.0, 11463.54145834174, 11573.363465009505, 11644.53421863133, 11650.184487085822, 11806.291622502677, 14605.269736513132, 14764.261786910827, 14765.926170369079, 14778.113313201242, 14798.63003424923, 8654.48073115615, 8765.60554775024, 8777.078406767325, 8781.297496200288, 8799.956000220085, 8839.15496758912, 8868.8669669955, 8870.467771933705, 8963.910360896307, 8981.697653840156, 3732.7760334380023, 4585.266357382803, 4588.839390621312, 4593.690798122586, 4645.907081858344, 1570.5131409263165, 1922.2983610981892, 1927.296536764071, 1927.681932481125, 1927.7301177835113, 881.5548148185126, 887.316766090107, 890.6081324217324, 936.447495977363, 966.1111777165025, 19517.414477565697, 21404.571908561742, 21413.250536231157, 21434.24980125688, 21582.460438489165, 919.5999740113073, 945.2626950978248, 947.1522986926662, 968.4141094637783, 1029.3669393323053, 10519.94800519942, 10953.671389858317, 11438.256513326774, 11450.427478626036, 11452.3701196435, 544847.0, 548024.0, 548493.0, 582001.0899945473, 587288.0169586423, 90515.0, 106907.0, 129577.0, 135588.0, 136217.0, 941.4445477168504, 952.3809523809441, 962.2927148545813, 963.1331801378815, 996.7707427291618, 18797.812021879607, 18936.242550298386, 18954.147063381904, 19035.52411189676, 19134.712979305044, 52420.689482763235, 53994.460055398944, 54559.72720136374, 55215.0, 55369.72315138399, 61722.14833555, 69759.30240697731, 74576.0, 78865.60567197128, 83324.58337708273, 7983.081945576101, 8150.26647601695, 8167.754967350989, 8356.749297521084, 8450.830983380421, 9382.155605995493, 12885.226886386847, 13234.27211503338, 13477.0, 13532.255725935169, 5425.376081750492, 5550.7779688812, 11323.830142548033, 11420.200585958903, 11635.709107272385, 11129.220954533104, 11153.386563739072, 11317.151213658844, 11510.884891151316, 11635.767284654428, 11712.590059347882, 11740.354280514654, 11910.761784764083, 11945.82081268799, 11969.521219151486, 8122.390820688353, 8238.83522329538, 8290.37822163328, 8341.290990265896, 8429.704960326359, 10264.0, 11466.0, 11496.655100347003, 11607.0, 11610.187286889837], "value": 9538.935288437593, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.1919486697716, 1548.628329200992, 1556.3152213026267, 1782.304901088575, 1925.4608709561328, 7418.035655364802, 8177.918220817797, 8177.959110204455, 8178.345732341406, 8178.4683995540245, 8322.084570697218, 8611.784705382353, 8624.65501379944, 8734.781630459242, 8759.211670949613, 2310.861348319102, 2393.640953856918, 2393.7007874015662, 2394.616861302192, 2395.796357309631, 2326.9534609307802, 2347.9060837566562, 2355.8233132515043, 2356.234223877238, 2364.917227897024, 2339.5671800716837, 2353.105819788478, 2362.9645555316683, 2366.3492539551676, 2375.3824005758543, 2178.16140785797, 2319.501307218939, 2349.835511514195, 2354.5761762882844, 2364.9408764780846, 2345.413646588353, 2347.3427440316736, 2347.835651504395, 2349.494858605391, 2352.3531028967, 8724.607392667345, 8737.21365077146, 8798.956005219998, 8803.559822008874, 8804.0, 4677.4620918594455, 4831.9275210871865, 5058.165402708549, 5334.093204155303, 5334.839954801362, 3478.0, 3480.425729754613, 3579.8031108289033, 3588.2105936693883, 3609.5488063992, 1002.0630710285906, 1025.943573103479, 1083.664064140119, 1084.360227465791, 1093.5079214353493, 11162.497687604076, 11413.258138221072, 11421.914918082768, 11616.825747613708, 11621.825672614917, 4324.632406245441, 4404.207242696345, 4425.269830478, 4601.930971035437, 4602.815887364535, 7689.154193038761, 8897.73306800797, 8912.287017038621, 8915.55422228886, 8929.508877011827, 5188.662736922088, 5195.896082078413, 5196.8960620787375, 5199.168133098686, 5199.896002079937, 4812.735299558558, 4874.293227481995, 4878.195097808822, 4882.0, 4931.654784165145, 3526.4886591444097, 3555.6977656899453, 3579.409397449444, 3615.7468977171347, 3640.8543658253902, 1063.1123012284675, 1068.9893101069044, 1070.983935240972, 1079.881213066562, 1080.6163811846839, 905.171767832426, 915.1351972386032, 924.7734305095295, 949.710338346797, 963.4412041016194, 5674.546036317085, 5858.707064646751, 5990.131430942489, 6076.240469941257, 6092.908606370908, 429.98925026874576, 434.4330648503733, 442.1334184997441, 444.3002271422458, 453.9977300113478, 3868.787216703052, 3955.782431966212, 3967.305721498726, 4029.1941611677194, 4055.047063939972, 10208.693739187836, 10543.103836174012, 10598.788024239471, 10615.84076238857, 10690.946545267223, 10447.895521044693, 10485.737856553495, 10520.736981575368, 10568.735781605368, 10597.20520960931, 7783.571903545359, 8210.384221183434, 8211.34309255258, 8218.547979861285, 8222.0, 5660.235868157911, 5764.106563482667, 5797.884042319046, 5871.970640146772, 5873.706314684334, 9824.852627210455, 9870.654527091518, 9874.358166719141, 9897.406155630686, 9899.307048506535, 9781.559829807818, 9902.306838521234, 9992.050755178538, 10136.645217417356, 10163.796724065329, 6817.5568588041615, 6903.965480172567, 6915.51591388598, 6917.446604271546, 6928.792136235921, 7669.463137580539, 8215.958920205361, 8292.958535207526, 8293.792655183668, 8305.460145090792, 7425.146108197627, 7428.777136685908, 7439.0, 7573.2805383488485, 7597.772066838003, 7306.634668266672, 7347.008153899155, 7393.9260607393235, 7445.255474452511, 7454.552726836408, 5933.199018132497, 6891.896621550781, 6951.478639101986, 7022.052022977037, 7039.753608623673, 4859.1496488113735, 5022.84931452057, 5083.974580127076, 5084.745762711924, 5151.098557752453, 4234.78826058702, 4985.700857948535, 5129.281900533853, 5205.609579281493, 5209.088409528242], "value": 4406.5407716493855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.5807160114211, 253.06870715765984, 285.2000139608398, 310.43810702628235, 317.07414350097713, 5132.384113906336, 5144.742762861856, 5210.921836172461, 5230.6338556301025, 5234.528892399688, 4111.876643700686, 4388.758618275993, 4457.398251236082, 5178.679436743634, 5373.328333958255, 3912.3153448146604, 4139.855105071323, 4149.958500414988, 4156.480439945007, 4158.106007208449, 3554.8222588870453, 4102.774347410891, 4167.729097608646, 4208.473940757405, 4236.9576304237025, 3605.7836529808164, 3859.2667393195343, 3909.7263191576603, 3941.960580394188, 4125.236831186226, 3528.1356067763304, 3943.949146443862, 4017.2568074905944, 4096.8566100186645, 4131.648809851166, 3209.502527108302, 3381.289929114864, 3426.7601267911255, 3428.8285585720614, 3619.511365965587, 5093.286939828427, 5211.374635043781, 5212.609054320941, 5225.973870130663, 5265.736713164365, 4496.662750293741, 4560.749158796265, 4702.600278976257, 4729.243321068647, 4781.187198176319, 5079.74601269935, 5355.919661205046, 5657.773689052433, 5699.943000569982, 5708.115242137475, 3475.0, 3701.5373078365205, 3933.0757272041046, 3940.2316548273097, 3953.743006704584, 5059.822906198302, 5916.97041514794, 5919.141724449931, 5938.673372964528, 5979.219662856705, 3540.5574303212097, 3897.61023897608, 4065.6137666921595, 4073.409355643415, 4080.6123418275215, 3392.5420068290464, 4077.9805048737817, 4106.240345536085, 4107.712460127822, 4117.8970525736495, 3896.5518965318784, 4366.825326986885, 4677.375034348339, 4706.576408123286, 4708.623310135181, 5425.9186112208345, 5534.584906132056, 5550.555955523549, 5714.0, 5758.683272420057, 1139.868915074777, 1334.4261967353937, 1388.8333399992066, 1395.2674845705883, 1412.1668215752754, 760.9391248700091, 811.1077814404226, 841.9705310314109, 872.3500991761051, 885.7776268749085, 3998.9208309601445, 4365.759883206454, 4389.275769498061, 4415.814171984888, 4417.734935903856, 4092.836286548564, 4310.370673888925, 4320.796393839102, 4845.5881250093435, 4849.951500485021, 3515.34966031285, 4029.9597004029583, 4121.072758629284, 4142.482189726285, 4414.381986521889, 2118.8981729500883, 2416.2509622017233, 2571.8199726019375, 2577.329894227514, 2612.2555071804823, 4581.5647513486165, 5045.142325804622, 5047.41954675217, 5078.0, 5127.717975511308, 4333.0, 4417.889552761142, 4891.192953162759, 4903.803847846118, 4910.979747455361, 3957.8614748483665, 4102.692298077596, 4441.378207050951, 4816.735079570657, 5115.411727651293, 4059.0571835419087, 4101.589841015874, 4200.306949353322, 4777.235642297354, 4847.781849816696, 4381.912361752683, 4449.28811390176, 4485.215087359764, 4514.977425112854, 4769.446762162212, 4481.439820022497, 4611.0547337795, 4637.25803871379, 4639.257718764981, 5008.899822003466, 2821.8024738268946, 3100.705432983863, 3423.828808559612, 3456.8962931112105, 3495.563054618173, 4108.096218831853, 4314.956850431456, 4872.781804548863, 5199.0, 5536.7819079802375, 4395.450568678915, 5387.595930305321, 5605.383407825055, 5605.439456054362, 5608.747606357805, 4814.903701926012, 4910.379574740398, 4957.479464656303, 5617.494425501724, 5629.493345598916, 5038.647294689336, 5301.734913254245, 5486.753096110764, 5514.443184146123, 5542.3626282977975, 4752.667313288037, 5267.9464107177955, 5604.187392828098, 5612.382637909908, 5629.157275575808, 5264.842054738364, 5489.204065410573, 5497.257870187634, 5507.834764957058, 5531.778728850801], "value": 3828.811354646346, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.9357457828794, 728.1407938632411, 734.9338559529641, 760.6957217113155, 901.8962819275783, 15105.395784168633, 16512.33950641973, 16516.504504864843, 16523.91738041311, 16524.0, 14668.11991280524, 14979.400823967055, 15137.848621513782, 15148.394064237445, 15153.469628563002, 871.9912800872005, 900.2887718702217, 901.8421776189141, 903.2503022491317, 905.5472263868066, 589.9203607512995, 733.6405161470867, 885.7077164535688, 908.550267617527, 911.7629416351763, 846.2003406780594, 961.1301771896445, 963.5326866469757, 963.6627180486844, 963.9180669643089, 742.3726950726681, 956.9425834449955, 958.1281034258826, 958.9904100959005, 960.865478832964, 679.0357692077262, 696.6203419136583, 698.5110422704076, 700.2367419512705, 701.7368486817444, 15509.379624814994, 15582.688346233012, 15620.765688514568, 15639.296231669488, 15641.452549160726, 10372.585096596127, 14616.12303261797, 14620.853791462161, 14623.268836558129, 14632.853671463363, 11322.60370887015, 11747.412629368582, 11756.353400562966, 12661.36693165338, 13186.604401867864, 4799.976000120012, 4818.566329030404, 4950.059488697136, 4950.8762280943265, 4976.7013979161, 99283.06674295395, 99400.50299748527, 100604.99999999927, 104165.4791726044, 107338.0, 11520.53917843277, 11543.826842597367, 11590.478428470738, 11620.186586939002, 11946.107635415254, 20270.49323766888, 21160.365189044354, 22631.43421414445, 22672.43318917007, 22692.432689182904, 7883.329916957008, 7995.200479951958, 8057.315128214167, 8076.0, 8177.9591102044105, 4421.115776844644, 4503.166914120898, 4523.819047238074, 4554.203014472454, 4598.149342371672, 3511.9297614047578, 3611.2416392557334, 3623.1847834237615, 3623.836927338276, 3627.1657518770303, 581.9185314056035, 660.2110477978865, 665.7270519087248, 665.747016133866, 680.7991642465485, 394.9466821979054, 552.8396764938122, 567.9545636349083, 593.7476572456688, 625.3184029407967, 8346.958265208636, 8639.136086391436, 9166.404183728037, 9235.353525253167, 9296.442213467213, 601.4586871815401, 606.5117580347774, 628.0767272109991, 634.7714822663837, 641.9614823110535, 4263.957360426418, 4496.055828276033, 4496.752678602708, 4529.592336689648, 4578.130155270488, 143715.0, 147234.0, 149226.25386872995, 152346.7147992781, 155538.22230888772, 133081.3345933264, 134043.3297833524, 135458.0, 135610.0, 140151.29924350313, 113617.0, 115469.0, 117540.8245917547, 118434.22348664777, 118924.81075189137, 8686.565671716515, 8757.824843502967, 8795.868061979203, 8817.482375438853, 8919.464832110094, 63835.0, 64566.67716661387, 64974.35025649683, 65663.6716816432, 66211.66894165498, 89599.55200223956, 95749.0, 97059.51470242604, 97959.53060703953, 99296.50351748195, 12357.25856448616, 13223.801642975555, 13543.458261669422, 13742.725145497234, 14062.859371406155, 15176.696466070396, 15190.0, 15277.847221527643, 15787.763183552026, 15897.284622191797, 14135.222562758736, 14379.28103594837, 14420.495282665057, 14544.345504452114, 14858.851411485746, 13785.310734463437, 14295.213763243122, 14477.855221448071, 14768.113913165245, 14891.553253402415, 13767.173969561858, 13928.72142557123, 14109.929450353093, 14307.64230894236, 14675.92662036683, 11895.405229738306, 11917.404129793302, 11934.641960740844, 12052.819207711716, 12162.452689629165, 11356.318620882774, 11492.94253528727, 11497.677767056895, 11641.5343386267, 11958.402079896145], "value": 8016.058819483692, "unit": "score"}], "name": "dromaeo_css", "value": 7262.052421948286, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 112.0, 112.0, 112.0, 114.0, 113.0, 112.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 110.0, 156.0, 109.0, 106.0, 128.0, 106.0, 140.0, 106.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 186.0, 184.0, 183.0, 181.0, 183.0, 186.0, 181.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 107.0, 56.0, 57.0, 63.0, 61.0, 96.0, 57.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 91.0, 71.0, 70.0, 69.0, 123.0, 79.0, 57.0, 123.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 86.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 93.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 83.0, 83.0, 85.0, 86.0, 85.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 56.0, 53.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 78.0, 77.0, 72.0, 71.0, 71.0, 71.0, 72.0, 74.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 82.0, 82.0, 82.0, 78.0, 82.0, 75.0, 96.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 130.0, 134.0, 136.0, 139.0, 154.0, 145.0, 227.0, 138.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 163.0, 164.0, 166.0, 163.0, 160.0, 161.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 68.0, 67.0, 81.0, 85.0, 84.0, 85.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1431.0, "alertThreshold": 2.0}]} 06:18:59 INFO - Return code: 0 06:18:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:18:59 INFO - # TBPL SUCCESS # 06:18:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:18:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:18:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:18:59 INFO - 2016-07-11 06:18:59.529 screenresolution[1669:9735] starting screenresolution argv=screenresolution get 06:18:59 INFO - 2016-07-11 06:18:59.531 screenresolution[1669:9735] Display 0: 1600x1200x32@60 06:18:59 INFO - 2016-07-11 06:18:59.537 screenresolution[1670:9738] starting screenresolution argv=screenresolution list 06:18:59 INFO - Available Modes on Display 0 06:18:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:18:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:18:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:18:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:18:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:18:59 INFO - Intel Iris: 06:18:59 INFO - Chipset Model: Intel Iris 06:18:59 INFO - Type: GPU 06:18:59 INFO - Bus: Built-In 06:18:59 INFO - VRAM (Dynamic, Max): 1536 MB 06:18:59 INFO - Vendor: Intel (0x8086) 06:18:59 INFO - Device ID: 0x0a2e 06:18:59 INFO - Revision ID: 0x0009 06:18:59 INFO - Displays: 06:18:59 INFO - Display: 06:18:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:18:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:18:59 INFO - Display Serial Number: 1600x1200 60 06:18:59 INFO - Main Display: Yes 06:18:59 INFO - Mirror: Off 06:18:59 INFO - Online: Yes 06:18:59 INFO - Rotation: Supported 06:18:59 INFO - Return code: 0 06:18:59 INFO - Running post-action listener: _resource_record_post_action 06:18:59 INFO - ##### 06:18:59 INFO - ##### Finished run-tests step (success) 06:18:59 INFO - ##### 06:18:59 INFO - Running post-run listener: _resource_record_post_run 06:18:59 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37766656; Write bytes: 702851584; Read time: 389; Write time: 2052 06:18:59 INFO - TinderboxPrint: CPU usage
53.4% 06:18:59 INFO - TinderboxPrint: I/O read bytes / time
37,766,656 / 389 06:18:59 INFO - TinderboxPrint: I/O write bytes / time
702,851,584 / 2,052 06:18:59 INFO - TinderboxPrint: CPU idle
2,256.3 (46.6%) 06:18:59 INFO - TinderboxPrint: CPU system
965.6 (19.9%) 06:18:59 INFO - TinderboxPrint: CPU user
1,623.8 (33.5%) 06:18:59 INFO - TinderboxPrint: Swap in / out
443,805,696 / 0 06:18:59 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389665280; Write bytes: 383505920; Read time: 29113; Write time: 743 06:18:59 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36795904; Write bytes: 294208512; Read time: 373; Write time: 1254 06:19:00 INFO - Running post-run listener: _upload_blobber_files 06:19:00 INFO - Blob upload gear active. 06:19:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:00 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 06:19:00 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'] 06:19:00 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 06:19:00 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:19:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:19:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:01 INFO - (blobuploader) - INFO - Done attempting. 06:19:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:19:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:19:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:02 INFO - (blobuploader) - INFO - Done attempting. 06:19:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:19:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:19:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:05 INFO - (blobuploader) - INFO - Done attempting. 06:19:05 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:05 INFO - Return code: 0 06:19:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c48019c547fc2105f422a5eda28a722489ec7fe0a189954f4b8687e01313aebc0bc05d0377504fcfbd1309e725645c57fe6dd0f9a158d95e5f6f5acd50ca039d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51877e3d56e92e76725f410018b0d7ff0823544096c25b031eef268146e4536f5dd345bf0057ac41ba3fadd0d8e9be8e6de77fb8746b0ac9c0b3c43074f40196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e65baf6040a5ca64d0175ce476db2645e4c721ad6fe6d2b4df43bd9f639b742a8b18346b49bfd3b5ec526908f3481c927c62676e6ad21e8bf0835ba869d92552"} 06:19:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:05 INFO - Contents: 06:19:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c48019c547fc2105f422a5eda28a722489ec7fe0a189954f4b8687e01313aebc0bc05d0377504fcfbd1309e725645c57fe6dd0f9a158d95e5f6f5acd50ca039d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51877e3d56e92e76725f410018b0d7ff0823544096c25b031eef268146e4536f5dd345bf0057ac41ba3fadd0d8e9be8e6de77fb8746b0ac9c0b3c43074f40196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e65baf6040a5ca64d0175ce476db2645e4c721ad6fe6d2b4df43bd9f639b742a8b18346b49bfd3b5ec526908f3481c927c62676e6ad21e8bf0835ba869d92552"} 06:19:05 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:05 INFO - Copying logs to upload dir... 06:19:05 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.288584 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-11 06:19:05.085034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 06:19:05.088046) ========= 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.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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/c48019c547fc2105f422a5eda28a722489ec7fe0a189954f4b8687e01313aebc0bc05d0377504fcfbd1309e725645c57fe6dd0f9a158d95e5f6f5acd50ca039d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51877e3d56e92e76725f410018b0d7ff0823544096c25b031eef268146e4536f5dd345bf0057ac41ba3fadd0d8e9be8e6de77fb8746b0ac9c0b3c43074f40196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e65baf6040a5ca64d0175ce476db2645e4c721ad6fe6d2b4df43bd9f639b742a8b18346b49bfd3b5ec526908f3481c927c62676e6ad21e8bf0835ba869d92552"} build_url:https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010989 build_url: 'https://queue.taskcluster.net/v1/task/cRCrwmesRtaf2lUe598QEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c48019c547fc2105f422a5eda28a722489ec7fe0a189954f4b8687e01313aebc0bc05d0377504fcfbd1309e725645c57fe6dd0f9a158d95e5f6f5acd50ca039d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/51877e3d56e92e76725f410018b0d7ff0823544096c25b031eef268146e4536f5dd345bf0057ac41ba3fadd0d8e9be8e6de77fb8746b0ac9c0b3c43074f40196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e65baf6040a5ca64d0175ce476db2645e4c721ad6fe6d2b4df43bd9f639b742a8b18346b49bfd3b5ec526908f3481c927c62676e6ad21e8bf0835ba869d92552"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 06:19:05.120587) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 06:19:05.120926) ========= 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.LHxOAeyosi/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.zCwC2HYoxf/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005179 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 06:19:05.169753) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 06:19:05.170070) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 06:19:05.170398) ========= ========= Total master_lag: 0.17 =========