builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0186 starttime: 1468862158.0 results: success (0) buildid: 20160718081648 builduid: fabf472bba5041b2be35c69f87e3be58 revision: 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.004581) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.005017) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.005290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.134615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.135344) ========= 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.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-18 10:15:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.4M=0.001s 2016-07-18 10:15:58 (10.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.539403 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.699974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.700299) ========= 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.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.028362 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:15:58.749418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:15:58.749820) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a --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 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-18 10:15:58,822 truncating revision to first 12 chars 2016-07-18 10:15:58,822 Setting DEBUG logging. 2016-07-18 10:15:58,823 attempt 1/10 2016-07-18 10:15:58,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/10ef4e6ed0a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 10:15:59,886 unpacking tar archive at: fx-team-10ef4e6ed0a3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.321792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:16:00.093367) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:16:00.093728) ========= script_repo_revision: 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:16:00.094136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:16:00.094397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:16:00.112032) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 10:16:00.112310) ========= /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.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:00 INFO - MultiFileLogger online at 20160718 10:16:00 in /builds/slave/test 10:16:00 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 10:16:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:16:00 INFO - {'append_to_log': False, 10:16:00 INFO - 'base_work_dir': '/builds/slave/test', 10:16:00 INFO - 'blob_upload_branch': 'Fx-Team', 10:16:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:16:00 INFO - 'branch': 'Fx-Team', 10:16:00 INFO - 'buildbot_json_path': 'buildprops.json', 10:16:00 INFO - 'config_files': ('talos/mac_config.py',), 10:16:00 INFO - 'default_actions': ('clobber', 10:16:00 INFO - 'read-buildbot-config', 10:16:00 INFO - 'download-and-extract', 10:16:00 INFO - 'populate-webroot', 10:16:00 INFO - 'create-virtualenv', 10:16:00 INFO - 'install', 10:16:00 INFO - 'run-tests'), 10:16:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:16:00 INFO - 'download_minidump_stackwalk': True, 10:16:00 INFO - 'download_symbols': 'ondemand', 10:16:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:16:00 INFO - 'tooltool.py': '/tools/tooltool.py', 10:16:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:16:00 INFO - '/tools/misc-python/virtualenv.py')}, 10:16:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:16:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:16:00 INFO - 'installer_path': 'installer.exe', 10:16:00 INFO - 'log_level': 'info', 10:16:00 INFO - 'log_name': 'talos', 10:16:00 INFO - 'log_to_console': True, 10:16:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:16:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:16:00 INFO - 'opt_config_files': (), 10:16:00 INFO - 'pip_index': False, 10:16:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:00 INFO - 'cmd': ('bash', 10:16:00 INFO - '-c', 10:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:00 INFO - 'enabled': True, 10:16:00 INFO - 'halt_on_failure': False, 10:16:00 INFO - 'name': 'check_screen_resolution'},), 10:16:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:00 INFO - 'cmd': ('bash', 10:16:00 INFO - '-c', 10:16:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:00 INFO - 'enabled': True, 10:16:00 INFO - 'halt_on_failure': False, 10:16:00 INFO - 'name': 'check_screen_resolution'},), 10:16:00 INFO - 'run_cmd_checks_enabled': True, 10:16:00 INFO - 'sps_profile': False, 10:16:00 INFO - 'sps_profile_interval': 0, 10:16:00 INFO - 'suite': 'dromaeojs', 10:16:00 INFO - 'system_bits': '32', 10:16:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:16:00 INFO - 'title': 't-yosemite-r7-0186', 10:16:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:16:00 INFO - 'use_talos_json': True, 10:16:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:16:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:16:00 INFO - 'work_dir': 'build'} 10:16:00 INFO - ##### 10:16:00 INFO - ##### Running clobber step. 10:16:00 INFO - ##### 10:16:00 INFO - Running pre-action listener: _resource_record_pre_action 10:16:00 INFO - Running main action method: clobber 10:16:00 INFO - rmtree: /builds/slave/test/build 10:16:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:16:02 INFO - Running post-action listener: _resource_record_post_action 10:16:02 INFO - ##### 10:16:02 INFO - ##### Finished clobber step (success) 10:16:02 INFO - ##### 10:16:02 INFO - ##### 10:16:02 INFO - ##### Running read-buildbot-config step. 10:16:02 INFO - ##### 10:16:02 INFO - Running pre-action listener: _resource_record_pre_action 10:16:02 INFO - Running main action method: read_buildbot_config 10:16:02 INFO - Using buildbot properties: 10:16:02 INFO - { 10:16:02 INFO - "project": "", 10:16:02 INFO - "product": "firefox", 10:16:02 INFO - "script_repo_revision": "production", 10:16:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:16:02 INFO - "repository": "", 10:16:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:16:02 INFO - "buildid": "20160718081648", 10:16:02 INFO - "pgo_build": "False", 10:16:02 INFO - "basedir": "/builds/slave/test", 10:16:02 INFO - "buildnumber": 848, 10:16:02 INFO - "slavename": "t-yosemite-r7-0186", 10:16:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:16:02 INFO - "platform": "yosemite_r7", 10:16:02 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 10:16:02 INFO - "branch": "fx-team", 10:16:02 INFO - "repo_path": "integration/fx-team", 10:16:02 INFO - "revision": "10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a", 10:16:02 INFO - "stage_platform": "macosx64", 10:16:02 INFO - "builduid": "fabf472bba5041b2be35c69f87e3be58", 10:16:02 INFO - "slavebuilddir": "test" 10:16:02 INFO - } 10:16:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:16:02 INFO - Running post-action listener: _resource_record_post_action 10:16:02 INFO - ##### 10:16:02 INFO - ##### Finished read-buildbot-config step (success) 10:16:02 INFO - ##### 10:16:02 INFO - ##### 10:16:02 INFO - ##### Running download-and-extract step. 10:16:02 INFO - ##### 10:16:02 INFO - Running pre-action listener: _resource_record_pre_action 10:16:02 INFO - Running main action method: download_and_extract 10:16:02 INFO - mkdir: /builds/slave/test/build/tests 10:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:02 INFO - https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:16:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:16:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:16:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:03 INFO - Downloaded 1199 bytes. 10:16:03 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:16:03 INFO - Using the following test package requirements: 10:16:03 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:16:03 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:16:03 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:16:03 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'jsshell-mac.zip'], 10:16:03 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:16:03 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:16:03 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:16:03 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:16:03 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:16:03 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:16:03 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:16:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:16:03 INFO - https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:16:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:16:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:16:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:06 INFO - Downloaded 18835828 bytes. 10:16:06 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'] 10:16:06 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 10:16:09 INFO - Return code: 0 10:16:09 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 10:16: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')]} 10:16:09 INFO - https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:16:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:16:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:16:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:10 INFO - Downloaded 18835828 bytes. 10:16:10 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'] 10:16:10 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 10:16:14 INFO - Return code: 0 10:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:14 INFO - https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:16:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:16:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:16:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:15 INFO - Downloaded 14403317 bytes. 10:16:15 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'] 10:16:15 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 10:16:16 INFO - Return code: 0 10:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:16 INFO - https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:16:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:16:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:16:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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 10:16:19 INFO - Downloaded 112755396 bytes. 10:16:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:16:19 INFO - mkdir: /builds/slave/test/properties 10:16:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:16:19 INFO - Writing to file /builds/slave/test/properties/build_url 10:16:19 INFO - Contents: 10:16:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:16:20 INFO - Running post-action listener: _resource_record_post_action 10:16:20 INFO - Running post-action listener: set_extra_try_arguments 10:16:20 INFO - ##### 10:16:20 INFO - ##### Finished download-and-extract step (success) 10:16:20 INFO - ##### 10:16:20 INFO - ##### 10:16:20 INFO - ##### Running populate-webroot step. 10:16:20 INFO - ##### 10:16:20 INFO - Running pre-action listener: _resource_record_pre_action 10:16:20 INFO - Running main action method: populate_webroot 10:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:16:20 INFO - '--tppagecycles', 10:16:20 INFO - '7'], 10:16:20 INFO - 'tests': ['tsvgm']}}, 10:16:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:16:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:16:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:16:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['damp', 'tps']}, 10:16:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'tests': ['damp', 'tps']}, 10:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['dromaeo_dom']}, 10:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['basic_compositor_video']}, 10:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['a11yr', 10:16:20 INFO - 'ts_paint', 10:16:20 INFO - 'tpaint', 10:16:20 INFO - 'sessionrestore', 10:16:20 INFO - 'sessionrestore_no_auto_restore', 10:16:20 INFO - 'tabpaint']}, 10:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['tsvgx', 10:16:20 INFO - 'tsvgr_opacity', 10:16:20 INFO - 'tart', 10:16:20 INFO - 'tscrollx', 10:16:20 INFO - 'cart']}, 10:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:16:20 INFO - 'tsvgr_opacity', 10:16:20 INFO - 'tart', 10:16:20 INFO - 'tscrollx', 10:16:20 INFO - 'cart']}, 10:16:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'talos_options': ['--disable-e10s'], 10:16:20 INFO - 'tests': ['tp5o']}, 10:16:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'tests': ['tp5o']}, 10:16:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'talos_options': ['--disable-e10s', 10:16:20 INFO - '--xperf_path', 10:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:20 INFO - 'tests': ['tp5n']}, 10:16:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:16:20 INFO - 'talos_options': ['--xperf_path', 10:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:16:20 INFO - 'tests': ['tp5n']}}, 10:16:20 INFO - 'talos.zip': {'path': '', 10:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:16:20 INFO - Running post-action listener: _resource_record_post_action 10:16:20 INFO - ##### 10:16:20 INFO - ##### Finished populate-webroot step (success) 10:16:20 INFO - ##### 10:16:20 INFO - ##### 10:16:20 INFO - ##### Running create-virtualenv step. 10:16:20 INFO - ##### 10:16:20 INFO - Running pre-action listener: _resource_record_pre_action 10:16:20 INFO - Running main action method: create_virtualenv 10:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 10:16:20 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 10:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:16:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:16:20 INFO - Using real prefix '/tools/python27' 10:16:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 10:16:24 INFO - Installing pip.................done. 10:16:24 INFO - Return code: 0 10:16:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16: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')]} 10:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:24 INFO - 'HOME': '/Users/cltbld', 10:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:24 INFO - 'LOGNAME': 'cltbld', 10:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:24 INFO - 'MOZ_NO_REMOTE': '1', 10:16:24 INFO - 'NO_EM_RESTART': '1', 10:16:24 INFO - 'PAGER': '/bin/cat', 10:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:24 INFO - 'PWD': '/builds/slave/test', 10:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:24 INFO - 'SHELL': '/bin/bash', 10:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:24 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:24 INFO - 'USER': 'cltbld', 10:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:24 INFO - 'XPC_FLAGS': '0x0', 10:16:24 INFO - 'XPC_SERVICE_NAME': '0', 10:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:24 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 10:16:24 INFO - Cleaning up... 10:16:24 INFO - Return code: 0 10:16:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16: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')]} 10:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:24 INFO - 'HOME': '/Users/cltbld', 10:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:24 INFO - 'LOGNAME': 'cltbld', 10:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:24 INFO - 'MOZ_NO_REMOTE': '1', 10:16:24 INFO - 'NO_EM_RESTART': '1', 10:16:24 INFO - 'PAGER': '/bin/cat', 10:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:24 INFO - 'PWD': '/builds/slave/test', 10:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:24 INFO - 'SHELL': '/bin/bash', 10:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:24 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:24 INFO - 'USER': 'cltbld', 10:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:24 INFO - 'XPC_FLAGS': '0x0', 10:16:24 INFO - 'XPC_SERVICE_NAME': '0', 10:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:24 INFO - Downloading/unpacking psutil>=3.1.1 10:16:24 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 10:16:24 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 10:16:24 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 10:16:24 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 10:16:24 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 10:16:24 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 10:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:16:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:27 INFO - Installing collected packages: psutil 10:16:27 INFO - Running setup.py install for psutil 10:16:28 INFO - building 'psutil._psutil_osx' extension 10:16:28 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 10:16:28 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 10:16:28 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 10:16:28 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 10:16:28 INFO - building 'psutil._psutil_posix' extension 10:16:28 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 10:16:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:16:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:16:28 INFO - ^ 10:16:28 INFO - 1 warning generated. 10:16:28 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 10:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:29 INFO - Successfully installed psutil 10:16:29 INFO - Cleaning up... 10:16:29 INFO - Return code: 0 10:16:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16: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')]} 10:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:29 INFO - 'HOME': '/Users/cltbld', 10:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:29 INFO - 'LOGNAME': 'cltbld', 10:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:29 INFO - 'MOZ_NO_REMOTE': '1', 10:16:29 INFO - 'NO_EM_RESTART': '1', 10:16:29 INFO - 'PAGER': '/bin/cat', 10:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:29 INFO - 'PWD': '/builds/slave/test', 10:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:29 INFO - 'SHELL': '/bin/bash', 10:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:29 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:29 INFO - 'USER': 'cltbld', 10:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:29 INFO - 'XPC_FLAGS': '0x0', 10:16:29 INFO - 'XPC_SERVICE_NAME': '0', 10:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:16:32 INFO - Installing collected packages: mozsystemmonitor 10:16:32 INFO - Running setup.py install for mozsystemmonitor 10:16:32 INFO - Successfully installed mozsystemmonitor 10:16:32 INFO - Cleaning up... 10:16:32 INFO - Return code: 0 10:16:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:32 INFO - 'HOME': '/Users/cltbld', 10:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:32 INFO - 'LOGNAME': 'cltbld', 10:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:32 INFO - 'MOZ_NO_REMOTE': '1', 10:16:32 INFO - 'NO_EM_RESTART': '1', 10:16:32 INFO - 'PAGER': '/bin/cat', 10:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:32 INFO - 'PWD': '/builds/slave/test', 10:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:32 INFO - 'SHELL': '/bin/bash', 10:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:32 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:32 INFO - 'USER': 'cltbld', 10:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:32 INFO - 'XPC_FLAGS': '0x0', 10:16:32 INFO - 'XPC_SERVICE_NAME': '0', 10:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:32 INFO - Downloading/unpacking jsonschema==2.5.1 10:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:16:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:35 INFO - Downloading functools32-3.2.3-2.tar.gz 10:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:16:35 INFO - warning: no files found matching '*.txt' 10:16:35 INFO - no previously-included directories found matching 'build' 10:16:35 INFO - no previously-included directories found matching 'dist' 10:16:35 INFO - no previously-included directories found matching '.git*' 10:16:35 INFO - Installing collected packages: jsonschema, functools32 10:16:35 INFO - Running setup.py install for functools32 10:16:35 INFO - warning: no files found matching '*.txt' 10:16:35 INFO - no previously-included directories found matching 'build' 10:16:35 INFO - no previously-included directories found matching 'dist' 10:16:35 INFO - no previously-included directories found matching '.git*' 10:16:35 INFO - Successfully installed jsonschema functools32 10:16:35 INFO - Cleaning up... 10:16:35 INFO - Return code: 0 10:16:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:35 INFO - 'HOME': '/Users/cltbld', 10:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:35 INFO - 'LOGNAME': 'cltbld', 10:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:35 INFO - 'MOZ_NO_REMOTE': '1', 10:16:35 INFO - 'NO_EM_RESTART': '1', 10:16:35 INFO - 'PAGER': '/bin/cat', 10:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:35 INFO - 'PWD': '/builds/slave/test', 10:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:35 INFO - 'SHELL': '/bin/bash', 10:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:35 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:35 INFO - 'USER': 'cltbld', 10:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:35 INFO - 'XPC_FLAGS': '0x0', 10:16:35 INFO - 'XPC_SERVICE_NAME': '0', 10:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:35 INFO - Downloading/unpacking blobuploader==1.2.4 10:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:16:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:16:38 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 10:16:38 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 10:16:38 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 10:16:38 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 10:16:38 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 10:16:38 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 10:16:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:16:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:16:39 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 10:16:39 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 10:16:39 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 10:16:39 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 10:16:39 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 10:16:39 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 10:16:39 INFO - Downloading docopt-0.6.1.tar.gz 10:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:16:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:16:39 INFO - Running setup.py install for blobuploader 10:16:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:16:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:16:39 INFO - Running setup.py install for requests 10:16:40 INFO - Running setup.py install for docopt 10:16:40 INFO - Successfully installed blobuploader requests docopt 10:16:40 INFO - Cleaning up... 10:16:40 INFO - Return code: 0 10:16:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16:40 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')]} 10:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:40 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')]} 10:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:40 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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:40 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 10:16:40 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 10:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:40 INFO - 'HOME': '/Users/cltbld', 10:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:40 INFO - 'LOGNAME': 'cltbld', 10:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:40 INFO - 'MOZ_NO_REMOTE': '1', 10:16:40 INFO - 'NO_EM_RESTART': '1', 10:16:40 INFO - 'PAGER': '/bin/cat', 10:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:40 INFO - 'PWD': '/builds/slave/test', 10:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:40 INFO - 'SHELL': '/bin/bash', 10:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:40 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:40 INFO - 'USER': 'cltbld', 10:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:40 INFO - 'XPC_FLAGS': '0x0', 10:16:40 INFO - 'XPC_SERVICE_NAME': '0', 10:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:16:40 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Xm9yX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-T1SCSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-02abHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-0w9sek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-8Lqqak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-KAwahR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Pa5TJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-z_NoDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-sa7dKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-uzNf0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:16:41 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WYOttC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Ixy9TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-KOkpzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-tqhYOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-MQFUo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Lndvp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:16:42 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-EmU1gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:16:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:16:42 INFO - Running setup.py install for manifestparser 10:16:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:16:42 INFO - Running setup.py install for mozcrash 10:16:42 INFO - Running setup.py install for mozdebug 10:16:43 INFO - Running setup.py install for mozdevice 10:16:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Running setup.py install for mozfile 10:16:43 INFO - Running setup.py install for mozhttpd 10:16:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Running setup.py install for mozinfo 10:16:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Running setup.py install for mozInstall 10:16:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:16:43 INFO - Running setup.py install for mozleak 10:16:43 INFO - Running setup.py install for mozlog 10:16:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Running setup.py install for moznetwork 10:16:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Running setup.py install for mozprocess 10:16:44 INFO - Running setup.py install for mozprofile 10:16:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Running setup.py install for mozrunner 10:16:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:16:44 INFO - Running setup.py install for mozscreenshot 10:16:44 INFO - Running setup.py install for moztest 10:16:45 INFO - Running setup.py install for mozversion 10:16:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:16:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:16:45 INFO - Cleaning up... 10:16:45 INFO - Return code: 0 10:16:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16:45 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')]} 10:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:45 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')]} 10:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:45 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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:45 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 10:16:45 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 10:16:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:45 INFO - 'HOME': '/Users/cltbld', 10:16:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:45 INFO - 'LOGNAME': 'cltbld', 10:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:45 INFO - 'MOZ_NO_REMOTE': '1', 10:16:45 INFO - 'NO_EM_RESTART': '1', 10:16:45 INFO - 'PAGER': '/bin/cat', 10:16:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:45 INFO - 'PWD': '/builds/slave/test', 10:16:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:45 INFO - 'SHELL': '/bin/bash', 10:16:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:45 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:45 INFO - 'USER': 'cltbld', 10:16:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:45 INFO - 'XPC_FLAGS': '0x0', 10:16:45 INFO - 'XPC_SERVICE_NAME': '0', 10:16:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:16:45 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-o8Yyce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:16:45 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)) 10:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:16:45 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-asCzTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:16:45 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)) 10:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:16:45 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-nUxnj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:16:45 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)) 10:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:16:45 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-k8Hlgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:16:45 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)) 10:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:16:45 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-nXaIk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ypYS1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-8k5mep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2dBw4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2zPyIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-9Z7VLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-fZKhFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-4I2W8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ekAwss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:16:46 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)) 10:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:16:46 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WGDoAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:16:47 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)) 10:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-PNs9v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:16:47 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)) 10:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:16:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-lyQOaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:16:47 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)) 10:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:16:47 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WcXQrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:16:47 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)) 10:16:47 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)) 10:16:47 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)) 10:16:47 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)) 10:16:47 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)) 10:16:47 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)) 10:16:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:16:47 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 10:16:47 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 10:16:47 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 10:16:47 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 10:16:47 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 10:16:47 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 10:16:49 INFO - Downloading blessings-1.6.tar.gz 10:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:16:49 INFO - Installing collected packages: blessings 10:16:49 INFO - Running setup.py install for blessings 10:16:50 INFO - Successfully installed blessings 10:16:50 INFO - Cleaning up... 10:16:50 INFO - Return code: 0 10:16:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:16:50 INFO - Reading from file tmpfile_stdout 10:16:50 INFO - Current package versions: 10:16:50 INFO - blessings == 1.6 10:16:50 INFO - blobuploader == 1.2.4 10:16:50 INFO - docopt == 0.6.1 10:16:50 INFO - functools32 == 3.2.3-2 10:16:50 INFO - jsonschema == 2.5.1 10:16:50 INFO - manifestparser == 1.1 10:16:50 INFO - mozInstall == 1.12 10:16:50 INFO - mozcrash == 0.17 10:16:50 INFO - mozdebug == 0.1 10:16:50 INFO - mozdevice == 0.48 10:16:50 INFO - mozfile == 1.2 10:16:50 INFO - mozhttpd == 0.7 10:16:50 INFO - mozinfo == 0.9 10:16:50 INFO - mozleak == 0.1 10:16:50 INFO - mozlog == 3.2 10:16:50 INFO - moznetwork == 0.27 10:16:50 INFO - mozprocess == 0.23 10:16:50 INFO - mozprofile == 0.28 10:16:50 INFO - mozrunner == 6.12 10:16:50 INFO - mozscreenshot == 0.1 10:16:50 INFO - mozsystemmonitor == 0.3 10:16:50 INFO - moztest == 0.7 10:16:50 INFO - mozversion == 1.4 10:16:50 INFO - psutil == 3.1.1 10:16:50 INFO - requests == 1.2.3 10:16:50 INFO - wsgiref == 0.1.2 10:16:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16: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')]} 10:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:50 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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:50 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 10:16:50 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 10:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:50 INFO - 'HOME': '/Users/cltbld', 10:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:50 INFO - 'LOGNAME': 'cltbld', 10:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:50 INFO - 'MOZ_NO_REMOTE': '1', 10:16:50 INFO - 'NO_EM_RESTART': '1', 10:16:50 INFO - 'PAGER': '/bin/cat', 10:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:50 INFO - 'PWD': '/builds/slave/test', 10:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:50 INFO - 'SHELL': '/bin/bash', 10:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:50 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:50 INFO - 'USER': 'cltbld', 10:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:50 INFO - 'XPC_FLAGS': '0x0', 10:16:50 INFO - 'XPC_SERVICE_NAME': '0', 10:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 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)) 10:16:50 INFO - Cleaning up... 10:16:50 INFO - Return code: 0 10:16:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:16: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')]} 10:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16: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')]} 10:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:50 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 0x106d7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e13618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb510d6f2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, '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.llYeFLQ88V/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.kIYivg1h82/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 10:16:50 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 10:16:50 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 10:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:50 INFO - 'HOME': '/Users/cltbld', 10:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:50 INFO - 'LOGNAME': 'cltbld', 10:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:50 INFO - 'MOZ_NO_REMOTE': '1', 10:16:50 INFO - 'NO_EM_RESTART': '1', 10:16:50 INFO - 'PAGER': '/bin/cat', 10:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:50 INFO - 'PWD': '/builds/slave/test', 10:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:50 INFO - 'SHELL': '/bin/bash', 10:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:16:50 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:50 INFO - 'USER': 'cltbld', 10:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:50 INFO - 'XPC_FLAGS': '0x0', 10:16:50 INFO - 'XPC_SERVICE_NAME': '0', 10:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:16:50 INFO - Cleaning up... 10:16:50 INFO - Return code: 0 10:16:50 INFO - Running post-action listener: _resource_record_post_action 10:16:50 INFO - Running post-action listener: _start_resource_monitoring 10:16:50 INFO - Starting resource monitoring. 10:16:50 INFO - ##### 10:16:50 INFO - ##### Finished create-virtualenv step (success) 10:16:50 INFO - ##### 10:16:50 INFO - ##### 10:16:50 INFO - ##### Running install step. 10:16:50 INFO - ##### 10:16:50 INFO - Running pre-action listener: _resource_record_pre_action 10:16:50 INFO - Running main action method: install 10:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:16:51 INFO - Reading from file tmpfile_stdout 10:16:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:16:51 INFO - Reading from file tmpfile_stdout 10:16:51 INFO - Output received: 10:16:51 INFO - Usage: mozinstall [options] installer 10:16:51 INFO - Options: 10:16:51 INFO - -h, --help show this help message and exit 10:16:51 INFO - -d DEST, --destination=DEST 10:16:51 INFO - Directory to install application into. [default: 10:16:51 INFO - "/builds/slave/test"] 10:16:51 INFO - --app=APP Application being installed. [default: firefox] 10:16:51 INFO - mkdir: /builds/slave/test/build/application 10:16:51 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'] 10:16:51 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 10:17:25 INFO - Reading from file tmpfile_stdout 10:17:25 INFO - Output received: 10:17:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:17:25 INFO - Running post-action listener: _resource_record_post_action 10:17:25 INFO - ##### 10:17:25 INFO - ##### Finished install step (success) 10:17:25 INFO - ##### 10:17:25 INFO - ##### 10:17:25 INFO - ##### Running run-tests step. 10:17:25 INFO - ##### 10:17:25 INFO - Running pre-action listener: _resource_record_pre_action 10:17:25 INFO - Running main action method: run_tests 10:17:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:17:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:17:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:17:25 INFO - 2016-07-18 10:17:25.444 screenresolution[1643:7969] starting screenresolution argv=screenresolution get 10:17:25 INFO - 2016-07-18 10:17:25.447 screenresolution[1643:7969] Display 0: 1600x1200x32@60 10:17:25 INFO - 2016-07-18 10:17:25.452 screenresolution[1644:7971] starting screenresolution argv=screenresolution list 10:17:25 INFO - Available Modes on Display 0 10:17:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:17:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:17:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:17:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:17:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:17:25 INFO - Intel Iris: 10:17:25 INFO - Chipset Model: Intel Iris 10:17:25 INFO - Type: GPU 10:17:25 INFO - Bus: Built-In 10:17:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:17:25 INFO - Vendor: Intel (0x8086) 10:17:25 INFO - Device ID: 0x0a2e 10:17:25 INFO - Revision ID: 0x0009 10:17:25 INFO - Displays: 10:17:25 INFO - Display: 10:17:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:17:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:17:25 INFO - Display Serial Number: 1600x1200 60 10:17:25 INFO - Main Display: Yes 10:17:25 INFO - Mirror: Off 10:17:25 INFO - Online: Yes 10:17:25 INFO - Rotation: Supported 10:17:25 INFO - Return code: 0 10:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:17:25 INFO - Python 2.7.3 10:17:25 INFO - Return code: 0 10:17:25 INFO - grabbing minidump binary from tooltool 10:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 10:17:25 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 0x106ce2d30>, '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 0x106a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cdab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:17:25 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 10:17:25 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 10:17:25 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 10:17:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:17:25 INFO - Return code: 0 10:17:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:17:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:17:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:17:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:17:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:17:25 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/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 10:17:25 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/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0186 --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 10:17:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.llYeFLQ88V/Render', 10:17:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:25 INFO - 'HOME': '/Users/cltbld', 10:17:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:25 INFO - 'LOGNAME': 'cltbld', 10:17:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:17:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:17:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:25 INFO - 'MOZ_NO_REMOTE': '1', 10:17:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:17:25 INFO - 'NO_EM_RESTART': '1', 10:17:25 INFO - 'PAGER': '/bin/cat', 10:17:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:25 INFO - 'PWD': '/builds/slave/test', 10:17:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:17:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:25 INFO - 'SHELL': '/bin/bash', 10:17:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kIYivg1h82/Listeners', 10:17:25 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 10:17:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:25 INFO - 'USER': 'cltbld', 10:17:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:25 INFO - 'XPC_FLAGS': '0x0', 10:17:25 INFO - 'XPC_SERVICE_NAME': '0', 10:17:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:25 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/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 10:17:26 INFO - mozversion application_buildid: 20160718081648 10:17:26 INFO - mozversion application_changeset: 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a 10:17:26 INFO - mozversion application_display_name: Nightly 10:17:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:17:26 INFO - mozversion application_name: Firefox 10:17:26 INFO - mozversion application_remotingname: firefox 10:17:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:17:26 INFO - mozversion application_vendor: Mozilla 10:17:26 INFO - mozversion application_version: 50.0a1 10:17:26 INFO - mozversion platform_buildid: 20160718081648 10:17:26 INFO - mozversion platform_changeset: 10ef4e6ed0a3dfa4f1523069647c7d93c2b28e7a 10:17:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:17:26 INFO - mozversion platform_version: 50.0a1 10:17:26 INFO - using testdate: 1468862246 10:17:26 INFO - actual date: 1468862246 10:17:26 INFO - starting webserver on 'localhost:49275' 10:17:26 INFO - SUITE-START | Running 2 tests 10:17:26 INFO - TEST-START | dromaeo_css 10:17:26 INFO - operating with platform_type : mac_ 10:17:26 INFO - Initialising browser for dromaeo_css test... 10:17:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmppxZNx9/profile http://localhost:49275/getInfo.html) 10:17:28 INFO - PROCESS | 1654 | JavaScript error: , line 0: uncaught exception: undefined 10:17:28 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 10:17:28 INFO - PROCESS | 1654 | colorDepth:24 10:17:28 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 10:17:28 INFO - PROCESS | 1654 | __metrics 10:17:28 INFO - PROCESS | 1654 | 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" 10:17:28 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:29 INFO - TEST-INFO | 1654: exit 0 10:17:29 INFO - Browser initialized. 10:17:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:17:29 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmppxZNx9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:41 INFO - PROCESS | 1656 | RSS: Main: 245227520 10:17:41 INFO - PROCESS | 1656 | 10:18:32 INFO - PROCESS | 1656 | 1468862312217 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] 10:20:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 10:20:45 INFO - PROCESS | 1656 | RSS: Main: 373088256 10:20:45 INFO - PROCESS | 1656 | 10:23:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 10:23:49 INFO - PROCESS | 1656 | RSS: Main: 389054464 10:23:49 INFO - PROCESS | 1656 | 10:26:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 10:26:51 INFO - PROCESS | 1656 | RSS: Main: 374558720 10:26:51 INFO - PROCESS | 1656 | 10:29:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 10:29:54 INFO - PROCESS | 1656 | RSS: Main: 370102272 10:29:54 INFO - PROCESS | 1656 | 10:32:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 10:32:58 INFO - PROCESS | 1656 | RSS: Main: 341786624 10:32:58 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 10:36:02 INFO - PROCESS | 1656 | RSS: Main: 387428352 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | __start_tp_report 10:36:02 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 10:36:02 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 10:36:02 INFO - PROCESS | 1656 | |i|pagename|runs| 10:36:02 INFO - PROCESS | 1656 | |0;dojo.html;3320.535125082489;3343.8160901150436;3346.916327091824;3366.5791776027995;3404.523366728657;5087.643864929451;5104.846854594363;5469.316335458067;5578.9163162552495;5601.663900165988;5577;5635.295588051494;5639.069553523664;5639.830805075844;5643.435656434365;5131.666441681288;5171.948280517185;5184.7148406837605;5230.21546767984;5231.163013917774;5158.690478571279;5240.475952404767;5245.344331958505;5257.76340064698;5261;5117.1556693145585;5179.715115668656;5186.196139598368;5196.090684130281;5243.4232234454175;5247.947520524803;5283.365996080456;5287.286216360781;5297.337832770903;5336.30628018359;5066.620003499752;5074.086664400406;5106.64253502255;5169.612279079084;5186.662866913639;5444.292242008513;5485.807996720136;5556.277683901108;5602.24369710088;5619.353774315965;6168.568200225986;6173.043178307369;6185.721642526052;6190.907136392958;6192.504599632019;6050.304215015286;6101.4896331263535;6143.723532441006;6245.406686364788;6247.843803904893;5728.026235539968;5744.080947048452;5750.712464376807;5756.42435756421;5756.942430575725;6257.030160325156;6261.5616906816995;6282.905756413657;6288.213973253343;6293.37066293367;5997.220361353084;6039.909401358983;6045;6057.273127224673;6102.352364861463;6239.157713708682;6243.562950593415;6248.187735594343;6249.25008998923;6264.154339164245;6125.693715314305;6169.413905679043;6179.16581261533;6185.288691800411;6206.627602343874;6050.969745151246;6083.574149809471;6097.634141951497;6120.142398366865;6203.100550420164;4860.027994401064;4872.975635121873;4874.2201247800895;4877.609791216694;4913.901721965541;4411.580899814512;4417.293233082692;4417.359482874966;4420.160169567772;4450.1767173073085;5722.685252311163;5726;5732.226149469851;5743.971280143573;5786.508146807569;6052.334243233241;6151;6169.01295792671;6230.563860529719;6232.781852635227;5731.398203188689;5732.541396688255;5750.166225897305;5751.971240143773;5788.855278617984;5372.865678358073;5379.65032272901;5389.191621256843;5392.865178370572;5405.405405405402;6165.630062196283;6215.285242197115;6220.564560480813;6242.438180563771;6253.6560489173535;6232.09634602971;6271.216097987752;6283.308836027942;6287.779927702509;6300.369963003663;6265.937340626718;6266.780662676784;6268.091126786681;6282.371762823681;6282.591631543935;5869.706514674334;5898.8230353089475;6097.5246788036175;6128.203333566607;6141.486007784186;6232.594881332699;6248.4376406123665;6249.937500624936;6252.9374706254175;6280;6055.697215139314;6075.969620151871;6103.8779224414375;6111.113888486234;6122.755089796358;5672.886542269214;5829.125631155361;5863.824085277449;5883.0881213411985;5925.851853703692;5754.992876246556;5770.451807078321;5782.335031471266;5837.124431335334;5850.385709500441;5276.261323414802;5913.940860591339;5920.407959204045;5938.554608404301;5978.970105149447;5820.097884827858;5822.621529600562;5860.120981852586;5882.352941176553;5886.705664716833 10:36:02 INFO - PROCESS | 1656 | |1;ext.html;482.9953696311674;506.38727140160404;523.9816606418775;553.9861503462413;555.0286997753931;30953.535696964536;31418.528722069186;32620;32691.346173076523;32716.99999999997;41650;42358.788206058925;42383.57616423835;44771.77614111925;44820.775896120555;9666.709998700051;9802.264830137732;9817.705468835911;9824.852627210597;9825.65610203643;8743.562821858914;8921.598528066253;9684.854727179063;9798.706038818847;9799.314048016642;11736.178467507278;11904.047676185928;11911.821322680167;12163.695907602292;12205.267683938948;12000.279983200991;12069.456874440628;12087.274763514217;12099;12112.39438028095;8624.827503449958;11453.541858325658;11703.122265830098;11742.647720568311;11810.409479526075;41380.1723965522;41611.167776644295;41627.37558936619;41669;41889.58110418918;3544.078539579728;3611.855525778966;3682.2451397463456;4003.219372222418;4085.1216988347646;31561.526577101362;32912;33075.84234551803;33125.6687433125;33429.3314133716;3269.199046233688;3297.093299342676;3310.784798988058;3330.5670262865915;3371.915702107467;520087;521007.39496302657;521043;522962;525477;57522;57764.71117644385;58021.8395632085;58039.70980145072;58201.70899145562;62043.689781550805;62058.44853878689;62121.68939155366;62262;62332.68833655803;15455.459058932884;15948.441804536786;15952.521424357288;15953.282102305424;15968.920155399148;12425.19236249641;12487.93756031214;12494.125411221308;12516.061295402882;12558.120931534888;13752;14003.43986240539;15355.462558810388;15684.451044213398;15709.685806283811;6303.653299068504;6312.810615681445;7182.2458641842895;7311.268873112646;7361.337479626753;843.5023336231653;896.2740180453853;969.4474149734724;1042.8435734639866;1046.8696203226398;29807.85096074549;30150.547741783892;30325;30991;31355.372892542022;1014.1025192704362;1039.7608550033535;1054.478033373485;1063.8085144673878;1066.3495267886583;15458.5362439127;15573.844261557466;15661.451849185223;15731.370745170065;16011.59971000711;683176;689000;689111;690013;690072;57810.710946445;59490;59586.70206648939;59593.70203149043;59657;1189.1972918280126;1250.9929506746973;1404.8735613794809;1420.7300612883519;1440.488626537585;29798.25504362408;30945.381092377575;31056.91300804461;31483.37033259276;31640.525392118678;48862.75568622134;49305.26042109299;49331.50668493269;49394;49523.752381237864;562347;565296;565313;568082;568177;38767;39200;40096.39855402113;40167;40196.598034020455;47471.76264118657;47740.52259477361;48350.75824620854;48509.75745121252;48951.02097958092;39357.80321098376;39446.40830387604;39967.600323997554;40036.799816000734;41027;39563.81308560748;39815.80092099521;39867.80066099651;40532;40818;38017.61982380141;38577.421338680506;38638.80680596579;38821;39016.804915976376;28456.857715712118;29123.85438072796;32943.50584741279;35084.29831403409;35096.64903351036;24644;24707.25878223656;25102;25489;26276.817543210218 10:36:02 INFO - PROCESS | 1656 | |2;jquery.html;317.4080340165591;361.37121408748754;382.3614563678656;387.30865405251615;409.77052850403777;14832.77750833737;15590.45433409831;20713.78307023541;20848.89575552124;21604.243851465202;20770.68843967338;21328;21359.893200533974;21571.67642485364;21596.8920155399;5437.34751829781;5847.912281315784;5882.647041177543;5979.611325263866;6071.3625069367745;4151.522574903895;4359.171757366106;5941;6593.20881494219;6603.349162709323;6579.17760279965;6606.075149479053;6612.867742645169;6623.933760662404;6676.2989886062;6322.304546499904;6539.182602174728;6572.047053177311;6572.835679108012;6587.011948207759;5437.34751829779;5493.176023596453;5698.34469036062;5700.3159620845345;5701.543876489913;20705.757654540626;25947.870260648764;26547.469050618878;26659;26802;2303.4126297793955;2314;2652.1778248742953;3003.80475269109;3009.48838697422;15333.463328783439;15826.366945322174;16100.75848862268;16130.919345403316;16667.416640417647;1701.7787687600658;1836.8806027608164;1840.8987505687182;1901.610169915164;1920.3470819921154;87359;117093;122399;123708;124944.37527812393;9057.81884362309;9695.466749328714;10493;11041.83437248442;11240.156988225915;12567.748645027048;12763.489460421479;12771.425285862158;12772.297523636289;12802.231866088065;6000.759969601254;7827.686892524349;8053.919460805434;8148.918510814935;8208.958955205186;8001.639926203335;8026.5986700664735;8112.02655681322;8122.472039317426;8183.795405114801;6522.5760325578685;6600.438962688129;6617;6631.701573429207;6642.933570664231;1815.9818401815812;1846.843018343429;1847.5750577367012;1851.6389304085574;1855.5082903030707;823.5882058970515;925.6019911438011;954.5561313989028;1009.5154325923597;1053.1785207538132;20118.39644810658;20277.986100694907;21375.67936480954;22479.55040899173;22546.887265563895;970.4225985538593;974.3715303629119;981.5975450065582;1008.6772232885406;1095.2059756676347;11417.828732569018;11681.415929203506;11897;11909.58316458937;11924.582639607572;460340;586079;592451;610645;615475;140303;150844.49155508523;151872.9999999978;152229;152736;980.1178938954997;995.0944640377222;1002.8595996560488;1024.774549599087;1026.573971801709;18120.093995300445;19257.51856203606;19569.510762231057;19634.410967670992;19738.802611973697;56576.434235657114;57565.42434575768;58931;59716.70141649264;59953.700231498566;71821.28178718146;78056.60971695105;80375.19624803677;87361;88991.55504222437;7823.2567906048835;8176.182381761776;8309;8481.618327175169;8513.74458766238;11120.833187502356;13226.272555009567;13499.932500337436;13520.391582379014;13554.051216414757;11411.942940285246;11488.885111148782;11662.416879156179;11705.941470292593;11740.41297935117;9728.221742260745;10209.489525523843;10991.010809027226;11036.392998384845;11261.042811361127;11225.382603956861;11481.655550333504;11790.705232369259;11886.583969561023;11948.402579871146;8229.218224268685;8245.422820402515;8306.044804847279;8311.709090181814;8487.151284871463;8394;8611.741647750578;11006.394648294421;11643.301401915913;11739.882601173878 10:36:02 INFO - PROCESS | 1656 | |3;mootools.html;1366.7129902720428;1520.369046845559;1560.6176486760742;1756.8067512573623;1916.1473144450717;7739.613019349038;8251.628676709555;8270.421070525066;8536.103709110546;8763.95618021909;8534.061253262136;8632.17994290543;8704.173103555167;8742.038375778675;8746.12538746125;2362.9881850590723;2401.2196036288187;2401.7838394544488;2404.3147702904675;2405.807535397164;2359.9410014749683;2381.345130089222;2396.9161079362225;2400.303911865557;2400.843945143569;1996.850236232281;2334.2647066174186;2354.564405584972;2357.834951553392;2383.3445802404303;2295.162265772981;2317.06159005603;2328.5924963131383;2329.9301020969315;2359.9970012744516;2338.5968418948664;2361.881905904715;2364.680768096302;2364.8581085134947;2365.1958334166293;8775;8783.429077109968;8850.645974161025;8890.244329232022;8892.599833007465;4283;5381.919271210935;5384.946150538483;5413.242146099549;5416.783328666848;3585.766925149857;3680.2823449427374;3741.625837416264;3743.2513497300647;3744.0826997385816;1062.5920655131981;1072.5816931396762;1086.7391825961713;1099.060303440555;1099.8451625792886;11590.188686791927;11697.88302116985;11710.180287379886;11715.882841171564;11731.76536469266;4435.536273029892;4446.466424029072;4635.119327327797;4642.953570464252;4677.859664210079;9188.402753821114;9198.080191980882;9203.309751768644;9215.907840921505;9217.631294748135;5230.660007099526;5246.396664383568;5253.290805741176;5268.157094864803;5285.2600635911;4379.978100109479;4382.298832186899;4513.526079761577;4887.7556122194455;4922.1140194765285;3355.2282974915906;3808.961910380916;3870.3613903705573;3886.9805650971566;3922.764634121962;1141.7088642396302;1147.145376694368;1148.626696323698;1153.7577108807077;1154.96535103947;909.5497728624373;918.4856480371013;934.7522906429801;938.9671361502315;992.9950350248203;5426.213199086189;5859.2382990211;5861.827634473122;6046.2139921809885;6203.976343903296;378.251062895463;430.9418228539107;440.5528388685487;444.0275796006799;454.20740807291486;4006.45912801768;4063.89840253996;4086.7547947123267;4119.464469618931;4166.958330416717;10371.014753598396;10893;10895.509702063426;10907.509162087725;10907.781844363228;10776;10859.2398532102;10870.565177392815;10887.727806804893;10888.78222435547;8260.628271727786;8324.25081742634;8339.8332033359;8355.874661880076;8373.45572537771;5841.795537156179;5942.762289508544;5964.7912323068485;6093.48205402528;6225.657588832477;8908.73273801787;10108.191344692259;10110.646127385506;10135.847962280424;10211.642592509226;9269.6292148316;9631.08504692053;10126.088652021353;10258;10288.2798204128;7343.632818358954;7602.961985190039;7611.706009978207;7686.807829804299;7711.8072048197;8080.797980050545;8399.832003360021;8428.4942903426;8514.78713032179;8528.104549022511;7436.144843343085;7486.663100160615;7587.468877178544;7607.847843043219;7675.347595454449;6278.4349408551625;7432.442566807403;7445.218252083453;7561.243875612395;7572.356349710336;6949.131358580177;7056.435485161277;7076.71693132269;7076.964615176891;7086.716531338689;4445.355423463644;5044.369453818273;5073.746312684425;5097.1844504879045;5233.712145832016;5107.259447380184;5111.412187598474;5159.17453207485;5171.250168725589;5328.3605967284175 10:36:02 INFO - PROCESS | 1656 | |4;prototype.html;302.352964655637;311.98752049918005;313.68631368631367;316.88275338124885;320.6328753577156;4515.622735065804;5373.838784836456;5421.241026256323;5449.182622606611;5465.961467321211;5246.475352464751;5288.629795914288;5348.251244825718;5362.168863826103;5369.382521010075;3334.2997970426118;3849.9230015399676;3917.2165566886592;4134.400511925784;4156.314208155666;4070.9796451017705;4096;4104.917901641966;4138.951219146512;4160.981923766971;3568.2506673598577;3737.4025052588463;3737.8878633641034;3901.804909754515;4103.753774773509;3806.7715937043868;3869.342211823983;4006.5392479864895;4086.959130408717;4088.1823635273063;3127.8592463339205;3539.4513850353237;3544.929101417957;3549.8757543486113;3553.875614353511;4829.975850120762;5032.3896353167;5219.921701174486;5240.558846317255;5245.347715469653;3972.165845172517;4514.571115744032;4640.95359046412;4672.135654903854;4684.039771846763;4824.721448816066;4824.782884770194;5427.348718153808;5532.6680399176175;5558.888822223574;3290.3090351026312;3929.057026313693;3944.4361165242535;3948.2498325318097;3951.723379363446;5350.662334416396;5402.37872644647;5866.50134738552;5925.525957923356;5942.465178133989;3955.4264631628253;3971.860984865516;4003.2393845169327;4007.1785284016705;4010.7593544387432;3532.156613925763;3947.822347994347;4056.553779084298;4058.5941405859767;4088.7546747195265;4479.394290754301;4580.450345958507;4653.976730116396;4681.976590117028;4691.202495575761;5586.944130558723;5600.663960162403;5602;5652.6325788824415;5668.461496157858;1303.67408147963;1362.8909687224998;1382.6059573021842;1394.4282844033899;1398.5384823008383;751.4551949836434;809.5183365897282;843.9493630382074;851.8764779107119;869.8303830753069;5036.7733451994745;5062.797488100435;5147.124988751922;5244.606654500928;5264.236685680555;5416.377116631559;5730.455606717355;5756.884862302731;5757.8560535986935;5762.337331206881;4854.68444551103;4945.727984960861;5252.923150754163;5271.551918086929;5335.573154147659;2653.3366658335417;2939.9706002940125;2996.715312045352;3032.4238394705376;3032.5147976323683;4824.421069471686;4998.900241946766;5975.40245975399;5995.940040599626;5998.890205312031;5600.695589602073;5748.942510574924;5909.704514774244;6007.309159446632;6019.729112189962;4843.515648435128;5305.549028332558;6041.667708275999;6048.213732214784;6113.877722445615;4968.248972026242;4976.859950221295;5260.316158899319;5657.9151312731165;5828.406359170129;4785.774614804132;4934.333864928189;5238.768889311085;5734.741936612779;5737.311522617312;5260;6022.608530445507;6086.573939824169;6097.146399503984;6112.8777424452155;3440.7935523867645;3812.0088776917646;3905.9218815624095;3906.1797022625565;3915.7258991870294;4605.7466839322815;4973.975130124326;5781.768729250783;5795.536357091507;5813.302403711582;4331.935020974624;5525.640833345819;5654.717264136859;5678.801241956512;5752.798652047326;5483.341998960151;5497.581064331681;5650.29371328584;5679.034564124191;5681.687507186979;5233.136532472252;5572.3034620672415;5573.972130139324;5575.358833734173;5583.832485025456;4306.569343065668;4659.883502912454;5196.766145523537;5625.481120097469;5661.235733175969;5012.248162775612;5053.570446512088;5103.800606857311;5192.948070519246;5525.834224973257 10:36:02 INFO - PROCESS | 1656 | |5;yui.html;728.8141523911402;757.0725860820493;778.9415793815465;835.8662613981764;845.8900342955417;15512.84487155128;16421.589460263498;16473.176341182952;16480.34078636853;16489.917550412247;14838.703225935475;14858.47995320164;14988.325525351358;14999.85000149998;15005.249737513135;699.0353312428841;724.7789424225609;873.6549063120063;900.7612982559627;905.8822353094089;721.0914248047471;722.0649259209337;722.5881247688819;724.3010494872447;871.3029576338904;956.7225504603657;959.1319855530721;959.3859929645033;961.3462845265217;961.6586111930269;939.4504215034214;952.1716106986896;952.5808644196532;953.4755884263627;953.6471505542925;548.2078396716746;613.4601550635457;707.9115110611174;709.2446544430165;709.3792931185213;15230.238488075664;15270.38918443261;15274.923625381913;15277.770833437507;15297.158656273901;13966.441342346294;14302.213378264192;14397.712045759026;14414.783778243334;14503.70992580153;12382.442790074378;13020.804687929689;13180.275084870329;13264.933675331658;13640.590782276548;3983.721139520235;4591.517890621503;4775.6657034007285;4819.879503012417;4836.951630483721;99217;103242.48378758134;109992.4500377501;110058.44970775174;114040;10834.133269338434;11109.388983605817;11132.888671113347;11147.442627868737;11342.546298147981;21940.341789746013;22233.777662223496;22262.55474890493;22268.99789509476;22275.331740047826;7700.730474433368;7722.189170137167;7733.033370828646;7817.335526480313;7963.203679632106;4284.4430224070675;4337.132573485315;4344.782760861945;4349.891252718644;4515.593596576324;3553.36039512885;3633.2733453309443;3674.412094064937;3679.411294192916;3681.5398075240596;660.6927778582982;667.1160712056544;668.5119862500402;674.5682763031664;688.4114082459525;553.3636318234063;625.7715933684264;642.8649983503517;644.8387903024244;647.1231481342713;9691.466969316756;9750.756231094138;9759.658411955548;9772.462514561625;9779.755506112404;516.2926790297316;626.8620903401245;645.7094307561616;669.1301308299192;693.4209934704477;4413.13943780966;4651.90680190155;4681.578657920735;4695.835645752382;4718.386609740712;128518;148072.25963870113;165484;167981;168555.1572242131;140940.29529852286;148306;148918;151092.2445387766;153001;124829;124997;126514;126819.36590316989;127463;9599.232061434928;9804.068613481708;9847.31068825204;9951.900480995097;9995.350302230332;51689.741551292005;59697.80604387975;63261.05108423469;67224.66387668031;67445;87747;100971.3785776778;101699;103142;104297.47851260695;13505.729885402434;13642;13644.454221831016;14154.646133846736;14392.928035359757;14934;14992.850071499146;15006.6998660024;15453.072815631098;15454.99542529732;9890.901090989286;14197.148171109766;14241.857581424467;14421.7836732447;14802.62993425173;13796.586102416944;13901.235432051335;14010.719785604027;14197.503087391891;14576.271186440423;13771.724565508834;13938.651533711738;13953.162810231419;14074.437022519394;14500.419983200976;10710;11223.887761122285;11357.261777984404;11564.826527601925;11889.821652675044;10209.84685229737;10298.176145908159;11092.223544351818;11304.265222760494;11440.256383335056 10:36:02 INFO - PROCESS | 1656 | __end_tp_report 10:36:02 INFO - PROCESS | 1656 | __start_cc_report 10:36:02 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1274 10:36:02 INFO - PROCESS | 1656 | __end_cc_report 10:36:02 INFO - PROCESS | 1656 | __startTimestamp1468863362311__endTimestamp 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | ------- Summary: start ------- 10:36:02 INFO - PROCESS | 1656 | Number of tests: 6 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5686.18 Median:5829.13 stddev:625.36 (10.7%) stddev-sans-first:599.11 10:36:02 INFO - PROCESS | 1656 | Values: 3320.5 3343.8 3346.9 3366.6 3404.5 5087.6 5104.8 5469.3 5578.9 5601.7 5577.0 5635.3 5639.1 5639.8 5643.4 5131.7 5171.9 5184.7 5230.2 5231.2 5158.7 5240.5 5245.3 5257.8 5261.0 5117.2 5179.7 5186.2 5196.1 5243.4 5247.9 5283.4 5287.3 5297.3 5336.3 5066.6 5074.1 5106.6 5169.6 5186.7 5444.3 5485.8 5556.3 5602.2 5619.4 6168.6 6173.0 6185.7 6190.9 6192.5 6050.3 6101.5 6143.7 6245.4 6247.8 5728.0 5744.1 5750.7 5756.4 5756.9 6257.0 6261.6 6282.9 6288.2 6293.4 5997.2 6039.9 6045.0 6057.3 6102.4 6239.2 6243.6 6248.2 6249.3 6264.2 6125.7 6169.4 6179.2 6185.3 6206.6 6051.0 6083.6 6097.6 6120.1 6203.1 4860.0 4873.0 4874.2 4877.6 4913.9 4411.6 4417.3 4417.4 4420.2 4450.2 5722.7 5726.0 5732.2 5744.0 5786.5 6052.3 6151.0 6169.0 6230.6 6232.8 5731.4 5732.5 5750.2 5752.0 5788.9 5372.9 5379.7 5389.2 5392.9 5405.4 6165.6 6215.3 6220.6 6242.4 6253.7 6232.1 6271.2 6283.3 6287.8 6300.4 6265.9 6266.8 6268.1 6282.4 6282.6 5869.7 5898.8 6097.5 6128.2 6141.5 6232.6 6248.4 6249.9 6252.9 6280.0 6055.7 6076.0 6103.9 6111.1 6122.8 5672.9 5829.1 5863.8 5883.1 5925.9 5755.0 5770.5 5782.3 5837.1 5850.4 5276.3 5913.9 5920.4 5938.6 5979.0 5820.1 5822.6 5860.1 5882.4 5886.7 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:72531.90 Median:39278.90 stddev:159408.16 (405.8%) stddev-sans-first:159763.55 10:36:02 INFO - PROCESS | 1656 | Values: 483.0 506.4 524.0 554.0 555.0 30953.5 31418.5 32620.0 32691.3 32717.0 41650.0 42358.8 42383.6 44771.8 44820.8 9666.7 9802.3 9817.7 9824.9 9825.7 8743.6 8921.6 9684.9 9798.7 9799.3 11736.2 11904.0 11911.8 12163.7 12205.3 12000.3 12069.5 12087.3 12099.0 12112.4 8624.8 11453.5 11703.1 11742.6 11810.4 41380.2 41611.2 41627.4 41669.0 41889.6 3544.1 3611.9 3682.2 4003.2 4085.1 31561.5 32912.0 33075.8 33125.7 33429.3 3269.2 3297.1 3310.8 3330.6 3371.9 520087.0 521007.4 521043.0 522962.0 525477.0 57522.0 57764.7 58021.8 58039.7 58201.7 62043.7 62058.4 62121.7 62262.0 62332.7 15455.5 15948.4 15952.5 15953.3 15968.9 12425.2 12487.9 12494.1 12516.1 12558.1 13752.0 14003.4 15355.5 15684.5 15709.7 6303.7 6312.8 7182.2 7311.3 7361.3 843.5 896.3 969.4 1042.8 1046.9 29807.9 30150.5 30325.0 30991.0 31355.4 1014.1 1039.8 1054.5 1063.8 1066.3 15458.5 15573.8 15661.5 15731.4 16011.6 683176.0 689000.0 689111.0 690013.0 690072.0 57810.7 59490.0 59586.7 59593.7 59657.0 1189.2 1251.0 1404.9 1420.7 1440.5 29798.3 30945.4 31056.9 31483.4 31640.5 48862.8 49305.3 49331.5 49394.0 49523.8 562347.0 565296.0 565313.0 568082.0 568177.0 38767.0 39200.0 40096.4 40167.0 40196.6 47471.8 47740.5 48350.8 48509.8 48951.0 39357.8 39446.4 39967.6 40036.8 41027.0 39563.8 39815.8 39867.8 40532.0 40818.0 38017.6 38577.4 38638.8 38821.0 39016.8 28456.9 29123.9 32943.5 35084.3 35096.6 24644.0 24707.3 25102.0 25489.0 26276.8 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35285.37 Median:14599.82 stddev:97001.15 (664.4%) stddev-sans-first:97237.73 10:36:02 INFO - PROCESS | 1656 | Values: 317.4 361.4 382.4 387.3 409.8 14832.8 15590.5 20713.8 20848.9 21604.2 20770.7 21328.0 21359.9 21571.7 21596.9 5437.3 5847.9 5882.6 5979.6 6071.4 4151.5 4359.2 5941.0 6593.2 6603.3 6579.2 6606.1 6612.9 6623.9 6676.3 6322.3 6539.2 6572.0 6572.8 6587.0 5437.3 5493.2 5698.3 5700.3 5701.5 20705.8 25947.9 26547.5 26659.0 26802.0 2303.4 2314.0 2652.2 3003.8 3009.5 15333.5 15826.4 16100.8 16130.9 16667.4 1701.8 1836.9 1840.9 1901.6 1920.3 87359.0 117093.0 122399.0 123708.0 124944.4 9057.8 9695.5 10493.0 11041.8 11240.2 12567.7 12763.5 12771.4 12772.3 12802.2 6000.8 7827.7 8053.9 8148.9 8209.0 8001.6 8026.6 8112.0 8122.5 8183.8 6522.6 6600.4 6617.0 6631.7 6642.9 1816.0 1846.8 1847.6 1851.6 1855.5 823.6 925.6 954.6 1009.5 1053.2 20118.4 20278.0 21375.7 22479.6 22546.9 970.4 974.4 981.6 1008.7 1095.2 11417.8 11681.4 11897.0 11909.6 11924.6 460340.0 586079.0 592451.0 610645.0 615475.0 140303.0 150844.5 151873.0 152229.0 152736.0 980.1 995.1 1002.9 1024.8 1026.6 18120.1 19257.5 19569.5 19634.4 19738.8 56576.4 57565.4 58931.0 59716.7 59953.7 71821.3 78056.6 80375.2 87361.0 88991.6 7823.3 8176.2 8309.0 8481.6 8513.7 11120.8 13226.3 13499.9 13520.4 13554.1 11411.9 11488.9 11662.4 11705.9 11740.4 9728.2 10209.5 10991.0 11036.4 11261.0 11225.4 11481.7 11790.7 11886.6 11948.4 8229.2 8245.4 8306.0 8311.7 8487.2 8394.0 8611.7 11006.4 11643.3 11739.9 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5682.14 Median:4660.41 stddev:3205.14 (68.8%) stddev-sans-first:3197.72 10:36:02 INFO - PROCESS | 1656 | Values: 1366.7 1520.4 1560.6 1756.8 1916.1 7739.6 8251.6 8270.4 8536.1 8764.0 8534.1 8632.2 8704.2 8742.0 8746.1 2363.0 2401.2 2401.8 2404.3 2405.8 2359.9 2381.3 2396.9 2400.3 2400.8 1996.9 2334.3 2354.6 2357.8 2383.3 2295.2 2317.1 2328.6 2329.9 2360.0 2338.6 2361.9 2364.7 2364.9 2365.2 8775.0 8783.4 8850.6 8890.2 8892.6 4283.0 5381.9 5384.9 5413.2 5416.8 3585.8 3680.3 3741.6 3743.3 3744.1 1062.6 1072.6 1086.7 1099.1 1099.8 11590.2 11697.9 11710.2 11715.9 11731.8 4435.5 4446.5 4635.1 4643.0 4677.9 9188.4 9198.1 9203.3 9215.9 9217.6 5230.7 5246.4 5253.3 5268.2 5285.3 4380.0 4382.3 4513.5 4887.8 4922.1 3355.2 3809.0 3870.4 3887.0 3922.8 1141.7 1147.1 1148.6 1153.8 1155.0 909.5 918.5 934.8 939.0 993.0 5426.2 5859.2 5861.8 6046.2 6204.0 378.3 430.9 440.6 444.0 454.2 4006.5 4063.9 4086.8 4119.5 4167.0 10371.0 10893.0 10895.5 10907.5 10907.8 10776.0 10859.2 10870.6 10887.7 10888.8 8260.6 8324.3 8339.8 8355.9 8373.5 5841.8 5942.8 5964.8 6093.5 6225.7 8908.7 10108.2 10110.6 10135.8 10211.6 9269.6 9631.1 10126.1 10258.0 10288.3 7343.6 7603.0 7611.7 7686.8 7711.8 8080.8 8399.8 8428.5 8514.8 8528.1 7436.1 7486.7 7587.5 7607.8 7675.3 6278.4 7432.4 7445.2 7561.2 7572.4 6949.1 7056.4 7076.7 7077.0 7086.7 4445.4 5044.4 5073.7 5097.2 5233.7 5107.3 5111.4 5159.2 5171.3 5328.4 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4536.43 Median:5045.17 stddev:1390.18 (27.6%) stddev-sans-first:1357.27 10:36:02 INFO - PROCESS | 1656 | Values: 302.4 312.0 313.7 316.9 320.6 4515.6 5373.8 5421.2 5449.2 5466.0 5246.5 5288.6 5348.3 5362.2 5369.4 3334.3 3849.9 3917.2 4134.4 4156.3 4071.0 4096.0 4104.9 4139.0 4161.0 3568.3 3737.4 3737.9 3901.8 4103.8 3806.8 3869.3 4006.5 4087.0 4088.2 3127.9 3539.5 3544.9 3549.9 3553.9 4830.0 5032.4 5219.9 5240.6 5245.3 3972.2 4514.6 4641.0 4672.1 4684.0 4824.7 4824.8 5427.3 5532.7 5558.9 3290.3 3929.1 3944.4 3948.2 3951.7 5350.7 5402.4 5866.5 5925.5 5942.5 3955.4 3971.9 4003.2 4007.2 4010.8 3532.2 3947.8 4056.6 4058.6 4088.8 4479.4 4580.5 4654.0 4682.0 4691.2 5586.9 5600.7 5602.0 5652.6 5668.5 1303.7 1362.9 1382.6 1394.4 1398.5 751.5 809.5 843.9 851.9 869.8 5036.8 5062.8 5147.1 5244.6 5264.2 5416.4 5730.5 5756.9 5757.9 5762.3 4854.7 4945.7 5252.9 5271.6 5335.6 2653.3 2940.0 2996.7 3032.4 3032.5 4824.4 4998.9 5975.4 5995.9 5998.9 5600.7 5748.9 5909.7 6007.3 6019.7 4843.5 5305.5 6041.7 6048.2 6113.9 4968.2 4976.9 5260.3 5657.9 5828.4 4785.8 4934.3 5238.8 5734.7 5737.3 5260.0 6022.6 6086.6 6097.1 6112.9 3440.8 3812.0 3905.9 3906.2 3915.7 4605.7 4974.0 5781.8 5795.5 5813.3 4331.9 5525.6 5654.7 5678.8 5752.8 5483.3 5497.6 5650.3 5679.0 5681.7 5233.1 5572.3 5574.0 5575.4 5583.8 4306.6 4659.9 5196.8 5625.5 5661.2 5012.2 5053.6 5103.8 5192.9 5525.8 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:26406.60 Median:22272.16 stddev:42938.92 (192.8%) stddev-sans-first:43016.09 10:36:02 INFO - PROCESS | 1656 | Values: 728.8 757.1 778.9 835.9 845.9 15512.8 16421.6 16473.2 16480.3 16489.9 14838.7 14858.5 14988.3 14999.9 15005.2 699.0 724.8 873.7 900.8 905.9 721.1 722.1 722.6 724.3 871.3 956.7 959.1 959.4 961.3 961.7 939.5 952.2 952.6 953.5 953.6 548.2 613.5 707.9 709.2 709.4 15230.2 15270.4 15274.9 15277.8 15297.2 13966.4 14302.2 14397.7 14414.8 14503.7 12382.4 13020.8 13180.3 13264.9 13640.6 3983.7 4591.5 4775.7 4819.9 4837.0 99217.0 103242.5 109992.5 110058.4 114040.0 10834.1 11109.4 11132.9 11147.4 11342.5 21940.3 22233.8 22262.6 22269.0 22275.3 7700.7 7722.2 7733.0 7817.3 7963.2 4284.4 4337.1 4344.8 4349.9 4515.6 3553.4 3633.3 3674.4 3679.4 3681.5 660.7 667.1 668.5 674.6 688.4 553.4 625.8 642.9 644.8 647.1 9691.5 9750.8 9759.7 9772.5 9779.8 516.3 626.9 645.7 669.1 693.4 4413.1 4651.9 4681.6 4695.8 4718.4 128518.0 148072.3 165484.0 167981.0 168555.2 140940.3 148306.0 148918.0 151092.2 153001.0 124829.0 124997.0 126514.0 126819.4 127463.0 9599.2 9804.1 9847.3 9951.9 9995.4 51689.7 59697.8 63261.1 67224.7 67445.0 87747.0 100971.4 101699.0 103142.0 104297.5 13505.7 13642.0 13644.5 14154.6 14392.9 14934.0 14992.9 15006.7 15453.1 15455.0 9890.9 14197.1 14241.9 14421.8 14802.6 13796.6 13901.2 14010.7 14197.5 14576.3 13771.7 13938.7 13953.2 14074.4 14500.4 10710.0 11223.9 11357.3 11564.8 11889.8 10209.8 10298.2 11092.2 11304.3 11440.3 10:36:02 INFO - PROCESS | 1656 | -------- Summary: end -------- 10:36:02 INFO - PROCESS | 1656 | 10:36:02 INFO - TEST-INFO | 1656: exit 0 10:36:02 INFO - TEST-OK | dromaeo_css | took 1116890ms 10:36:02 INFO - TEST-START | kraken 10:36:02 INFO - operating with platform_type : mac_ 10:36:02 INFO - Initialising browser for kraken test... 10:36:02 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpK4pJNB/profile http://localhost:49275/getInfo.html) 10:36:05 INFO - PROCESS | 1663 | JavaScript error: , line 0: uncaught exception: undefined 10:36:05 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:36:05 INFO - PROCESS | 1663 | colorDepth:24 10:36:05 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:36:05 INFO - PROCESS | 1663 | __metrics 10:36:05 INFO - PROCESS | 1663 | 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" 10:36:05 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:36:06 INFO - TEST-INFO | 1663: exit 0 10:36:06 INFO - Browser initialized. 10:36:06 INFO - Running cycle 1/1 for kraken test... 10:36:06 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpK4pJNB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:36:18 INFO - PROCESS | 1664 | RSS: Main: 245161984 10:36:18 INFO - PROCESS | 1664 | 10:36:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 10:36:21 INFO - PROCESS | 1664 | RSS: Main: 293834752 10:36:21 INFO - PROCESS | 1664 | 10:36:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 10:36:24 INFO - PROCESS | 1664 | RSS: Main: 300859392 10:36:24 INFO - PROCESS | 1664 | 10:36:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 10:36:29 INFO - PROCESS | 1664 | RSS: Main: 388751360 10:36:29 INFO - PROCESS | 1664 | 10:36:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 10:36:31 INFO - PROCESS | 1664 | RSS: Main: 290889728 10:36:31 INFO - PROCESS | 1664 | 10:36:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:36:34 INFO - PROCESS | 1664 | RSS: Main: 295006208 10:36:34 INFO - PROCESS | 1664 | 10:36:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 10:36:37 INFO - PROCESS | 1664 | RSS: Main: 334331904 10:36:37 INFO - PROCESS | 1664 | 10:36:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 10:36:41 INFO - PROCESS | 1664 | RSS: Main: 334106624 10:36:41 INFO - PROCESS | 1664 | 10:36:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 10:36:44 INFO - PROCESS | 1664 | RSS: Main: 330575872 10:36:44 INFO - PROCESS | 1664 | 10:36:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:36:46 INFO - PROCESS | 1664 | RSS: Main: 311484416 10:36:46 INFO - PROCESS | 1664 | 10:36:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:36:49 INFO - PROCESS | 1664 | RSS: Main: 317239296 10:36:49 INFO - PROCESS | 1664 | 10:36:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:36:52 INFO - PROCESS | 1664 | RSS: Main: 320888832 10:36:52 INFO - PROCESS | 1664 | 10:36:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:36:55 INFO - PROCESS | 1664 | RSS: Main: 324620288 10:36:55 INFO - PROCESS | 1664 | 10:36:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:36:59 INFO - PROCESS | 1664 | RSS: Main: 327397376 10:36:59 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 10:37:01 INFO - PROCESS | 1664 | RSS: Main: 370896896 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | __start_tp_report 10:37:01 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 10:37:01 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 10:37:01 INFO - PROCESS | 1664 | |i|pagename|runs| 10:37:01 INFO - PROCESS | 1664 | |0;ai-astar;101;113;113;112;114;113;112;113;112;114 10:37:01 INFO - PROCESS | 1664 | |1;audio-beat-detection;149;114;108;162;131;136;132;106;164;130 10:37:01 INFO - PROCESS | 1664 | |2;audio-dft;187;181;187;182;184;181;184;185;181;186 10:37:01 INFO - PROCESS | 1664 | |3;audio-fft;76;58;58;109;64;79;90;62;84;56 10:37:01 INFO - PROCESS | 1664 | |4;audio-oscillator;86;70;92;72;71;69;99;70;68;102 10:37:01 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;84;87;84;87;83;87;84;88;83 10:37:01 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;93;94;92;93;92;93;98;88;121 10:37:01 INFO - PROCESS | 1664 | |7;imaging-desaturate;85;84;87;87;85;85;87;85;83;83 10:37:01 INFO - PROCESS | 1664 | |8;json-parse-financial;52;52;54;54;53;54;53;53;53;51 10:37:01 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;66;78;77;72;69;72;71;71;73 10:37:01 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;86;84;84;85;79;85;85;84;83;87 10:37:01 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;138;150;145;145;135;138;143;144;230;144 10:37:01 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;199;220;167;163;164;160;164;162;217;215 10:37:01 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;65;67;66;65;66;66;60;58;77 10:37:01 INFO - PROCESS | 1664 | __end_tp_report 10:37:01 INFO - PROCESS | 1664 | __start_cc_report 10:37:01 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2079 10:37:01 INFO - PROCESS | 1664 | __end_cc_report 10:37:01 INFO - PROCESS | 1664 | __startTimestamp1468863421571__endTimestamp 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | ------- Summary: start ------- 10:37:01 INFO - PROCESS | 1664 | Number of tests: 14 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.83 (3.4%) stddev-sans-first:0.78 10:37:01 INFO - PROCESS | 1664 | Values: 101.0 113.0 113.0 112.0 114.0 113.0 112.0 113.0 112.0 114.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:134.00 stddev:20.54 (15.3%) stddev-sans-first:20.97 10:37:01 INFO - PROCESS | 1664 | Values: 149.0 114.0 108.0 162.0 131.0 136.0 132.0 106.0 164.0 130.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:2.44 (1.3%) stddev-sans-first:2.30 10:37:01 INFO - PROCESS | 1664 | Values: 187.0 181.0 187.0 182.0 184.0 181.0 184.0 185.0 181.0 186.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.60 Median:70.00 stddev:17.28 (24.7%) stddev-sans-first:18.31 10:37:01 INFO - PROCESS | 1664 | Values: 76.0 58.0 58.0 109.0 64.0 79.0 90.0 62.0 84.0 56.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:71.50 stddev:13.48 (18.9%) stddev-sans-first:14.11 10:37:01 INFO - PROCESS | 1664 | Values: 86.0 70.0 92.0 72.0 71.0 69.0 99.0 70.0 68.0 102.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:2.07 (2.4%) stddev-sans-first:1.99 10:37:01 INFO - PROCESS | 1664 | Values: 88.0 84.0 87.0 84.0 87.0 83.0 87.0 84.0 88.0 83.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:93.00 stddev:9.21 (9.9%) stddev-sans-first:9.72 10:37:01 INFO - PROCESS | 1664 | Values: 93.0 93.0 94.0 92.0 93.0 92.0 93.0 98.0 88.0 121.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.52 (1.8%) stddev-sans-first:1.62 10:37:01 INFO - PROCESS | 1664 | Values: 85.0 84.0 87.0 87.0 85.0 85.0 87.0 85.0 83.0 83.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.99 (1.9%) stddev-sans-first:1.00 10:37:01 INFO - PROCESS | 1664 | Values: 52.0 52.0 54.0 54.0 53.0 54.0 53.0 53.0 53.0 51.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:3.84 (5.3%) stddev-sans-first:3.69 10:37:01 INFO - PROCESS | 1664 | Values: 67.0 66.0 78.0 77.0 72.0 69.0 72.0 71.0 71.0 73.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:2.15 (2.5%) stddev-sans-first:2.18 10:37:01 INFO - PROCESS | 1664 | Values: 86.0 84.0 84.0 85.0 79.0 85.0 85.0 84.0 83.0 87.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:144.50 stddev:28.03 (19.4%) stddev-sans-first:29.32 10:37:01 INFO - PROCESS | 1664 | Values: 138.0 150.0 145.0 145.0 135.0 138.0 143.0 144.0 230.0 144.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:183.00 stddev:26.15 (14.3%) stddev-sans-first:27.09 10:37:01 INFO - PROCESS | 1664 | Values: 199.0 220.0 167.0 163.0 164.0 160.0 164.0 162.0 217.0 215.0 10:37:01 INFO - PROCESS | 1664 | 10:37:01 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:4.97 (7.5%) stddev-sans-first:5.27 10:37:01 INFO - PROCESS | 1664 | Values: 66.0 65.0 67.0 66.0 65.0 66.0 66.0 60.0 58.0 77.0 10:37:01 INFO - PROCESS | 1664 | -------- Summary: end -------- 10:37:01 INFO - PROCESS | 1664 | 10:37:02 INFO - TEST-INFO | 1664: exit 0 10:37:02 INFO - TEST-OK | kraken | took 59628ms 10:37:02 INFO - SUITE-END | took 1176s 10:37:02 INFO - Completed test suite (00:19:36) 10:37:02 INFO - javascript benchmark 10:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.535125082489, 3343.8160901150436, 3346.916327091824, 3366.5791776027995, 3404.523366728657, 5087.643864929451, 5104.846854594363, 5469.316335458067, 5578.9163162552495, 5601.663900165988, 5577.0, 5635.295588051494, 5639.069553523664, 5639.830805075844, 5643.435656434365, 5131.666441681288, 5171.948280517185, 5184.7148406837605, 5230.21546767984, 5231.163013917774, 5158.690478571279, 5240.475952404767, 5245.344331958505, 5257.76340064698, 5261.0, 5117.1556693145585, 5179.715115668656, 5186.196139598368, 5196.090684130281, 5243.4232234454175, 5247.947520524803, 5283.365996080456, 5287.286216360781, 5297.337832770903, 5336.30628018359, 5066.620003499752, 5074.086664400406, 5106.64253502255, 5169.612279079084, 5186.662866913639, 5444.292242008513, 5485.807996720136, 5556.277683901108, 5602.24369710088, 5619.353774315965, 6168.568200225986, 6173.043178307369, 6185.721642526052, 6190.907136392958, 6192.504599632019, 6050.304215015286, 6101.4896331263535, 6143.723532441006, 6245.406686364788, 6247.843803904893, 5728.026235539968, 5744.080947048452, 5750.712464376807, 5756.42435756421, 5756.942430575725, 6257.030160325156, 6261.5616906816995, 6282.905756413657, 6288.213973253343, 6293.37066293367, 5997.220361353084, 6039.909401358983, 6045.0, 6057.273127224673, 6102.352364861463, 6239.157713708682, 6243.562950593415, 6248.187735594343, 6249.25008998923, 6264.154339164245, 6125.693715314305, 6169.413905679043, 6179.16581261533, 6185.288691800411, 6206.627602343874, 6050.969745151246, 6083.574149809471, 6097.634141951497, 6120.142398366865, 6203.100550420164, 4860.027994401064, 4872.975635121873, 4874.2201247800895, 4877.609791216694, 4913.901721965541, 4411.580899814512, 4417.293233082692, 4417.359482874966, 4420.160169567772, 4450.1767173073085, 5722.685252311163, 5726.0, 5732.226149469851, 5743.971280143573, 5786.508146807569, 6052.334243233241, 6151.0, 6169.01295792671, 6230.563860529719, 6232.781852635227, 5731.398203188689, 5732.541396688255, 5750.166225897305, 5751.971240143773, 5788.855278617984, 5372.865678358073, 5379.65032272901, 5389.191621256843, 5392.865178370572, 5405.405405405402, 6165.630062196283, 6215.285242197115, 6220.564560480813, 6242.438180563771, 6253.6560489173535, 6232.09634602971, 6271.216097987752, 6283.308836027942, 6287.779927702509, 6300.369963003663, 6265.937340626718, 6266.780662676784, 6268.091126786681, 6282.371762823681, 6282.591631543935, 5869.706514674334, 5898.8230353089475, 6097.5246788036175, 6128.203333566607, 6141.486007784186, 6232.594881332699, 6248.4376406123665, 6249.937500624936, 6252.9374706254175, 6280.0, 6055.697215139314, 6075.969620151871, 6103.8779224414375, 6111.113888486234, 6122.755089796358, 5672.886542269214, 5829.125631155361, 5863.824085277449, 5883.0881213411985, 5925.851853703692, 5754.992876246556, 5770.451807078321, 5782.335031471266, 5837.124431335334, 5850.385709500441, 5276.261323414802, 5913.940860591339, 5920.407959204045, 5938.554608404301, 5978.970105149447, 5820.097884827858, 5822.621529600562, 5860.120981852586, 5882.352941176553, 5886.705664716833], "value": 5646.230808734587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.9953696311674, 506.38727140160404, 523.9816606418775, 553.9861503462413, 555.0286997753931, 30953.535696964536, 31418.528722069186, 32620.0, 32691.346173076523, 32716.99999999997, 41650.0, 42358.788206058925, 42383.57616423835, 44771.77614111925, 44820.775896120555, 9666.709998700051, 9802.264830137732, 9817.705468835911, 9824.852627210597, 9825.65610203643, 8743.562821858914, 8921.598528066253, 9684.854727179063, 9798.706038818847, 9799.314048016642, 11736.178467507278, 11904.047676185928, 11911.821322680167, 12163.695907602292, 12205.267683938948, 12000.279983200991, 12069.456874440628, 12087.274763514217, 12099.0, 12112.39438028095, 8624.827503449958, 11453.541858325658, 11703.122265830098, 11742.647720568311, 11810.409479526075, 41380.1723965522, 41611.167776644295, 41627.37558936619, 41669.0, 41889.58110418918, 3544.078539579728, 3611.855525778966, 3682.2451397463456, 4003.219372222418, 4085.1216988347646, 31561.526577101362, 32912.0, 33075.84234551803, 33125.6687433125, 33429.3314133716, 3269.199046233688, 3297.093299342676, 3310.784798988058, 3330.5670262865915, 3371.915702107467, 520087.0, 521007.39496302657, 521043.0, 522962.0, 525477.0, 57522.0, 57764.71117644385, 58021.8395632085, 58039.70980145072, 58201.70899145562, 62043.689781550805, 62058.44853878689, 62121.68939155366, 62262.0, 62332.68833655803, 15455.459058932884, 15948.441804536786, 15952.521424357288, 15953.282102305424, 15968.920155399148, 12425.19236249641, 12487.93756031214, 12494.125411221308, 12516.061295402882, 12558.120931534888, 13752.0, 14003.43986240539, 15355.462558810388, 15684.451044213398, 15709.685806283811, 6303.653299068504, 6312.810615681445, 7182.2458641842895, 7311.268873112646, 7361.337479626753, 843.5023336231653, 896.2740180453853, 969.4474149734724, 1042.8435734639866, 1046.8696203226398, 29807.85096074549, 30150.547741783892, 30325.0, 30991.0, 31355.372892542022, 1014.1025192704362, 1039.7608550033535, 1054.478033373485, 1063.8085144673878, 1066.3495267886583, 15458.5362439127, 15573.844261557466, 15661.451849185223, 15731.370745170065, 16011.59971000711, 683176.0, 689000.0, 689111.0, 690013.0, 690072.0, 57810.710946445, 59490.0, 59586.70206648939, 59593.70203149043, 59657.0, 1189.1972918280126, 1250.9929506746973, 1404.8735613794809, 1420.7300612883519, 1440.488626537585, 29798.25504362408, 30945.381092377575, 31056.91300804461, 31483.37033259276, 31640.525392118678, 48862.75568622134, 49305.26042109299, 49331.50668493269, 49394.0, 49523.752381237864, 562347.0, 565296.0, 565313.0, 568082.0, 568177.0, 38767.0, 39200.0, 40096.39855402113, 40167.0, 40196.598034020455, 47471.76264118657, 47740.52259477361, 48350.75824620854, 48509.75745121252, 48951.02097958092, 39357.80321098376, 39446.40830387604, 39967.600323997554, 40036.799816000734, 41027.0, 39563.81308560748, 39815.80092099521, 39867.80066099651, 40532.0, 40818.0, 38017.61982380141, 38577.421338680506, 38638.80680596579, 38821.0, 39016.804915976376, 28456.857715712118, 29123.85438072796, 32943.50584741279, 35084.29831403409, 35096.64903351036, 24644.0, 24707.25878223656, 25102.0, 25489.0, 26276.817543210218], "value": 20454.034774727264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.4080340165591, 361.37121408748754, 382.3614563678656, 387.30865405251615, 409.77052850403777, 14832.77750833737, 15590.45433409831, 20713.78307023541, 20848.89575552124, 21604.243851465202, 20770.68843967338, 21328.0, 21359.893200533974, 21571.67642485364, 21596.8920155399, 5437.34751829781, 5847.912281315784, 5882.647041177543, 5979.611325263866, 6071.3625069367745, 4151.522574903895, 4359.171757366106, 5941.0, 6593.20881494219, 6603.349162709323, 6579.17760279965, 6606.075149479053, 6612.867742645169, 6623.933760662404, 6676.2989886062, 6322.304546499904, 6539.182602174728, 6572.047053177311, 6572.835679108012, 6587.011948207759, 5437.34751829779, 5493.176023596453, 5698.34469036062, 5700.3159620845345, 5701.543876489913, 20705.757654540626, 25947.870260648764, 26547.469050618878, 26659.0, 26802.0, 2303.4126297793955, 2314.0, 2652.1778248742953, 3003.80475269109, 3009.48838697422, 15333.463328783439, 15826.366945322174, 16100.75848862268, 16130.919345403316, 16667.416640417647, 1701.7787687600658, 1836.8806027608164, 1840.8987505687182, 1901.610169915164, 1920.3470819921154, 87359.0, 117093.0, 122399.0, 123708.0, 124944.37527812393, 9057.81884362309, 9695.466749328714, 10493.0, 11041.83437248442, 11240.156988225915, 12567.748645027048, 12763.489460421479, 12771.425285862158, 12772.297523636289, 12802.231866088065, 6000.759969601254, 7827.686892524349, 8053.919460805434, 8148.918510814935, 8208.958955205186, 8001.639926203335, 8026.5986700664735, 8112.02655681322, 8122.472039317426, 8183.795405114801, 6522.5760325578685, 6600.438962688129, 6617.0, 6631.701573429207, 6642.933570664231, 1815.9818401815812, 1846.843018343429, 1847.5750577367012, 1851.6389304085574, 1855.5082903030707, 823.5882058970515, 925.6019911438011, 954.5561313989028, 1009.5154325923597, 1053.1785207538132, 20118.39644810658, 20277.986100694907, 21375.67936480954, 22479.55040899173, 22546.887265563895, 970.4225985538593, 974.3715303629119, 981.5975450065582, 1008.6772232885406, 1095.2059756676347, 11417.828732569018, 11681.415929203506, 11897.0, 11909.58316458937, 11924.582639607572, 460340.0, 586079.0, 592451.0, 610645.0, 615475.0, 140303.0, 150844.49155508523, 151872.9999999978, 152229.0, 152736.0, 980.1178938954997, 995.0944640377222, 1002.8595996560488, 1024.774549599087, 1026.573971801709, 18120.093995300445, 19257.51856203606, 19569.510762231057, 19634.410967670992, 19738.802611973697, 56576.434235657114, 57565.42434575768, 58931.0, 59716.70141649264, 59953.700231498566, 71821.28178718146, 78056.60971695105, 80375.19624803677, 87361.0, 88991.55504222437, 7823.2567906048835, 8176.182381761776, 8309.0, 8481.618327175169, 8513.74458766238, 11120.833187502356, 13226.272555009567, 13499.932500337436, 13520.391582379014, 13554.051216414757, 11411.942940285246, 11488.885111148782, 11662.416879156179, 11705.941470292593, 11740.41297935117, 9728.221742260745, 10209.489525523843, 10991.010809027226, 11036.392998384845, 11261.042811361127, 11225.382603956861, 11481.655550333504, 11790.705232369259, 11886.583969561023, 11948.402579871146, 8229.218224268685, 8245.422820402515, 8306.044804847279, 8311.709090181814, 8487.151284871463, 8394.0, 8611.741647750578, 11006.394648294421, 11643.301401915913, 11739.882601173878], "value": 9886.421284460377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.7129902720428, 1520.369046845559, 1560.6176486760742, 1756.8067512573623, 1916.1473144450717, 7739.613019349038, 8251.628676709555, 8270.421070525066, 8536.103709110546, 8763.95618021909, 8534.061253262136, 8632.17994290543, 8704.173103555167, 8742.038375778675, 8746.12538746125, 2362.9881850590723, 2401.2196036288187, 2401.7838394544488, 2404.3147702904675, 2405.807535397164, 2359.9410014749683, 2381.345130089222, 2396.9161079362225, 2400.303911865557, 2400.843945143569, 1996.850236232281, 2334.2647066174186, 2354.564405584972, 2357.834951553392, 2383.3445802404303, 2295.162265772981, 2317.06159005603, 2328.5924963131383, 2329.9301020969315, 2359.9970012744516, 2338.5968418948664, 2361.881905904715, 2364.680768096302, 2364.8581085134947, 2365.1958334166293, 8775.0, 8783.429077109968, 8850.645974161025, 8890.244329232022, 8892.599833007465, 4283.0, 5381.919271210935, 5384.946150538483, 5413.242146099549, 5416.783328666848, 3585.766925149857, 3680.2823449427374, 3741.625837416264, 3743.2513497300647, 3744.0826997385816, 1062.5920655131981, 1072.5816931396762, 1086.7391825961713, 1099.060303440555, 1099.8451625792886, 11590.188686791927, 11697.88302116985, 11710.180287379886, 11715.882841171564, 11731.76536469266, 4435.536273029892, 4446.466424029072, 4635.119327327797, 4642.953570464252, 4677.859664210079, 9188.402753821114, 9198.080191980882, 9203.309751768644, 9215.907840921505, 9217.631294748135, 5230.660007099526, 5246.396664383568, 5253.290805741176, 5268.157094864803, 5285.2600635911, 4379.978100109479, 4382.298832186899, 4513.526079761577, 4887.7556122194455, 4922.1140194765285, 3355.2282974915906, 3808.961910380916, 3870.3613903705573, 3886.9805650971566, 3922.764634121962, 1141.7088642396302, 1147.145376694368, 1148.626696323698, 1153.7577108807077, 1154.96535103947, 909.5497728624373, 918.4856480371013, 934.7522906429801, 938.9671361502315, 992.9950350248203, 5426.213199086189, 5859.2382990211, 5861.827634473122, 6046.2139921809885, 6203.976343903296, 378.251062895463, 430.9418228539107, 440.5528388685487, 444.0275796006799, 454.20740807291486, 4006.45912801768, 4063.89840253996, 4086.7547947123267, 4119.464469618931, 4166.958330416717, 10371.014753598396, 10893.0, 10895.509702063426, 10907.509162087725, 10907.781844363228, 10776.0, 10859.2398532102, 10870.565177392815, 10887.727806804893, 10888.78222435547, 8260.628271727786, 8324.25081742634, 8339.8332033359, 8355.874661880076, 8373.45572537771, 5841.795537156179, 5942.762289508544, 5964.7912323068485, 6093.48205402528, 6225.657588832477, 8908.73273801787, 10108.191344692259, 10110.646127385506, 10135.847962280424, 10211.642592509226, 9269.6292148316, 9631.08504692053, 10126.088652021353, 10258.0, 10288.2798204128, 7343.632818358954, 7602.961985190039, 7611.706009978207, 7686.807829804299, 7711.8072048197, 8080.797980050545, 8399.832003360021, 8428.4942903426, 8514.78713032179, 8528.104549022511, 7436.144843343085, 7486.663100160615, 7587.468877178544, 7607.847843043219, 7675.347595454449, 6278.4349408551625, 7432.442566807403, 7445.218252083453, 7561.243875612395, 7572.356349710336, 6949.131358580177, 7056.435485161277, 7076.71693132269, 7076.964615176891, 7086.716531338689, 4445.355423463644, 5044.369453818273, 5073.746312684425, 5097.1844504879045, 5233.712145832016, 5107.259447380184, 5111.412187598474, 5159.17453207485, 5171.250168725589, 5328.3605967284175], "value": 4481.78158611276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.352964655637, 311.98752049918005, 313.68631368631367, 316.88275338124885, 320.6328753577156, 4515.622735065804, 5373.838784836456, 5421.241026256323, 5449.182622606611, 5465.961467321211, 5246.475352464751, 5288.629795914288, 5348.251244825718, 5362.168863826103, 5369.382521010075, 3334.2997970426118, 3849.9230015399676, 3917.2165566886592, 4134.400511925784, 4156.314208155666, 4070.9796451017705, 4096.0, 4104.917901641966, 4138.951219146512, 4160.981923766971, 3568.2506673598577, 3737.4025052588463, 3737.8878633641034, 3901.804909754515, 4103.753774773509, 3806.7715937043868, 3869.342211823983, 4006.5392479864895, 4086.959130408717, 4088.1823635273063, 3127.8592463339205, 3539.4513850353237, 3544.929101417957, 3549.8757543486113, 3553.875614353511, 4829.975850120762, 5032.3896353167, 5219.921701174486, 5240.558846317255, 5245.347715469653, 3972.165845172517, 4514.571115744032, 4640.95359046412, 4672.135654903854, 4684.039771846763, 4824.721448816066, 4824.782884770194, 5427.348718153808, 5532.6680399176175, 5558.888822223574, 3290.3090351026312, 3929.057026313693, 3944.4361165242535, 3948.2498325318097, 3951.723379363446, 5350.662334416396, 5402.37872644647, 5866.50134738552, 5925.525957923356, 5942.465178133989, 3955.4264631628253, 3971.860984865516, 4003.2393845169327, 4007.1785284016705, 4010.7593544387432, 3532.156613925763, 3947.822347994347, 4056.553779084298, 4058.5941405859767, 4088.7546747195265, 4479.394290754301, 4580.450345958507, 4653.976730116396, 4681.976590117028, 4691.202495575761, 5586.944130558723, 5600.663960162403, 5602.0, 5652.6325788824415, 5668.461496157858, 1303.67408147963, 1362.8909687224998, 1382.6059573021842, 1394.4282844033899, 1398.5384823008383, 751.4551949836434, 809.5183365897282, 843.9493630382074, 851.8764779107119, 869.8303830753069, 5036.7733451994745, 5062.797488100435, 5147.124988751922, 5244.606654500928, 5264.236685680555, 5416.377116631559, 5730.455606717355, 5756.884862302731, 5757.8560535986935, 5762.337331206881, 4854.68444551103, 4945.727984960861, 5252.923150754163, 5271.551918086929, 5335.573154147659, 2653.3366658335417, 2939.9706002940125, 2996.715312045352, 3032.4238394705376, 3032.5147976323683, 4824.421069471686, 4998.900241946766, 5975.40245975399, 5995.940040599626, 5998.890205312031, 5600.695589602073, 5748.942510574924, 5909.704514774244, 6007.309159446632, 6019.729112189962, 4843.515648435128, 5305.549028332558, 6041.667708275999, 6048.213732214784, 6113.877722445615, 4968.248972026242, 4976.859950221295, 5260.316158899319, 5657.9151312731165, 5828.406359170129, 4785.774614804132, 4934.333864928189, 5238.768889311085, 5734.741936612779, 5737.311522617312, 5260.0, 6022.608530445507, 6086.573939824169, 6097.146399503984, 6112.8777424452155, 3440.7935523867645, 3812.0088776917646, 3905.9218815624095, 3906.1797022625565, 3915.7258991870294, 4605.7466839322815, 4973.975130124326, 5781.768729250783, 5795.536357091507, 5813.302403711582, 4331.935020974624, 5525.640833345819, 5654.717264136859, 5678.801241956512, 5752.798652047326, 5483.341998960151, 5497.581064331681, 5650.29371328584, 5679.034564124191, 5681.687507186979, 5233.136532472252, 5572.3034620672415, 5573.972130139324, 5575.358833734173, 5583.832485025456, 4306.569343065668, 4659.883502912454, 5196.766145523537, 5625.481120097469, 5661.235733175969, 5012.248162775612, 5053.570446512088, 5103.800606857311, 5192.948070519246, 5525.834224973257], "value": 4092.4399912315675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.8141523911402, 757.0725860820493, 778.9415793815465, 835.8662613981764, 845.8900342955417, 15512.84487155128, 16421.589460263498, 16473.176341182952, 16480.34078636853, 16489.917550412247, 14838.703225935475, 14858.47995320164, 14988.325525351358, 14999.85000149998, 15005.249737513135, 699.0353312428841, 724.7789424225609, 873.6549063120063, 900.7612982559627, 905.8822353094089, 721.0914248047471, 722.0649259209337, 722.5881247688819, 724.3010494872447, 871.3029576338904, 956.7225504603657, 959.1319855530721, 959.3859929645033, 961.3462845265217, 961.6586111930269, 939.4504215034214, 952.1716106986896, 952.5808644196532, 953.4755884263627, 953.6471505542925, 548.2078396716746, 613.4601550635457, 707.9115110611174, 709.2446544430165, 709.3792931185213, 15230.238488075664, 15270.38918443261, 15274.923625381913, 15277.770833437507, 15297.158656273901, 13966.441342346294, 14302.213378264192, 14397.712045759026, 14414.783778243334, 14503.70992580153, 12382.442790074378, 13020.804687929689, 13180.275084870329, 13264.933675331658, 13640.590782276548, 3983.721139520235, 4591.517890621503, 4775.6657034007285, 4819.879503012417, 4836.951630483721, 99217.0, 103242.48378758134, 109992.4500377501, 110058.44970775174, 114040.0, 10834.133269338434, 11109.388983605817, 11132.888671113347, 11147.442627868737, 11342.546298147981, 21940.341789746013, 22233.777662223496, 22262.55474890493, 22268.99789509476, 22275.331740047826, 7700.730474433368, 7722.189170137167, 7733.033370828646, 7817.335526480313, 7963.203679632106, 4284.4430224070675, 4337.132573485315, 4344.782760861945, 4349.891252718644, 4515.593596576324, 3553.36039512885, 3633.2733453309443, 3674.412094064937, 3679.411294192916, 3681.5398075240596, 660.6927778582982, 667.1160712056544, 668.5119862500402, 674.5682763031664, 688.4114082459525, 553.3636318234063, 625.7715933684264, 642.8649983503517, 644.8387903024244, 647.1231481342713, 9691.466969316756, 9750.756231094138, 9759.658411955548, 9772.462514561625, 9779.755506112404, 516.2926790297316, 626.8620903401245, 645.7094307561616, 669.1301308299192, 693.4209934704477, 4413.13943780966, 4651.90680190155, 4681.578657920735, 4695.835645752382, 4718.386609740712, 128518.0, 148072.25963870113, 165484.0, 167981.0, 168555.1572242131, 140940.29529852286, 148306.0, 148918.0, 151092.2445387766, 153001.0, 124829.0, 124997.0, 126514.0, 126819.36590316989, 127463.0, 9599.232061434928, 9804.068613481708, 9847.31068825204, 9951.900480995097, 9995.350302230332, 51689.741551292005, 59697.80604387975, 63261.05108423469, 67224.66387668031, 67445.0, 87747.0, 100971.3785776778, 101699.0, 103142.0, 104297.47851260695, 13505.729885402434, 13642.0, 13644.454221831016, 14154.646133846736, 14392.928035359757, 14934.0, 14992.850071499146, 15006.6998660024, 15453.072815631098, 15454.99542529732, 9890.901090989286, 14197.148171109766, 14241.857581424467, 14421.7836732447, 14802.62993425173, 13796.586102416944, 13901.235432051335, 14010.719785604027, 14197.503087391891, 14576.271186440423, 13771.724565508834, 13938.651533711738, 13953.162810231419, 14074.437022519394, 14500.419983200976, 10710.0, 11223.887761122285, 11357.261777984404, 11564.826527601925, 11889.821652675044, 10209.84685229737, 10298.176145908159, 11092.223544351818, 11304.265222760494, 11440.256383335056], "value": 8074.642718092198, "unit": "score"}], "name": "dromaeo_css", "value": 7436.414461857071, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 112.0, 114.0, 113.0, 112.0, 113.0, 112.0, 114.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 108.0, 162.0, 131.0, 136.0, 132.0, 106.0, 164.0, 130.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 187.0, 182.0, 184.0, 181.0, 184.0, 185.0, 181.0, 186.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 58.0, 109.0, 64.0, 79.0, 90.0, 62.0, 84.0, 56.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 92.0, 72.0, 71.0, 69.0, 99.0, 70.0, 68.0, 102.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0, 88.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 92.0, 93.0, 92.0, 93.0, 98.0, 88.0, 121.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 87.0, 85.0, 85.0, 87.0, 85.0, 83.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 54.0, 54.0, 53.0, 54.0, 53.0, 53.0, 53.0, 51.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 78.0, 77.0, 72.0, 69.0, 72.0, 71.0, 71.0, 73.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 79.0, 85.0, 85.0, 84.0, 83.0, 87.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 150.0, 145.0, 145.0, 135.0, 138.0, 143.0, 144.0, 230.0, 144.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 220.0, 167.0, 163.0, 164.0, 160.0, 164.0, 162.0, 217.0, 215.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 66.0, 65.0, 66.0, 66.0, 60.0, 58.0, 77.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1457.1, "alertThreshold": 2.0}]} 10:37:02 INFO - Return code: 0 10:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:37:02 INFO - # TBPL SUCCESS # 10:37:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:37:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:37:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:37:02 INFO - 2016-07-18 10:37:02.869 screenresolution[1668:10094] starting screenresolution argv=screenresolution get 10:37:02 INFO - 2016-07-18 10:37:02.872 screenresolution[1668:10094] Display 0: 1600x1200x32@60 10:37:02 INFO - 2016-07-18 10:37:02.878 screenresolution[1669:10097] starting screenresolution argv=screenresolution list 10:37:02 INFO - Available Modes on Display 0 10:37:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:37:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:37:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:37:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:37:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:37:03 INFO - Intel Iris: 10:37:03 INFO - Chipset Model: Intel Iris 10:37:03 INFO - Type: GPU 10:37:03 INFO - Bus: Built-In 10:37:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:37:03 INFO - Vendor: Intel (0x8086) 10:37:03 INFO - Device ID: 0x0a2e 10:37:03 INFO - Revision ID: 0x0009 10:37:03 INFO - Displays: 10:37:03 INFO - Display: 10:37:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:37:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:37:03 INFO - Display Serial Number: 1600x1200 60 10:37:03 INFO - Main Display: Yes 10:37:03 INFO - Mirror: Off 10:37:03 INFO - Online: Yes 10:37:03 INFO - Rotation: Supported 10:37:03 INFO - Return code: 0 10:37:03 INFO - Running post-action listener: _resource_record_post_action 10:37:03 INFO - ##### 10:37:03 INFO - ##### Finished run-tests step (success) 10:37:03 INFO - ##### 10:37:03 INFO - Running post-run listener: _resource_record_post_run 10:37:03 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37352960; Write bytes: 706339840; Read time: 382; Write time: 2025 10:37:03 INFO - TinderboxPrint: CPU usage
53.2% 10:37:03 INFO - TinderboxPrint: I/O read bytes / time
37,352,960 / 382 10:37:03 INFO - TinderboxPrint: I/O write bytes / time
706,339,840 / 2,025 10:37:03 INFO - TinderboxPrint: CPU idle
2,268.5 (46.8%) 10:37:03 INFO - TinderboxPrint: CPU system
960.7 (19.8%) 10:37:03 INFO - TinderboxPrint: CPU user
1,616.5 (33.4%) 10:37:03 INFO - TinderboxPrint: Swap in / out
444,665,856 / 0 10:37:03 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 404224512; Write bytes: 396074496; Read time: 29541; Write time: 763 10:37:03 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30047744; Write bytes: 292566528; Read time: 294; Write time: 1222 10:37:03 INFO - Running post-run listener: _upload_blobber_files 10:37:03 INFO - Blob upload gear active. 10:37:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:37:03 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 10:37:03 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'] 10:37:03 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 10:37:03 INFO - (blobuploader) - INFO - Open directory for files ... 10:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:05 INFO - (blobuploader) - INFO - Done attempting. 10:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:06 INFO - (blobuploader) - INFO - Done attempting. 10:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:37:10 INFO - (blobuploader) - INFO - Done attempting. 10:37:10 INFO - (blobuploader) - INFO - Iteration through files over. 10:37:10 INFO - Return code: 0 10:37:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:37:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:37:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35cb6b471998c95709ab2d1737047fd77f170c28e2f2f28e2d88feef0ecb4e6c2a373c6a60aa6a33b986db7847cc4445ecf17a80047edbbbf9c10148093e2c75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6089d5bf3897f094825d0adb90837bf9fe03c8fe5be6501658dbb589ce323d97e06773a3b22f15a8d205935abeba85c3001876b3d1d0e326d32dca2ae4a8f6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/393523b5de4d6ed1dd78331ae62db3c06b3f26a589f2e2fb35c0581a7e760bcf8ae93d88bf1e6a3198312ee083b63c7a0287e8fd5edc921bca585508f22c0f4c"} 10:37:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:37:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:37:10 INFO - Contents: 10:37:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35cb6b471998c95709ab2d1737047fd77f170c28e2f2f28e2d88feef0ecb4e6c2a373c6a60aa6a33b986db7847cc4445ecf17a80047edbbbf9c10148093e2c75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6089d5bf3897f094825d0adb90837bf9fe03c8fe5be6501658dbb589ce323d97e06773a3b22f15a8d205935abeba85c3001876b3d1d0e326d32dca2ae4a8f6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/393523b5de4d6ed1dd78331ae62db3c06b3f26a589f2e2fb35c0581a7e760bcf8ae93d88bf1e6a3198312ee083b63c7a0287e8fd5edc921bca585508f22c0f4c"} 10:37:10 INFO - Running post-run listener: copy_logs_to_upload_dir 10:37:10 INFO - Copying logs to upload dir... 10:37:10 INFO - mkdir: /builds/slave/test/build/upload/logs 10:37:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.924140 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 10:37:10.073232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:37:10.076516) ========= 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.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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/35cb6b471998c95709ab2d1737047fd77f170c28e2f2f28e2d88feef0ecb4e6c2a373c6a60aa6a33b986db7847cc4445ecf17a80047edbbbf9c10148093e2c75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6089d5bf3897f094825d0adb90837bf9fe03c8fe5be6501658dbb589ce323d97e06773a3b22f15a8d205935abeba85c3001876b3d1d0e326d32dca2ae4a8f6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/393523b5de4d6ed1dd78331ae62db3c06b3f26a589f2e2fb35c0581a7e760bcf8ae93d88bf1e6a3198312ee083b63c7a0287e8fd5edc921bca585508f22c0f4c"} build_url:https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/Wk_Z9NFOR0K30Cr3FFhuyQ/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/35cb6b471998c95709ab2d1737047fd77f170c28e2f2f28e2d88feef0ecb4e6c2a373c6a60aa6a33b986db7847cc4445ecf17a80047edbbbf9c10148093e2c75", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6089d5bf3897f094825d0adb90837bf9fe03c8fe5be6501658dbb589ce323d97e06773a3b22f15a8d205935abeba85c3001876b3d1d0e326d32dca2ae4a8f6a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/393523b5de4d6ed1dd78331ae62db3c06b3f26a589f2e2fb35c0581a7e760bcf8ae93d88bf1e6a3198312ee083b63c7a0287e8fd5edc921bca585508f22c0f4c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:37:10.109825) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:37:10.110181) ========= 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.llYeFLQ88V/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.kIYivg1h82/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.005069 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:37:10.158824) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:37:10.159142) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:37:10.159474) ========= ========= Total master_lag: 0.17 =========