builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0219 starttime: 1465288793.94 results: success (0) buildid: 20160606194329 builduid: 3d31ab4f42854c829e06ac6160cc676b revision: 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:53.939878) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:53.940329) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:53.940636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:53.965169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:53.965562) ========= 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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-06-07 01:39:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.3M=0s 2016-06-07 01:39:54 (26.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116510 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:54.103608) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:54.103932) ========= 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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.025962 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:54.153330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:39:54.153745) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-06-07 01:39:54,238 truncating revision to first 12 chars 2016-06-07 01:39:54,239 Setting DEBUG logging. 2016-06-07 01:39:54,239 attempt 1/10 2016-06-07 01:39:54,239 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1f9f6bdee31c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 01:39:55,414 unpacking tar archive at: mozilla-beta-1f9f6bdee31c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.442747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 01:39:55.618113) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:55.618459) ========= script_repo_revision: 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:55.618897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:55.619180) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 01:39:55.637508) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-07 01:39:55.637958) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:39:55 INFO - MultiFileLogger online at 20160607 01:39:55 in /builds/slave/test 01:39:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:39:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:39:55 INFO - {'append_to_log': False, 01:39:55 INFO - 'base_work_dir': '/builds/slave/test', 01:39:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:39:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:39:55 INFO - 'branch': 'Mozilla-Beta', 01:39:55 INFO - 'buildbot_json_path': 'buildprops.json', 01:39:55 INFO - 'config_files': ('talos/mac_config.py',), 01:39:55 INFO - 'default_actions': ('clobber', 01:39:55 INFO - 'read-buildbot-config', 01:39:55 INFO - 'download-and-extract', 01:39:55 INFO - 'populate-webroot', 01:39:55 INFO - 'create-virtualenv', 01:39:55 INFO - 'install', 01:39:55 INFO - 'run-tests'), 01:39:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:39:55 INFO - 'download_minidump_stackwalk': True, 01:39:55 INFO - 'download_symbols': 'ondemand', 01:39:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:39:55 INFO - 'tooltool.py': '/tools/tooltool.py', 01:39:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:39:55 INFO - '/tools/misc-python/virtualenv.py')}, 01:39:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:39:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:39:55 INFO - 'installer_path': 'installer.exe', 01:39:55 INFO - 'log_level': 'info', 01:39:55 INFO - 'log_name': 'talos', 01:39:55 INFO - 'log_to_console': True, 01:39:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:39:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:39:55 INFO - 'opt_config_files': (), 01:39:55 INFO - 'pip_index': False, 01:39:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:39:55 INFO - 'cmd': ('bash', 01:39:55 INFO - '-c', 01:39:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:39:55 INFO - 'enabled': True, 01:39:55 INFO - 'halt_on_failure': False, 01:39:55 INFO - 'name': 'check_screen_resolution'},), 01:39:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:39:55 INFO - 'cmd': ('bash', 01:39:55 INFO - '-c', 01:39:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:39:55 INFO - 'enabled': True, 01:39:55 INFO - 'halt_on_failure': False, 01:39:55 INFO - 'name': 'check_screen_resolution'},), 01:39:55 INFO - 'run_cmd_checks_enabled': True, 01:39:55 INFO - 'sps_profile': False, 01:39:55 INFO - 'sps_profile_interval': 0, 01:39:55 INFO - 'suite': 'dromaeojs', 01:39:55 INFO - 'system_bits': '32', 01:39:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:39:55 INFO - 'title': 't-yosemite-r7-0219', 01:39:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:39:55 INFO - 'use_talos_json': True, 01:39:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:39:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:39:55 INFO - 'work_dir': 'build'} 01:39:55 INFO - ##### 01:39:55 INFO - ##### Running clobber step. 01:39:55 INFO - ##### 01:39:55 INFO - Running pre-action listener: _resource_record_pre_action 01:39:55 INFO - Running main action method: clobber 01:39:55 INFO - rmtree: /builds/slave/test/build 01:39:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:39:57 INFO - Running post-action listener: _resource_record_post_action 01:39:57 INFO - ##### 01:39:57 INFO - ##### Running read-buildbot-config step. 01:39:57 INFO - ##### 01:39:57 INFO - Running pre-action listener: _resource_record_pre_action 01:39:57 INFO - Running main action method: read_buildbot_config 01:39:57 INFO - Using buildbot properties: 01:39:57 INFO - { 01:39:57 INFO - "project": "", 01:39:57 INFO - "product": "firefox", 01:39:57 INFO - "script_repo_revision": "production", 01:39:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:39:57 INFO - "repository": "", 01:39:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:39:57 INFO - "buildid": "20160606194329", 01:39:57 INFO - "pgo_build": "False", 01:39:57 INFO - "basedir": "/builds/slave/test", 01:39:57 INFO - "buildnumber": 26, 01:39:57 INFO - "slavename": "t-yosemite-r7-0219", 01:39:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:39:57 INFO - "platform": "yosemite_r7", 01:39:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:39:57 INFO - "branch": "mozilla-beta", 01:39:57 INFO - "repo_path": "releases/mozilla-beta", 01:39:57 INFO - "revision": "1f9f6bdee31c14fd5a39485e8ae326de2928ef7d", 01:39:57 INFO - "stage_platform": "macosx64", 01:39:57 INFO - "builduid": "3d31ab4f42854c829e06ac6160cc676b", 01:39:57 INFO - "slavebuilddir": "test" 01:39:57 INFO - } 01:39:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 01:39:57 INFO - Running post-action listener: _resource_record_post_action 01:39:57 INFO - ##### 01:39:57 INFO - ##### Running download-and-extract step. 01:39:57 INFO - ##### 01:39:57 INFO - Running pre-action listener: _resource_record_pre_action 01:39:57 INFO - Running main action method: download_and_extract 01:39:57 INFO - mkdir: /builds/slave/test/build/tests 01:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:57 INFO - https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:39:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:39:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:39:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:39:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 01:39:58 INFO - Downloaded 1165 bytes. 01:39:58 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:39:58 INFO - Using the following test package requirements: 01:39:58 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:39:58 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 01:39:58 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 01:39:58 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:39:58 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 01:39:58 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:39:58 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 01:39:58 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 01:39:58 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 01:39:58 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:39:58 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 01:39:58 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 01:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:39:58 INFO - https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:39:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:39:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:39:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:39:59 INFO - Downloaded 18292404 bytes. 01:39:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:39:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:40:02 INFO - Return code: 0 01:40:02 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:40: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')]} 01:40:02 INFO - https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:40:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:40:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:40:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:40:03 INFO - Downloaded 18292404 bytes. 01:40:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:40:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:40:07 INFO - Return code: 0 01:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:07 INFO - https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:40:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:40:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:40:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 01:40:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 01:40:08 INFO - Downloaded 10840145 bytes. 01:40:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:40:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:40:08 INFO - Return code: 0 01:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:08 INFO - https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 01:40:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:40:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:40:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 01:40:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 01:40:11 INFO - Downloaded 86247433 bytes. 01:40:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:40:11 INFO - mkdir: /builds/slave/test/properties 01:40:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:11 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:11 INFO - Contents: 01:40:11 INFO - build_url:https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:40:12 INFO - Running post-action listener: _resource_record_post_action 01:40:12 INFO - Running post-action listener: set_extra_try_arguments 01:40:12 INFO - ##### 01:40:12 INFO - ##### Running populate-webroot step. 01:40:12 INFO - ##### 01:40:12 INFO - Running pre-action listener: _resource_record_pre_action 01:40:12 INFO - Running main action method: populate_webroot 01:40:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:12 INFO - '--tppagecycles', 01:40:12 INFO - '7'], 01:40:12 INFO - 'tests': ['tsvgm']}}, 01:40:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['damp', 'tps']}, 01:40:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'tests': ['damp', 'tps']}, 01:40:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['dromaeo_dom']}, 01:40:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['a11yr', 01:40:12 INFO - 'ts_paint', 01:40:12 INFO - 'tpaint', 01:40:12 INFO - 'sessionrestore', 01:40:12 INFO - 'sessionrestore_no_auto_restore', 01:40:12 INFO - 'tabpaint']}, 01:40:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['tsvgx', 01:40:12 INFO - 'tsvgr_opacity', 01:40:12 INFO - 'tart', 01:40:12 INFO - 'tscrollx', 01:40:12 INFO - 'cart']}, 01:40:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:12 INFO - 'tsvgr_opacity', 01:40:12 INFO - 'tart', 01:40:12 INFO - 'tscrollx', 01:40:12 INFO - 'cart']}, 01:40:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'talos_options': ['--disable-e10s'], 01:40:12 INFO - 'tests': ['tp5o']}, 01:40:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'tests': ['tp5o']}, 01:40:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'talos_options': ['--disable-e10s', 01:40:12 INFO - '--xperf_path', 01:40:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:12 INFO - 'tests': ['tp5n']}, 01:40:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:40:12 INFO - 'talos_options': ['--xperf_path', 01:40:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:12 INFO - 'tests': ['tp5n']}}, 01:40:12 INFO - 'talos.zip': {'path': '', 01:40:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:12 INFO - Running post-action listener: _resource_record_post_action 01:40:12 INFO - ##### 01:40:12 INFO - ##### Running create-virtualenv step. 01:40:12 INFO - ##### 01:40:12 INFO - Running pre-action listener: _resource_record_pre_action 01:40:12 INFO - Running main action method: create_virtualenv 01:40:12 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:40:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:12 INFO - Using real prefix '/tools/python27' 01:40:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:13 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:15 INFO - Installing pip.................done. 01:40:15 INFO - Return code: 0 01:40:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:40:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:15 INFO - 'HOME': '/Users/cltbld', 01:40:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:15 INFO - 'LOGNAME': 'cltbld', 01:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:15 INFO - 'MOZ_NO_REMOTE': '1', 01:40:15 INFO - 'NO_EM_RESTART': '1', 01:40:15 INFO - 'PAGER': '/bin/cat', 01:40:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:15 INFO - 'PWD': '/builds/slave/test', 01:40:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:15 INFO - 'SHELL': '/bin/bash', 01:40:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:15 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:15 INFO - 'USER': 'cltbld', 01:40:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:15 INFO - 'XPC_FLAGS': '0x0', 01:40:15 INFO - 'XPC_SERVICE_NAME': '0', 01:40:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:40:16 INFO - Cleaning up... 01:40:16 INFO - Return code: 0 01:40:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 01:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:40:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:16 INFO - 'HOME': '/Users/cltbld', 01:40:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:16 INFO - 'LOGNAME': 'cltbld', 01:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:16 INFO - 'MOZ_NO_REMOTE': '1', 01:40:16 INFO - 'NO_EM_RESTART': '1', 01:40:16 INFO - 'PAGER': '/bin/cat', 01:40:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:16 INFO - 'PWD': '/builds/slave/test', 01:40:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:16 INFO - 'SHELL': '/bin/bash', 01:40:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:16 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:16 INFO - 'USER': 'cltbld', 01:40:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:16 INFO - 'XPC_FLAGS': '0x0', 01:40:16 INFO - 'XPC_SERVICE_NAME': '0', 01:40:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:16 INFO - Downloading/unpacking psutil>=0.7.1 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:19 INFO - Installing collected packages: psutil 01:40:19 INFO - Running setup.py install for psutil 01:40:19 INFO - building 'psutil._psutil_osx' extension 01:40:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:40:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:40:20 INFO - building 'psutil._psutil_posix' extension 01:40:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:40:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:40:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:40:20 INFO - ^ 01:40:20 INFO - 1 warning generated. 01:40:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:40:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:20 INFO - Successfully installed psutil 01:40:20 INFO - Cleaning up... 01:40:20 INFO - Return code: 0 01:40:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:20 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:20 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.0.0'] in /builds/slave/test/build 01:40:20 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.0.0 01:40:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:20 INFO - 'HOME': '/Users/cltbld', 01:40:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:20 INFO - 'LOGNAME': 'cltbld', 01:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:20 INFO - 'MOZ_NO_REMOTE': '1', 01:40:20 INFO - 'NO_EM_RESTART': '1', 01:40:20 INFO - 'PAGER': '/bin/cat', 01:40:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:20 INFO - 'PWD': '/builds/slave/test', 01:40:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:20 INFO - 'SHELL': '/bin/bash', 01:40:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:20 INFO - 'USER': 'cltbld', 01:40:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:20 INFO - 'XPC_FLAGS': '0x0', 01:40:20 INFO - 'XPC_SERVICE_NAME': '0', 01:40:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:40:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:40:23 INFO - Installing collected packages: mozsystemmonitor 01:40:23 INFO - Running setup.py install for mozsystemmonitor 01:40:23 INFO - Successfully installed mozsystemmonitor 01:40:23 INFO - Cleaning up... 01:40:23 INFO - Return code: 0 01:40:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:40:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:23 INFO - 'HOME': '/Users/cltbld', 01:40:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:23 INFO - 'LOGNAME': 'cltbld', 01:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:23 INFO - 'MOZ_NO_REMOTE': '1', 01:40:23 INFO - 'NO_EM_RESTART': '1', 01:40:23 INFO - 'PAGER': '/bin/cat', 01:40:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:23 INFO - 'PWD': '/builds/slave/test', 01:40:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:23 INFO - 'SHELL': '/bin/bash', 01:40:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:23 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:23 INFO - 'USER': 'cltbld', 01:40:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:23 INFO - 'XPC_FLAGS': '0x0', 01:40:23 INFO - 'XPC_SERVICE_NAME': '0', 01:40:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:23 INFO - Downloading/unpacking blobuploader==1.2.4 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:26 INFO - Downloading blobuploader-1.2.4.tar.gz 01:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:40:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:40:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:27 INFO - Downloading docopt-0.6.1.tar.gz 01:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:40:27 INFO - Installing collected packages: blobuploader, requests, docopt 01:40:27 INFO - Running setup.py install for blobuploader 01:40:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:40:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:40:27 INFO - Running setup.py install for requests 01:40:28 INFO - Running setup.py install for docopt 01:40:28 INFO - Successfully installed blobuploader requests docopt 01:40:28 INFO - Cleaning up... 01:40:28 INFO - Return code: 0 01:40:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:28 INFO - 'HOME': '/Users/cltbld', 01:40:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:28 INFO - 'LOGNAME': 'cltbld', 01:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:28 INFO - 'MOZ_NO_REMOTE': '1', 01:40:28 INFO - 'NO_EM_RESTART': '1', 01:40:28 INFO - 'PAGER': '/bin/cat', 01:40:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:28 INFO - 'PWD': '/builds/slave/test', 01:40:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:28 INFO - 'SHELL': '/bin/bash', 01:40:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:28 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:28 INFO - 'USER': 'cltbld', 01:40:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:28 INFO - 'XPC_FLAGS': '0x0', 01:40:28 INFO - 'XPC_SERVICE_NAME': '0', 01:40:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Zt8zcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-nSwdqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-RPGmLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-qTVkte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VUA3DD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-S93y9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-cm0WEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jk9ecK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-22fTME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-47GP7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-PLEv2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-vyGFu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-19EnZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-xyAvg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-hI3Pcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JIau9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-BjZk4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:40:30 INFO - Running setup.py install for manifestparser 01:40:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:40:30 INFO - Running setup.py install for mozcrash 01:40:30 INFO - Running setup.py install for mozdebug 01:40:30 INFO - Running setup.py install for mozdevice 01:40:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:40:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:40:30 INFO - Running setup.py install for mozfile 01:40:31 INFO - Running setup.py install for mozhttpd 01:40:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Running setup.py install for mozinfo 01:40:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Running setup.py install for mozInstall 01:40:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Running setup.py install for mozleak 01:40:31 INFO - Running setup.py install for mozlog 01:40:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:40:31 INFO - Running setup.py install for moznetwork 01:40:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozprocess 01:40:32 INFO - Running setup.py install for mozprofile 01:40:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozrunner 01:40:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Running setup.py install for mozscreenshot 01:40:32 INFO - Running setup.py install for moztest 01:40:32 INFO - Running setup.py install for mozversion 01:40:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:40:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:40:32 INFO - Cleaning up... 01:40:32 INFO - Return code: 0 01:40:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:32 INFO - 'HOME': '/Users/cltbld', 01:40:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:32 INFO - 'LOGNAME': 'cltbld', 01:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:32 INFO - 'MOZ_NO_REMOTE': '1', 01:40:32 INFO - 'NO_EM_RESTART': '1', 01:40:32 INFO - 'PAGER': '/bin/cat', 01:40:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:32 INFO - 'PWD': '/builds/slave/test', 01:40:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:32 INFO - 'SHELL': '/bin/bash', 01:40:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:32 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:32 INFO - 'USER': 'cltbld', 01:40:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:32 INFO - 'XPC_FLAGS': '0x0', 01:40:32 INFO - 'XPC_SERVICE_NAME': '0', 01:40:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-iDukj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-FMJUz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-rIWDDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WfZInq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Fa3kCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-GpXT6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-cIt6cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-amC6vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-aKhmvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-bnmAyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-hGaiF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VYuXkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-YfNNZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-e1BUlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-bBHGEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-boUn5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-B9faux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:37 INFO - Downloading blessings-1.6.tar.gz 01:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:40:37 INFO - Installing collected packages: blessings 01:40:37 INFO - Running setup.py install for blessings 01:40:37 INFO - Successfully installed blessings 01:40:37 INFO - Cleaning up... 01:40:37 INFO - Return code: 0 01:40:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:40:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:38 INFO - Reading from file tmpfile_stdout 01:40:38 INFO - Current package versions: 01:40:38 INFO - blessings == 1.6 01:40:38 INFO - blobuploader == 1.2.4 01:40:38 INFO - docopt == 0.6.1 01:40:38 INFO - manifestparser == 1.1 01:40:38 INFO - mozInstall == 1.12 01:40:38 INFO - mozcrash == 0.17 01:40:38 INFO - mozdebug == 0.1 01:40:38 INFO - mozdevice == 0.48 01:40:38 INFO - mozfile == 1.2 01:40:38 INFO - mozhttpd == 0.7 01:40:38 INFO - mozinfo == 0.9 01:40:38 INFO - mozleak == 0.1 01:40:38 INFO - mozlog == 3.1 01:40:38 INFO - moznetwork == 0.27 01:40:38 INFO - mozprocess == 0.22 01:40:38 INFO - mozprofile == 0.28 01:40:38 INFO - mozrunner == 6.11 01:40:38 INFO - mozscreenshot == 0.1 01:40:38 INFO - mozsystemmonitor == 0.0 01:40:38 INFO - moztest == 0.7 01:40:38 INFO - mozversion == 1.4 01:40:38 INFO - psutil == 3.1.1 01:40:38 INFO - requests == 1.2.3 01:40:38 INFO - wsgiref == 0.1.2 01:40:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:38 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 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:38 INFO - 'HOME': '/Users/cltbld', 01:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:38 INFO - 'LOGNAME': 'cltbld', 01:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:38 INFO - 'MOZ_NO_REMOTE': '1', 01:40:38 INFO - 'NO_EM_RESTART': '1', 01:40:38 INFO - 'PAGER': '/bin/cat', 01:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:38 INFO - 'PWD': '/builds/slave/test', 01:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:38 INFO - 'SHELL': '/bin/bash', 01:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:38 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:38 INFO - 'USER': 'cltbld', 01:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:38 INFO - 'XPC_FLAGS': '0x0', 01:40:38 INFO - 'XPC_SERVICE_NAME': '0', 01:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:40:38 INFO - Cleaning up... 01:40:38 INFO - Return code: 0 01:40:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:38 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 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:38 INFO - 'HOME': '/Users/cltbld', 01:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:38 INFO - 'LOGNAME': 'cltbld', 01:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:38 INFO - 'MOZ_NO_REMOTE': '1', 01:40:38 INFO - 'NO_EM_RESTART': '1', 01:40:38 INFO - 'PAGER': '/bin/cat', 01:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:38 INFO - 'PWD': '/builds/slave/test', 01:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:38 INFO - 'SHELL': '/bin/bash', 01:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:38 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:38 INFO - 'USER': 'cltbld', 01:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:38 INFO - 'XPC_FLAGS': '0x0', 01:40:38 INFO - 'XPC_SERVICE_NAME': '0', 01:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:38 INFO - Downloading/unpacking jsonschema 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:40:41 INFO - Downloading/unpacking functools32 (from jsonschema) 01:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:41 INFO - Downloading functools32-3.2.3-2.tar.gz 01:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:40:41 INFO - warning: no files found matching '*.txt' 01:40:41 INFO - no previously-included directories found matching 'build' 01:40:41 INFO - no previously-included directories found matching 'dist' 01:40:41 INFO - no previously-included directories found matching '.git*' 01:40:41 INFO - Installing collected packages: jsonschema, functools32 01:40:41 INFO - Running setup.py install for functools32 01:40:41 INFO - warning: no files found matching '*.txt' 01:40:41 INFO - no previously-included directories found matching 'build' 01:40:41 INFO - no previously-included directories found matching 'dist' 01:40:41 INFO - no previously-included directories found matching '.git*' 01:40:41 INFO - Successfully installed jsonschema functools32 01:40:41 INFO - Cleaning up... 01:40:41 INFO - Return code: 0 01:40:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:41 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105c71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c81420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f52d94990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, '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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:40:41 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', 'flake8'] in /builds/slave/test/build 01:40:41 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 flake8 01:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:40:41 INFO - 'HOME': '/Users/cltbld', 01:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:40:41 INFO - 'LOGNAME': 'cltbld', 01:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:41 INFO - 'MOZ_NO_REMOTE': '1', 01:40:41 INFO - 'NO_EM_RESTART': '1', 01:40:41 INFO - 'PAGER': '/bin/cat', 01:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:41 INFO - 'PWD': '/builds/slave/test', 01:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:40:41 INFO - 'SHELL': '/bin/bash', 01:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:40:41 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:40:41 INFO - 'USER': 'cltbld', 01:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:41 INFO - 'XPC_FLAGS': '0x0', 01:40:41 INFO - 'XPC_SERVICE_NAME': '0', 01:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:42 INFO - Downloading/unpacking flake8 01:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:40:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:40:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:40:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:40:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:40:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:40:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:40:45 INFO - no previously-included directories found matching 'docs/_build' 01:40:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:40:45 INFO - Running setup.py install for pep8 01:40:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:40:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:40:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:40:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:40:46 INFO - no previously-included directories found matching 'docs/_build' 01:40:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:40:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:40:46 INFO - Cleaning up... 01:40:46 INFO - Return code: 0 01:40:46 INFO - Running post-action listener: _resource_record_post_action 01:40:46 INFO - Running post-action listener: _start_resource_monitoring 01:40:46 INFO - Starting resource monitoring. 01:40:46 INFO - ##### 01:40:46 INFO - ##### Running install step. 01:40:46 INFO - ##### 01:40:46 INFO - Running pre-action listener: _resource_record_pre_action 01:40:46 INFO - Running main action method: install 01:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:46 INFO - Reading from file tmpfile_stdout 01:40:46 INFO - Detecting whether we're running mozinstall >=1.0... 01:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:40:46 INFO - Reading from file tmpfile_stdout 01:40:46 INFO - Output received: 01:40:46 INFO - Usage: mozinstall [options] installer 01:40:46 INFO - Options: 01:40:46 INFO - -h, --help show this help message and exit 01:40:46 INFO - -d DEST, --destination=DEST 01:40:46 INFO - Directory to install application into. [default: 01:40:46 INFO - "/builds/slave/test"] 01:40:46 INFO - --app=APP Application being installed. [default: firefox] 01:40:46 INFO - mkdir: /builds/slave/test/build/application 01:40:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:41:08 INFO - Reading from file tmpfile_stdout 01:41:08 INFO - Output received: 01:41:08 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:41:08 INFO - Running post-action listener: _resource_record_post_action 01:41:08 INFO - ##### 01:41:08 INFO - ##### Running run-tests step. 01:41:08 INFO - ##### 01:41:08 INFO - Running pre-action listener: _resource_record_pre_action 01:41:08 INFO - Running main action method: run_tests 01:41:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:41:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:41:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:41:08 INFO - 2016-06-07 01:41:08.790 screenresolution[1640:7485] starting screenresolution argv=screenresolution get 01:41:08 INFO - 2016-06-07 01:41:08.793 screenresolution[1640:7485] Display 0: 1600x1200x32@60 01:41:08 INFO - 2016-06-07 01:41:08.799 screenresolution[1641:7488] starting screenresolution argv=screenresolution list 01:41:08 INFO - Available Modes on Display 0 01:41:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:41:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:41:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:41:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:41:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:41:09 INFO - Intel Iris: 01:41:09 INFO - Chipset Model: Intel Iris 01:41:09 INFO - Type: GPU 01:41:09 INFO - Bus: Built-In 01:41:09 INFO - VRAM (Dynamic, Max): 1536 MB 01:41:09 INFO - Vendor: Intel (0x8086) 01:41:09 INFO - Device ID: 0x0a2e 01:41:09 INFO - Revision ID: 0x0009 01:41:09 INFO - Displays: 01:41:09 INFO - Display: 01:41:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:41:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:41:09 INFO - Display Serial Number: 1600x1200 60 01:41:09 INFO - Main Display: Yes 01:41:09 INFO - Mirror: Off 01:41:09 INFO - Online: Yes 01:41:09 INFO - Rotation: Supported 01:41:09 INFO - Return code: 0 01:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:41:09 INFO - Python 2.7.3 01:41:09 INFO - Return code: 0 01:41:09 INFO - grabbing minidump binary from tooltool 01:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:09 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b10e00>, '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 0x105979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b0bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:41:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:41:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:41:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:41:09 INFO - Return code: 0 01:41:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:41:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:41:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:41:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:41:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:41:10 INFO - Return code: 0 01:41:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:41:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w6p5MoZ1I3/Render', 01:41:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:10 INFO - 'HOME': '/Users/cltbld', 01:41:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:10 INFO - 'LOGNAME': 'cltbld', 01:41:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:41:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:10 INFO - 'MOZ_NO_REMOTE': '1', 01:41:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:41:10 INFO - 'NO_EM_RESTART': '1', 01:41:10 INFO - 'PAGER': '/bin/cat', 01:41:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:10 INFO - 'PWD': '/builds/slave/test', 01:41:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:41:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:10 INFO - 'SHELL': '/bin/bash', 01:41:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6K9OUgdX8O/Listeners', 01:41:10 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 01:41:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:10 INFO - 'USER': 'cltbld', 01:41:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:10 INFO - 'XPC_FLAGS': '0x0', 01:41:10 INFO - 'XPC_SERVICE_NAME': '0', 01:41:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:41:10 INFO - mozversion application_buildid: 20160606194329 01:41:10 INFO - mozversion application_changeset: 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d 01:41:10 INFO - mozversion application_display_name: Firefox 01:41:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:41:10 INFO - mozversion application_name: Firefox 01:41:10 INFO - mozversion application_remotingname: firefox 01:41:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:41:10 INFO - mozversion application_vendor: Mozilla 01:41:10 INFO - mozversion application_version: 48.0 01:41:10 INFO - mozversion platform_buildid: 20160606194329 01:41:10 INFO - mozversion platform_changeset: 1f9f6bdee31c14fd5a39485e8ae326de2928ef7d 01:41:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:41:10 INFO - mozversion platform_version: 48.0 01:41:10 INFO - using testdate: 1465288870 01:41:10 INFO - actual date: 1465288870 01:41:10 INFO - starting webserver on 'localhost:49272' 01:41:10 INFO - SUITE-START | Running 2 tests 01:41:10 INFO - TEST-START | dromaeo_css 01:41:10 INFO - operating with platform_type : mac_ 01:41:10 INFO - Initialising browser for dromaeo_css test... 01:41:10 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpEtF5ct/profile http://localhost:49272/getInfo.html) 01:41:12 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 01:41:12 INFO - PROCESS | 1659 | colorDepth:24 01:41:12 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 01:41:12 INFO - PROCESS | 1659 | __metrics 01:41:12 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:41:12 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:41:13 INFO - TEST-INFO | 1659: exit 0 01:41:13 INFO - Browser initialized. 01:41:13 INFO - Running cycle 1/1 for dromaeo_css test... 01:41:13 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpEtF5ct/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:25 INFO - PROCESS | 1662 | RSS: Main: 161529856 01:41:25 INFO - PROCESS | 1662 | 01:42:16 INFO - PROCESS | 1662 | 1465288936790 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:44:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 01:44:28 INFO - PROCESS | 1662 | RSS: Main: 295141376 01:44:28 INFO - PROCESS | 1662 | 01:47:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 01:47:31 INFO - PROCESS | 1662 | RSS: Main: 303931392 01:47:31 INFO - PROCESS | 1662 | 01:50:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 01:50:34 INFO - PROCESS | 1662 | RSS: Main: 309280768 01:50:34 INFO - PROCESS | 1662 | 01:53:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 01:53:37 INFO - PROCESS | 1662 | RSS: Main: 308920320 01:53:37 INFO - PROCESS | 1662 | 01:56:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 01:56:40 INFO - PROCESS | 1662 | RSS: Main: 273133568 01:56:40 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 01:59:43 INFO - PROCESS | 1662 | RSS: Main: 316272640 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | __start_tp_report 01:59:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 01:59:43 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 01:59:43 INFO - PROCESS | 1662 | |i|pagename|runs| 01:59:43 INFO - PROCESS | 1662 | |0;dojo.html;3531.7174626029914;3585.1574879903233;3595.568531776189;3600.8739694110704;3671.9449208261863;5838.2702162229725;5854.590178687494;5875.647461152334;5889.941100588998;5951.821445356641;5028.220625802997;5393.217983392416;5856.970715146429;5901.586888917766;5910.497607703351;5268.446813084628;5283.128283833173;5284.392294886099;5374.193870919355;5401.810936617219;5347.224652425396;5388;5397.001554712371;5398.190271459273;5398.91901621476;5301.992621401941;5351.983123206598;5371.489708477708;5378.031954248234;5399.082156033464;5419.970205660912;5424.403315635277;5435.83692489226;5438.6736795791985;5446.782128714846;5178.585713142939;5210.895782084337;5333.120035194188;5336.759845806948;5341.946580534222;4780.521947805227;5722.942770572283;5742.626729262626;5785.305763308388;5815.82552523425;6277.8744425111245;6332.208473940757;6334.556581039329;6338.841528961812;6362.840928976766;6179.19670442844;6235.532335074888;6296.118543403927;6304.684765761694;6326.68366581669;5973.01061901835;6017.669028203403;6018.849528761816;6034.547408944347;6065.909011364833;6368.840778980562;6377.139086223393;6390.265119511223;6391.712372943229;6396.84007899797;6032.607880487754;6213.565050446426;6301.873962520815;6339.873202535923;6353.110564520971;6290.654014029181;6304.211973503312;6308.400701933309;6312.684365781693;6316.652584107826;6298.653574053379;6330.272018717907;6355.777547785898;6356.173697419398;6367.235931688227;6245.687715614201;6246.968765156236;6322.620642761449;6327.968360158262;6333.050042493663;4145.398917156996;4983.127952608352;5025.773840177201;5038.395392552917;5051.494850514992;4518.1415531049;4520.977395113004;4528.569785870337;4529.071540334224;4547.590716835434;5842.094475356326;5879.2944846618675;5942.316633587193;6005.519558435315;6013.188219590387;6242.781502647385;6275.090111933744;6285.9057114143325;6287.182666253358;6288.9056664150075;5825.563082768809;5906.379830117776;5926.170336152942;5941.792037278675;5979.372165922602;5450.91823622646;5533.7786488540005;5534.308211473566;5537.833864984057;5539.7784088636;6406.903896441557;6430.549861509743;6437.227532696106;6524.380183882524;6540.771073012422;6249.875002500015;6272.843178920563;6285.717142728497;6291.402316779899;6296.874062518632;6457.773977910751;6462.386073323027;6471.870562588628;6488.9026664601015;6513.576617519846;6177.62934223948;6234.812955611339;6335.619862808247;6343.651099189589;6369.267534233437;6268.968655156695;6298.0867774171575;6301.180846489927;6301.40136687014;6312.589681670676;6287.748490060347;6297.842553936188;6314.905276420949;6317.526185536011;6320.241571011508;6129.114290130839;6171.1668924694595;6225.937740622536;6285.402886725754;6291.150694656346;6133.417325354084;6181.412765787243;6186.659733714689;6193;6201.348858369806;5634.577406694432;5665.348484924287;6157.630542167484;6243.313235544178;6290.779822706183;6143.72682107623;6174.8147555573405;6178.104174894705;6192.349803270592;6208.627482351073 01:59:43 INFO - PROCESS | 1662 | |1;ext.html;530.700154412757;552.2544564837468;566.4052744618152;566.4335664335664;633.6831584207896;32627.836860815692;33198.50202246965;40457.786266412055;40744.18511629765;40820.183596328054;43287.35068973968;43522.7823860881;43548.78225608868;43561;43625.78187109068;9689.85465218024;9931.602735890592;9935.90064099357;9936.850947235796;9944.502774861265;9194.586243619053;10262.846057309147;12042.698932526713;12271.754564908697;12290.56983005595;11914.46384912681;11987.100967427434;11988.940055299712;12012.819807702892;12014.579489717862;11943.70140746484;12113.273203607767;12114.939425302906;12128.272303661808;12131.272123672608;11214.439278036147;11732.64802055931;11750.764984700258;11756.823647645293;11980.520779168823;41838;42232.57767422348;42317.36523952143;42343.576564234274;42445;3375.0043737097617;3395.422778127724;3428.4171690812623;3566.625504322061;3786.318462676717;25000.624990625154;29493.262668433246;29688.851555742298;30140.24649383783;30449.08652740421;2366.8934897929635;2760.1305588739483;2791.6091747155415;2808.4663913856302;2866.1115054333222;513258;516475;517592.9999999962;517634;518005.8199417995;38833.80583097123;39725.80137099354;39737.80131099326;39790.00524986841;67009;38470.038249043995;38518;38546.03634909094;38605.613943860764;38656.03359915968;16524.75212871808;16651.25069371881;16667.083310417802;16670.666586668198;16716.24776885043;12924.289164095877;13099.803502947463;13242.536511222253;14172.362243699059;14283.714325713427;8032.959835200903;8119.309858661962;8439.704610338731;8623.094575069654;8640.438371505832;3501.7548771586253;3564.554430696163;3575.37430949588;3577.83899724513;3596.8561257549927;990.1435258501344;1042.6871938418444;1044.8589440425596;1044.9915831222906;1061.7345663584103;29476;29643.703562964096;29988.700112999028;30597.69402305993;30728.846355768525;1134.9432528373547;1153.936533490666;1169.0764296206073;1205.0660737928176;1293.0948336164533;13922.86077139236;14830.036047656868;15269.847301527065;15524.534263972098;15905.761413578806;625338;635510;638756;640558;642951;65124.674376627816;66067.66966165138;66159.66920165368;66311;66637.00266975995;1511.2292730707418;1554.813422389298;1600.5118438876254;1602.8797840162035;1677.0273241520133;29965.700342997163;30502.237444063187;31686.68313316837;32628.021159365257;33184.336313274085;62573.68713156587;63505.68247158919;63553.364466354746;63585.36414635794;63771.7245655075;543411;559032;561853.1907340437;562577;565051;36109.819450903626;37733.622663774106;43421;44572.77136143115;44694.3295850569;36816.07959801026;43945.12109757851;44118.89702757457;44730;45142;39683;40121.59878401179;40296.597034029284;40921.18157636771;41366.37950430686;36545.634543654225;36693.26613467769;37005;37254;37453.43819842759;36550;36895.07762305856;37345.813270933475;37618.43572346362;37650.62349376581;31025.8448707755;32544.186395340304;32631.347373052882;32643;33764.83117584397;21457.892710536347;25382.365440864127;25495;25737.099201527857;26123.477530448905 01:59:43 INFO - PROCESS | 1662 | |2;jquery.html;418.50616272798106;418.55214920035576;419;422.15779519857887;431.4326660441519;15529.22353882307;16077.356905723771;21256.256031038916;21257.36227913163;21411.892940535316;20895.955202239904;21279.59526475764;22266.332010039725;22287.331380058582;22291.331260062223;5346.4118946915805;5470.9452905470935;6161.661108639046;6209.813705588817;6210;5982.581219314628;6142.539309551779;6145.4161854623735;6158.414950579688;6178.814635560918;4450.465944086698;6544.869102617945;6836.5556238844565;6837.521373503857;6844.760433384832;6518.869622607522;6774.119364482585;6787.9321206788045;6805.31946805323;6821.590704557718;5968.985272503684;5985.700714964278;5999.490043346321;6000.75996960121;6003.579749417542;25836.8708156458;27022.864885675444;27112.59331110035;27213.77538010794;27687.892484300603;1966.5575245569776;2238.3620668109675;2341.730700969393;2468.8148388870904;2932.262840449529;10216.591336346537;10611.363318200856;11404.258723182966;11552.018078463341;15607.843921560867;1841.5764374194011;1867.2624313396268;1886.56608979934;1913.7799153097433;1965.2433812981963;113730;115593;117548;121248;123276;11904.583339583247;11941.044716422666;11999;12002.939985300018;12053.397330133634;14327.140371577738;14775.482858099915;14785.778213326808;15816.446424375092;15840;8884.37809353339;9214.76963075915;9245.861312080324;9336.159745622926;9343.71968840936;9361.859572106423;9402.48286344244;9413.48225847585;9420.528973551294;9511.811023622047;4817.253325734517;4823;4840.75796210188;4846.321514987904;4881.8535443936735;1996.8502362322884;2046.478148072232;2058.3824852544176;2067.7208576841936;2081.9271325503537;955.9187469065068;961.1685891703626;980.9558569864373;999.4602914426129;1073.1522097543009;20395.082221300076;21046.473838153863;21219.36341909745;21418.03618837125;21490.032948517353;996.4021587047713;1024.5799222318813;1034.818906691326;1054.6466933577185;1060.437967877011;10901.509432075576;11217.326960382403;11249.931256530617;11262;11274.98525132742;455001;556294;643960.5603943995;645687;659404;130892;133539.66460335467;143305.28347358198;143902;149687.25156374148;1045.1012129568564;1077.5205033760058;1094.370736826328;1096.276457538021;1098.653924013949;17991.730124048412;18017.639647207245;18066.290056491107;18388.632227355647;18678.439646810617;51217.46347609578;51373.71565710738;51656.158911260245;51998.22002670039;53854.73072634612;69522;72837.6358118206;72861.6356918212;73099;73107.63446182734;6931.92555153952;7579.772606821804;8083.676652934052;8127.512349259064;8345.707900223462;9194.402363846328;12331;12912;12964.935175324063;13192.670183245496;8606.483610983361;10243.94878025605;11282.661520154408;11453.942730286295;11663.59177428786;10906;11284.661460156209;11520.654380368602;11696.005839503738;11736.354500502553;11747.647570572897;11748.94125529401;11948.223365481215;12106.607740109648;12252.754944900873;8405.579721014046;8508.319334453105;8536.487810731376;8573.699920502753;8657.264132548804;11373.829392558953;11730.589429370273;11913.046956243654;12004.75990480203;12034.217775844892 01:59:43 INFO - PROCESS | 1662 | |3;mootools.html;1505.051817355066;1646.6295083606187;1668.5911951571863;1984.7320611717414;2046.8362530997522;8230.75307740768;8767.51778652174;8920.643174273022;8922.420042697227;8922.95538522308;6887.793366199009;8934.64261429542;8938.821223575525;8944.28445724342;8959.23846473049;2516.308015295799;2518.874056297187;2544.185860524633;2547.8343907645944;2559.2706078767555;2451.558719430511;2536.4039450729156;2537.4544472938314;2549.8980040798347;2552.7702506774385;2506.1729629222314;2537.7969762419057;2554.361409647588;2555.0418593027616;2555.6038813983864;2504.636827659979;2527.064985955208;2529.165375426105;2541.428178659796;2554.9361265968405;2500.3999040230397;2509.1719732488236;2515.019142534413;2517.32032351266;2561.743825617442;8657.826843463095;9025.323100767402;9106.77233069172;9108.726738197865;9123.543822808833;5409.945900541023;5580.637258578179;5588.469095435927;5590.944090559083;5596.720163991825;3568.018794831416;3597.2625611749527;3638.3450978823803;3686.6129056448954;3694.371956767356;1046.382634245791;1054.8628678271853;1056.4242487844144;1060;1062.9681109566725;12094;12169.391530423443;12198.878011219864;12203.877961220453;12233.69415764613;4660.370849935215;4682;4798.928016079762;4801.063792560417;4818.975905120522;9550.617975280911;9578.712638620853;9580.856287155699;9584.568694408768;9585.568649410794;5500.119980803052;5531.225628412026;5554.638948468337;5600.6919619420505;5604.075327570987;4493.505714371417;4636.281376386665;4957.107720610253;5098.980203959223;5151.150060240094;3900.2004589059743;3982.880513584597;4019.2363450944226;4034.11249525104;4040.0303927057294;1246.2896149195053;1259.9118061735774;1266.645339304996;1267.7211013576998;1278.961631151067;823.1962827232925;933.6638810028385;967.9225661947028;998.8951160128228;1014.7260239735231;6110.786122485781;6121.051237058263;6127.96936015326;6223.564350495421;6243.125962365272;472.05117713395634;477.44854692829836;481.55456203012074;484.7358189786575;486.2123360156569;3497.8425970830863;3972.82122304497;4055.8377664893665;4106.527749308808;4144.564820693844;9823.705288841345;10272.897271027343;10689.679309620724;10698.465076746132;10713.250072494851;9434;9719.319647624598;10502.789944201073;10518.474076296154;10659.467026648637;7833.8249262609925;7870;7927.365810735012;7994.800129996796;8009.159038300894;5750.568707347049;5991.400859914148;6044.153818465506;6136.938630613637;6152.723127459186;9512.476813775032;9682.806343873224;9809;9810.362326448758;9832.705018849447;8195.46729462583;9663.855042174513;9845.655402060893;9855.063768941938;9987.550560224952;6966.1640603125925;7059.399951004222;7072.82317942035;7503.212162723054;7655.464117511943;7523.623818809147;8025.07711613172;8193.590320483832;8231.135730748425;8255.504669719836;7315.231900650355;7452.888206677013;7477.663505142173;7540.547567145989;7558.244175582618;7322.670479828314;7356.190819009797;7375.37309328713;7410.073740782403;7461.850762984602;7077.469189810682;7115.715371385087;7119.786406407608;7129.1445026595065;7193.5324203926575;4922.261660750916;4962.727050012284;5078.720670363018;5134.998675258438;5156.510131537498;5000.2999580058095;5043.293938848491;5060.1650727631;5116.8720781979255;5295.099833028317 01:59:43 INFO - PROCESS | 1662 | |4;prototype.html;331.1588565044786;334.2279334736758;341.6685814759683;343.7215855157321;347.056007659167;6678;6742.044807005968;6761.459083273338;6778.322167783219;6787.15160604925;5454.481824226692;6089.5128389728825;6698.095757072793;6703.899441508382;6720.46236301096;4753.16819556578;4839.879003024917;4843.539863712942;4938.654294199408;4964.851054468371;4871.537203965618;4940.160172770619;4946.505349465061;4951.603871690256;4982.850514484571;4931.3835770528685;4935.593355893571;4938.308636790852;4946.901061978759;4952.702837829742;4695.201815691324;4774.427068751737;4902.166631672624;4910.323873488957;4927.7536123193695;3525.7355698322726;4215.915681686349;4232.343986682069;4243.215005224012;4255.468066491689;5185.222216667492;6469.611823290617;6480.773172938924;6485.93514064858;6498.317676643979;5834.241548598698;5837.328707198684;5838.241028666289;5839.416058394169;5847.736851841677;7136.500444968854;7142.642867856586;7158.069450971392;7175.103112110986;7201.675924583406;3826.9808650956847;4393.8462153824785;4656.790444430008;4659.347691323217;4661.137689527414;6106.2977757558;7406.59263740494;7582.810429739246;7591.051118610174;7599.126100498458;4717.646176536773;4721.527847215251;4731.101090792738;4732.834350797705;4735.052989402065;4781.187198176319;4784.162771514971;4791.6645834791925;4797.40032495938;4809.2305231162845;4808.975955120202;5620.971895140498;5696.202531645573;5699.686517241508;5772;6785.219699734495;7130.786076417716;7138.072050633385;7182.281771822881;7192.568445893263;1627.8697704183637;1640.0323808952776;1654.7683324334603;1656.213298683116;1656.6438215783664;990.1880458024351;1035.3529044347283;1035.8187317219456;1059.777446736194;1063.5905176507101;6560.770373037016;6738.831529211809;6914.619695916672;6925.549839260432;6947.89578156328;7473.327400533869;7503.737369192052;7504.6247687615405;7506.399488040944;7523.285287897641;6861.142357205349;6877.277885822016;6880.415164710956;6880.69036893341;6917.515773895779;3254.316593515341;3826.445830047072;3916.549596796348;3920.843166273374;3981.2283533827167;7515.797472404498;7861.764147075597;7867.9606601966625;7926.207379262143;7932.722354717557;6600.405963463311;7751.147373788879;7758.573278469626;7763.340116090196;7772.339351155102;5502.972485137629;7880.133185349607;7886.0536735592095;7890.447668663253;7895.447318687637;7162.856742865218;7422.591757453392;7453.515521491086;7466.962665186639;7485.513441626277;5974.641521508724;7340.816479588053;7390.778276651709;7391.40868730511;7393.482456228227;7856.525194563694;7875.094364148196;7891.171427000312;7892.289693927574;7901.565413902291;4708.858734237979;4710.8351207707565;4712.104700106968;4723.527647235386;4727.645426592951;7197.964010179916;7453.105627324756;7498.62506874665;7508.924910750822;7517.173110957681;6295.244570651551;7203.459740519586;7209.603471808891;7224.74713385029;7237.131544214728;6244.250689917239;7178.102737157856;7194.496385252982;7206.675699593426;7237.674304656409;7049.894251586333;7061;7064.470164737562;7080.327368899946;7086.503944724026;6977.581345119309;7238.23998480152;7253.927460725326;7263.055802745609;7267.60028198433;6292;6868.587884726932;7036.542624729376;7112.537685050455;7134.857302854018 01:59:43 INFO - PROCESS | 1662 | |5;yui.html;702.5608994378514;732.7398773435431;735.7608777147425;750.8273097187647;766.5860435364904;15246.92376538117;15437.305321260543;15450.072995620243;15457.149856757887;15457.381704731812;14086.57740267791;14257.928710356433;14272.643183920407;14284.142951422897;14312.785308220386;776.5030380556434;794.5907857453416;947.303731757158;954.4034978138664;957.9233661307079;953.50894289441;959.9760005999871;961.0437614573509;961.5192403798101;964.0841200859177;828.2876726015621;1017.4912543728136;1017.5980487707351;1021.0249212002519;1024.4263212600931;1009.2884516415946;1045.545187843283;1045.6131231444376;1047.748540350318;1047.9161667066617;757.9924200757977;758.446334176049;759.1497522774473;760.125855266448;760.9619519024027;12376;15019.624509387244;15074.849251507563;15093.24533773307;15100.395984160621;12680.492780288778;12831.550895718698;13060.34698265083;13256.654058888556;13480.865191348157;10111.747206319827;10284.691459256233;10319.380837149794;10599.523021463976;11011.559537618487;3294.390537750524;3601.603823579404;3634.055145662147;3657.6159503252306;3684.392075307571;70940.58118837595;71571;71599;71633.6418317905;71736;9646.228301735844;9762.170215531618;9803.264755143393;9808.117269445784;10098.141657959155;22682.88658556697;22699.88650056739;22702.205422810122;22713.772862271497;23494.412639684146;7516.210797866254;7532.472726909064;7573.091229052438;7580.3556697680215;7596.2023987481625;4152.086540960984;4223.852165174204;4251.086016506466;4288.635465985364;4406.074724307931;3821.541415030214;3884.6309600587897;3892.746971446847;3894.532656081288;3904.882853514399;617.2531237202945;700.1003710232429;717.5264325545115;741.4476215219696;754.6490881740017;597.8744463662592;616.4082480818375;616.8149555133532;632.8164832198703;649.1107183159103;7469.215732348134;7473.252674732592;7542.585157816263;7566.697332106654;7699.191584883619;480.1304057892239;516.1097107489551;607.0196632438668;625.8779537990139;630.2562975688639;3732.4028155495535;3927.7250592458827;3980.5024371953505;4066.3290557058344;4067.369557718499;89804.5509772447;91221.63167552359;91723.54138229266;92026.61960070605;92569.0743092574;77503.61248193835;81154;81168.59415702884;81749.36501269942;82341.58829205815;66972.66054678879;69266;71050;72030;73843;8389.916100838913;8523.95738021306;8573.399862009599;8596.527191004554;8753.38726289179;49008;50287.497125029746;50672;51570.74214628903;51675.483245167066;63715.36284637095;70237;71163.64418178084;71337;74662;11159.051480624237;11200.607978720705;11320;11370.374629395132;11998.700032499257;11566.537338506701;11898.286102833858;11980.520779168735;12067.396630168281;12873.742525149632;11526.366049867338;11618.825717614412;11710.355930423908;11724.58963936258;11792.29246245228;11569.6529104127;11676.649700508999;11758.823617645914;11772.587959421378;12128.575499857463;11484.770304594029;11497;11643.126765492452;11675.532978680758;11720.941395292968;9797.31418800677;9940.10539051489;9941.019407802962;9962.900370996487;10096.798064038825;8261.958690206751;9790.608375664893;9887.209023278265;9889.653862114792;10014.098731114234 01:59:43 INFO - PROCESS | 1662 | __end_tp_report 01:59:43 INFO - PROCESS | 1662 | __start_cc_report 01:59:43 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1230 01:59:43 INFO - PROCESS | 1662 | __end_cc_report 01:59:43 INFO - PROCESS | 1662 | __startTimestamp1465289983543__endTimestamp 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | ------- Summary: start ------- 01:59:43 INFO - PROCESS | 1662 | Number of tests: 6 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5859.71 Median:6171.17 stddev:641.63 (10.4%) stddev-sans-first:617.06 01:59:43 INFO - PROCESS | 1662 | Values: 3531.7 3585.2 3595.6 3600.9 3671.9 5838.3 5854.6 5875.6 5889.9 5951.8 5028.2 5393.2 5857.0 5901.6 5910.5 5268.4 5283.1 5284.4 5374.2 5401.8 5347.2 5388.0 5397.0 5398.2 5398.9 5302.0 5352.0 5371.5 5378.0 5399.1 5420.0 5424.4 5435.8 5438.7 5446.8 5178.6 5210.9 5333.1 5336.8 5341.9 4780.5 5722.9 5742.6 5785.3 5815.8 6277.9 6332.2 6334.6 6338.8 6362.8 6179.2 6235.5 6296.1 6304.7 6326.7 5973.0 6017.7 6018.8 6034.5 6065.9 6368.8 6377.1 6390.3 6391.7 6396.8 6032.6 6213.6 6301.9 6339.9 6353.1 6290.7 6304.2 6308.4 6312.7 6316.7 6298.7 6330.3 6355.8 6356.2 6367.2 6245.7 6247.0 6322.6 6328.0 6333.1 4145.4 4983.1 5025.8 5038.4 5051.5 4518.1 4521.0 4528.6 4529.1 4547.6 5842.1 5879.3 5942.3 6005.5 6013.2 6242.8 6275.1 6285.9 6287.2 6288.9 5825.6 5906.4 5926.2 5941.8 5979.4 5450.9 5533.8 5534.3 5537.8 5539.8 6406.9 6430.5 6437.2 6524.4 6540.8 6249.9 6272.8 6285.7 6291.4 6296.9 6457.8 6462.4 6471.9 6488.9 6513.6 6177.6 6234.8 6335.6 6343.7 6369.3 6269.0 6298.1 6301.2 6301.4 6312.6 6287.7 6297.8 6314.9 6317.5 6320.2 6129.1 6171.2 6225.9 6285.4 6291.2 6133.4 6181.4 6186.7 6193.0 6201.3 5634.6 5665.3 6157.6 6243.3 6290.8 6143.7 6174.8 6178.1 6192.3 6208.6 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:69988.13 Median:36547.82 stddev:153057.49 (418.8%) stddev-sans-first:153398.02 01:59:43 INFO - PROCESS | 1662 | Values: 530.7 552.3 566.4 566.4 633.7 32627.8 33198.5 40457.8 40744.2 40820.2 43287.4 43522.8 43548.8 43561.0 43625.8 9689.9 9931.6 9935.9 9936.9 9944.5 9194.6 10262.8 12042.7 12271.8 12290.6 11914.5 11987.1 11988.9 12012.8 12014.6 11943.7 12113.3 12114.9 12128.3 12131.3 11214.4 11732.6 11750.8 11756.8 11980.5 41838.0 42232.6 42317.4 42343.6 42445.0 3375.0 3395.4 3428.4 3566.6 3786.3 25000.6 29493.3 29688.9 30140.2 30449.1 2366.9 2760.1 2791.6 2808.5 2866.1 513258.0 516475.0 517593.0 517634.0 518005.8 38833.8 39725.8 39737.8 39790.0 67009.0 38470.0 38518.0 38546.0 38605.6 38656.0 16524.8 16651.3 16667.1 16670.7 16716.2 12924.3 13099.8 13242.5 14172.4 14283.7 8033.0 8119.3 8439.7 8623.1 8640.4 3501.8 3564.6 3575.4 3577.8 3596.9 990.1 1042.7 1044.9 1045.0 1061.7 29476.0 29643.7 29988.7 30597.7 30728.8 1134.9 1153.9 1169.1 1205.1 1293.1 13922.9 14830.0 15269.8 15524.5 15905.8 625338.0 635510.0 638756.0 640558.0 642951.0 65124.7 66067.7 66159.7 66311.0 66637.0 1511.2 1554.8 1600.5 1602.9 1677.0 29965.7 30502.2 31686.7 32628.0 33184.3 62573.7 63505.7 63553.4 63585.4 63771.7 543411.0 559032.0 561853.2 562577.0 565051.0 36109.8 37733.6 43421.0 44572.8 44694.3 36816.1 43945.1 44118.9 44730.0 45142.0 39683.0 40121.6 40296.6 40921.2 41366.4 36545.6 36693.3 37005.0 37254.0 37453.4 36550.0 36895.1 37345.8 37618.4 37650.6 31025.8 32544.2 32631.3 32643.0 33764.8 21457.9 25382.4 25495.0 25737.1 26123.5 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35305.25 Median:21878.18 stddev:99941.74 (456.8%) stddev-sans-first:100187.78 01:59:43 INFO - PROCESS | 1662 | Values: 418.5 418.6 419.0 422.2 431.4 15529.2 16077.4 21256.3 21257.4 21411.9 20896.0 21279.6 22266.3 22287.3 22291.3 5346.4 5470.9 6161.7 6209.8 6210.0 5982.6 6142.5 6145.4 6158.4 6178.8 4450.5 6544.9 6836.6 6837.5 6844.8 6518.9 6774.1 6787.9 6805.3 6821.6 5969.0 5985.7 5999.5 6000.8 6003.6 25836.9 27022.9 27112.6 27213.8 27687.9 1966.6 2238.4 2341.7 2468.8 2932.3 10216.6 10611.4 11404.3 11552.0 15607.8 1841.6 1867.3 1886.6 1913.8 1965.2 113730.0 115593.0 117548.0 121248.0 123276.0 11904.6 11941.0 11999.0 12002.9 12053.4 14327.1 14775.5 14785.8 15816.4 15840.0 8884.4 9214.8 9245.9 9336.2 9343.7 9361.9 9402.5 9413.5 9420.5 9511.8 4817.3 4823.0 4840.8 4846.3 4881.9 1996.9 2046.5 2058.4 2067.7 2081.9 955.9 961.2 981.0 999.5 1073.2 20395.1 21046.5 21219.4 21418.0 21490.0 996.4 1024.6 1034.8 1054.6 1060.4 10901.5 11217.3 11249.9 11262.0 11275.0 455001.0 556294.0 643960.6 645687.0 659404.0 130892.0 133539.7 143305.3 143902.0 149687.3 1045.1 1077.5 1094.4 1096.3 1098.7 17991.7 18017.6 18066.3 18388.6 18678.4 51217.5 51373.7 51656.2 51998.2 53854.7 69522.0 72837.6 72861.6 73099.0 73107.6 6931.9 7579.8 8083.7 8127.5 8345.7 9194.4 12331.0 12912.0 12964.9 13192.7 8606.5 10243.9 11282.7 11453.9 11663.6 10906.0 11284.7 11520.7 11696.0 11736.4 11747.6 11748.9 11948.2 12106.6 12252.8 8405.6 8508.3 8536.5 8573.7 8657.3 11373.8 11730.6 11913.0 12004.8 12034.2 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5720.17 Median:5153.83 stddev:3131.02 (60.8%) stddev-sans-first:3123.77 01:59:43 INFO - PROCESS | 1662 | Values: 1505.1 1646.6 1668.6 1984.7 2046.8 8230.8 8767.5 8920.6 8922.4 8923.0 6887.8 8934.6 8938.8 8944.3 8959.2 2516.3 2518.9 2544.2 2547.8 2559.3 2451.6 2536.4 2537.5 2549.9 2552.8 2506.2 2537.8 2554.4 2555.0 2555.6 2504.6 2527.1 2529.2 2541.4 2554.9 2500.4 2509.2 2515.0 2517.3 2561.7 8657.8 9025.3 9106.8 9108.7 9123.5 5409.9 5580.6 5588.5 5590.9 5596.7 3568.0 3597.3 3638.3 3686.6 3694.4 1046.4 1054.9 1056.4 1060.0 1063.0 12094.0 12169.4 12198.9 12203.9 12233.7 4660.4 4682.0 4798.9 4801.1 4819.0 9550.6 9578.7 9580.9 9584.6 9585.6 5500.1 5531.2 5554.6 5600.7 5604.1 4493.5 4636.3 4957.1 5099.0 5151.2 3900.2 3982.9 4019.2 4034.1 4040.0 1246.3 1259.9 1266.6 1267.7 1279.0 823.2 933.7 967.9 998.9 1014.7 6110.8 6121.1 6128.0 6223.6 6243.1 472.1 477.4 481.6 484.7 486.2 3497.8 3972.8 4055.8 4106.5 4144.6 9823.7 10272.9 10689.7 10698.5 10713.3 9434.0 9719.3 10502.8 10518.5 10659.5 7833.8 7870.0 7927.4 7994.8 8009.2 5750.6 5991.4 6044.2 6136.9 6152.7 9512.5 9682.8 9809.0 9810.4 9832.7 8195.5 9663.9 9845.7 9855.1 9987.6 6966.2 7059.4 7072.8 7503.2 7655.5 7523.6 8025.1 8193.6 8231.1 8255.5 7315.2 7452.9 7477.7 7540.5 7558.2 7322.7 7356.2 7375.4 7410.1 7461.9 7077.5 7115.7 7119.8 7129.1 7193.5 4922.3 4962.7 5078.7 5135.0 5156.5 5000.3 5043.3 5060.2 5116.9 5295.1 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5842.18 Median:6701.00 stddev:1899.60 (28.3%) stddev-sans-first:1859.35 01:59:43 INFO - PROCESS | 1662 | Values: 331.2 334.2 341.7 343.7 347.1 6678.0 6742.0 6761.5 6778.3 6787.2 5454.5 6089.5 6698.1 6703.9 6720.5 4753.2 4839.9 4843.5 4938.7 4964.9 4871.5 4940.2 4946.5 4951.6 4982.9 4931.4 4935.6 4938.3 4946.9 4952.7 4695.2 4774.4 4902.2 4910.3 4927.8 3525.7 4215.9 4232.3 4243.2 4255.5 5185.2 6469.6 6480.8 6485.9 6498.3 5834.2 5837.3 5838.2 5839.4 5847.7 7136.5 7142.6 7158.1 7175.1 7201.7 3827.0 4393.8 4656.8 4659.3 4661.1 6106.3 7406.6 7582.8 7591.1 7599.1 4717.6 4721.5 4731.1 4732.8 4735.1 4781.2 4784.2 4791.7 4797.4 4809.2 4809.0 5621.0 5696.2 5699.7 5772.0 6785.2 7130.8 7138.1 7182.3 7192.6 1627.9 1640.0 1654.8 1656.2 1656.6 990.2 1035.4 1035.8 1059.8 1063.6 6560.8 6738.8 6914.6 6925.5 6947.9 7473.3 7503.7 7504.6 7506.4 7523.3 6861.1 6877.3 6880.4 6880.7 6917.5 3254.3 3826.4 3916.5 3920.8 3981.2 7515.8 7861.8 7868.0 7926.2 7932.7 6600.4 7751.1 7758.6 7763.3 7772.3 5503.0 7880.1 7886.1 7890.4 7895.4 7162.9 7422.6 7453.5 7467.0 7485.5 5974.6 7340.8 7390.8 7391.4 7393.5 7856.5 7875.1 7891.2 7892.3 7901.6 4708.9 4710.8 4712.1 4723.5 4727.6 7198.0 7453.1 7498.6 7508.9 7517.2 6295.2 7203.5 7209.6 7224.7 7237.1 6244.3 7178.1 7194.5 7206.7 7237.7 7049.9 7061.0 7064.5 7080.3 7086.5 6977.6 7238.2 7253.9 7263.1 7267.6 6292.0 6868.6 7036.5 7112.5 7134.9 01:59:43 INFO - PROCESS | 1662 | 01:59:43 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18342.24 Median:51121.37 stddev:25393.64 (49.7%) stddev-sans-first:25430.33 01:59:43 INFO - PROCESS | 1662 | Values: 702.6 732.7 735.8 750.8 766.6 15246.9 15437.3 15450.1 15457.1 15457.4 14086.6 14257.9 14272.6 14284.1 14312.8 776.5 794.6 947.3 954.4 957.9 953.5 960.0 961.0 961.5 964.1 828.3 1017.5 1017.6 1021.0 1024.4 1009.3 1045.5 1045.6 1047.7 1047.9 758.0 758.4 759.1 760.1 761.0 12376.0 15019.6 15074.8 15093.2 15100.4 12680.5 12831.6 13060.3 13256.7 13480.9 10111.7 10284.7 10319.4 10599.5 11011.6 3294.4 3601.6 3634.1 3657.6 3684.4 70940.6 71571.0 71599.0 71633.6 71736.0 9646.2 9762.2 9803.3 9808.1 10098.1 22682.9 22699.9 22702.2 22713.8 23494.4 7516.2 7532.5 7573.1 7580.4 7596.2 4152.1 4223.9 4251.1 4288.6 4406.1 3821.5 3884.6 3892.7 3894.5 3904.9 617.3 700.1 717.5 741.4 754.6 597.9 616.4 616.8 632.8 649.1 7469.2 7473.3 7542.6 7566.7 7699.2 480.1 516.1 607.0 625.9 630.3 3732.4 3927.7 3980.5 4066.3 4067.4 89804.6 91221.6 91723.5 92026.6 92569.1 77503.6 81154.0 81168.6 81749.4 82341.6 66972.7 69266.0 71050.0 72030.0 73843.0 8389.9 8524.0 8573.4 8596.5 8753.4 49008.0 50287.5 50672.0 51570.7 51675.5 63715.4 70237.0 71163.6 71337.0 74662.0 11159.1 11200.6 11320.0 11370.4 11998.7 11566.5 11898.3 11980.5 12067.4 12873.7 11526.4 11618.8 11710.4 11724.6 11792.3 11569.7 11676.6 11758.8 11772.6 12128.6 11484.8 11497.0 11643.1 11675.5 11720.9 9797.3 9940.1 9941.0 9962.9 10096.8 8262.0 9790.6 9887.2 9889.7 10014.1 01:59:43 INFO - PROCESS | 1662 | -------- Summary: end -------- 01:59:43 INFO - PROCESS | 1662 | 01:59:44 INFO - TEST-INFO | 1662: exit 0 01:59:44 INFO - TEST-OK | dromaeo_css | took 1113649ms 01:59:44 INFO - TEST-START | kraken 01:59:44 INFO - operating with platform_type : mac_ 01:59:44 INFO - Initialising browser for kraken test... 01:59:44 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp1iM6SZ/profile http://localhost:49272/getInfo.html) 01:59:46 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 01:59:46 INFO - PROCESS | 1672 | colorDepth:24 01:59:46 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 01:59:46 INFO - PROCESS | 1672 | __metrics 01:59:46 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:59:46 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:59:46 INFO - TEST-INFO | 1672: exit 0 01:59:46 INFO - Browser initialized. 01:59:46 INFO - Running cycle 1/1 for kraken test... 01:59:46 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp1iM6SZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:58 INFO - PROCESS | 1675 | RSS: Main: 161308672 01:59:58 INFO - PROCESS | 1675 | 02:00:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 02:00:02 INFO - PROCESS | 1675 | RSS: Main: 223752192 02:00:02 INFO - PROCESS | 1675 | 02:00:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 02:00:05 INFO - PROCESS | 1675 | RSS: Main: 238944256 02:00:05 INFO - PROCESS | 1675 | 02:00:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 02:00:09 INFO - PROCESS | 1675 | RSS: Main: 326299648 02:00:09 INFO - PROCESS | 1675 | 02:00:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 02:00:11 INFO - PROCESS | 1675 | RSS: Main: 230297600 02:00:11 INFO - PROCESS | 1675 | 02:00:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:00:14 INFO - PROCESS | 1675 | RSS: Main: 230596608 02:00:14 INFO - PROCESS | 1675 | 02:00:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 02:00:17 INFO - PROCESS | 1675 | RSS: Main: 269172736 02:00:17 INFO - PROCESS | 1675 | 02:00:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 02:00:21 INFO - PROCESS | 1675 | RSS: Main: 270802944 02:00:21 INFO - PROCESS | 1675 | 02:00:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 02:00:24 INFO - PROCESS | 1675 | RSS: Main: 268316672 02:00:24 INFO - PROCESS | 1675 | 02:00:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:00:27 INFO - PROCESS | 1675 | RSS: Main: 259944448 02:00:27 INFO - PROCESS | 1675 | 02:00:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:00:29 INFO - PROCESS | 1675 | RSS: Main: 266772480 02:00:29 INFO - PROCESS | 1675 | 02:00:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:00:32 INFO - PROCESS | 1675 | RSS: Main: 270528512 02:00:32 INFO - PROCESS | 1675 | 02:00:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:00:35 INFO - PROCESS | 1675 | RSS: Main: 277778432 02:00:35 INFO - PROCESS | 1675 | 02:00:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:00:39 INFO - PROCESS | 1675 | RSS: Main: 274587648 02:00:39 INFO - PROCESS | 1675 | 02:00:40 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 02:00:41 INFO - PROCESS | 1675 | RSS: Main: 335904768 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | __start_tp_report 02:00:41 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 02:00:41 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 02:00:41 INFO - PROCESS | 1675 | |i|pagename|runs| 02:00:41 INFO - PROCESS | 1675 | |0;ai-astar;113;113;120;112;112;111;113;113;115;135 02:00:41 INFO - PROCESS | 1675 | |1;audio-beat-detection;183;149;180;162;118;138;138;117;166;115 02:00:41 INFO - PROCESS | 1675 | |2;audio-dft;185;186;188;184;185;184;186;184;182;183 02:00:41 INFO - PROCESS | 1675 | |3;audio-fft;72;75;77;157;74;82;83;95;128;56 02:00:41 INFO - PROCESS | 1675 | |4;audio-oscillator;91;91;138;76;87;86;123;79;79;124 02:00:41 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;87;90;89;135;121;91;87;90;85 02:00:41 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;94;95;96;94;94;93;94;93;95 02:00:41 INFO - PROCESS | 1675 | |7;imaging-desaturate;130;132;89;85;84;82;99;142;134;85 02:00:41 INFO - PROCESS | 1675 | |8;json-parse-financial;58;62;62;63;62;64;63;77;59;56 02:00:41 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;60;63;71;73;70;66;65;65;65;66 02:00:41 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;79;78;77;78;93;103;103;102;81;84 02:00:41 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;125;126;126;128;140;145;122;130;164;156 02:00:41 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;167;165;167;161;164;164;164;166;169;161 02:00:41 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;67;70;68;66;66;67;69;66;66 02:00:41 INFO - PROCESS | 1675 | __end_tp_report 02:00:41 INFO - PROCESS | 1675 | __start_cc_report 02:00:41 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1878 02:00:41 INFO - PROCESS | 1675 | __end_cc_report 02:00:41 INFO - PROCESS | 1675 | __startTimestamp1465290041182__endTimestamp 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | ------- Summary: start ------- 02:00:41 INFO - PROCESS | 1675 | Number of tests: 14 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.70 Median:113.00 stddev:7.23 (6.4%) stddev-sans-first:7.60 02:00:41 INFO - PROCESS | 1675 | Values: 113.0 113.0 120.0 112.0 112.0 111.0 113.0 113.0 115.0 135.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:155.50 stddev:25.60 (16.5%) stddev-sans-first:23.53 02:00:41 INFO - PROCESS | 1675 | Values: 183.0 149.0 180.0 162.0 118.0 138.0 138.0 117.0 166.0 115.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.70 (0.9%) stddev-sans-first:1.80 02:00:41 INFO - PROCESS | 1675 | Values: 185.0 186.0 188.0 184.0 185.0 184.0 186.0 184.0 182.0 183.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:89.90 Median:76.00 stddev:30.19 (39.7%) stddev-sans-first:31.31 02:00:41 INFO - PROCESS | 1675 | Values: 72.0 75.0 77.0 157.0 74.0 82.0 83.0 95.0 128.0 56.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:82.50 stddev:22.28 (27.0%) stddev-sans-first:23.51 02:00:41 INFO - PROCESS | 1675 | Values: 91.0 91.0 138.0 76.0 87.0 86.0 123.0 79.0 79.0 124.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:89.00 stddev:17.07 (19.2%) stddev-sans-first:17.89 02:00:41 INFO - PROCESS | 1675 | Values: 89.0 87.0 90.0 89.0 135.0 121.0 91.0 87.0 90.0 85.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:0.97 02:00:41 INFO - PROCESS | 1675 | Values: 93.0 94.0 95.0 96.0 94.0 94.0 93.0 94.0 93.0 95.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:106.20 Median:84.50 stddev:24.97 (29.6%) stddev-sans-first:24.96 02:00:41 INFO - PROCESS | 1675 | Values: 130.0 132.0 89.0 85.0 84.0 82.0 99.0 142.0 134.0 85.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.50 stddev:5.66 (9.1%) stddev-sans-first:5.75 02:00:41 INFO - PROCESS | 1675 | Values: 58.0 62.0 62.0 63.0 62.0 64.0 63.0 77.0 59.0 56.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:3.89 (5.9%) stddev-sans-first:3.37 02:00:41 INFO - PROCESS | 1675 | Values: 60.0 63.0 71.0 73.0 70.0 66.0 65.0 65.0 65.0 66.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:78.50 stddev:11.24 (14.3%) stddev-sans-first:11.46 02:00:41 INFO - PROCESS | 1675 | Values: 79.0 78.0 77.0 78.0 93.0 103.0 103.0 102.0 81.0 84.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:135.00 stddev:14.52 (10.8%) stddev-sans-first:14.82 02:00:41 INFO - PROCESS | 1675 | Values: 125.0 126.0 126.0 128.0 140.0 145.0 122.0 130.0 164.0 156.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.50 stddev:2.57 (1.6%) stddev-sans-first:2.60 02:00:41 INFO - PROCESS | 1675 | Values: 167.0 165.0 167.0 161.0 164.0 164.0 164.0 166.0 169.0 161.0 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.42 (2.1%) stddev-sans-first:1.48 02:00:41 INFO - PROCESS | 1675 | Values: 68.0 67.0 70.0 68.0 66.0 66.0 67.0 69.0 66.0 66.0 02:00:41 INFO - PROCESS | 1675 | -------- Summary: end -------- 02:00:41 INFO - PROCESS | 1675 | 02:00:41 INFO - TEST-INFO | 1675: exit 0 02:00:42 INFO - TEST-OK | kraken | took 57913ms 02:00:42 INFO - SUITE-END | took 1171s 02:00:42 INFO - Completed test suite (00:19:31) 02:00:42 INFO - javascript benchmark 02:00:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3531.7174626029914, 3585.1574879903233, 3595.568531776189, 3600.8739694110704, 3671.9449208261863, 5838.2702162229725, 5854.590178687494, 5875.647461152334, 5889.941100588998, 5951.821445356641, 5028.220625802997, 5393.217983392416, 5856.970715146429, 5901.586888917766, 5910.497607703351, 5268.446813084628, 5283.128283833173, 5284.392294886099, 5374.193870919355, 5401.810936617219, 5347.224652425396, 5388.0, 5397.001554712371, 5398.190271459273, 5398.91901621476, 5301.992621401941, 5351.983123206598, 5371.489708477708, 5378.031954248234, 5399.082156033464, 5419.970205660912, 5424.403315635277, 5435.83692489226, 5438.6736795791985, 5446.782128714846, 5178.585713142939, 5210.895782084337, 5333.120035194188, 5336.759845806948, 5341.946580534222, 4780.521947805227, 5722.942770572283, 5742.626729262626, 5785.305763308388, 5815.82552523425, 6277.8744425111245, 6332.208473940757, 6334.556581039329, 6338.841528961812, 6362.840928976766, 6179.19670442844, 6235.532335074888, 6296.118543403927, 6304.684765761694, 6326.68366581669, 5973.01061901835, 6017.669028203403, 6018.849528761816, 6034.547408944347, 6065.909011364833, 6368.840778980562, 6377.139086223393, 6390.265119511223, 6391.712372943229, 6396.84007899797, 6032.607880487754, 6213.565050446426, 6301.873962520815, 6339.873202535923, 6353.110564520971, 6290.654014029181, 6304.211973503312, 6308.400701933309, 6312.684365781693, 6316.652584107826, 6298.653574053379, 6330.272018717907, 6355.777547785898, 6356.173697419398, 6367.235931688227, 6245.687715614201, 6246.968765156236, 6322.620642761449, 6327.968360158262, 6333.050042493663, 4145.398917156996, 4983.127952608352, 5025.773840177201, 5038.395392552917, 5051.494850514992, 4518.1415531049, 4520.977395113004, 4528.569785870337, 4529.071540334224, 4547.590716835434, 5842.094475356326, 5879.2944846618675, 5942.316633587193, 6005.519558435315, 6013.188219590387, 6242.781502647385, 6275.090111933744, 6285.9057114143325, 6287.182666253358, 6288.9056664150075, 5825.563082768809, 5906.379830117776, 5926.170336152942, 5941.792037278675, 5979.372165922602, 5450.91823622646, 5533.7786488540005, 5534.308211473566, 5537.833864984057, 5539.7784088636, 6406.903896441557, 6430.549861509743, 6437.227532696106, 6524.380183882524, 6540.771073012422, 6249.875002500015, 6272.843178920563, 6285.717142728497, 6291.402316779899, 6296.874062518632, 6457.773977910751, 6462.386073323027, 6471.870562588628, 6488.9026664601015, 6513.576617519846, 6177.62934223948, 6234.812955611339, 6335.619862808247, 6343.651099189589, 6369.267534233437, 6268.968655156695, 6298.0867774171575, 6301.180846489927, 6301.40136687014, 6312.589681670676, 6287.748490060347, 6297.842553936188, 6314.905276420949, 6317.526185536011, 6320.241571011508, 6129.114290130839, 6171.1668924694595, 6225.937740622536, 6285.402886725754, 6291.150694656346, 6133.417325354084, 6181.412765787243, 6186.659733714689, 6193.0, 6201.348858369806, 5634.577406694432, 5665.348484924287, 6157.630542167484, 6243.313235544178, 6290.779822706183, 6143.72682107623, 6174.8147555573405, 6178.104174894705, 6192.349803270592, 6208.627482351073], "value": 5820.535559941135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.700154412757, 552.2544564837468, 566.4052744618152, 566.4335664335664, 633.6831584207896, 32627.836860815692, 33198.50202246965, 40457.786266412055, 40744.18511629765, 40820.183596328054, 43287.35068973968, 43522.7823860881, 43548.78225608868, 43561.0, 43625.78187109068, 9689.85465218024, 9931.602735890592, 9935.90064099357, 9936.850947235796, 9944.502774861265, 9194.586243619053, 10262.846057309147, 12042.698932526713, 12271.754564908697, 12290.56983005595, 11914.46384912681, 11987.100967427434, 11988.940055299712, 12012.819807702892, 12014.579489717862, 11943.70140746484, 12113.273203607767, 12114.939425302906, 12128.272303661808, 12131.272123672608, 11214.439278036147, 11732.64802055931, 11750.764984700258, 11756.823647645293, 11980.520779168823, 41838.0, 42232.57767422348, 42317.36523952143, 42343.576564234274, 42445.0, 3375.0043737097617, 3395.422778127724, 3428.4171690812623, 3566.625504322061, 3786.318462676717, 25000.624990625154, 29493.262668433246, 29688.851555742298, 30140.24649383783, 30449.08652740421, 2366.8934897929635, 2760.1305588739483, 2791.6091747155415, 2808.4663913856302, 2866.1115054333222, 513258.0, 516475.0, 517592.9999999962, 517634.0, 518005.8199417995, 38833.80583097123, 39725.80137099354, 39737.80131099326, 39790.00524986841, 67009.0, 38470.038249043995, 38518.0, 38546.03634909094, 38605.613943860764, 38656.03359915968, 16524.75212871808, 16651.25069371881, 16667.083310417802, 16670.666586668198, 16716.24776885043, 12924.289164095877, 13099.803502947463, 13242.536511222253, 14172.362243699059, 14283.714325713427, 8032.959835200903, 8119.309858661962, 8439.704610338731, 8623.094575069654, 8640.438371505832, 3501.7548771586253, 3564.554430696163, 3575.37430949588, 3577.83899724513, 3596.8561257549927, 990.1435258501344, 1042.6871938418444, 1044.8589440425596, 1044.9915831222906, 1061.7345663584103, 29476.0, 29643.703562964096, 29988.700112999028, 30597.69402305993, 30728.846355768525, 1134.9432528373547, 1153.936533490666, 1169.0764296206073, 1205.0660737928176, 1293.0948336164533, 13922.86077139236, 14830.036047656868, 15269.847301527065, 15524.534263972098, 15905.761413578806, 625338.0, 635510.0, 638756.0, 640558.0, 642951.0, 65124.674376627816, 66067.66966165138, 66159.66920165368, 66311.0, 66637.00266975995, 1511.2292730707418, 1554.813422389298, 1600.5118438876254, 1602.8797840162035, 1677.0273241520133, 29965.700342997163, 30502.237444063187, 31686.68313316837, 32628.021159365257, 33184.336313274085, 62573.68713156587, 63505.68247158919, 63553.364466354746, 63585.36414635794, 63771.7245655075, 543411.0, 559032.0, 561853.1907340437, 562577.0, 565051.0, 36109.819450903626, 37733.622663774106, 43421.0, 44572.77136143115, 44694.3295850569, 36816.07959801026, 43945.12109757851, 44118.89702757457, 44730.0, 45142.0, 39683.0, 40121.59878401179, 40296.597034029284, 40921.18157636771, 41366.37950430686, 36545.634543654225, 36693.26613467769, 37005.0, 37254.0, 37453.43819842759, 36550.0, 36895.07762305856, 37345.813270933475, 37618.43572346362, 37650.62349376581, 31025.8448707755, 32544.186395340304, 32631.347373052882, 32643.0, 33764.83117584397, 21457.892710536347, 25382.365440864127, 25495.0, 25737.099201527857, 26123.477530448905], "value": 19720.128231204697, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.50616272798106, 418.55214920035576, 419.0, 422.15779519857887, 431.4326660441519, 15529.22353882307, 16077.356905723771, 21256.256031038916, 21257.36227913163, 21411.892940535316, 20895.955202239904, 21279.59526475764, 22266.332010039725, 22287.331380058582, 22291.331260062223, 5346.4118946915805, 5470.9452905470935, 6161.661108639046, 6209.813705588817, 6210.0, 5982.581219314628, 6142.539309551779, 6145.4161854623735, 6158.414950579688, 6178.814635560918, 4450.465944086698, 6544.869102617945, 6836.5556238844565, 6837.521373503857, 6844.760433384832, 6518.869622607522, 6774.119364482585, 6787.9321206788045, 6805.31946805323, 6821.590704557718, 5968.985272503684, 5985.700714964278, 5999.490043346321, 6000.75996960121, 6003.579749417542, 25836.8708156458, 27022.864885675444, 27112.59331110035, 27213.77538010794, 27687.892484300603, 1966.5575245569776, 2238.3620668109675, 2341.730700969393, 2468.8148388870904, 2932.262840449529, 10216.591336346537, 10611.363318200856, 11404.258723182966, 11552.018078463341, 15607.843921560867, 1841.5764374194011, 1867.2624313396268, 1886.56608979934, 1913.7799153097433, 1965.2433812981963, 113730.0, 115593.0, 117548.0, 121248.0, 123276.0, 11904.583339583247, 11941.044716422666, 11999.0, 12002.939985300018, 12053.397330133634, 14327.140371577738, 14775.482858099915, 14785.778213326808, 15816.446424375092, 15840.0, 8884.37809353339, 9214.76963075915, 9245.861312080324, 9336.159745622926, 9343.71968840936, 9361.859572106423, 9402.48286344244, 9413.48225847585, 9420.528973551294, 9511.811023622047, 4817.253325734517, 4823.0, 4840.75796210188, 4846.321514987904, 4881.8535443936735, 1996.8502362322884, 2046.478148072232, 2058.3824852544176, 2067.7208576841936, 2081.9271325503537, 955.9187469065068, 961.1685891703626, 980.9558569864373, 999.4602914426129, 1073.1522097543009, 20395.082221300076, 21046.473838153863, 21219.36341909745, 21418.03618837125, 21490.032948517353, 996.4021587047713, 1024.5799222318813, 1034.818906691326, 1054.6466933577185, 1060.437967877011, 10901.509432075576, 11217.326960382403, 11249.931256530617, 11262.0, 11274.98525132742, 455001.0, 556294.0, 643960.5603943995, 645687.0, 659404.0, 130892.0, 133539.66460335467, 143305.28347358198, 143902.0, 149687.25156374148, 1045.1012129568564, 1077.5205033760058, 1094.370736826328, 1096.276457538021, 1098.653924013949, 17991.730124048412, 18017.639647207245, 18066.290056491107, 18388.632227355647, 18678.439646810617, 51217.46347609578, 51373.71565710738, 51656.158911260245, 51998.22002670039, 53854.73072634612, 69522.0, 72837.6358118206, 72861.6356918212, 73099.0, 73107.63446182734, 6931.92555153952, 7579.772606821804, 8083.676652934052, 8127.512349259064, 8345.707900223462, 9194.402363846328, 12331.0, 12912.0, 12964.935175324063, 13192.670183245496, 8606.483610983361, 10243.94878025605, 11282.661520154408, 11453.942730286295, 11663.59177428786, 10906.0, 11284.661460156209, 11520.654380368602, 11696.005839503738, 11736.354500502553, 11747.647570572897, 11748.94125529401, 11948.223365481215, 12106.607740109648, 12252.754944900873, 8405.579721014046, 8508.319334453105, 8536.487810731376, 8573.699920502753, 8657.264132548804, 11373.829392558953, 11730.589429370273, 11913.046956243654, 12004.75990480203, 12034.217775844892], "value": 9970.904956391916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.051817355066, 1646.6295083606187, 1668.5911951571863, 1984.7320611717414, 2046.8362530997522, 8230.75307740768, 8767.51778652174, 8920.643174273022, 8922.420042697227, 8922.95538522308, 6887.793366199009, 8934.64261429542, 8938.821223575525, 8944.28445724342, 8959.23846473049, 2516.308015295799, 2518.874056297187, 2544.185860524633, 2547.8343907645944, 2559.2706078767555, 2451.558719430511, 2536.4039450729156, 2537.4544472938314, 2549.8980040798347, 2552.7702506774385, 2506.1729629222314, 2537.7969762419057, 2554.361409647588, 2555.0418593027616, 2555.6038813983864, 2504.636827659979, 2527.064985955208, 2529.165375426105, 2541.428178659796, 2554.9361265968405, 2500.3999040230397, 2509.1719732488236, 2515.019142534413, 2517.32032351266, 2561.743825617442, 8657.826843463095, 9025.323100767402, 9106.77233069172, 9108.726738197865, 9123.543822808833, 5409.945900541023, 5580.637258578179, 5588.469095435927, 5590.944090559083, 5596.720163991825, 3568.018794831416, 3597.2625611749527, 3638.3450978823803, 3686.6129056448954, 3694.371956767356, 1046.382634245791, 1054.8628678271853, 1056.4242487844144, 1060.0, 1062.9681109566725, 12094.0, 12169.391530423443, 12198.878011219864, 12203.877961220453, 12233.69415764613, 4660.370849935215, 4682.0, 4798.928016079762, 4801.063792560417, 4818.975905120522, 9550.617975280911, 9578.712638620853, 9580.856287155699, 9584.568694408768, 9585.568649410794, 5500.119980803052, 5531.225628412026, 5554.638948468337, 5600.6919619420505, 5604.075327570987, 4493.505714371417, 4636.281376386665, 4957.107720610253, 5098.980203959223, 5151.150060240094, 3900.2004589059743, 3982.880513584597, 4019.2363450944226, 4034.11249525104, 4040.0303927057294, 1246.2896149195053, 1259.9118061735774, 1266.645339304996, 1267.7211013576998, 1278.961631151067, 823.1962827232925, 933.6638810028385, 967.9225661947028, 998.8951160128228, 1014.7260239735231, 6110.786122485781, 6121.051237058263, 6127.96936015326, 6223.564350495421, 6243.125962365272, 472.05117713395634, 477.44854692829836, 481.55456203012074, 484.7358189786575, 486.2123360156569, 3497.8425970830863, 3972.82122304497, 4055.8377664893665, 4106.527749308808, 4144.564820693844, 9823.705288841345, 10272.897271027343, 10689.679309620724, 10698.465076746132, 10713.250072494851, 9434.0, 9719.319647624598, 10502.789944201073, 10518.474076296154, 10659.467026648637, 7833.8249262609925, 7870.0, 7927.365810735012, 7994.800129996796, 8009.159038300894, 5750.568707347049, 5991.400859914148, 6044.153818465506, 6136.938630613637, 6152.723127459186, 9512.476813775032, 9682.806343873224, 9809.0, 9810.362326448758, 9832.705018849447, 8195.46729462583, 9663.855042174513, 9845.655402060893, 9855.063768941938, 9987.550560224952, 6966.1640603125925, 7059.399951004222, 7072.82317942035, 7503.212162723054, 7655.464117511943, 7523.623818809147, 8025.07711613172, 8193.590320483832, 8231.135730748425, 8255.504669719836, 7315.231900650355, 7452.888206677013, 7477.663505142173, 7540.547567145989, 7558.244175582618, 7322.670479828314, 7356.190819009797, 7375.37309328713, 7410.073740782403, 7461.850762984602, 7077.469189810682, 7115.715371385087, 7119.786406407608, 7129.1445026595065, 7193.5324203926575, 4922.261660750916, 4962.727050012284, 5078.720670363018, 5134.998675258438, 5156.510131537498, 5000.2999580058095, 5043.293938848491, 5060.1650727631, 5116.8720781979255, 5295.099833028317], "value": 4579.649794582854, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.1588565044786, 334.2279334736758, 341.6685814759683, 343.7215855157321, 347.056007659167, 6678.0, 6742.044807005968, 6761.459083273338, 6778.322167783219, 6787.15160604925, 5454.481824226692, 6089.5128389728825, 6698.095757072793, 6703.899441508382, 6720.46236301096, 4753.16819556578, 4839.879003024917, 4843.539863712942, 4938.654294199408, 4964.851054468371, 4871.537203965618, 4940.160172770619, 4946.505349465061, 4951.603871690256, 4982.850514484571, 4931.3835770528685, 4935.593355893571, 4938.308636790852, 4946.901061978759, 4952.702837829742, 4695.201815691324, 4774.427068751737, 4902.166631672624, 4910.323873488957, 4927.7536123193695, 3525.7355698322726, 4215.915681686349, 4232.343986682069, 4243.215005224012, 4255.468066491689, 5185.222216667492, 6469.611823290617, 6480.773172938924, 6485.93514064858, 6498.317676643979, 5834.241548598698, 5837.328707198684, 5838.241028666289, 5839.416058394169, 5847.736851841677, 7136.500444968854, 7142.642867856586, 7158.069450971392, 7175.103112110986, 7201.675924583406, 3826.9808650956847, 4393.8462153824785, 4656.790444430008, 4659.347691323217, 4661.137689527414, 6106.2977757558, 7406.59263740494, 7582.810429739246, 7591.051118610174, 7599.126100498458, 4717.646176536773, 4721.527847215251, 4731.101090792738, 4732.834350797705, 4735.052989402065, 4781.187198176319, 4784.162771514971, 4791.6645834791925, 4797.40032495938, 4809.2305231162845, 4808.975955120202, 5620.971895140498, 5696.202531645573, 5699.686517241508, 5772.0, 6785.219699734495, 7130.786076417716, 7138.072050633385, 7182.281771822881, 7192.568445893263, 1627.8697704183637, 1640.0323808952776, 1654.7683324334603, 1656.213298683116, 1656.6438215783664, 990.1880458024351, 1035.3529044347283, 1035.8187317219456, 1059.777446736194, 1063.5905176507101, 6560.770373037016, 6738.831529211809, 6914.619695916672, 6925.549839260432, 6947.89578156328, 7473.327400533869, 7503.737369192052, 7504.6247687615405, 7506.399488040944, 7523.285287897641, 6861.142357205349, 6877.277885822016, 6880.415164710956, 6880.69036893341, 6917.515773895779, 3254.316593515341, 3826.445830047072, 3916.549596796348, 3920.843166273374, 3981.2283533827167, 7515.797472404498, 7861.764147075597, 7867.9606601966625, 7926.207379262143, 7932.722354717557, 6600.405963463311, 7751.147373788879, 7758.573278469626, 7763.340116090196, 7772.339351155102, 5502.972485137629, 7880.133185349607, 7886.0536735592095, 7890.447668663253, 7895.447318687637, 7162.856742865218, 7422.591757453392, 7453.515521491086, 7466.962665186639, 7485.513441626277, 5974.641521508724, 7340.816479588053, 7390.778276651709, 7391.40868730511, 7393.482456228227, 7856.525194563694, 7875.094364148196, 7891.171427000312, 7892.289693927574, 7901.565413902291, 4708.858734237979, 4710.8351207707565, 4712.104700106968, 4723.527647235386, 4727.645426592951, 7197.964010179916, 7453.105627324756, 7498.62506874665, 7508.924910750822, 7517.173110957681, 6295.244570651551, 7203.459740519586, 7209.603471808891, 7224.74713385029, 7237.131544214728, 6244.250689917239, 7178.102737157856, 7194.496385252982, 7206.675699593426, 7237.674304656409, 7049.894251586333, 7061.0, 7064.470164737562, 7080.327368899946, 7086.503944724026, 6977.581345119309, 7238.23998480152, 7253.927460725326, 7263.055802745609, 7267.60028198433, 6292.0, 6868.587884726932, 7036.542624729376, 7112.537685050455, 7134.857302854018], "value": 5208.795586105156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.5608994378514, 732.7398773435431, 735.7608777147425, 750.8273097187647, 766.5860435364904, 15246.92376538117, 15437.305321260543, 15450.072995620243, 15457.149856757887, 15457.381704731812, 14086.57740267791, 14257.928710356433, 14272.643183920407, 14284.142951422897, 14312.785308220386, 776.5030380556434, 794.5907857453416, 947.303731757158, 954.4034978138664, 957.9233661307079, 953.50894289441, 959.9760005999871, 961.0437614573509, 961.5192403798101, 964.0841200859177, 828.2876726015621, 1017.4912543728136, 1017.5980487707351, 1021.0249212002519, 1024.4263212600931, 1009.2884516415946, 1045.545187843283, 1045.6131231444376, 1047.748540350318, 1047.9161667066617, 757.9924200757977, 758.446334176049, 759.1497522774473, 760.125855266448, 760.9619519024027, 12376.0, 15019.624509387244, 15074.849251507563, 15093.24533773307, 15100.395984160621, 12680.492780288778, 12831.550895718698, 13060.34698265083, 13256.654058888556, 13480.865191348157, 10111.747206319827, 10284.691459256233, 10319.380837149794, 10599.523021463976, 11011.559537618487, 3294.390537750524, 3601.603823579404, 3634.055145662147, 3657.6159503252306, 3684.392075307571, 70940.58118837595, 71571.0, 71599.0, 71633.6418317905, 71736.0, 9646.228301735844, 9762.170215531618, 9803.264755143393, 9808.117269445784, 10098.141657959155, 22682.88658556697, 22699.88650056739, 22702.205422810122, 22713.772862271497, 23494.412639684146, 7516.210797866254, 7532.472726909064, 7573.091229052438, 7580.3556697680215, 7596.2023987481625, 4152.086540960984, 4223.852165174204, 4251.086016506466, 4288.635465985364, 4406.074724307931, 3821.541415030214, 3884.6309600587897, 3892.746971446847, 3894.532656081288, 3904.882853514399, 617.2531237202945, 700.1003710232429, 717.5264325545115, 741.4476215219696, 754.6490881740017, 597.8744463662592, 616.4082480818375, 616.8149555133532, 632.8164832198703, 649.1107183159103, 7469.215732348134, 7473.252674732592, 7542.585157816263, 7566.697332106654, 7699.191584883619, 480.1304057892239, 516.1097107489551, 607.0196632438668, 625.8779537990139, 630.2562975688639, 3732.4028155495535, 3927.7250592458827, 3980.5024371953505, 4066.3290557058344, 4067.369557718499, 89804.5509772447, 91221.63167552359, 91723.54138229266, 92026.61960070605, 92569.0743092574, 77503.61248193835, 81154.0, 81168.59415702884, 81749.36501269942, 82341.58829205815, 66972.66054678879, 69266.0, 71050.0, 72030.0, 73843.0, 8389.916100838913, 8523.95738021306, 8573.399862009599, 8596.527191004554, 8753.38726289179, 49008.0, 50287.497125029746, 50672.0, 51570.74214628903, 51675.483245167066, 63715.36284637095, 70237.0, 71163.64418178084, 71337.0, 74662.0, 11159.051480624237, 11200.607978720705, 11320.0, 11370.374629395132, 11998.700032499257, 11566.537338506701, 11898.286102833858, 11980.520779168735, 12067.396630168281, 12873.742525149632, 11526.366049867338, 11618.825717614412, 11710.355930423908, 11724.58963936258, 11792.29246245228, 11569.6529104127, 11676.649700508999, 11758.823617645914, 11772.587959421378, 12128.575499857463, 11484.770304594029, 11497.0, 11643.126765492452, 11675.532978680758, 11720.941395292968, 9797.31418800677, 9940.10539051489, 9941.019407802962, 9962.900370996487, 10096.798064038825, 8261.958690206751, 9790.608375664893, 9887.209023278265, 9889.653862114792, 10014.098731114234], "value": 7072.25820183164, "unit": "score"}], "name": "dromaeo_css", "value": 7602.597534859646, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 120.0, 112.0, 112.0, 111.0, 113.0, 113.0, 115.0, 135.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 149.0, 180.0, 162.0, 118.0, 138.0, 138.0, 117.0, 166.0, 115.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 184.0, 185.0, 184.0, 186.0, 184.0, 182.0, 183.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 77.0, 157.0, 74.0, 82.0, 83.0, 95.0, 128.0, 56.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 138.0, 76.0, 87.0, 86.0, 123.0, 79.0, 79.0, 124.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 89.0, 135.0, 121.0, 91.0, 87.0, 90.0, 85.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 96.0, 94.0, 94.0, 93.0, 94.0, 93.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 89.0, 85.0, 84.0, 82.0, 99.0, 142.0, 134.0, 85.0], "value": 106.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 62.0, 63.0, 62.0, 64.0, 63.0, 77.0, 59.0, 56.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 71.0, 73.0, 70.0, 66.0, 65.0, 65.0, 65.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 77.0, 78.0, 93.0, 103.0, 103.0, 102.0, 81.0, 84.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 126.0, 126.0, 128.0, 140.0, 145.0, 122.0, 130.0, 164.0, 156.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 167.0, 161.0, 164.0, 164.0, 164.0, 166.0, 169.0, 161.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 68.0, 66.0, 66.0, 67.0, 69.0, 66.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1516.1, "alertThreshold": 2.0}]} 02:00:42 INFO - Return code: 0 02:00:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:00:42 ERROR - # TBPL SUCCESS # 02:00:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:00:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:00:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:00:42 INFO - 2016-06-07 02:00:42.512 screenresolution[1679:10109] starting screenresolution argv=screenresolution get 02:00:42 INFO - 2016-06-07 02:00:42.515 screenresolution[1679:10109] Display 0: 1600x1200x32@60 02:00:42 INFO - 2016-06-07 02:00:42.521 screenresolution[1680:10112] starting screenresolution argv=screenresolution list 02:00:42 INFO - Available Modes on Display 0 02:00:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:00:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:00:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:00:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:00:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:00:42 INFO - Intel Iris: 02:00:42 INFO - Chipset Model: Intel Iris 02:00:42 INFO - Type: GPU 02:00:42 INFO - Bus: Built-In 02:00:42 INFO - VRAM (Dynamic, Max): 1536 MB 02:00:42 INFO - Vendor: Intel (0x8086) 02:00:42 INFO - Device ID: 0x0a2e 02:00:42 INFO - Revision ID: 0x0009 02:00:42 INFO - Displays: 02:00:42 INFO - Display: 02:00:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:00:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:00:42 INFO - Display Serial Number: 1600x1200 60 02:00:42 INFO - Main Display: Yes 02:00:42 INFO - Mirror: Off 02:00:42 INFO - Online: Yes 02:00:42 INFO - Rotation: Supported 02:00:42 INFO - Return code: 0 02:00:42 INFO - Running post-action listener: _resource_record_post_action 02:00:42 INFO - Running post-run listener: _resource_record_post_run 02:00:43 INFO - Total resource usage - Wall time: 1196s; CPU: 52.0%; Read bytes: 37033472; Write bytes: 493596160; Read time: 241; Write time: 1537 02:00:43 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 189174272; Write bytes: 184684544; Read time: 17407; Write time: 382 02:00:43 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 35276288; Write bytes: 298061312; Read time: 226; Write time: 1117 02:00:43 INFO - Running post-run listener: _upload_blobber_files 02:00:43 INFO - Blob upload gear active. 02:00:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:00:43 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 02:00:43 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:00:43 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:00:44 INFO - (blobuploader) - INFO - Open directory for files ... 02:00:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:00:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:00:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:45 INFO - (blobuploader) - INFO - Done attempting. 02:00:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:00:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:00:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:46 INFO - (blobuploader) - INFO - Done attempting. 02:00:46 INFO - (blobuploader) - INFO - Iteration through files over. 02:00:46 INFO - Return code: 0 02:00:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:00:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:00:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d2a106be6f05ce2dbbb3e102873f6cae0777413e5bd72ec57ca7a89700120ef8abcb14377feaf65823ab85b1f5896888d552480b5d223a15a4017914efdfcd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bbc7b4cb3ba24f7adc381090c9b14cb349360b4c3fd152942a53c7f6f3dfa35854d04269558cfb677de0c4bedea90266eeadc50cafef9c3174b620a3fd045d"} 02:00:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:00:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:00:46 INFO - Contents: 02:00:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d2a106be6f05ce2dbbb3e102873f6cae0777413e5bd72ec57ca7a89700120ef8abcb14377feaf65823ab85b1f5896888d552480b5d223a15a4017914efdfcd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bbc7b4cb3ba24f7adc381090c9b14cb349360b4c3fd152942a53c7f6f3dfa35854d04269558cfb677de0c4bedea90266eeadc50cafef9c3174b620a3fd045d"} 02:00:46 INFO - Running post-run listener: copy_logs_to_upload_dir 02:00:46 INFO - Copying logs to upload dir... 02:00:46 INFO - mkdir: /builds/slave/test/build/upload/logs 02:00:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.654373 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-07 02:00:46.330570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 02:00:46.334461) ========= 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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/Mozilla-Beta/sha512/9d2a106be6f05ce2dbbb3e102873f6cae0777413e5bd72ec57ca7a89700120ef8abcb14377feaf65823ab85b1f5896888d552480b5d223a15a4017914efdfcd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bbc7b4cb3ba24f7adc381090c9b14cb349360b4c3fd152942a53c7f6f3dfa35854d04269558cfb677de0c4bedea90266eeadc50cafef9c3174b620a3fd045d"} build_url:https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010815 build_url: 'https://queue.taskcluster.net/v1/task/PxB3FQ5KTfyfEzLrGfqEzg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9d2a106be6f05ce2dbbb3e102873f6cae0777413e5bd72ec57ca7a89700120ef8abcb14377feaf65823ab85b1f5896888d552480b5d223a15a4017914efdfcd3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bbc7b4cb3ba24f7adc381090c9b14cb349360b4c3fd152942a53c7f6f3dfa35854d04269558cfb677de0c4bedea90266eeadc50cafef9c3174b620a3fd045d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 02:00:46.366475) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 02:00:46.366973) ========= 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.w6p5MoZ1I3/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.6K9OUgdX8O/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.004495 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 02:00:46.415155) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 02:00:46.415463) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 02:00:46.415797) ========= ========= Total master_lag: 0.17 =========