builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1469002361.51 results: success (0) buildid: 20160719231020 builduid: 89c61cd318834234a7069747215717b4 revision: 30b6f751cdcefc35226c771650782e987c66a649 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.515264) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.515770) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.516050) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.542860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.543172) ========= 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.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-20 01:12:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 65.8M=0s 2016-07-20 01:12:41 (65.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128763 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.694310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.694620) ========= 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.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.028512 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.743269) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:41.743611) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 30b6f751cdcefc35226c771650782e987c66a649 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 30b6f751cdcefc35226c771650782e987c66a649 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-20 01:12:41,818 truncating revision to first 12 chars 2016-07-20 01:12:41,818 Setting DEBUG logging. 2016-07-20 01:12:41,818 attempt 1/10 2016-07-20 01:12:41,819 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/30b6f751cdce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 01:12:42,184 unpacking tar archive at: fx-team-30b6f751cdce/testing/mozharness/ program finished with exit code 0 elapsedTime=0.620743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:42.385650) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:42.385953) ========= script_repo_revision: 30b6f751cdcefc35226c771650782e987c66a649 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:42.386355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:42.386616) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 01:12:42.404392) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-20 01:12:42.404708) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:12:42 INFO - MultiFileLogger online at 20160720 01:12:42 in /builds/slave/test 01:12:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:12:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:12:42 INFO - {'append_to_log': False, 01:12:42 INFO - 'base_work_dir': '/builds/slave/test', 01:12:42 INFO - 'blob_upload_branch': 'Fx-Team', 01:12:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:12:42 INFO - 'branch': 'Fx-Team', 01:12:42 INFO - 'buildbot_json_path': 'buildprops.json', 01:12:42 INFO - 'config_files': ('talos/mac_config.py',), 01:12:42 INFO - 'default_actions': ('clobber', 01:12:42 INFO - 'read-buildbot-config', 01:12:42 INFO - 'download-and-extract', 01:12:42 INFO - 'populate-webroot', 01:12:42 INFO - 'create-virtualenv', 01:12:42 INFO - 'install', 01:12:42 INFO - 'run-tests'), 01:12:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:12:42 INFO - 'download_minidump_stackwalk': True, 01:12:42 INFO - 'download_symbols': 'ondemand', 01:12:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:12:42 INFO - 'tooltool.py': '/tools/tooltool.py', 01:12:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:12:42 INFO - '/tools/misc-python/virtualenv.py')}, 01:12:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:12:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:12:42 INFO - 'installer_path': 'installer.exe', 01:12:42 INFO - 'log_level': 'info', 01:12:42 INFO - 'log_name': 'talos', 01:12:42 INFO - 'log_to_console': True, 01:12:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:12:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:12:42 INFO - 'opt_config_files': (), 01:12:42 INFO - 'pip_index': False, 01:12:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:12:42 INFO - 'cmd': ('bash', 01:12:42 INFO - '-c', 01:12:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:12:42 INFO - 'enabled': True, 01:12:42 INFO - 'halt_on_failure': False, 01:12:42 INFO - 'name': 'check_screen_resolution'},), 01:12:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:12:42 INFO - 'cmd': ('bash', 01:12:42 INFO - '-c', 01:12:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:12:42 INFO - 'enabled': True, 01:12:42 INFO - 'halt_on_failure': False, 01:12:42 INFO - 'name': 'check_screen_resolution'},), 01:12:42 INFO - 'run_cmd_checks_enabled': True, 01:12:42 INFO - 'sps_profile': False, 01:12:42 INFO - 'sps_profile_interval': 0, 01:12:42 INFO - 'suite': 'dromaeojs', 01:12:42 INFO - 'system_bits': '32', 01:12:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:12:42 INFO - 'title': 't-yosemite-r7-0110', 01:12:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:12:42 INFO - 'use_talos_json': True, 01:12:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:12:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:12:42 INFO - 'work_dir': 'build'} 01:12:42 INFO - ##### 01:12:42 INFO - ##### Running clobber step. 01:12:42 INFO - ##### 01:12:42 INFO - Running pre-action listener: _resource_record_pre_action 01:12:42 INFO - Running main action method: clobber 01:12:42 INFO - rmtree: /builds/slave/test/build 01:12:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:12:44 INFO - Running post-action listener: _resource_record_post_action 01:12:44 INFO - ##### 01:12:44 INFO - ##### Finished clobber step (success) 01:12:44 INFO - ##### 01:12:44 INFO - ##### 01:12:44 INFO - ##### Running read-buildbot-config step. 01:12:44 INFO - ##### 01:12:44 INFO - Running pre-action listener: _resource_record_pre_action 01:12:44 INFO - Running main action method: read_buildbot_config 01:12:44 INFO - Using buildbot properties: 01:12:44 INFO - { 01:12:44 INFO - "project": "", 01:12:44 INFO - "product": "firefox", 01:12:44 INFO - "script_repo_revision": "production", 01:12:44 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:12:44 INFO - "repository": "", 01:12:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:12:44 INFO - "buildid": "20160719231020", 01:12:44 INFO - "pgo_build": "False", 01:12:44 INFO - "basedir": "/builds/slave/test", 01:12:44 INFO - "buildnumber": 98, 01:12:44 INFO - "slavename": "t-yosemite-r7-0110", 01:12:44 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:12:44 INFO - "platform": "yosemite_r7", 01:12:44 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 01:12:44 INFO - "branch": "fx-team", 01:12:44 INFO - "repo_path": "integration/fx-team", 01:12:44 INFO - "revision": "30b6f751cdcefc35226c771650782e987c66a649", 01:12:44 INFO - "stage_platform": "macosx64", 01:12:44 INFO - "builduid": "89c61cd318834234a7069747215717b4", 01:12:44 INFO - "slavebuilddir": "test" 01:12:44 INFO - } 01:12:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 01:12:44 INFO - Running post-action listener: _resource_record_post_action 01:12:44 INFO - ##### 01:12:44 INFO - ##### Finished read-buildbot-config step (success) 01:12:44 INFO - ##### 01:12:44 INFO - ##### 01:12:44 INFO - ##### Running download-and-extract step. 01:12:44 INFO - ##### 01:12:44 INFO - Running pre-action listener: _resource_record_pre_action 01:12:44 INFO - Running main action method: download_and_extract 01:12:44 INFO - mkdir: /builds/slave/test/build/tests 01:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:12:44 INFO - https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:12:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:12:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:12:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:46 INFO - Downloaded 1199 bytes. 01:12:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:12:46 INFO - Using the following test package requirements: 01:12:46 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:12:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 01:12:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 01:12:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'jsshell-mac.zip'], 01:12:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 01:12:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:12:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 01:12:46 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 01:12:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 01:12:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:12:46 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 01:12:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:12:46 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')]} 01:12:46 INFO - https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:47 INFO - Downloaded 18835329 bytes. 01:12:47 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'] 01:12:47 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 01:12:50 INFO - Return code: 0 01:12:50 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 01:12:50 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')]} 01:12:50 INFO - https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:12:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:12:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:12:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:51 INFO - Downloaded 18835329 bytes. 01:12:51 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'] 01:12:51 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 01:12:54 INFO - Return code: 0 01:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:12:54 INFO - https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:12:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:12:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:12:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:56 INFO - Downloaded 14403317 bytes. 01:12:56 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'] 01:12:56 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 01:12:56 INFO - Return code: 0 01:12:56 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')]} 01:12:56 INFO - https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:12:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:12:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:12:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 01:12:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI8ebFVhTKiOJs08VuYRQw/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 01:12:59 INFO - Downloaded 112863738 bytes. 01:12:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:12:59 INFO - mkdir: /builds/slave/test/properties 01:12:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:12:59 INFO - Writing to file /builds/slave/test/properties/build_url 01:12:59 INFO - Contents: 01:12:59 INFO - build_url:https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:13:00 INFO - Running post-action listener: _resource_record_post_action 01:13:00 INFO - Running post-action listener: set_extra_try_arguments 01:13:00 INFO - ##### 01:13:00 INFO - ##### Finished download-and-extract step (success) 01:13:00 INFO - ##### 01:13:00 INFO - ##### 01:13:00 INFO - ##### Running populate-webroot step. 01:13:00 INFO - ##### 01:13:00 INFO - Running pre-action listener: _resource_record_pre_action 01:13:00 INFO - Running main action method: populate_webroot 01:13:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:13:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:13:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:13:00 INFO - '--tppagecycles', 01:13:00 INFO - '7'], 01:13:00 INFO - 'tests': ['tsvgm']}}, 01:13:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:13:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:13:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:13:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:13:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:13:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:13:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['damp', 'tps']}, 01:13:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'tests': ['damp', 'tps']}, 01:13:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['dromaeo_dom']}, 01:13:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:13:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['basic_compositor_video']}, 01:13:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:13:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'other-e10s': {'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['a11yr', 01:13:00 INFO - 'ts_paint', 01:13:00 INFO - 'tpaint', 01:13:00 INFO - 'sessionrestore', 01:13:00 INFO - 'sessionrestore_no_auto_restore', 01:13:00 INFO - 'tabpaint']}, 01:13:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['tsvgx', 01:13:00 INFO - 'tsvgr_opacity', 01:13:00 INFO - 'tart', 01:13:00 INFO - 'tscrollx', 01:13:00 INFO - 'cart']}, 01:13:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:13:00 INFO - 'tsvgr_opacity', 01:13:00 INFO - 'tart', 01:13:00 INFO - 'tscrollx', 01:13:00 INFO - 'cart']}, 01:13:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'talos_options': ['--disable-e10s'], 01:13:00 INFO - 'tests': ['tp5o']}, 01:13:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'tests': ['tp5o']}, 01:13:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'talos_options': ['--disable-e10s', 01:13:00 INFO - '--xperf_path', 01:13:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:13:00 INFO - 'tests': ['tp5n']}, 01:13:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:13:00 INFO - 'talos_options': ['--xperf_path', 01:13:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:13:00 INFO - 'tests': ['tp5n']}}, 01:13:00 INFO - 'talos.zip': {'path': '', 01:13:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:13:00 INFO - Running post-action listener: _resource_record_post_action 01:13:00 INFO - ##### 01:13:00 INFO - ##### Finished populate-webroot step (success) 01:13:00 INFO - ##### 01:13:00 INFO - ##### 01:13:00 INFO - ##### Running create-virtualenv step. 01:13:00 INFO - ##### 01:13:00 INFO - Running pre-action listener: _resource_record_pre_action 01:13:00 INFO - Running main action method: create_virtualenv 01:13:00 INFO - Creating virtualenv /builds/slave/test/build/venv 01:13:00 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 01:13:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:13:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:13:00 INFO - Using real prefix '/tools/python27' 01:13:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:13:01 INFO - Installing distribute.............................................................................................................................................................................................done. 01:13:04 INFO - Installing pip.................done. 01:13:04 INFO - Return code: 0 01:13:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:13:04 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')]} 01:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:04 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')]} 01:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:04 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:04 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 01:13:04 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 01:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:04 INFO - 'HOME': '/Users/cltbld', 01:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:04 INFO - 'LOGNAME': 'cltbld', 01:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:04 INFO - 'MOZ_NO_REMOTE': '1', 01:13:04 INFO - 'NO_EM_RESTART': '1', 01:13:04 INFO - 'PAGER': '/bin/cat', 01:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:04 INFO - 'PWD': '/builds/slave/test', 01:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:04 INFO - 'SHELL': '/bin/bash', 01:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:04 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:04 INFO - 'USER': 'cltbld', 01:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:04 INFO - 'XPC_FLAGS': '0x0', 01:13:04 INFO - 'XPC_SERVICE_NAME': '0', 01:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:04 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 01:13:04 INFO - Cleaning up... 01:13:04 INFO - Return code: 0 01:13:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:13:04 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')]} 01:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:04 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')]} 01:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:04 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:04 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 01:13:04 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 01:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:04 INFO - 'HOME': '/Users/cltbld', 01:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:04 INFO - 'LOGNAME': 'cltbld', 01:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:04 INFO - 'MOZ_NO_REMOTE': '1', 01:13:04 INFO - 'NO_EM_RESTART': '1', 01:13:04 INFO - 'PAGER': '/bin/cat', 01:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:04 INFO - 'PWD': '/builds/slave/test', 01:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:04 INFO - 'SHELL': '/bin/bash', 01:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:04 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:04 INFO - 'USER': 'cltbld', 01:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:04 INFO - 'XPC_FLAGS': '0x0', 01:13:04 INFO - 'XPC_SERVICE_NAME': '0', 01:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:04 INFO - Downloading/unpacking psutil>=3.1.1 01:13:04 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 01:13:04 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 01:13:04 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 01:13:04 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 01:13:04 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 01:13:04 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 01:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:13:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:13:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:13:07 INFO - Installing collected packages: psutil 01:13:07 INFO - Running setup.py install for psutil 01:13:07 INFO - building 'psutil._psutil_osx' extension 01:13:07 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 01:13:08 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 01:13:08 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 01:13:08 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 01:13:08 INFO - building 'psutil._psutil_posix' extension 01:13:08 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 01:13:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:13:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:13:08 INFO - ^ 01:13:08 INFO - 1 warning generated. 01:13:08 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 01:13:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:13:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:13:08 INFO - Successfully installed psutil 01:13:08 INFO - Cleaning up... 01:13:09 INFO - Return code: 0 01:13:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:13:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:09 INFO - 'HOME': '/Users/cltbld', 01:13:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:09 INFO - 'LOGNAME': 'cltbld', 01:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:09 INFO - 'MOZ_NO_REMOTE': '1', 01:13:09 INFO - 'NO_EM_RESTART': '1', 01:13:09 INFO - 'PAGER': '/bin/cat', 01:13:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:09 INFO - 'PWD': '/builds/slave/test', 01:13:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:09 INFO - 'SHELL': '/bin/bash', 01:13:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:09 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:09 INFO - 'USER': 'cltbld', 01:13:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:09 INFO - 'XPC_FLAGS': '0x0', 01:13:09 INFO - 'XPC_SERVICE_NAME': '0', 01:13:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:13:11 INFO - Installing collected packages: mozsystemmonitor 01:13:11 INFO - Running setup.py install for mozsystemmonitor 01:13:11 INFO - Successfully installed mozsystemmonitor 01:13:11 INFO - Cleaning up... 01:13:11 INFO - Return code: 0 01:13:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:11 INFO - 'HOME': '/Users/cltbld', 01:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:11 INFO - 'LOGNAME': 'cltbld', 01:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:11 INFO - 'MOZ_NO_REMOTE': '1', 01:13:11 INFO - 'NO_EM_RESTART': '1', 01:13:11 INFO - 'PAGER': '/bin/cat', 01:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:11 INFO - 'PWD': '/builds/slave/test', 01:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:11 INFO - 'SHELL': '/bin/bash', 01:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:11 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:11 INFO - 'USER': 'cltbld', 01:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:11 INFO - 'XPC_FLAGS': '0x0', 01:13:11 INFO - 'XPC_SERVICE_NAME': '0', 01:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:12 INFO - Downloading/unpacking jsonschema==2.5.1 01:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:13:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:13:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:13:14 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 01:13:14 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 01:13:14 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 01:13:14 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 01:13:14 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 01:13:14 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 01:13:15 INFO - Downloading functools32-3.2.3-2.tar.gz 01:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:13:15 INFO - warning: no files found matching '*.txt' 01:13:15 INFO - no previously-included directories found matching 'build' 01:13:15 INFO - no previously-included directories found matching 'dist' 01:13:15 INFO - no previously-included directories found matching '.git*' 01:13:15 INFO - Installing collected packages: jsonschema, functools32 01:13:15 INFO - Running setup.py install for functools32 01:13:15 INFO - warning: no files found matching '*.txt' 01:13:15 INFO - no previously-included directories found matching 'build' 01:13:15 INFO - no previously-included directories found matching 'dist' 01:13:15 INFO - no previously-included directories found matching '.git*' 01:13:15 INFO - Successfully installed jsonschema functools32 01:13:15 INFO - Cleaning up... 01:13:15 INFO - Return code: 0 01:13:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:13:15 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')]} 01:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:15 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')]} 01:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:15 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:15 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 01:13:15 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 01:13:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:15 INFO - 'HOME': '/Users/cltbld', 01:13:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:15 INFO - 'LOGNAME': 'cltbld', 01:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:15 INFO - 'MOZ_NO_REMOTE': '1', 01:13:15 INFO - 'NO_EM_RESTART': '1', 01:13:15 INFO - 'PAGER': '/bin/cat', 01:13:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:15 INFO - 'PWD': '/builds/slave/test', 01:13:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:15 INFO - 'SHELL': '/bin/bash', 01:13:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:15 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:15 INFO - 'USER': 'cltbld', 01:13:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:15 INFO - 'XPC_FLAGS': '0x0', 01:13:15 INFO - 'XPC_SERVICE_NAME': '0', 01:13:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:15 INFO - Downloading/unpacking blobuploader==1.2.4 01:13:15 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 01:13:15 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 01:13:15 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 01:13:15 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 01:13:15 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 01:13:15 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 01:13:18 INFO - Downloading blobuploader-1.2.4.tar.gz 01:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:13:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:13:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:18 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 01:13:19 INFO - Downloading docopt-0.6.1.tar.gz 01:13:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:13:19 INFO - Installing collected packages: blobuploader, requests, docopt 01:13:19 INFO - Running setup.py install for blobuploader 01:13:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:13:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:13:19 INFO - Running setup.py install for requests 01:13:20 INFO - Running setup.py install for docopt 01:13:20 INFO - Successfully installed blobuploader requests docopt 01:13:20 INFO - Cleaning up... 01:13:20 INFO - Return code: 0 01:13:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:20 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:20 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 01:13:20 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 01:13:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:20 INFO - 'HOME': '/Users/cltbld', 01:13:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:20 INFO - 'LOGNAME': 'cltbld', 01:13:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:20 INFO - 'MOZ_NO_REMOTE': '1', 01:13:20 INFO - 'NO_EM_RESTART': '1', 01:13:20 INFO - 'PAGER': '/bin/cat', 01:13:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:20 INFO - 'PWD': '/builds/slave/test', 01:13:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:20 INFO - 'SHELL': '/bin/bash', 01:13:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:20 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:20 INFO - 'USER': 'cltbld', 01:13:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:20 INFO - 'XPC_FLAGS': '0x0', 01:13:20 INFO - 'XPC_SERVICE_NAME': '0', 01:13:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:13:20 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-W0kMLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:13:20 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TWUqIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:13:20 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9XqNZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:13:20 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-r9IzrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:13:20 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-9Y0By5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xsbtmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-b_uaK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-8i9kIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GNSf6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ANFb4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PMYvC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-S31TeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-c0LMb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:13:21 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JXH9Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:22 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WRC2SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:13:22 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pCgIQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:13:22 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rtNixT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:13:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:13:22 INFO - Running setup.py install for manifestparser 01:13:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:13:22 INFO - Running setup.py install for mozcrash 01:13:22 INFO - Running setup.py install for mozdebug 01:13:22 INFO - Running setup.py install for mozdevice 01:13:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:13:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:13:22 INFO - Running setup.py install for mozfile 01:13:23 INFO - Running setup.py install for mozhttpd 01:13:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Running setup.py install for mozinfo 01:13:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Running setup.py install for mozInstall 01:13:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Running setup.py install for mozleak 01:13:23 INFO - Running setup.py install for mozlog 01:13:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Running setup.py install for moznetwork 01:13:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:13:23 INFO - Running setup.py install for mozprocess 01:13:24 INFO - Running setup.py install for mozprofile 01:13:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:13:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:13:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:13:24 INFO - Running setup.py install for mozrunner 01:13:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:13:24 INFO - Running setup.py install for mozscreenshot 01:13:24 INFO - Running setup.py install for moztest 01:13:24 INFO - Running setup.py install for mozversion 01:13:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:13:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:13:24 INFO - Cleaning up... 01:13:24 INFO - Return code: 0 01:13:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:24 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:24 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 01:13:24 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 01:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:24 INFO - 'HOME': '/Users/cltbld', 01:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:24 INFO - 'LOGNAME': 'cltbld', 01:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:24 INFO - 'MOZ_NO_REMOTE': '1', 01:13:24 INFO - 'NO_EM_RESTART': '1', 01:13:24 INFO - 'PAGER': '/bin/cat', 01:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:24 INFO - 'PWD': '/builds/slave/test', 01:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:24 INFO - 'SHELL': '/bin/bash', 01:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:24 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:24 INFO - 'USER': 'cltbld', 01:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:24 INFO - 'XPC_FLAGS': '0x0', 01:13:24 INFO - 'XPC_SERVICE_NAME': '0', 01:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CSjgPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RN4Du6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LeUjeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-c0dt9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Rx3ra2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xtQIFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_qc7r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:13:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-bc4JJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:13:25 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)) 01:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pQB6hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-SVohNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BMVkNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-gGWB6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LTrJtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-eeCd3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-uk3YWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ZIswyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:13:26 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)) 01:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:13:26 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-HRHX5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:13:26 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)) 01:13:26 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)) 01:13:26 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)) 01:13:26 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)) 01:13:26 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)) 01:13:26 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)) 01:13:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:13: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 01:13: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 01:13: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 01:13: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 01:13: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 01:13: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 01:13:29 INFO - Downloading blessings-1.6.tar.gz 01:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:13:29 INFO - Installing collected packages: blessings 01:13:29 INFO - Running setup.py install for blessings 01:13:29 INFO - Successfully installed blessings 01:13:29 INFO - Cleaning up... 01:13:29 INFO - Return code: 0 01:13:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:13:29 INFO - Reading from file tmpfile_stdout 01:13:29 INFO - Current package versions: 01:13:29 INFO - blessings == 1.6 01:13:29 INFO - blobuploader == 1.2.4 01:13:29 INFO - docopt == 0.6.1 01:13:29 INFO - functools32 == 3.2.3-2 01:13:29 INFO - jsonschema == 2.5.1 01:13:29 INFO - manifestparser == 1.1 01:13:29 INFO - mozInstall == 1.12 01:13:29 INFO - mozcrash == 0.17 01:13:29 INFO - mozdebug == 0.1 01:13:29 INFO - mozdevice == 0.48 01:13:29 INFO - mozfile == 1.2 01:13:29 INFO - mozhttpd == 0.7 01:13:29 INFO - mozinfo == 0.9 01:13:29 INFO - mozleak == 0.1 01:13:29 INFO - mozlog == 3.2 01:13:29 INFO - moznetwork == 0.27 01:13:29 INFO - mozprocess == 0.23 01:13:29 INFO - mozprofile == 0.28 01:13:29 INFO - mozrunner == 6.12 01:13:29 INFO - mozscreenshot == 0.1 01:13:29 INFO - mozsystemmonitor == 0.3 01:13:29 INFO - moztest == 0.7 01:13:29 INFO - mozversion == 1.4 01:13:29 INFO - psutil == 3.1.1 01:13:29 INFO - requests == 1.2.3 01:13:29 INFO - wsgiref == 0.1.2 01:13:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:13: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')]} 01:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13: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')]} 01:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:29 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:29 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 01:13:29 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 01:13:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:29 INFO - 'HOME': '/Users/cltbld', 01:13:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:29 INFO - 'LOGNAME': 'cltbld', 01:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:29 INFO - 'MOZ_NO_REMOTE': '1', 01:13:29 INFO - 'NO_EM_RESTART': '1', 01:13:29 INFO - 'PAGER': '/bin/cat', 01:13:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:29 INFO - 'PWD': '/builds/slave/test', 01:13:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:29 INFO - 'SHELL': '/bin/bash', 01:13:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:29 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:29 INFO - 'USER': 'cltbld', 01:13:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:29 INFO - 'XPC_FLAGS': '0x0', 01:13:29 INFO - 'XPC_SERVICE_NAME': '0', 01:13:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 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)) 01:13:30 INFO - Cleaning up... 01:13:30 INFO - Return code: 0 01:13:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:13:30 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 0x10a3f9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb158cc6b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, '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.BKDxEECbow/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.2tidiFRocf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 01:13:30 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 01:13:30 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 01:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:13:30 INFO - 'HOME': '/Users/cltbld', 01:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:13:30 INFO - 'LOGNAME': 'cltbld', 01:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:13:30 INFO - 'MOZ_NO_REMOTE': '1', 01:13:30 INFO - 'NO_EM_RESTART': '1', 01:13:30 INFO - 'PAGER': '/bin/cat', 01:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:13:30 INFO - 'PWD': '/builds/slave/test', 01:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:13:30 INFO - 'SHELL': '/bin/bash', 01:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:13:30 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:13:30 INFO - 'USER': 'cltbld', 01:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:13:30 INFO - 'XPC_FLAGS': '0x0', 01:13:30 INFO - 'XPC_SERVICE_NAME': '0', 01:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:13:30 INFO - Cleaning up... 01:13:30 INFO - Return code: 0 01:13:30 INFO - Running post-action listener: _resource_record_post_action 01:13:30 INFO - Running post-action listener: _start_resource_monitoring 01:13:30 INFO - Starting resource monitoring. 01:13:30 INFO - ##### 01:13:30 INFO - ##### Finished create-virtualenv step (success) 01:13:30 INFO - ##### 01:13:30 INFO - ##### 01:13:30 INFO - ##### Running install step. 01:13:30 INFO - ##### 01:13:30 INFO - Running pre-action listener: _resource_record_pre_action 01:13:30 INFO - Running main action method: install 01:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:13:30 INFO - Reading from file tmpfile_stdout 01:13:30 INFO - Detecting whether we're running mozinstall >=1.0... 01:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:13:30 INFO - Reading from file tmpfile_stdout 01:13:30 INFO - Output received: 01:13:30 INFO - Usage: mozinstall [options] installer 01:13:30 INFO - Options: 01:13:30 INFO - -h, --help show this help message and exit 01:13:30 INFO - -d DEST, --destination=DEST 01:13:30 INFO - Directory to install application into. [default: 01:13:30 INFO - "/builds/slave/test"] 01:13:30 INFO - --app=APP Application being installed. [default: firefox] 01:13:30 INFO - mkdir: /builds/slave/test/build/application 01:13:30 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'] 01:13:30 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 01:14:04 INFO - Reading from file tmpfile_stdout 01:14:04 INFO - Output received: 01:14:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:14:04 INFO - Running post-action listener: _resource_record_post_action 01:14:04 INFO - ##### 01:14:04 INFO - ##### Finished install step (success) 01:14:04 INFO - ##### 01:14:04 INFO - ##### 01:14:04 INFO - ##### Running run-tests step. 01:14:04 INFO - ##### 01:14:04 INFO - Running pre-action listener: _resource_record_pre_action 01:14:04 INFO - Running main action method: run_tests 01:14:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:14:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:14:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:14:04 INFO - 2016-07-20 01:14:04.821 screenresolution[1641:7464] starting screenresolution argv=screenresolution get 01:14:04 INFO - 2016-07-20 01:14:04.824 screenresolution[1641:7464] Display 0: 1600x1200x32@60 01:14:04 INFO - 2016-07-20 01:14:04.830 screenresolution[1642:7466] starting screenresolution argv=screenresolution list 01:14:04 INFO - Available Modes on Display 0 01:14:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:14:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:14:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:14:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:14:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:14:05 INFO - Intel Iris: 01:14:05 INFO - Chipset Model: Intel Iris 01:14:05 INFO - Type: GPU 01:14:05 INFO - Bus: Built-In 01:14:05 INFO - VRAM (Dynamic, Max): 1536 MB 01:14:05 INFO - Vendor: Intel (0x8086) 01:14:05 INFO - Device ID: 0x0a2e 01:14:05 INFO - Revision ID: 0x0009 01:14:05 INFO - Displays: 01:14:05 INFO - Display: 01:14:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:14:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:14:05 INFO - Display Serial Number: 1600x1200 60 01:14:05 INFO - Main Display: Yes 01:14:05 INFO - Mirror: Off 01:14:05 INFO - Online: Yes 01:14:05 INFO - Rotation: Supported 01:14:05 INFO - Return code: 0 01:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:14:05 INFO - Python 2.7.3 01:14:05 INFO - Return code: 0 01:14:05 INFO - grabbing minidump binary from tooltool 01:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 01:14:05 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 0x10a35dd30>, '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 0x10a0c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a355b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:14:05 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 01:14:05 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 01:14:05 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 01:14:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:14:05 INFO - Return code: 0 01:14:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:14:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:14:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:14:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:14:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 01:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 01:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BKDxEECbow/Render', 01:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:14:05 INFO - 'HOME': '/Users/cltbld', 01:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:14:05 INFO - 'LOGNAME': 'cltbld', 01:14:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:14:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:14:05 INFO - 'MOZ_NO_REMOTE': '1', 01:14:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:14:05 INFO - 'NO_EM_RESTART': '1', 01:14:05 INFO - 'PAGER': '/bin/cat', 01:14:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:05 INFO - 'PWD': '/builds/slave/test', 01:14:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:14:05 INFO - 'SHELL': '/bin/bash', 01:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2tidiFRocf/Listeners', 01:14:05 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 01:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:14:05 INFO - 'USER': 'cltbld', 01:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:14:05 INFO - 'XPC_FLAGS': '0x0', 01:14:05 INFO - 'XPC_SERVICE_NAME': '0', 01:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:14:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 01:14:05 INFO - mozversion application_buildid: 20160719231020 01:14:05 INFO - mozversion application_changeset: 30b6f751cdcefc35226c771650782e987c66a649 01:14:05 INFO - mozversion application_display_name: Nightly 01:14:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:14:05 INFO - mozversion application_name: Firefox 01:14:05 INFO - mozversion application_remotingname: firefox 01:14:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:14:05 INFO - mozversion application_vendor: Mozilla 01:14:05 INFO - mozversion application_version: 50.0a1 01:14:05 INFO - mozversion platform_buildid: 20160719231020 01:14:05 INFO - mozversion platform_changeset: 30b6f751cdcefc35226c771650782e987c66a649 01:14:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:14:05 INFO - mozversion platform_version: 50.0a1 01:14:05 INFO - using testdate: 1469002445 01:14:05 INFO - actual date: 1469002445 01:14:05 INFO - starting webserver on 'localhost:49270' 01:14:05 INFO - SUITE-START | Running 2 tests 01:14:05 INFO - TEST-START | dromaeo_css 01:14:05 INFO - operating with platform_type : mac_ 01:14:05 INFO - Initialising browser for dromaeo_css test... 01:14:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp4ssikG/profile http://localhost:49270/getInfo.html) 01:14:07 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 01:14:07 INFO - PROCESS | 1652 | colorDepth:24 01:14:07 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 01:14:07 INFO - PROCESS | 1652 | __metrics 01:14:07 INFO - PROCESS | 1652 | 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" 01:14:07 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:14:08 INFO - TEST-INFO | 1652: exit 0 01:14:08 INFO - Browser initialized. 01:14:08 INFO - Running cycle 1/1 for dromaeo_css test... 01:14:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp4ssikG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:14:20 INFO - PROCESS | 1657 | RSS: Main: 244998144 01:14:20 INFO - PROCESS | 1657 | 01:15:11 INFO - PROCESS | 1657 | 1469002511668 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] 01:17:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 01:17:25 INFO - PROCESS | 1657 | RSS: Main: 372195328 01:17:25 INFO - PROCESS | 1657 | 01:20:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 01:20:28 INFO - PROCESS | 1657 | RSS: Main: 372908032 01:20:28 INFO - PROCESS | 1657 | 01:23:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 01:23:31 INFO - PROCESS | 1657 | RSS: Main: 376131584 01:23:31 INFO - PROCESS | 1657 | 01:26:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 01:26:34 INFO - PROCESS | 1657 | RSS: Main: 374755328 01:26:34 INFO - PROCESS | 1657 | 01:29:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 01:29:38 INFO - PROCESS | 1657 | RSS: Main: 350326784 01:29:38 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 01:32:41 INFO - PROCESS | 1657 | RSS: Main: 388120576 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | __start_tp_report 01:32:41 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 01:32:41 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 01:32:41 INFO - PROCESS | 1657 | |i|pagename|runs| 01:32:41 INFO - PROCESS | 1657 | |0;dojo.html;3353.195233144043;3361.8319084045793;3386.3227354529095;3393.338299031688;3397.3714862750394;5758.884822303551;5854.473097421232;5880.26496687914;5937.02039163538;5957.9999999999945;5856.180134781123;5910.586258961875;5914.645121292726;5918;5920.792772252972;5288.814891478799;5321.680699158043;5356.143017117262;5396.757145928443;5417.864553386157;4548.954510454886;4732.881677958044;5176.145935920568;5370.838874833761;5384.703841288728;4354.5863143001525;5293.68237905725;5295.947040529603;5302.3637163540425;5400.405955344909;5384.326959130129;5410.188471729272;5415.539679127279;5418.566514678816;5477.424870388591;4824.372831531913;5153.912665370493;5263.763130659129;5334.626576139672;5336;4884.267359896008;5084.186530155194;5781.103928891029;5803.419658034205;5874.647521148745;5346.759395827197;6244.875102497925;6329.3354197809485;6329.905051424233;6340.017297318923;5101.387833459971;6223.564350495467;6312.242530896276;6329.461995730322;6354.459870910978;5910.113482977587;5980.940190598082;5997.070454079625;6015.127806468037;6026.457618814327;5402.540784033323;6418.903716444257;6425.357464253537;6426.903596446057;6432.324605916405;4606.424196975378;6090.126703322896;6185.628862268279;6282.465990390777;6299.244090709052;6227;6337.207849018872;6352.301246862841;6383.234011918599;6409.230892292955;6256.249250090018;6315.084312774622;6319.778807741707;6326.46225070865;6334.461570766444;6205.782797602153;6222.471089957314;6272;6281.057841323838;6322.0200868865395;4990.750462476934;4995.500449954975;5018.548330650259;5020.7740651670765;5082.313887625197;4475.5748203920575;4476.619487343547;4491.079328737601;4503.009337945647;4541.296099104644;5804.448577385142;5854.092615644582;5881.558883083786;5915.031373646158;5989.341172471025;5994.820155395345;6070.2108725865355;6106.816795496142;6114.480269177081;6136.631802091889;5564.10974244119;5565.777368905198;5582.748776305076;5585.609007369527;5602.075657516464;5170.8965820684125;5199.922001169985;5241.6068794839775;5405.729713514309;5447.400785913546;6280.780172693934;6359;6372.458341041063;6391.840203994937;6454.322296159024;6280.277768056732;6285.497160227263;6293.748250069946;6293.748250070129;6297.55917085818;6404.839879003062;6420.357964203542;6453.322401148;6526.706298216686;6591.110200122922;6102.847428814315;6166.075088736546;6218.036204388327;6274.6548939808745;6283.654399008098;6083.117947897618;6247.781327653511;6273.435390814849;6295.276043255084;6314.147590075465;6224.906626400517;6271.529635277281;6281.52888533371;6283.151774510383;6291.937080629135;5167.560757335668;6093.482054025458;6142.109394137736;6145.709401025656;6224.284207316216;5993.820185394445;6075.210222671025;6138.600990935575;6141.9385806143155;6144.17053697763;6082.635041897501;6107.219158344477;6250.062490626442;6254.593451425642;6268.310485846644;6068.787592434243;6078.027515597483;6120.051392034242;6132.601380910227;6146.139540464249 01:32:41 INFO - PROCESS | 1657 | |1;ext.html;505.2497041892789;522.3548917087397;536.6645846346033;558.0847410247193;567.8864227154569;30464.84767576162;31928.36143277133;31982.68017319826;32025.35949281013;32068.83965580175;33130;43534.78232608832;43697.34453983193;44860.87847803807;47598.524014759845;9729.270304727155;9785.119339259465;9825.06661867125;9826.55780489876;9827.115559599635;8819.206271435569;9751.12239898409;9853.802923941517;9858.211343092571;9874.9506252469;8700.869486957668;11817;11959.342236177006;11970.94014529926;12171.33057681827;12066.215695979778;12135.029197664255;12143.453544590515;12148;12151.574694885681;8706.564671766386;8889.511076890769;11879.762404751942;11976.580819671357;11977.520899164023;33194.6680533194;33548.66451335504;41638.542651007374;41995.7900210497;42031;3603.981980090109;3622.5834029086727;3679.6872265857432;3684.336819372512;4252;27348.31629209266;31816.204594885083;33646;33924.321513569594;34100.31799364024;3269.346130773855;3356.841889548102;3362.882299119519;3444.5522082129414;3466.5493485846678;550845;552234;554281;555872.2206388983;556344;64139.679301603195;64250.67874660597;64511;64602.676986615705;64821;67276.32723672799;67371;67386.32613673898;67405.3259467399;67477;16702;16921.323147074225;16947.661046778994;16969.321227151024;16979;13003.544875929298;13065.738685226243;13072;13074.477020919057;13135.211887286794;15327.923360383127;15591.532254032398;16540.007599544067;17448.47654570365;17903.910480447514;7288.8177795554475;7406.185319614838;7429.888551671621;7482.289182527651;7487.625618719042;949.7720547068792;1026.5996261458038;1073.3398959639765;1132.6262333429947;1168.7720894425674;29911.551326730118;31357.05928822139;31964.84017579897;31973.840130799195;32607.83696081552;935.8596210568332;1015.456730649114;1040.9687709368732;1074.301703892476;1102.691246450995;15015.873809464116;15610.843891560939;16233.2695028724;16238.10690412015;16298.674026519404;683531;726529;726843;726942.9222831134;730511;61114;62855.37144628495;63009;63059;63080.73838523204;1395.6231817409243;1404.052264721309;1426.5934208750457;1429.2996431748368;1439.3091316168297;30587.929422470108;30772.538411924288;30947.381052379278;31664;31777.364452710353;46338;53837.73081134569;53934.46065539294;54153.45846541484;54275.45724542704;518998.24007039296;521255;521311;521683;521742;43039.56960430481;43233.78383108064;43985.34021989737;44146.337804933595;44183;37093.81453092717;40049.19901601893;50608.746956264986;51115.97768044693;52108;38401;40443.3933490992;41794.164116716885;41813.37279940742;42980.35529466998;41973.580264198186;41999.370009449274;42952.78523607362;43109;43221.3516797242;39311.60688393079;39559.81320560388;39948.8002559997;40759.38860917148;40947.590524094376;34540.481892771124;35957.64042359647;36774.26451471009;36825;36859.815700921325;26035.869820650776;26494.86752566225;26550;26566.476184762418;26910 01:32:41 INFO - PROCESS | 1657 | |2;jquery.html;384.3715525116434;387.1154412168194;389;389.8577905105069;409.6211004820542;14021.369038393272;16270.349186032545;20406.89796551019;21369.0383932723;21374.145034198653;16882.577935551617;17357.82642173578;18385.632287354278;20988.58022839542;21194.89402552985;5713.542916566675;5952.047672372421;5980;5985.191999080112;5986.132010858423;5680.943190568083;5856.033754430514;5877.529797616202;5882.735276912549;5991.101334799771;5738.684372359518;5819.098039803837;5821.155932389801;5823.650580965134;5826.417358264182;5574.219609254707;5786.421357864222;5788.623739456964;5794.942050579482;5806.129080637896;5043.924341134886;5074.31496747938;5074.568661663763;5075.771590278409;5081.66969147008;25545.74454255471;25881.223563292944;26736.465270694476;27233.183004509698;27307.45385092287;2186.9987921509505;2239.4401399650087;2677.9598306025423;2685.9462810743676;3000.5499175123687;10141.797164056752;10209.336393134423;10399.584016639325;10525.894741052569;10645.84031239532;1824.644194382096;1892.6877065284211;1893.185930050078;1938.2440848069264;1956.992149043244;125420.74579254273;125933;126451.72256913075;133300.33597312192;133328.33335833257;11421.257618254787;11635.720070792206;11654.417279136009;11660.300381976938;11831.585894493823;14722.926385368004;14757;14771.261436928111;14780.334884930204;14782.48261310849;7717.344025757873;8096.10942796292;8243.587820609066;8398.832023359499;8853;7513.47405681608;8674.522901240493;8733.912660873437;8750.34372422071;8907.688230911886;4549.203889319356;4553.612942899825;4554.339620754972;4554.726716396961;4616.838410655611;1898.2596787252983;1940.8447324214028;1943.3295513047976;1943.4752616793387;1944.844412447029;873.8033942363045;942.3827393057559;943.9811203775886;944.9338546301831;947.0718695678274;20512.79487205139;21112.47218819511;21967.94740701189;22009.33971980843;22100.88949555242;805.7341077444429;950.4819873169139;985.9507024648882;987.2694206287296;998.3510718033336;10995.835062474069;11533.884661153448;11581.305121692725;11595.594154204562;11600.129990250596;612928;620667;633225.5080868953;633595;634465;78525.60737196392;127960;129035;130028;136297;999.8550210219478;1001.0240015984357;1006.7231511334442;1007.7430255285002;1023.1354505442889;19107.23571057142;19397;19424.320148794723;19515;19570.064490326564;59051.409485904594;60079;60125.565193089205;60439;60669.69665151823;81659.18340816516;85617.57191214005;85661;85701.57149214215;88544.55727721578;8001.319887809368;8139.511629302261;8329.50022998622;8392.622331995197;8404.411691181558;12973.805392918925;13255.801162982756;13276.601701948957;13367.064305498521;13460;9095.681651142178;11370.31778093317;11472.31166130035;11647.41762911868;11681.007114395032;10893.230949086063;11442.027427668409;11520.135989800967;11624.88375116238;11681.532738690697;11609;11698.76602467963;11781.352025638329;11830.822537662114;11990.040796736066;8289.461185022954;8356.456830306011;8369.95815020921;8462.449940753831;8514.574271286287;8333.33333333344;10443.68668939933;11485.82771258415;11565.826512602149;11566.595169169039 01:32:41 INFO - PROCESS | 1657 | |3;mootools.html;1389.6664800447895;1543.0895771494816;1559.150263106607;1596.433266190502;1925.335759163089;7888.447808653389;8539.573021348931;8731.912680873189;8755.036945935943;8765.254953328975;8546.145385461467;8564.700235491759;8725.43284686495;8729.694460693878;8731.170538798804;2032.2379107834563;2081.3443765213983;2147.559750251203;2304.8847557622134;2319.7912187903084;2325.186184835311;2379.976200237993;2383.165891937825;2386.474975505395;2387.140629373424;1973.9309124180656;2301.3671240408853;2327.522857814144;2331.160782118436;2332.88335583221;2065.173930427826;2141.1328411993168;2177.640689286266;2189.419803752007;2191.616467118256;2093.3510611710267;2365.0185173153122;2368.940776480602;2369.2418426103654;2374.3351861478814;8623.31013518917;8680.696175633822;8709.129087091304;8709.390342676015;8727.04002559718;5142.640015198937;5272.815451459181;5273.86815329616;5288.894222115575;5304.310439642861;3441.1913200397885;3517.085557755002;3625.873094441682;3632.727545434103;3637.308911306843;1087.0814162033148;1087.4127970895706;1091.1270983213396;1106.7454485468306;1108.2685427617814;11474.827877581843;11635.418229088596;11652.76694466106;11656.300621962624;11663;4501.7974191161475;4544.386507821402;4746.691465054761;4749.95250047502;4756.9524304756505;10190.490475476197;10203.336783109075;10210.13213876814;10210.540525676362;10212.285140040274;5302.734863256899;5303.04545181871;5304.363476382859;5305.151175811851;5310.840674779763;4392.868213953587;4521.186186486399;4744;4829.806807727651;4881.707097574157;3888.572257051722;3922.4508568800393;3971.3645816669773;4004.379321205218;4011.8796436106963;1251.6933351328983;1278.277773058219;1288.6585054960442;1290.587012156101;1295.7732396830706;661.3254480430031;820.5856042698518;831.3515457942814;863.8358711844729;994.1996692662476;5688.9759843227785;5764.164196191528;5877.88320219157;5901.29184497863;5980.880382392328;927.5547737086237;936.180841763457;944.606705109971;947.2138125355938;967.9806403872024;3944.901377465529;4074.246264441088;4083.7753923533896;4152.937705934413;4157.563455837154;8656.913430865736;10962.232643714864;11007;11068.667939961813;11193.608223712132;11007.504662290215;11026.614068507564;11029.558817647205;11041.171912106623;11042.392668403154;6770.627615481098;7968.362530997506;8343.666253349798;8346.33229341639;8432.578371081421;5857.677827719516;5974.37269086757;6001.849953751192;6072.392760723892;6127.264728232641;9738.610455581698;9903.207743380657;9903.257255705706;9910.900890991286;9925.751856203653;9372.531373431164;9983.201343892617;10003.799924001334;10005;10014.148797352016;7391.593462359622;7713.807154821175;7714.035745531809;7736.2650548197835;7739.4969326995815;8183.83632327362;8293;8372.07907130203;8384.622691978997;8389.993200815941;7465.552066876005;7558.470907036674;7604.125525564631;7665.578393188428;7760.029996250469;6876.965615171892;7461.81345466346;7517.398608111229;7567.848643026999;7625.275598818103;7032.824179395556;7088.326608972139;7148.535345202546;7150.213476517683;7156.499045066796;4974.925376119433;5145.331106956071;5159.613029022913;5221.608379371486;5232.502912223333;5205.854711963362;5230.529252367305;5239.790408383622;5267.183586544092;5381.085215513293 01:32:41 INFO - PROCESS | 1657 | |4;prototype.html;300.68277966745075;304.7272690941607;311.34617303662304;313.80387257963775;317.88556119796885;4364.541723119074;5301.840944771663;5385.138377859544;5394.999999999995;5430.284857571214;4375.628071613909;4988.950110498894;4996.1506543887535;5293.602979776522;5332.840014799563;3698.759580627264;3830.2148059647707;4110.486189226347;4127.215828992496;4131.896702582444;3391.7456190785665;3419.640937701543;3565.5899571549344;3599.4240921452574;3923.7836270756156;3936.7441116327345;4071.9209409506507;4089.5501494835694;4100.692448066392;4108.219438306727;3571.499990001402;3765.4728338032696;3998.0604557928864;4039.7778122203267;4059.6143366380147;3159.573457583243;3542.9114272143147;3543.964560354389;3549.6450354964554;3554.3246783111385;4417.933730994037;5152.416097689754;5231.044459880156;5247.239150323182;5253;4566.474855391606;4667.22990706533;4669.579737823612;4692.967547139624;4700.905981880377;4837.588804951583;4861.489543597942;5346.492083252085;5504.834854954358;5600.775968961237;3921.9019524512114;3925.5671679836655;3939.5469521005166;3941.763494190358;3946.8026598669953;5488.69812160331;5493.23094766733;5735.197072409825;5877.853818505393;5904.498117660004;3720.255948810249;3723.2181241939497;3738.04679806653;3748.793816340073;3773.8866234908537;3687.539057617798;3711.0907827582414;3775.131719704483;3921.6862650988423;3929.5636705242932;3367.7642565020215;4175.91648167035;4190.769507677046;4268.762059002916;4456.442944631921;4463.085067561142;5190.948090519122;5303.867403314871;5343.438938911436;5422;1231.5012419969926;1302.8241187439573;1319.4656164253493;1348.1664935687456;1354.5597680753795;697.8290318871807;792.3661071143063;829.4111181061394;832.6012299337051;835.9832803343899;3977.8607748728655;4272.718184544624;4432.312991486325;4441.578694817692;4493.326001099796;4480.88578437989;4864.73243971585;4890.755462226874;4906.730129842893;4921.391662418923;4104.630583247522;4447.488538818013;4459.866204013885;4481.014176881081;4516.216094642664;2372.8694921779124;2493.950120997606;2541.911033113869;2586.612008198785;2589.6881691983012;4095.7133000689664;4290;4310.956890431118;4418.955810441918;4723.5984941279685;4600.8024423026345;4763.54746299108;4811.518848115161;4996.925046124311;5016.49505148454;4280.636145927631;4583.427071616048;4722.134756771125;4838.371011768448;5030.37120359955;4320.531019453401;4833.3566587360465;4836.371271734793;4865.416150061874;4904.754762261801;3754.0478950945903;4662.417197850269;4730.965684755588;4781.82008964049;4809.629255662119;3614.728895332808;3898.6686131679126;4374.628750555325;4796.688215265996;5078.720670363018;3330.417176994065;3341.9665803341654;3372.6458721834833;3385.1057345968843;3404.7957122572725;4137.731047481904;4216.7259128156575;4298.591633794785;4903.327686410843;4909.232676236481;4403.106664134325;4679.3448917150945;4700.905981880412;4718.994427368286;4796.424429068534;4001.7985611510094;4137.81379837902;4702.647301452336;4731.361266229015;4755.690880092783;3951.3085210088693;4340.435743353344;4652.930206046844;4661.571507068231;4706.552877476634;3985.7608543487486;4171.87234510408;4720.811167553259;4766.952330476651;4791.856244312677;4162.5639154491655;4600.929581688187;4643.883902902455;4680.555347242007;4685.508021657677 01:32:41 INFO - PROCESS | 1657 | |5;yui.html;670.4368330602294;754.6460680269413;782.9843403131938;833.5665453963938;835.4026870787393;9922;15667.843321566766;15688.372465101396;15706.528804135865;15763.448279310227;14320.713585728305;14469.20419376934;14471.204083775388;14472.565823025327;14504.347304371302;720.2545365546661;747.5253214208963;899.7300809757079;909.3861643390718;933.63121566981;905.4069584422214;917.5274733512247;918.3250311021394;922.9861552076724;926.7868390270241;971.7182017214999;972.8054389122168;977.5063592885583;979.4172467381932;981.3719219699398;751.117437011512;772.737269328431;772.9033870766154;774.8256642255503;970.1559643110469;712.7113519024804;727.1891840597748;731.1116992853708;732.4506620034974;733.1019501111097;14682;14707.485238016618;14739.778903316459;14746.336414861356;14750.04124731889;14439;14589.270536473134;14629.92685036568;14656.780148297785;14659.560213193514;13423.798643020362;13430.127041742353;13434.865651343556;13509.729805403935;13871;4679.298105284201;4744.572988431058;4778.307145463888;4826.758662066918;4925.3350797642215;111604;112338;112340.43829780881;112571.87428125695;112593;10977.890221097847;11090.944545277383;11298.661040168809;11597.594084207012;11659.825102623467;20894.059767310508;21574;23360.415989600395;23384.18155364554;23401;7996.480228785111;8016.7194148204535;8259.628316725762;8413.537255451009;8461.703840365557;4485.282354823213;4544.568266014723;4691.202495575761;4696.506866779007;4700.788464519105;3572.1783989682;3674.52231209941;3676.062604035954;3680.852765889388;3681.9447708284397;647.7732793522324;676.8341756269746;679.0255982664892;683.282553319013;683.8837397642412;621.6705146272483;641.8555824939351;652.8628987912497;655.3872129558878;658.4798009572385;7352.062163777624;8844.55777211137;9040.773980650536;9205.355625106178;9295.58169882357;516.3958168942271;621.6021746082511;644.0428505810735;645.5126379583478;651.7621068310033;3785.470034195215;4295.355696645528;4424.80088396023;4454.7549884756;4556.977215113903;129926;155757;156096;156764;159870;135112.32443837717;137538;139129;140140;141356;72839.63580182065;76086;114057.28914066129;114160;115328;7602;8692.348073894356;8769.561521924006;8832.293416526534;8930.732078037669;47436.7628161857;49395.447368946;54586.727066364416;59723;65029.67485162544;43158;62525.74948501096;88529;89653;91556.54221728849;13682.521111761438;13719;13860.930695346458;13866.514671986431;14512.274386280855;15329.540113796604;15405.075695458308;15551.533453996399;15585.29866156003;16187.838121618634;13655.49680183377;13962.232077235443;14187.929060354632;14323.928380358031;14528.413585190974;14000.579982600539;14095.859041409865;14322.28388580546;14344.49794257196;14746.967712260039;13250.403731831899;13753.656158596115;13779.96650251215;13787.51743688966;13794.310284485535;11175.776484470427;11579;11697.590584329508;11933.105017123577;12014.519419223134;9688.951555242178;11352.978231959163;11461;11573.305601663928;11747.588834390755 01:32:41 INFO - PROCESS | 1657 | __end_tp_report 01:32:41 INFO - PROCESS | 1657 | __start_cc_report 01:32:41 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1335 01:32:41 INFO - PROCESS | 1657 | __end_cc_report 01:32:41 INFO - PROCESS | 1657 | __startTimestamp1469003561510__endTimestamp 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | ------- Summary: start ------- 01:32:41 INFO - PROCESS | 1657 | Number of tests: 6 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5774.47 Median:6068.79 stddev:680.90 (11.2%) stddev-sans-first:655.96 01:32:41 INFO - PROCESS | 1657 | Values: 3353.2 3361.8 3386.3 3393.3 3397.4 5758.9 5854.5 5880.3 5937.0 5958.0 5856.2 5910.6 5914.6 5918.0 5920.8 5288.8 5321.7 5356.1 5396.8 5417.9 4549.0 4732.9 5176.1 5370.8 5384.7 4354.6 5293.7 5295.9 5302.4 5400.4 5384.3 5410.2 5415.5 5418.6 5477.4 4824.4 5153.9 5263.8 5334.6 5336.0 4884.3 5084.2 5781.1 5803.4 5874.6 5346.8 6244.9 6329.3 6329.9 6340.0 5101.4 6223.6 6312.2 6329.5 6354.5 5910.1 5980.9 5997.1 6015.1 6026.5 5402.5 6418.9 6425.4 6426.9 6432.3 4606.4 6090.1 6185.6 6282.5 6299.2 6227.0 6337.2 6352.3 6383.2 6409.2 6256.2 6315.1 6319.8 6326.5 6334.5 6205.8 6222.5 6272.0 6281.1 6322.0 4990.8 4995.5 5018.5 5020.8 5082.3 4475.6 4476.6 4491.1 4503.0 4541.3 5804.4 5854.1 5881.6 5915.0 5989.3 5994.8 6070.2 6106.8 6114.5 6136.6 5564.1 5565.8 5582.7 5585.6 5602.1 5170.9 5199.9 5241.6 5405.7 5447.4 6280.8 6359.0 6372.5 6391.8 6454.3 6280.3 6285.5 6293.7 6293.7 6297.6 6404.8 6420.4 6453.3 6526.7 6591.1 6102.8 6166.1 6218.0 6274.7 6283.7 6083.1 6247.8 6273.4 6295.3 6314.1 6224.9 6271.5 6281.5 6283.2 6291.9 5167.6 6093.5 6142.1 6145.7 6224.3 5993.8 6075.2 6138.6 6141.9 6144.2 6082.6 6107.2 6250.1 6254.6 6268.3 6068.8 6078.0 6120.1 6132.6 6146.1 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:73873.78 Median:39754.31 stddev:161564.95 (406.4%) stddev-sans-first:161924.28 01:32:41 INFO - PROCESS | 1657 | Values: 505.2 522.4 536.7 558.1 567.9 30464.8 31928.4 31982.7 32025.4 32068.8 33130.0 43534.8 43697.3 44860.9 47598.5 9729.3 9785.1 9825.1 9826.6 9827.1 8819.2 9751.1 9853.8 9858.2 9875.0 8700.9 11817.0 11959.3 11970.9 12171.3 12066.2 12135.0 12143.5 12148.0 12151.6 8706.6 8889.5 11879.8 11976.6 11977.5 33194.7 33548.7 41638.5 41995.8 42031.0 3604.0 3622.6 3679.7 3684.3 4252.0 27348.3 31816.2 33646.0 33924.3 34100.3 3269.3 3356.8 3362.9 3444.6 3466.5 550845.0 552234.0 554281.0 555872.2 556344.0 64139.7 64250.7 64511.0 64602.7 64821.0 67276.3 67371.0 67386.3 67405.3 67477.0 16702.0 16921.3 16947.7 16969.3 16979.0 13003.5 13065.7 13072.0 13074.5 13135.2 15327.9 15591.5 16540.0 17448.5 17903.9 7288.8 7406.2 7429.9 7482.3 7487.6 949.8 1026.6 1073.3 1132.6 1168.8 29911.6 31357.1 31964.8 31973.8 32607.8 935.9 1015.5 1041.0 1074.3 1102.7 15015.9 15610.8 16233.3 16238.1 16298.7 683531.0 726529.0 726843.0 726942.9 730511.0 61114.0 62855.4 63009.0 63059.0 63080.7 1395.6 1404.1 1426.6 1429.3 1439.3 30587.9 30772.5 30947.4 31664.0 31777.4 46338.0 53837.7 53934.5 54153.5 54275.5 518998.2 521255.0 521311.0 521683.0 521742.0 43039.6 43233.8 43985.3 44146.3 44183.0 37093.8 40049.2 50608.7 51116.0 52108.0 38401.0 40443.4 41794.2 41813.4 42980.4 41973.6 41999.4 42952.8 43109.0 43221.4 39311.6 39559.8 39948.8 40759.4 40947.6 34540.5 35957.6 36774.3 36825.0 36859.8 26035.9 26494.9 26550.0 26566.5 26910.0 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:36372.52 Median:14707.21 stddev:104646.84 (711.5%) stddev-sans-first:104905.51 01:32:41 INFO - PROCESS | 1657 | Values: 384.4 387.1 389.0 389.9 409.6 14021.4 16270.3 20406.9 21369.0 21374.1 16882.6 17357.8 18385.6 20988.6 21194.9 5713.5 5952.0 5980.0 5985.2 5986.1 5680.9 5856.0 5877.5 5882.7 5991.1 5738.7 5819.1 5821.2 5823.7 5826.4 5574.2 5786.4 5788.6 5794.9 5806.1 5043.9 5074.3 5074.6 5075.8 5081.7 25545.7 25881.2 26736.5 27233.2 27307.5 2187.0 2239.4 2678.0 2685.9 3000.5 10141.8 10209.3 10399.6 10525.9 10645.8 1824.6 1892.7 1893.2 1938.2 1957.0 125420.7 125933.0 126451.7 133300.3 133328.3 11421.3 11635.7 11654.4 11660.3 11831.6 14722.9 14757.0 14771.3 14780.3 14782.5 7717.3 8096.1 8243.6 8398.8 8853.0 7513.5 8674.5 8733.9 8750.3 8907.7 4549.2 4553.6 4554.3 4554.7 4616.8 1898.3 1940.8 1943.3 1943.5 1944.8 873.8 942.4 944.0 944.9 947.1 20512.8 21112.5 21967.9 22009.3 22100.9 805.7 950.5 986.0 987.3 998.4 10995.8 11533.9 11581.3 11595.6 11600.1 612928.0 620667.0 633225.5 633595.0 634465.0 78525.6 127960.0 129035.0 130028.0 136297.0 999.9 1001.0 1006.7 1007.7 1023.1 19107.2 19397.0 19424.3 19515.0 19570.1 59051.4 60079.0 60125.6 60439.0 60669.7 81659.2 85617.6 85661.0 85701.6 88544.6 8001.3 8139.5 8329.5 8392.6 8404.4 12973.8 13255.8 13276.6 13367.1 13460.0 9095.7 11370.3 11472.3 11647.4 11681.0 10893.2 11442.0 11520.1 11624.9 11681.5 11609.0 11698.8 11781.4 11830.8 11990.0 8289.5 8356.5 8370.0 8462.4 8514.6 8333.3 10443.7 11485.8 11565.8 11566.6 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5721.03 Median:5182.73 stddev:3218.56 (62.1%) stddev-sans-first:3211.13 01:32:41 INFO - PROCESS | 1657 | Values: 1389.7 1543.1 1559.2 1596.4 1925.3 7888.4 8539.6 8731.9 8755.0 8765.3 8546.1 8564.7 8725.4 8729.7 8731.2 2032.2 2081.3 2147.6 2304.9 2319.8 2325.2 2380.0 2383.2 2386.5 2387.1 1973.9 2301.4 2327.5 2331.2 2332.9 2065.2 2141.1 2177.6 2189.4 2191.6 2093.4 2365.0 2368.9 2369.2 2374.3 8623.3 8680.7 8709.1 8709.4 8727.0 5142.6 5272.8 5273.9 5288.9 5304.3 3441.2 3517.1 3625.9 3632.7 3637.3 1087.1 1087.4 1091.1 1106.7 1108.3 11474.8 11635.4 11652.8 11656.3 11663.0 4501.8 4544.4 4746.7 4750.0 4757.0 10190.5 10203.3 10210.1 10210.5 10212.3 5302.7 5303.0 5304.4 5305.2 5310.8 4392.9 4521.2 4744.0 4829.8 4881.7 3888.6 3922.5 3971.4 4004.4 4011.9 1251.7 1278.3 1288.7 1290.6 1295.8 661.3 820.6 831.4 863.8 994.2 5689.0 5764.2 5877.9 5901.3 5980.9 927.6 936.2 944.6 947.2 968.0 3944.9 4074.2 4083.8 4152.9 4157.6 8656.9 10962.2 11007.0 11068.7 11193.6 11007.5 11026.6 11029.6 11041.2 11042.4 6770.6 7968.4 8343.7 8346.3 8432.6 5857.7 5974.4 6001.8 6072.4 6127.3 9738.6 9903.2 9903.3 9910.9 9925.8 9372.5 9983.2 10003.8 10005.0 10014.1 7391.6 7713.8 7714.0 7736.3 7739.5 8183.8 8293.0 8372.1 8384.6 8390.0 7465.6 7558.5 7604.1 7665.6 7760.0 6877.0 7461.8 7517.4 7567.8 7625.3 7032.8 7088.3 7148.5 7150.2 7156.5 4974.9 5145.3 5159.6 5221.6 5232.5 5205.9 5230.5 5239.8 5267.2 5381.1 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4084.22 Median:4425.63 stddev:1190.22 (26.9%) stddev-sans-first:1159.19 01:32:41 INFO - PROCESS | 1657 | Values: 300.7 304.7 311.3 313.8 317.9 4364.5 5301.8 5385.1 5395.0 5430.3 4375.6 4989.0 4996.2 5293.6 5332.8 3698.8 3830.2 4110.5 4127.2 4131.9 3391.7 3419.6 3565.6 3599.4 3923.8 3936.7 4071.9 4089.6 4100.7 4108.2 3571.5 3765.5 3998.1 4039.8 4059.6 3159.6 3542.9 3544.0 3549.6 3554.3 4417.9 5152.4 5231.0 5247.2 5253.0 4566.5 4667.2 4669.6 4693.0 4700.9 4837.6 4861.5 5346.5 5504.8 5600.8 3921.9 3925.6 3939.5 3941.8 3946.8 5488.7 5493.2 5735.2 5877.9 5904.5 3720.3 3723.2 3738.0 3748.8 3773.9 3687.5 3711.1 3775.1 3921.7 3929.6 3367.8 4175.9 4190.8 4268.8 4456.4 4463.1 5190.9 5303.9 5343.4 5422.0 1231.5 1302.8 1319.5 1348.2 1354.6 697.8 792.4 829.4 832.6 836.0 3977.9 4272.7 4432.3 4441.6 4493.3 4480.9 4864.7 4890.8 4906.7 4921.4 4104.6 4447.5 4459.9 4481.0 4516.2 2372.9 2494.0 2541.9 2586.6 2589.7 4095.7 4290.0 4311.0 4419.0 4723.6 4600.8 4763.5 4811.5 4996.9 5016.5 4280.6 4583.4 4722.1 4838.4 5030.4 4320.5 4833.4 4836.4 4865.4 4904.8 3754.0 4662.4 4731.0 4781.8 4809.6 3614.7 3898.7 4374.6 4796.7 5078.7 3330.4 3342.0 3372.6 3385.1 3404.8 4137.7 4216.7 4298.6 4903.3 4909.2 4403.1 4679.3 4700.9 4719.0 4796.4 4001.8 4137.8 4702.6 4731.4 4755.7 3951.3 4340.4 4652.9 4661.6 4706.6 3985.8 4171.9 4720.8 4767.0 4791.9 4162.6 4600.9 4643.9 4680.6 4685.5 01:32:41 INFO - PROCESS | 1657 | 01:32:41 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24487.01 Median:3733.71 stddev:39491.98 (1057.7%) stddev-sans-first:39562.28 01:32:41 INFO - PROCESS | 1657 | Values: 670.4 754.6 783.0 833.6 835.4 9922.0 15667.8 15688.4 15706.5 15763.4 14320.7 14469.2 14471.2 14472.6 14504.3 720.3 747.5 899.7 909.4 933.6 905.4 917.5 918.3 923.0 926.8 971.7 972.8 977.5 979.4 981.4 751.1 772.7 772.9 774.8 970.2 712.7 727.2 731.1 732.5 733.1 14682.0 14707.5 14739.8 14746.3 14750.0 14439.0 14589.3 14629.9 14656.8 14659.6 13423.8 13430.1 13434.9 13509.7 13871.0 4679.3 4744.6 4778.3 4826.8 4925.3 111604.0 112338.0 112340.4 112571.9 112593.0 10977.9 11090.9 11298.7 11597.6 11659.8 20894.1 21574.0 23360.4 23384.2 23401.0 7996.5 8016.7 8259.6 8413.5 8461.7 4485.3 4544.6 4691.2 4696.5 4700.8 3572.2 3674.5 3676.1 3680.9 3681.9 647.8 676.8 679.0 683.3 683.9 621.7 641.9 652.9 655.4 658.5 7352.1 8844.6 9040.8 9205.4 9295.6 516.4 621.6 644.0 645.5 651.8 3785.5 4295.4 4424.8 4454.8 4557.0 129926.0 155757.0 156096.0 156764.0 159870.0 135112.3 137538.0 139129.0 140140.0 141356.0 72839.6 76086.0 114057.3 114160.0 115328.0 7602.0 8692.3 8769.6 8832.3 8930.7 47436.8 49395.4 54586.7 59723.0 65029.7 43158.0 62525.7 88529.0 89653.0 91556.5 13682.5 13719.0 13860.9 13866.5 14512.3 15329.5 15405.1 15551.5 15585.3 16187.8 13655.5 13962.2 14187.9 14323.9 14528.4 14000.6 14095.9 14322.3 14344.5 14747.0 13250.4 13753.7 13780.0 13787.5 13794.3 11175.8 11579.0 11697.6 11933.1 12014.5 9689.0 11353.0 11461.0 11573.3 11747.6 01:32:41 INFO - PROCESS | 1657 | -------- Summary: end -------- 01:32:41 INFO - PROCESS | 1657 | 01:32:42 INFO - TEST-INFO | 1657: exit 0 01:32:42 INFO - TEST-OK | dromaeo_css | took 1116755ms 01:32:42 INFO - TEST-START | kraken 01:32:42 INFO - operating with platform_type : mac_ 01:32:42 INFO - Initialising browser for kraken test... 01:32:42 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpa6f1Xj/profile http://localhost:49270/getInfo.html) 01:32:44 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 01:32:44 INFO - PROCESS | 1665 | colorDepth:24 01:32:44 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 01:32:44 INFO - PROCESS | 1665 | __metrics 01:32:44 INFO - PROCESS | 1665 | 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" 01:32:44 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:32:45 INFO - TEST-INFO | 1665: exit 0 01:32:45 INFO - Browser initialized. 01:32:45 INFO - Running cycle 1/1 for kraken test... 01:32:45 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpa6f1Xj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:32:56 INFO - PROCESS | 1666 | RSS: Main: 245043200 01:32:56 INFO - PROCESS | 1666 | 01:32:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 01:33:00 INFO - PROCESS | 1666 | RSS: Main: 294559744 01:33:00 INFO - PROCESS | 1666 | 01:33:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 01:33:03 INFO - PROCESS | 1666 | RSS: Main: 304988160 01:33:03 INFO - PROCESS | 1666 | 01:33:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 01:33:07 INFO - PROCESS | 1666 | RSS: Main: 391118848 01:33:07 INFO - PROCESS | 1666 | 01:33:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 01:33:10 INFO - PROCESS | 1666 | RSS: Main: 292532224 01:33:10 INFO - PROCESS | 1666 | 01:33:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:33:13 INFO - PROCESS | 1666 | RSS: Main: 296849408 01:33:13 INFO - PROCESS | 1666 | 01:33:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 01:33:16 INFO - PROCESS | 1666 | RSS: Main: 330977280 01:33:16 INFO - PROCESS | 1666 | 01:33:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 01:33:20 INFO - PROCESS | 1666 | RSS: Main: 333881344 01:33:20 INFO - PROCESS | 1666 | 01:33:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 01:33:23 INFO - PROCESS | 1666 | RSS: Main: 335085568 01:33:23 INFO - PROCESS | 1666 | 01:33:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:33:25 INFO - PROCESS | 1666 | RSS: Main: 312283136 01:33:25 INFO - PROCESS | 1666 | 01:33:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:33:28 INFO - PROCESS | 1666 | RSS: Main: 318218240 01:33:28 INFO - PROCESS | 1666 | 01:33:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:33:31 INFO - PROCESS | 1666 | RSS: Main: 316321792 01:33:31 INFO - PROCESS | 1666 | 01:33:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:33:34 INFO - PROCESS | 1666 | RSS: Main: 325292032 01:33:34 INFO - PROCESS | 1666 | 01:33:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:33:37 INFO - PROCESS | 1666 | RSS: Main: 326897664 01:33:37 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 01:33:39 INFO - PROCESS | 1666 | RSS: Main: 370946048 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | __start_tp_report 01:33:39 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 01:33:39 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 01:33:39 INFO - PROCESS | 1666 | |i|pagename|runs| 01:33:39 INFO - PROCESS | 1666 | |0;ai-astar;102;112;113;112;112;111;111;112;112;112 01:33:39 INFO - PROCESS | 1666 | |1;audio-beat-detection;141;106;105;156;106;108;148;107;137;106 01:33:39 INFO - PROCESS | 1666 | |2;audio-dft;182;186;183;183;184;183;180;180;199;196 01:33:39 INFO - PROCESS | 1666 | |3;audio-fft;77;73;57;86;58;61;58;67;80;57 01:33:39 INFO - PROCESS | 1666 | |4;audio-oscillator;88;68;101;71;73;68;96;68;67;100 01:33:39 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;87;84;87;84;87;83;89;85;73;112 01:33:39 INFO - PROCESS | 1666 | |6;imaging-darkroom;92;93;93;93;93;107;149;93;93;93 01:33:39 INFO - PROCESS | 1666 | |7;imaging-desaturate;86;106;109;86;84;84;84;85;84;83 01:33:39 INFO - PROCESS | 1666 | |8;json-parse-financial;52;52;51;51;52;53;52;52;53;52 01:33:39 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;74;70;78;73;72;71;71;71;72 01:33:39 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;83;81;84;82;89;98;85;82;83;85 01:33:39 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;161;135;137;132;135;135;136;133;172;139 01:33:39 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;160;161;162;160;162;160;160;161;161;162 01:33:39 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;66;65;65;65;66;65;65;66;65;65 01:33:39 INFO - PROCESS | 1666 | __end_tp_report 01:33:39 INFO - PROCESS | 1666 | __start_cc_report 01:33:39 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2004 01:33:39 INFO - PROCESS | 1666 | __end_cc_report 01:33:39 INFO - PROCESS | 1666 | __startTimestamp1469003619729__endTimestamp 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | ------- Summary: start ------- 01:33:39 INFO - PROCESS | 1666 | Number of tests: 14 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.18 (2.8%) stddev-sans-first:0.60 01:33:39 INFO - PROCESS | 1666 | Values: 102.0 112.0 113.0 112.0 112.0 111.0 111.0 112.0 112.0 112.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:122.50 stddev:20.81 (17.0%) stddev-sans-first:20.90 01:33:39 INFO - PROCESS | 1666 | Values: 141.0 106.0 105.0 156.0 106.0 108.0 148.0 107.0 137.0 106.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:185.60 Median:183.50 stddev:6.55 (3.6%) stddev-sans-first:6.82 01:33:39 INFO - PROCESS | 1666 | Values: 182.0 186.0 183.0 183.0 184.0 183.0 180.0 180.0 199.0 196.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:67.40 Median:70.00 stddev:10.86 (15.5%) stddev-sans-first:10.95 01:33:39 INFO - PROCESS | 1666 | Values: 77.0 73.0 57.0 86.0 58.0 61.0 58.0 67.0 80.0 57.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:69.50 stddev:14.50 (20.9%) stddev-sans-first:15.09 01:33:39 INFO - PROCESS | 1666 | Values: 88.0 68.0 101.0 71.0 73.0 68.0 96.0 68.0 67.0 100.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:86.00 stddev:9.79 (11.4%) stddev-sans-first:10.39 01:33:39 INFO - PROCESS | 1666 | Values: 87.0 84.0 87.0 84.0 87.0 83.0 89.0 85.0 73.0 112.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:93.00 stddev:17.82 (19.2%) stddev-sans-first:18.67 01:33:39 INFO - PROCESS | 1666 | Values: 92.0 93.0 93.0 93.0 93.0 107.0 149.0 93.0 93.0 93.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:84.00 stddev:9.77 (11.6%) stddev-sans-first:10.30 01:33:39 INFO - PROCESS | 1666 | Values: 86.0 106.0 109.0 86.0 84.0 84.0 84.0 85.0 84.0 83.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 01:33:39 INFO - PROCESS | 1666 | Values: 52.0 52.0 51.0 51.0 52.0 53.0 52.0 52.0 53.0 52.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:2.85 (4.0%) stddev-sans-first:2.40 01:33:39 INFO - PROCESS | 1666 | Values: 67.0 74.0 70.0 78.0 73.0 72.0 71.0 71.0 71.0 72.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.50 stddev:5.03 (6.0%) stddev-sans-first:5.27 01:33:39 INFO - PROCESS | 1666 | Values: 83.0 81.0 84.0 82.0 89.0 98.0 85.0 82.0 83.0 85.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:136.50 stddev:13.57 (9.9%) stddev-sans-first:12.42 01:33:39 INFO - PROCESS | 1666 | Values: 161.0 135.0 137.0 132.0 135.0 135.0 136.0 133.0 172.0 139.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 01:33:39 INFO - PROCESS | 1666 | Values: 160.0 161.0 162.0 160.0 162.0 160.0 160.0 161.0 161.0 162.0 01:33:39 INFO - PROCESS | 1666 | 01:33:39 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 01:33:39 INFO - PROCESS | 1666 | Values: 66.0 65.0 65.0 65.0 66.0 65.0 65.0 66.0 65.0 65.0 01:33:39 INFO - PROCESS | 1666 | -------- Summary: end -------- 01:33:39 INFO - PROCESS | 1666 | 01:33:40 INFO - TEST-INFO | 1666: exit 0 01:33:40 INFO - TEST-OK | kraken | took 58402ms 01:33:40 INFO - SUITE-END | took 1175s 01:33:40 INFO - Completed test suite (00:19:35) 01:33:40 INFO - javascript benchmark 01:33:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.195233144043, 3361.8319084045793, 3386.3227354529095, 3393.338299031688, 3397.3714862750394, 5758.884822303551, 5854.473097421232, 5880.26496687914, 5937.02039163538, 5957.9999999999945, 5856.180134781123, 5910.586258961875, 5914.645121292726, 5918.0, 5920.792772252972, 5288.814891478799, 5321.680699158043, 5356.143017117262, 5396.757145928443, 5417.864553386157, 4548.954510454886, 4732.881677958044, 5176.145935920568, 5370.838874833761, 5384.703841288728, 4354.5863143001525, 5293.68237905725, 5295.947040529603, 5302.3637163540425, 5400.405955344909, 5384.326959130129, 5410.188471729272, 5415.539679127279, 5418.566514678816, 5477.424870388591, 4824.372831531913, 5153.912665370493, 5263.763130659129, 5334.626576139672, 5336.0, 4884.267359896008, 5084.186530155194, 5781.103928891029, 5803.419658034205, 5874.647521148745, 5346.759395827197, 6244.875102497925, 6329.3354197809485, 6329.905051424233, 6340.017297318923, 5101.387833459971, 6223.564350495467, 6312.242530896276, 6329.461995730322, 6354.459870910978, 5910.113482977587, 5980.940190598082, 5997.070454079625, 6015.127806468037, 6026.457618814327, 5402.540784033323, 6418.903716444257, 6425.357464253537, 6426.903596446057, 6432.324605916405, 4606.424196975378, 6090.126703322896, 6185.628862268279, 6282.465990390777, 6299.244090709052, 6227.0, 6337.207849018872, 6352.301246862841, 6383.234011918599, 6409.230892292955, 6256.249250090018, 6315.084312774622, 6319.778807741707, 6326.46225070865, 6334.461570766444, 6205.782797602153, 6222.471089957314, 6272.0, 6281.057841323838, 6322.0200868865395, 4990.750462476934, 4995.500449954975, 5018.548330650259, 5020.7740651670765, 5082.313887625197, 4475.5748203920575, 4476.619487343547, 4491.079328737601, 4503.009337945647, 4541.296099104644, 5804.448577385142, 5854.092615644582, 5881.558883083786, 5915.031373646158, 5989.341172471025, 5994.820155395345, 6070.2108725865355, 6106.816795496142, 6114.480269177081, 6136.631802091889, 5564.10974244119, 5565.777368905198, 5582.748776305076, 5585.609007369527, 5602.075657516464, 5170.8965820684125, 5199.922001169985, 5241.6068794839775, 5405.729713514309, 5447.400785913546, 6280.780172693934, 6359.0, 6372.458341041063, 6391.840203994937, 6454.322296159024, 6280.277768056732, 6285.497160227263, 6293.748250069946, 6293.748250070129, 6297.55917085818, 6404.839879003062, 6420.357964203542, 6453.322401148, 6526.706298216686, 6591.110200122922, 6102.847428814315, 6166.075088736546, 6218.036204388327, 6274.6548939808745, 6283.654399008098, 6083.117947897618, 6247.781327653511, 6273.435390814849, 6295.276043255084, 6314.147590075465, 6224.906626400517, 6271.529635277281, 6281.52888533371, 6283.151774510383, 6291.937080629135, 5167.560757335668, 6093.482054025458, 6142.109394137736, 6145.709401025656, 6224.284207316216, 5993.820185394445, 6075.210222671025, 6138.600990935575, 6141.9385806143155, 6144.17053697763, 6082.635041897501, 6107.219158344477, 6250.062490626442, 6254.593451425642, 6268.310485846644, 6068.787592434243, 6078.027515597483, 6120.051392034242, 6132.601380910227, 6146.139540464249], "value": 5732.231341493587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.2497041892789, 522.3548917087397, 536.6645846346033, 558.0847410247193, 567.8864227154569, 30464.84767576162, 31928.36143277133, 31982.68017319826, 32025.35949281013, 32068.83965580175, 33130.0, 43534.78232608832, 43697.34453983193, 44860.87847803807, 47598.524014759845, 9729.270304727155, 9785.119339259465, 9825.06661867125, 9826.55780489876, 9827.115559599635, 8819.206271435569, 9751.12239898409, 9853.802923941517, 9858.211343092571, 9874.9506252469, 8700.869486957668, 11817.0, 11959.342236177006, 11970.94014529926, 12171.33057681827, 12066.215695979778, 12135.029197664255, 12143.453544590515, 12148.0, 12151.574694885681, 8706.564671766386, 8889.511076890769, 11879.762404751942, 11976.580819671357, 11977.520899164023, 33194.6680533194, 33548.66451335504, 41638.542651007374, 41995.7900210497, 42031.0, 3603.981980090109, 3622.5834029086727, 3679.6872265857432, 3684.336819372512, 4252.0, 27348.31629209266, 31816.204594885083, 33646.0, 33924.321513569594, 34100.31799364024, 3269.346130773855, 3356.841889548102, 3362.882299119519, 3444.5522082129414, 3466.5493485846678, 550845.0, 552234.0, 554281.0, 555872.2206388983, 556344.0, 64139.679301603195, 64250.67874660597, 64511.0, 64602.676986615705, 64821.0, 67276.32723672799, 67371.0, 67386.32613673898, 67405.3259467399, 67477.0, 16702.0, 16921.323147074225, 16947.661046778994, 16969.321227151024, 16979.0, 13003.544875929298, 13065.738685226243, 13072.0, 13074.477020919057, 13135.211887286794, 15327.923360383127, 15591.532254032398, 16540.007599544067, 17448.47654570365, 17903.910480447514, 7288.8177795554475, 7406.185319614838, 7429.888551671621, 7482.289182527651, 7487.625618719042, 949.7720547068792, 1026.5996261458038, 1073.3398959639765, 1132.6262333429947, 1168.7720894425674, 29911.551326730118, 31357.05928822139, 31964.84017579897, 31973.840130799195, 32607.83696081552, 935.8596210568332, 1015.456730649114, 1040.9687709368732, 1074.301703892476, 1102.691246450995, 15015.873809464116, 15610.843891560939, 16233.2695028724, 16238.10690412015, 16298.674026519404, 683531.0, 726529.0, 726843.0, 726942.9222831134, 730511.0, 61114.0, 62855.37144628495, 63009.0, 63059.0, 63080.73838523204, 1395.6231817409243, 1404.052264721309, 1426.5934208750457, 1429.2996431748368, 1439.3091316168297, 30587.929422470108, 30772.538411924288, 30947.381052379278, 31664.0, 31777.364452710353, 46338.0, 53837.73081134569, 53934.46065539294, 54153.45846541484, 54275.45724542704, 518998.24007039296, 521255.0, 521311.0, 521683.0, 521742.0, 43039.56960430481, 43233.78383108064, 43985.34021989737, 44146.337804933595, 44183.0, 37093.81453092717, 40049.19901601893, 50608.746956264986, 51115.97768044693, 52108.0, 38401.0, 40443.3933490992, 41794.164116716885, 41813.37279940742, 42980.35529466998, 41973.580264198186, 41999.370009449274, 42952.78523607362, 43109.0, 43221.3516797242, 39311.60688393079, 39559.81320560388, 39948.8002559997, 40759.38860917148, 40947.590524094376, 34540.481892771124, 35957.64042359647, 36774.26451471009, 36825.0, 36859.815700921325, 26035.869820650776, 26494.86752566225, 26550.0, 26566.476184762418, 26910.0], "value": 21041.945486372424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.3715525116434, 387.1154412168194, 389.0, 389.8577905105069, 409.6211004820542, 14021.369038393272, 16270.349186032545, 20406.89796551019, 21369.0383932723, 21374.145034198653, 16882.577935551617, 17357.82642173578, 18385.632287354278, 20988.58022839542, 21194.89402552985, 5713.542916566675, 5952.047672372421, 5980.0, 5985.191999080112, 5986.132010858423, 5680.943190568083, 5856.033754430514, 5877.529797616202, 5882.735276912549, 5991.101334799771, 5738.684372359518, 5819.098039803837, 5821.155932389801, 5823.650580965134, 5826.417358264182, 5574.219609254707, 5786.421357864222, 5788.623739456964, 5794.942050579482, 5806.129080637896, 5043.924341134886, 5074.31496747938, 5074.568661663763, 5075.771590278409, 5081.66969147008, 25545.74454255471, 25881.223563292944, 26736.465270694476, 27233.183004509698, 27307.45385092287, 2186.9987921509505, 2239.4401399650087, 2677.9598306025423, 2685.9462810743676, 3000.5499175123687, 10141.797164056752, 10209.336393134423, 10399.584016639325, 10525.894741052569, 10645.84031239532, 1824.644194382096, 1892.6877065284211, 1893.185930050078, 1938.2440848069264, 1956.992149043244, 125420.74579254273, 125933.0, 126451.72256913075, 133300.33597312192, 133328.33335833257, 11421.257618254787, 11635.720070792206, 11654.417279136009, 11660.300381976938, 11831.585894493823, 14722.926385368004, 14757.0, 14771.261436928111, 14780.334884930204, 14782.48261310849, 7717.344025757873, 8096.10942796292, 8243.587820609066, 8398.832023359499, 8853.0, 7513.47405681608, 8674.522901240493, 8733.912660873437, 8750.34372422071, 8907.688230911886, 4549.203889319356, 4553.612942899825, 4554.339620754972, 4554.726716396961, 4616.838410655611, 1898.2596787252983, 1940.8447324214028, 1943.3295513047976, 1943.4752616793387, 1944.844412447029, 873.8033942363045, 942.3827393057559, 943.9811203775886, 944.9338546301831, 947.0718695678274, 20512.79487205139, 21112.47218819511, 21967.94740701189, 22009.33971980843, 22100.88949555242, 805.7341077444429, 950.4819873169139, 985.9507024648882, 987.2694206287296, 998.3510718033336, 10995.835062474069, 11533.884661153448, 11581.305121692725, 11595.594154204562, 11600.129990250596, 612928.0, 620667.0, 633225.5080868953, 633595.0, 634465.0, 78525.60737196392, 127960.0, 129035.0, 130028.0, 136297.0, 999.8550210219478, 1001.0240015984357, 1006.7231511334442, 1007.7430255285002, 1023.1354505442889, 19107.23571057142, 19397.0, 19424.320148794723, 19515.0, 19570.064490326564, 59051.409485904594, 60079.0, 60125.565193089205, 60439.0, 60669.69665151823, 81659.18340816516, 85617.57191214005, 85661.0, 85701.57149214215, 88544.55727721578, 8001.319887809368, 8139.511629302261, 8329.50022998622, 8392.622331995197, 8404.411691181558, 12973.805392918925, 13255.801162982756, 13276.601701948957, 13367.064305498521, 13460.0, 9095.681651142178, 11370.31778093317, 11472.31166130035, 11647.41762911868, 11681.007114395032, 10893.230949086063, 11442.027427668409, 11520.135989800967, 11624.88375116238, 11681.532738690697, 11609.0, 11698.76602467963, 11781.352025638329, 11830.822537662114, 11990.040796736066, 8289.461185022954, 8356.456830306011, 8369.95815020921, 8462.449940753831, 8514.574271286287, 8333.33333333344, 10443.68668939933, 11485.82771258415, 11565.826512602149, 11566.595169169039], "value": 9723.270452762088, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.6664800447895, 1543.0895771494816, 1559.150263106607, 1596.433266190502, 1925.335759163089, 7888.447808653389, 8539.573021348931, 8731.912680873189, 8755.036945935943, 8765.254953328975, 8546.145385461467, 8564.700235491759, 8725.43284686495, 8729.694460693878, 8731.170538798804, 2032.2379107834563, 2081.3443765213983, 2147.559750251203, 2304.8847557622134, 2319.7912187903084, 2325.186184835311, 2379.976200237993, 2383.165891937825, 2386.474975505395, 2387.140629373424, 1973.9309124180656, 2301.3671240408853, 2327.522857814144, 2331.160782118436, 2332.88335583221, 2065.173930427826, 2141.1328411993168, 2177.640689286266, 2189.419803752007, 2191.616467118256, 2093.3510611710267, 2365.0185173153122, 2368.940776480602, 2369.2418426103654, 2374.3351861478814, 8623.31013518917, 8680.696175633822, 8709.129087091304, 8709.390342676015, 8727.04002559718, 5142.640015198937, 5272.815451459181, 5273.86815329616, 5288.894222115575, 5304.310439642861, 3441.1913200397885, 3517.085557755002, 3625.873094441682, 3632.727545434103, 3637.308911306843, 1087.0814162033148, 1087.4127970895706, 1091.1270983213396, 1106.7454485468306, 1108.2685427617814, 11474.827877581843, 11635.418229088596, 11652.76694466106, 11656.300621962624, 11663.0, 4501.7974191161475, 4544.386507821402, 4746.691465054761, 4749.95250047502, 4756.9524304756505, 10190.490475476197, 10203.336783109075, 10210.13213876814, 10210.540525676362, 10212.285140040274, 5302.734863256899, 5303.04545181871, 5304.363476382859, 5305.151175811851, 5310.840674779763, 4392.868213953587, 4521.186186486399, 4744.0, 4829.806807727651, 4881.707097574157, 3888.572257051722, 3922.4508568800393, 3971.3645816669773, 4004.379321205218, 4011.8796436106963, 1251.6933351328983, 1278.277773058219, 1288.6585054960442, 1290.587012156101, 1295.7732396830706, 661.3254480430031, 820.5856042698518, 831.3515457942814, 863.8358711844729, 994.1996692662476, 5688.9759843227785, 5764.164196191528, 5877.88320219157, 5901.29184497863, 5980.880382392328, 927.5547737086237, 936.180841763457, 944.606705109971, 947.2138125355938, 967.9806403872024, 3944.901377465529, 4074.246264441088, 4083.7753923533896, 4152.937705934413, 4157.563455837154, 8656.913430865736, 10962.232643714864, 11007.0, 11068.667939961813, 11193.608223712132, 11007.504662290215, 11026.614068507564, 11029.558817647205, 11041.171912106623, 11042.392668403154, 6770.627615481098, 7968.362530997506, 8343.666253349798, 8346.33229341639, 8432.578371081421, 5857.677827719516, 5974.37269086757, 6001.849953751192, 6072.392760723892, 6127.264728232641, 9738.610455581698, 9903.207743380657, 9903.257255705706, 9910.900890991286, 9925.751856203653, 9372.531373431164, 9983.201343892617, 10003.799924001334, 10005.0, 10014.148797352016, 7391.593462359622, 7713.807154821175, 7714.035745531809, 7736.2650548197835, 7739.4969326995815, 8183.83632327362, 8293.0, 8372.07907130203, 8384.622691978997, 8389.993200815941, 7465.552066876005, 7558.470907036674, 7604.125525564631, 7665.578393188428, 7760.029996250469, 6876.965615171892, 7461.81345466346, 7517.398608111229, 7567.848643026999, 7625.275598818103, 7032.824179395556, 7088.326608972139, 7148.535345202546, 7150.213476517683, 7156.499045066796, 4974.925376119433, 5145.331106956071, 5159.613029022913, 5221.608379371486, 5232.502912223333, 5205.854711963362, 5230.529252367305, 5239.790408383622, 5267.183586544092, 5381.085215513293], "value": 4577.858050283966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.68277966745075, 304.7272690941607, 311.34617303662304, 313.80387257963775, 317.88556119796885, 4364.541723119074, 5301.840944771663, 5385.138377859544, 5394.999999999995, 5430.284857571214, 4375.628071613909, 4988.950110498894, 4996.1506543887535, 5293.602979776522, 5332.840014799563, 3698.759580627264, 3830.2148059647707, 4110.486189226347, 4127.215828992496, 4131.896702582444, 3391.7456190785665, 3419.640937701543, 3565.5899571549344, 3599.4240921452574, 3923.7836270756156, 3936.7441116327345, 4071.9209409506507, 4089.5501494835694, 4100.692448066392, 4108.219438306727, 3571.499990001402, 3765.4728338032696, 3998.0604557928864, 4039.7778122203267, 4059.6143366380147, 3159.573457583243, 3542.9114272143147, 3543.964560354389, 3549.6450354964554, 3554.3246783111385, 4417.933730994037, 5152.416097689754, 5231.044459880156, 5247.239150323182, 5253.0, 4566.474855391606, 4667.22990706533, 4669.579737823612, 4692.967547139624, 4700.905981880377, 4837.588804951583, 4861.489543597942, 5346.492083252085, 5504.834854954358, 5600.775968961237, 3921.9019524512114, 3925.5671679836655, 3939.5469521005166, 3941.763494190358, 3946.8026598669953, 5488.69812160331, 5493.23094766733, 5735.197072409825, 5877.853818505393, 5904.498117660004, 3720.255948810249, 3723.2181241939497, 3738.04679806653, 3748.793816340073, 3773.8866234908537, 3687.539057617798, 3711.0907827582414, 3775.131719704483, 3921.6862650988423, 3929.5636705242932, 3367.7642565020215, 4175.91648167035, 4190.769507677046, 4268.762059002916, 4456.442944631921, 4463.085067561142, 5190.948090519122, 5303.867403314871, 5343.438938911436, 5422.0, 1231.5012419969926, 1302.8241187439573, 1319.4656164253493, 1348.1664935687456, 1354.5597680753795, 697.8290318871807, 792.3661071143063, 829.4111181061394, 832.6012299337051, 835.9832803343899, 3977.8607748728655, 4272.718184544624, 4432.312991486325, 4441.578694817692, 4493.326001099796, 4480.88578437989, 4864.73243971585, 4890.755462226874, 4906.730129842893, 4921.391662418923, 4104.630583247522, 4447.488538818013, 4459.866204013885, 4481.014176881081, 4516.216094642664, 2372.8694921779124, 2493.950120997606, 2541.911033113869, 2586.612008198785, 2589.6881691983012, 4095.7133000689664, 4290.0, 4310.956890431118, 4418.955810441918, 4723.5984941279685, 4600.8024423026345, 4763.54746299108, 4811.518848115161, 4996.925046124311, 5016.49505148454, 4280.636145927631, 4583.427071616048, 4722.134756771125, 4838.371011768448, 5030.37120359955, 4320.531019453401, 4833.3566587360465, 4836.371271734793, 4865.416150061874, 4904.754762261801, 3754.0478950945903, 4662.417197850269, 4730.965684755588, 4781.82008964049, 4809.629255662119, 3614.728895332808, 3898.6686131679126, 4374.628750555325, 4796.688215265996, 5078.720670363018, 3330.417176994065, 3341.9665803341654, 3372.6458721834833, 3385.1057345968843, 3404.7957122572725, 4137.731047481904, 4216.7259128156575, 4298.591633794785, 4903.327686410843, 4909.232676236481, 4403.106664134325, 4679.3448917150945, 4700.905981880412, 4718.994427368286, 4796.424429068534, 4001.7985611510094, 4137.81379837902, 4702.647301452336, 4731.361266229015, 4755.690880092783, 3951.3085210088693, 4340.435743353344, 4652.930206046844, 4661.571507068231, 4706.552877476634, 3985.7608543487486, 4171.87234510408, 4720.811167553259, 4766.952330476651, 4791.856244312677, 4162.5639154491655, 4600.929581688187, 4643.883902902455, 4680.555347242007, 4685.508021657677], "value": 3716.745468453849, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.4368330602294, 754.6460680269413, 782.9843403131938, 833.5665453963938, 835.4026870787393, 9922.0, 15667.843321566766, 15688.372465101396, 15706.528804135865, 15763.448279310227, 14320.713585728305, 14469.20419376934, 14471.204083775388, 14472.565823025327, 14504.347304371302, 720.2545365546661, 747.5253214208963, 899.7300809757079, 909.3861643390718, 933.63121566981, 905.4069584422214, 917.5274733512247, 918.3250311021394, 922.9861552076724, 926.7868390270241, 971.7182017214999, 972.8054389122168, 977.5063592885583, 979.4172467381932, 981.3719219699398, 751.117437011512, 772.737269328431, 772.9033870766154, 774.8256642255503, 970.1559643110469, 712.7113519024804, 727.1891840597748, 731.1116992853708, 732.4506620034974, 733.1019501111097, 14682.0, 14707.485238016618, 14739.778903316459, 14746.336414861356, 14750.04124731889, 14439.0, 14589.270536473134, 14629.92685036568, 14656.780148297785, 14659.560213193514, 13423.798643020362, 13430.127041742353, 13434.865651343556, 13509.729805403935, 13871.0, 4679.298105284201, 4744.572988431058, 4778.307145463888, 4826.758662066918, 4925.3350797642215, 111604.0, 112338.0, 112340.43829780881, 112571.87428125695, 112593.0, 10977.890221097847, 11090.944545277383, 11298.661040168809, 11597.594084207012, 11659.825102623467, 20894.059767310508, 21574.0, 23360.415989600395, 23384.18155364554, 23401.0, 7996.480228785111, 8016.7194148204535, 8259.628316725762, 8413.537255451009, 8461.703840365557, 4485.282354823213, 4544.568266014723, 4691.202495575761, 4696.506866779007, 4700.788464519105, 3572.1783989682, 3674.52231209941, 3676.062604035954, 3680.852765889388, 3681.9447708284397, 647.7732793522324, 676.8341756269746, 679.0255982664892, 683.282553319013, 683.8837397642412, 621.6705146272483, 641.8555824939351, 652.8628987912497, 655.3872129558878, 658.4798009572385, 7352.062163777624, 8844.55777211137, 9040.773980650536, 9205.355625106178, 9295.58169882357, 516.3958168942271, 621.6021746082511, 644.0428505810735, 645.5126379583478, 651.7621068310033, 3785.470034195215, 4295.355696645528, 4424.80088396023, 4454.7549884756, 4556.977215113903, 129926.0, 155757.0, 156096.0, 156764.0, 159870.0, 135112.32443837717, 137538.0, 139129.0, 140140.0, 141356.0, 72839.63580182065, 76086.0, 114057.28914066129, 114160.0, 115328.0, 7602.0, 8692.348073894356, 8769.561521924006, 8832.293416526534, 8930.732078037669, 47436.7628161857, 49395.447368946, 54586.727066364416, 59723.0, 65029.67485162544, 43158.0, 62525.74948501096, 88529.0, 89653.0, 91556.54221728849, 13682.521111761438, 13719.0, 13860.930695346458, 13866.514671986431, 14512.274386280855, 15329.540113796604, 15405.075695458308, 15551.533453996399, 15585.29866156003, 16187.838121618634, 13655.49680183377, 13962.232077235443, 14187.929060354632, 14323.928380358031, 14528.413585190974, 14000.579982600539, 14095.859041409865, 14322.28388580546, 14344.49794257196, 14746.967712260039, 13250.403731831899, 13753.656158596115, 13779.96650251215, 13787.51743688966, 13794.310284485535, 11175.776484470427, 11579.0, 11697.590584329508, 11933.105017123577, 12014.519419223134, 9688.951555242178, 11352.978231959163, 11461.0, 11573.305601663928, 11747.588834390755], "value": 7929.787450781806, "unit": "score"}], "name": "dromaeo_css", "value": 7354.621296616561, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 112.0, 112.0, 111.0, 111.0, 112.0, 112.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 105.0, 156.0, 106.0, 108.0, 148.0, 107.0, 137.0, 106.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 183.0, 183.0, 184.0, 183.0, 180.0, 180.0, 199.0, 196.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 57.0, 86.0, 58.0, 61.0, 58.0, 67.0, 80.0, 57.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 101.0, 71.0, 73.0, 68.0, 96.0, 68.0, 67.0, 100.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 89.0, 85.0, 73.0, 112.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 93.0, 107.0, 149.0, 93.0, 93.0, 93.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 106.0, 109.0, 86.0, 84.0, 84.0, 84.0, 85.0, 84.0, 83.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 51.0, 52.0, 53.0, 52.0, 52.0, 53.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 70.0, 78.0, 73.0, 72.0, 71.0, 71.0, 71.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 84.0, 82.0, 89.0, 98.0, 85.0, 82.0, 83.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 135.0, 137.0, 132.0, 135.0, 135.0, 136.0, 133.0, 172.0, 139.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 160.0, 162.0, 160.0, 160.0, 161.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1418.8, "alertThreshold": 2.0}]} 01:33:40 INFO - Return code: 0 01:33:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:33:41 INFO - # TBPL SUCCESS # 01:33:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:33:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:33:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:33:41 INFO - 2016-07-20 01:33:41.049 screenresolution[1670:9981] starting screenresolution argv=screenresolution get 01:33:41 INFO - 2016-07-20 01:33:41.051 screenresolution[1670:9981] Display 0: 1600x1200x32@60 01:33:41 INFO - 2016-07-20 01:33:41.057 screenresolution[1671:9984] starting screenresolution argv=screenresolution list 01:33:41 INFO - Available Modes on Display 0 01:33:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:33:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:33:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:33:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:33:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:33:41 INFO - Intel Iris: 01:33:41 INFO - Chipset Model: Intel Iris 01:33:41 INFO - Type: GPU 01:33:41 INFO - Bus: Built-In 01:33:41 INFO - VRAM (Dynamic, Max): 1536 MB 01:33:41 INFO - Vendor: Intel (0x8086) 01:33:41 INFO - Device ID: 0x0a2e 01:33:41 INFO - Revision ID: 0x0009 01:33:41 INFO - Displays: 01:33:41 INFO - Display: 01:33:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:33:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:33:41 INFO - Display Serial Number: 1600x1200 60 01:33:41 INFO - Main Display: Yes 01:33:41 INFO - Mirror: Off 01:33:41 INFO - Online: Yes 01:33:41 INFO - Rotation: Supported 01:33:41 INFO - Return code: 0 01:33:41 INFO - Running post-action listener: _resource_record_post_action 01:33:41 INFO - ##### 01:33:41 INFO - ##### Finished run-tests step (success) 01:33:41 INFO - ##### 01:33:41 INFO - Running post-run listener: _resource_record_post_run 01:33:41 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38278656; Write bytes: 706887168; Read time: 412; Write time: 1883 01:33:41 INFO - TinderboxPrint: CPU usage
53.7% 01:33:41 INFO - TinderboxPrint: I/O read bytes / time
38,278,656 / 412 01:33:41 INFO - TinderboxPrint: I/O write bytes / time
706,887,168 / 1,883 01:33:41 INFO - TinderboxPrint: CPU idle
2,240.8 (46.3%) 01:33:41 INFO - TinderboxPrint: CPU system
964.3 (19.9%) 01:33:41 INFO - TinderboxPrint: CPU user
1,636.5 (33.8%) 01:33:41 INFO - TinderboxPrint: Swap in / out
445,865,984 / 0 01:33:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405635584; Write bytes: 399284224; Read time: 29780; Write time: 718 01:33:41 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34641408; Write bytes: 295859712; Read time: 350; Write time: 1131 01:33:41 INFO - Running post-run listener: _upload_blobber_files 01:33:41 INFO - Blob upload gear active. 01:33:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:33:41 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 01:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:33:42 INFO - (blobuploader) - INFO - Open directory for files ... 01:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:33:43 INFO - (blobuploader) - INFO - Done attempting. 01:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:33:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:33:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:33:44 INFO - (blobuploader) - INFO - Done attempting. 01:33:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:33:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:33:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:33:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:33:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:33:48 INFO - (blobuploader) - INFO - Done attempting. 01:33:48 INFO - (blobuploader) - INFO - Iteration through files over. 01:33:48 INFO - Return code: 0 01:33:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:33:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:33:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14222dd977cb287d84c7f0c73ba1473c2b4e13355245aedada1304e55008519180ade34eb767b7eb1dbb54b271736ba5dbd68af7d7b2619a9f3ce537dfcc2245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e2474cfb3dc32ff6f699d1aac0c95acdc692644681abbc35e11980d7ca182b402df874a8e24764f07a30a169d971b2a1d0e6ac12a4b57fba54e893e5a0a9087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bb9b9b1ec06d268332f4b79e9fe0d3e125502be9c9ce7c858818df9808819c5bd7f306341516597bedc7febccb5f29387181d3bdd620e658908d315b297661d"} 01:33:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:33:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:33:48 INFO - Contents: 01:33:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14222dd977cb287d84c7f0c73ba1473c2b4e13355245aedada1304e55008519180ade34eb767b7eb1dbb54b271736ba5dbd68af7d7b2619a9f3ce537dfcc2245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e2474cfb3dc32ff6f699d1aac0c95acdc692644681abbc35e11980d7ca182b402df874a8e24764f07a30a169d971b2a1d0e6ac12a4b57fba54e893e5a0a9087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bb9b9b1ec06d268332f4b79e9fe0d3e125502be9c9ce7c858818df9808819c5bd7f306341516597bedc7febccb5f29387181d3bdd620e658908d315b297661d"} 01:33:48 INFO - Running post-run listener: copy_logs_to_upload_dir 01:33:48 INFO - Copying logs to upload dir... 01:33:48 INFO - mkdir: /builds/slave/test/build/upload/logs 01:33:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.318646 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-20 01:33:48.766984) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 01:33:48.770319) ========= 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.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14222dd977cb287d84c7f0c73ba1473c2b4e13355245aedada1304e55008519180ade34eb767b7eb1dbb54b271736ba5dbd68af7d7b2619a9f3ce537dfcc2245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e2474cfb3dc32ff6f699d1aac0c95acdc692644681abbc35e11980d7ca182b402df874a8e24764f07a30a169d971b2a1d0e6ac12a4b57fba54e893e5a0a9087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bb9b9b1ec06d268332f4b79e9fe0d3e125502be9c9ce7c858818df9808819c5bd7f306341516597bedc7febccb5f29387181d3bdd620e658908d315b297661d"} build_url:https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010529 build_url: 'https://queue.taskcluster.net/v1/task/OI8ebFVhTKiOJs08VuYRQw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/14222dd977cb287d84c7f0c73ba1473c2b4e13355245aedada1304e55008519180ade34eb767b7eb1dbb54b271736ba5dbd68af7d7b2619a9f3ce537dfcc2245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0e2474cfb3dc32ff6f699d1aac0c95acdc692644681abbc35e11980d7ca182b402df874a8e24764f07a30a169d971b2a1d0e6ac12a4b57fba54e893e5a0a9087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0bb9b9b1ec06d268332f4b79e9fe0d3e125502be9c9ce7c858818df9808819c5bd7f306341516597bedc7febccb5f29387181d3bdd620e658908d315b297661d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 01:33:48.807971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:33:48.808519) ========= 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.BKDxEECbow/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.2tidiFRocf/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.004374 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 01:33:48.857618) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 01:33:48.857955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 01:33:48.858331) ========= ========= Total master_lag: 0.18 =========