builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0074 starttime: 1461066584.01 results: success (0) buildid: 20160419024446 builduid: 206e70f1227742a590c9adc83b0f7e6c revision: 4992cef658a669d514b3469c4b4449ac5826359d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.009133) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.009575) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.009883) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.038629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.038957) ========= 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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-04-19 04:49:44-- 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% 7.20M=0.002s 2016-04-19 04:49:44 (7.20 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097020 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.155305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.155677) ========= 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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.027016 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:44.225510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-19 04:49:44.229216) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4992cef658a669d514b3469c4b4449ac5826359d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4992cef658a669d514b3469c4b4449ac5826359d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-04-19 04:49:44,304 truncating revision to first 12 chars 2016-04-19 04:49:44,304 Setting DEBUG logging. 2016-04-19 04:49:44,304 attempt 1/10 2016-04-19 04:49:44,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4992cef658a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 04:49:46,131 unpacking tar archive at: fx-team-4992cef658a6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.072211 ========= master_lag: 2.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-19 04:49:48.312593) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:48.312882) ========= script_repo_revision: 4992cef658a669d514b3469c4b4449ac5826359d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:48.313252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:48.313517) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 04:49:48.334744) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 04:49:48.335098) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:49:48 INFO - MultiFileLogger online at 20160419 04:49:48 in /builds/slave/test 04:49:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:49:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:49:48 INFO - {'append_to_log': False, 04:49:48 INFO - 'base_work_dir': '/builds/slave/test', 04:49:48 INFO - 'blob_upload_branch': 'Fx-Team', 04:49:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:49:48 INFO - 'branch': 'Fx-Team', 04:49:48 INFO - 'buildbot_json_path': 'buildprops.json', 04:49:48 INFO - 'config_files': ('talos/mac_config.py',), 04:49:48 INFO - 'default_actions': ('clobber', 04:49:48 INFO - 'read-buildbot-config', 04:49:48 INFO - 'download-and-extract', 04:49:48 INFO - 'populate-webroot', 04:49:48 INFO - 'create-virtualenv', 04:49:48 INFO - 'install', 04:49:48 INFO - 'run-tests'), 04:49:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:49:48 INFO - 'download_minidump_stackwalk': True, 04:49:48 INFO - 'download_symbols': 'ondemand', 04:49:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:49:48 INFO - 'tooltool.py': '/tools/tooltool.py', 04:49:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:49:48 INFO - '/tools/misc-python/virtualenv.py')}, 04:49:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:49:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:49:48 INFO - 'installer_path': 'installer.exe', 04:49:48 INFO - 'log_level': 'info', 04:49:48 INFO - 'log_name': 'talos', 04:49:48 INFO - 'log_to_console': True, 04:49:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:49:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:49:48 INFO - 'opt_config_files': (), 04:49:48 INFO - 'pip_index': False, 04:49:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:48 INFO - 'cmd': ('bash', 04:49:48 INFO - '-c', 04:49:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:48 INFO - 'enabled': True, 04:49:48 INFO - 'halt_on_failure': False, 04:49:48 INFO - 'name': 'check_screen_resolution'},), 04:49:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:49:48 INFO - 'cmd': ('bash', 04:49:48 INFO - '-c', 04:49:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:49:48 INFO - 'enabled': True, 04:49:48 INFO - 'halt_on_failure': False, 04:49:48 INFO - 'name': 'check_screen_resolution'},), 04:49:48 INFO - 'run_cmd_checks_enabled': True, 04:49:48 INFO - 'sps_profile': False, 04:49:48 INFO - 'sps_profile_interval': 0, 04:49:48 INFO - 'suite': 'dromaeojs-e10s', 04:49:48 INFO - 'system_bits': '32', 04:49:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:49:48 INFO - 'title': 't-yosemite-r7-0074', 04:49:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:49:48 INFO - 'use_talos_json': True, 04:49:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:49:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:49:48 INFO - 'work_dir': 'build'} 04:49:48 INFO - ##### 04:49:48 INFO - ##### Running clobber step. 04:49:48 INFO - ##### 04:49:48 INFO - Running pre-action listener: _resource_record_pre_action 04:49:48 INFO - Running main action method: clobber 04:49:48 INFO - rmtree: /builds/slave/test/build 04:49:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:49:50 INFO - Running post-action listener: _resource_record_post_action 04:49:50 INFO - ##### 04:49:50 INFO - ##### Running read-buildbot-config step. 04:49:50 INFO - ##### 04:49:50 INFO - Running pre-action listener: _resource_record_pre_action 04:49:50 INFO - Running main action method: read_buildbot_config 04:49:50 INFO - Using buildbot properties: 04:49:50 INFO - { 04:49:50 INFO - "project": "", 04:49:50 INFO - "product": "firefox", 04:49:50 INFO - "script_repo_revision": "production", 04:49:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 04:49:50 INFO - "repository": "", 04:49:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 04:49:50 INFO - "buildid": "20160419024446", 04:49:50 INFO - "pgo_build": "False", 04:49:50 INFO - "basedir": "/builds/slave/test", 04:49:50 INFO - "buildnumber": 620, 04:49:50 INFO - "slavename": "t-yosemite-r7-0074", 04:49:50 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:49:50 INFO - "platform": "yosemite_r7", 04:49:50 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 04:49:50 INFO - "branch": "fx-team", 04:49:50 INFO - "repo_path": "integration/fx-team", 04:49:50 INFO - "revision": "4992cef658a669d514b3469c4b4449ac5826359d", 04:49:50 INFO - "stage_platform": "macosx64", 04:49:50 INFO - "builduid": "206e70f1227742a590c9adc83b0f7e6c", 04:49:50 INFO - "slavebuilddir": "test" 04:49:50 INFO - } 04:49:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:49:50 INFO - Running post-action listener: _resource_record_post_action 04:49:50 INFO - ##### 04:49:50 INFO - ##### Running download-and-extract step. 04:49:50 INFO - ##### 04:49:50 INFO - Running pre-action listener: _resource_record_pre_action 04:49:50 INFO - Running main action method: download_and_extract 04:49:50 INFO - mkdir: /builds/slave/test/build/tests 04:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:50 INFO - https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:49:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:49:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:49:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:49:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:49:51 INFO - Downloaded 1199 bytes. 04:49:51 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:49:51 INFO - Using the following test package requirements: 04:49:51 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:49:51 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:49:51 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:49:51 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'jsshell-mac.zip'], 04:49:51 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:49:51 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:49:51 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:49:51 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:49:51 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:49:51 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:49:51 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:49:51 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:49:51 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')]} 04:49:51 INFO - https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:49:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:49:52 INFO - Downloaded 18037860 bytes. 04:49:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:49:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:49:55 INFO - Return code: 0 04:49:55 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:49:55 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')]} 04:49:55 INFO - https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:49:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:49:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:49:57 INFO - Downloaded 18037860 bytes. 04:49:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:49:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:50:00 INFO - Return code: 0 04:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:50:00 INFO - https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:50:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:50:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:50:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:50:01 INFO - Downloaded 10845952 bytes. 04:50:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:50:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:50:02 INFO - Return code: 0 04:50: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')]} 04:50:02 INFO - https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:50:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:50:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:50:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:50:09 INFO - Downloaded 109948931 bytes. 04:50:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:50:09 INFO - mkdir: /builds/slave/test/properties 04:50:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:50:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:50:09 INFO - Contents: 04:50:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:50:10 INFO - Running post-action listener: _resource_record_post_action 04:50:10 INFO - Running post-action listener: set_extra_try_arguments 04:50:10 INFO - ##### 04:50:10 INFO - ##### Running populate-webroot step. 04:50:10 INFO - ##### 04:50:10 INFO - Running pre-action listener: _resource_record_pre_action 04:50:10 INFO - Running main action method: populate_webroot 04:50:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:50:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:50:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:50:10 INFO - '--tppagecycles', 04:50:10 INFO - '7'], 04:50:10 INFO - 'tests': ['tsvgm']}}, 04:50:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:50:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:50:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:50:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:50:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:50:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['damp', 'tps']}, 04:50:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'tests': ['damp', 'tps']}, 04:50:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['dromaeo_dom']}, 04:50:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:50:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['a11yr', 04:50:10 INFO - 'ts_paint', 04:50:10 INFO - 'tpaint', 04:50:10 INFO - 'sessionrestore', 04:50:10 INFO - 'sessionrestore_no_auto_restore', 04:50:10 INFO - 'tabpaint']}, 04:50:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['tsvgx', 04:50:10 INFO - 'tsvgr_opacity', 04:50:10 INFO - 'tart', 04:50:10 INFO - 'tscrollx', 04:50:10 INFO - 'cart']}, 04:50:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:50:10 INFO - 'tsvgr_opacity', 04:50:10 INFO - 'tart', 04:50:10 INFO - 'tscrollx', 04:50:10 INFO - 'cart']}, 04:50:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'talos_options': ['--disable-e10s'], 04:50:10 INFO - 'tests': ['tp5o']}, 04:50:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'tests': ['tp5o']}, 04:50:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'talos_options': ['--disable-e10s', 04:50:10 INFO - '--xperf_path', 04:50:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:10 INFO - 'tests': ['tp5n']}, 04:50:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:50:10 INFO - 'talos_options': ['--xperf_path', 04:50:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:50:10 INFO - 'tests': ['tp5n']}}, 04:50:10 INFO - 'talos.zip': {'path': '', 04:50:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:50:10 INFO - Running post-action listener: _resource_record_post_action 04:50:10 INFO - ##### 04:50:10 INFO - ##### Running create-virtualenv step. 04:50:10 INFO - ##### 04:50:10 INFO - Running pre-action listener: _resource_record_pre_action 04:50:10 INFO - Running main action method: create_virtualenv 04:50:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:50:10 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 04:50:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:50:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:50:10 INFO - Using real prefix '/tools/python27' 04:50:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:50:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:50:14 INFO - Installing pip.................done. 04:50:14 INFO - Return code: 0 04:50:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:14 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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:14 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 04:50:14 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 04:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:14 INFO - 'HOME': '/Users/cltbld', 04:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:14 INFO - 'LOGNAME': 'cltbld', 04:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:14 INFO - 'MOZ_NO_REMOTE': '1', 04:50:14 INFO - 'NO_EM_RESTART': '1', 04:50:14 INFO - 'PAGER': '/bin/cat', 04:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:14 INFO - 'PWD': '/builds/slave/test', 04:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:14 INFO - 'SHELL': '/bin/bash', 04:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:14 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:14 INFO - 'USER': 'cltbld', 04:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:14 INFO - 'XPC_FLAGS': '0x0', 04:50:14 INFO - 'XPC_SERVICE_NAME': '0', 04:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:14 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 04:50:14 INFO - Cleaning up... 04:50:14 INFO - Return code: 0 04:50:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:14 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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:14 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 04:50:14 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 04:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:14 INFO - 'HOME': '/Users/cltbld', 04:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:14 INFO - 'LOGNAME': 'cltbld', 04:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:14 INFO - 'MOZ_NO_REMOTE': '1', 04:50:14 INFO - 'NO_EM_RESTART': '1', 04:50:14 INFO - 'PAGER': '/bin/cat', 04:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:14 INFO - 'PWD': '/builds/slave/test', 04:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:14 INFO - 'SHELL': '/bin/bash', 04:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:14 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:14 INFO - 'USER': 'cltbld', 04:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:14 INFO - 'XPC_FLAGS': '0x0', 04:50:14 INFO - 'XPC_SERVICE_NAME': '0', 04:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:14 INFO - Downloading/unpacking psutil>=0.7.1 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:50:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:17 INFO - Installing collected packages: psutil 04:50:17 INFO - Running setup.py install for psutil 04:50:17 INFO - building 'psutil._psutil_osx' extension 04:50:17 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 04:50:18 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 04:50:18 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 04:50:18 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 04:50:18 INFO - building 'psutil._psutil_posix' extension 04:50:18 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 04:50:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:50:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:50:18 INFO - ^ 04:50:18 INFO - 1 warning generated. 04:50:18 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 04:50:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:50:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:50:18 INFO - Successfully installed psutil 04:50:18 INFO - Cleaning up... 04:50:19 INFO - Return code: 0 04:50:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:19 INFO - 'HOME': '/Users/cltbld', 04:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:19 INFO - 'LOGNAME': 'cltbld', 04:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:19 INFO - 'MOZ_NO_REMOTE': '1', 04:50:19 INFO - 'NO_EM_RESTART': '1', 04:50:19 INFO - 'PAGER': '/bin/cat', 04:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:19 INFO - 'PWD': '/builds/slave/test', 04:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:19 INFO - 'SHELL': '/bin/bash', 04:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:19 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:19 INFO - 'USER': 'cltbld', 04:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:19 INFO - 'XPC_FLAGS': '0x0', 04:50:19 INFO - 'XPC_SERVICE_NAME': '0', 04:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:50:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:50:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:50:21 INFO - Installing collected packages: mozsystemmonitor 04:50:21 INFO - Running setup.py install for mozsystemmonitor 04:50:21 INFO - Successfully installed mozsystemmonitor 04:50:21 INFO - Cleaning up... 04:50:22 INFO - Return code: 0 04:50:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:50:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:22 INFO - 'HOME': '/Users/cltbld', 04:50:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:22 INFO - 'LOGNAME': 'cltbld', 04:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:22 INFO - 'MOZ_NO_REMOTE': '1', 04:50:22 INFO - 'NO_EM_RESTART': '1', 04:50:22 INFO - 'PAGER': '/bin/cat', 04:50:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:22 INFO - 'PWD': '/builds/slave/test', 04:50:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:22 INFO - 'SHELL': '/bin/bash', 04:50:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:22 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:22 INFO - 'USER': 'cltbld', 04:50:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:22 INFO - 'XPC_FLAGS': '0x0', 04:50:22 INFO - 'XPC_SERVICE_NAME': '0', 04:50:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:22 INFO - Downloading/unpacking blobuploader==1.2.4 04:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:50:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:50:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:50:25 INFO - Downloading docopt-0.6.1.tar.gz 04:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:50:25 INFO - Installing collected packages: blobuploader, requests, docopt 04:50:25 INFO - Running setup.py install for blobuploader 04:50:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:50:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:50:26 INFO - Running setup.py install for requests 04:50:26 INFO - Running setup.py install for docopt 04:50:26 INFO - Successfully installed blobuploader requests docopt 04:50:26 INFO - Cleaning up... 04:50:26 INFO - Return code: 0 04:50:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:26 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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:26 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 04:50:26 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 04:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:26 INFO - 'HOME': '/Users/cltbld', 04:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:26 INFO - 'LOGNAME': 'cltbld', 04:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:26 INFO - 'MOZ_NO_REMOTE': '1', 04:50:26 INFO - 'NO_EM_RESTART': '1', 04:50:26 INFO - 'PAGER': '/bin/cat', 04:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:26 INFO - 'PWD': '/builds/slave/test', 04:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:26 INFO - 'SHELL': '/bin/bash', 04:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:26 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:26 INFO - 'USER': 'cltbld', 04:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:26 INFO - 'XPC_FLAGS': '0x0', 04:50:26 INFO - 'XPC_SERVICE_NAME': '0', 04:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aNylD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-JyNI2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Z9Ke4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LaWXHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-b3CGv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-M9iUkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-9WeMaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hhR6D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:27 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Vn352P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-juGtPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-W2Qmeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-qJRoI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aMzver-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6nUkIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-lnCbZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QMgUtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Gy6frx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:50:28 INFO - Running setup.py install for manifestparser 04:50:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Running setup.py install for mozcrash 04:50:29 INFO - Running setup.py install for mozdebug 04:50:29 INFO - Running setup.py install for mozdevice 04:50:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Running setup.py install for mozfile 04:50:29 INFO - Running setup.py install for mozhttpd 04:50:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Running setup.py install for mozinfo 04:50:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:50:29 INFO - Running setup.py install for mozInstall 04:50:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozleak 04:50:30 INFO - Running setup.py install for mozlog 04:50:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for moznetwork 04:50:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozprocess 04:50:30 INFO - Running setup.py install for mozprofile 04:50:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozrunner 04:50:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:50:30 INFO - Running setup.py install for mozscreenshot 04:50:31 INFO - Running setup.py install for moztest 04:50:31 INFO - Running setup.py install for mozversion 04:50:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:50:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:50:31 INFO - Cleaning up... 04:50:31 INFO - Return code: 0 04:50:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:31 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')]} 04:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:31 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')]} 04:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:31 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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:31 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 04:50:31 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 04:50:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:31 INFO - 'HOME': '/Users/cltbld', 04:50:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:31 INFO - 'LOGNAME': 'cltbld', 04:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:31 INFO - 'MOZ_NO_REMOTE': '1', 04:50:31 INFO - 'NO_EM_RESTART': '1', 04:50:31 INFO - 'PAGER': '/bin/cat', 04:50:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:31 INFO - 'PWD': '/builds/slave/test', 04:50:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:31 INFO - 'SHELL': '/bin/bash', 04:50:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:31 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:31 INFO - 'USER': 'cltbld', 04:50:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:31 INFO - 'XPC_FLAGS': '0x0', 04:50:31 INFO - 'XPC_SERVICE_NAME': '0', 04:50:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:50:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-3sGOra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:50:31 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)) 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-rn01XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:50:31 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)) 04:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:50:31 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-cLvgwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-vhej9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-54S3Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ce4IAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-nz93Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7r6OL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_6eOXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ZQd81h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-FIvF0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-WayABP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:50:32 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)) 04:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:50:32 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-KyE24S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:50:33 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)) 04:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:50:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-3rhdYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:50:33 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)) 04:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ndkLd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:50:33 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)) 04:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:50:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-99WFA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:50:33 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)) 04:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:50:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-DA8AqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:50:33 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)) 04:50:33 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)) 04:50:33 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)) 04:50:33 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)) 04:50:33 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)) 04:50:33 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)) 04:50:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:50:33 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 04:50:33 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 04:50:33 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 04:50:33 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 04:50:33 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 04:50:33 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 04:50:36 INFO - Downloading blessings-1.6.tar.gz 04:50:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:50:36 INFO - Installing collected packages: blessings 04:50:36 INFO - Running setup.py install for blessings 04:50:36 INFO - Successfully installed blessings 04:50:36 INFO - Cleaning up... 04:50:36 INFO - Return code: 0 04:50:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:50:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:36 INFO - Reading from file tmpfile_stdout 04:50:36 INFO - Current package versions: 04:50:36 INFO - blessings == 1.6 04:50:36 INFO - blobuploader == 1.2.4 04:50:36 INFO - docopt == 0.6.1 04:50:36 INFO - manifestparser == 1.1 04:50:36 INFO - mozInstall == 1.12 04:50:36 INFO - mozcrash == 0.17 04:50:36 INFO - mozdebug == 0.1 04:50:36 INFO - mozdevice == 0.48 04:50:36 INFO - mozfile == 1.2 04:50:36 INFO - mozhttpd == 0.7 04:50:36 INFO - mozinfo == 0.9 04:50:36 INFO - mozleak == 0.1 04:50:36 INFO - mozlog == 3.1 04:50:36 INFO - moznetwork == 0.27 04:50:36 INFO - mozprocess == 0.22 04:50:36 INFO - mozprofile == 0.28 04:50:36 INFO - mozrunner == 6.11 04:50:36 INFO - mozscreenshot == 0.1 04:50:36 INFO - mozsystemmonitor == 0.0 04:50:36 INFO - moztest == 0.7 04:50:36 INFO - mozversion == 1.4 04:50:36 INFO - psutil == 3.1.1 04:50:36 INFO - requests == 1.2.3 04:50:36 INFO - wsgiref == 0.1.2 04:50:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:50:36 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')]} 04:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:50:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:37 INFO - 'HOME': '/Users/cltbld', 04:50:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:37 INFO - 'LOGNAME': 'cltbld', 04:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:37 INFO - 'MOZ_NO_REMOTE': '1', 04:50:37 INFO - 'NO_EM_RESTART': '1', 04:50:37 INFO - 'PAGER': '/bin/cat', 04:50:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:37 INFO - 'PWD': '/builds/slave/test', 04:50:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:37 INFO - 'SHELL': '/bin/bash', 04:50:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:37 INFO - 'USER': 'cltbld', 04:50:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:37 INFO - 'XPC_FLAGS': '0x0', 04:50:37 INFO - 'XPC_SERVICE_NAME': '0', 04:50:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:50:37 INFO - Cleaning up... 04:50:37 INFO - Return code: 0 04:50:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:50:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:37 INFO - 'HOME': '/Users/cltbld', 04:50:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:37 INFO - 'LOGNAME': 'cltbld', 04:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:37 INFO - 'MOZ_NO_REMOTE': '1', 04:50:37 INFO - 'NO_EM_RESTART': '1', 04:50:37 INFO - 'PAGER': '/bin/cat', 04:50:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:37 INFO - 'PWD': '/builds/slave/test', 04:50:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:37 INFO - 'SHELL': '/bin/bash', 04:50:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:37 INFO - 'USER': 'cltbld', 04:50:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:37 INFO - 'XPC_FLAGS': '0x0', 04:50:37 INFO - 'XPC_SERVICE_NAME': '0', 04:50:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:37 INFO - Downloading/unpacking jsonschema 04:50:37 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 04:50:37 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 04:50:37 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 04:50:37 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 04:50:37 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 04:50:37 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 04:50:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:50:40 INFO - Downloading/unpacking functools32 (from jsonschema) 04:50:40 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 04:50:40 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 04:50:40 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 04:50:40 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 04:50:40 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 04:50:40 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 04:50:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:50:40 INFO - warning: no files found matching '*.txt' 04:50:40 INFO - no previously-included directories found matching 'build' 04:50:40 INFO - no previously-included directories found matching 'dist' 04:50:40 INFO - no previously-included directories found matching '.git*' 04:50:40 INFO - Installing collected packages: jsonschema, functools32 04:50:40 INFO - Running setup.py install for functools32 04:50:40 INFO - warning: no files found matching '*.txt' 04:50:40 INFO - no previously-included directories found matching 'build' 04:50:40 INFO - no previously-included directories found matching 'dist' 04:50:40 INFO - no previously-included directories found matching '.git*' 04:50:40 INFO - Successfully installed jsonschema functools32 04:50:40 INFO - Cleaning up... 04:50:40 INFO - Return code: 0 04:50:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:50:40 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 0x106a5ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a6b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b68d94aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, '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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 04:50:40 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 04:50:40 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 04:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:40 INFO - 'HOME': '/Users/cltbld', 04:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:40 INFO - 'LOGNAME': 'cltbld', 04:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:40 INFO - 'MOZ_NO_REMOTE': '1', 04:50:40 INFO - 'NO_EM_RESTART': '1', 04:50:40 INFO - 'PAGER': '/bin/cat', 04:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:40 INFO - 'PWD': '/builds/slave/test', 04:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:40 INFO - 'SHELL': '/bin/bash', 04:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:50:40 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:40 INFO - 'USER': 'cltbld', 04:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:40 INFO - 'XPC_FLAGS': '0x0', 04:50:40 INFO - 'XPC_SERVICE_NAME': '0', 04:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:50:41 INFO - Downloading/unpacking flake8 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:50:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:50:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:43 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 04:50:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50: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 04:50:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:50:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:50:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:50:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:50:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:50:44 INFO - no previously-included directories found matching 'docs/_build' 04:50:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:50:44 INFO - Running setup.py install for pep8 04:50:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:50:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:50:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:50:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:50:45 INFO - no previously-included directories found matching 'docs/_build' 04:50:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:50:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:50:45 INFO - Cleaning up... 04:50:45 INFO - Return code: 0 04:50:45 INFO - Running post-action listener: _resource_record_post_action 04:50:45 INFO - Running post-action listener: _start_resource_monitoring 04:50:45 INFO - Starting resource monitoring. 04:50:45 INFO - ##### 04:50:45 INFO - ##### Running install step. 04:50:45 INFO - ##### 04:50:45 INFO - Running pre-action listener: _resource_record_pre_action 04:50:45 INFO - Running main action method: install 04:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:50:45 INFO - Reading from file tmpfile_stdout 04:50:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:50:45 INFO - Reading from file tmpfile_stdout 04:50:45 INFO - Output received: 04:50:45 INFO - Usage: mozinstall [options] installer 04:50:45 INFO - Options: 04:50:45 INFO - -h, --help show this help message and exit 04:50:45 INFO - -d DEST, --destination=DEST 04:50:45 INFO - Directory to install application into. [default: 04:50:45 INFO - "/builds/slave/test"] 04:50:45 INFO - --app=APP Application being installed. [default: firefox] 04:50:45 INFO - mkdir: /builds/slave/test/build/application 04:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:51:18 INFO - Reading from file tmpfile_stdout 04:51:18 INFO - Output received: 04:51:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:51:18 INFO - Running post-action listener: _resource_record_post_action 04:51:18 INFO - ##### 04:51:18 INFO - ##### Running run-tests step. 04:51:18 INFO - ##### 04:51:18 INFO - Running pre-action listener: _resource_record_pre_action 04:51:18 INFO - Running main action method: run_tests 04:51:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:51:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:51:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:51:18 INFO - 2016-04-19 04:51:18.901 screenresolution[1635:7769] starting screenresolution argv=screenresolution get 04:51:18 INFO - 2016-04-19 04:51:18.904 screenresolution[1635:7769] Display 0: 1600x1200x32@60 04:51:18 INFO - 2016-04-19 04:51:18.910 screenresolution[1636:7771] starting screenresolution argv=screenresolution list 04:51:18 INFO - Available Modes on Display 0 04:51:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:51:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:51:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:51:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:51:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:51:19 INFO - Intel Iris: 04:51:19 INFO - Chipset Model: Intel Iris 04:51:19 INFO - Type: GPU 04:51:19 INFO - Bus: Built-In 04:51:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:51:19 INFO - Vendor: Intel (0x8086) 04:51:19 INFO - Device ID: 0x0a2e 04:51:19 INFO - Revision ID: 0x0009 04:51:19 INFO - Displays: 04:51:19 INFO - Display: 04:51:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:51:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:51:19 INFO - Display Serial Number: 1600x1200 60 04:51:19 INFO - Main Display: Yes 04:51:19 INFO - Mirror: Off 04:51:19 INFO - Online: Yes 04:51:19 INFO - Rotation: Supported 04:51:19 INFO - Return code: 0 04:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:51:19 INFO - Python 2.7.3 04:51:19 INFO - Return code: 0 04:51:19 INFO - grabbing minidump binary from tooltool 04:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:51:19 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 0x1068fae00>, '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 0x106763db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:51:19 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 04:51:19 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 04:51:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:51:19 INFO - Return code: 0 04:51:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:51:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:51:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:51:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:51:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:51:20 INFO - Return code: 0 04:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xj1HN6ZHrY/Render', 04:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:20 INFO - 'HOME': '/Users/cltbld', 04:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:20 INFO - 'LOGNAME': 'cltbld', 04:51:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:51:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:20 INFO - 'MOZ_NO_REMOTE': '1', 04:51:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:51:20 INFO - 'NO_EM_RESTART': '1', 04:51:20 INFO - 'PAGER': '/bin/cat', 04:51:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:20 INFO - 'PWD': '/builds/slave/test', 04:51:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:20 INFO - 'SHELL': '/bin/bash', 04:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ve3KZUkOzB/Listeners', 04:51:20 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 04:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:20 INFO - 'USER': 'cltbld', 04:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:20 INFO - 'XPC_FLAGS': '0x0', 04:51:20 INFO - 'XPC_SERVICE_NAME': '0', 04:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:51:20 INFO - mozversion application_buildid: 20160419024446 04:51:20 INFO - mozversion application_changeset: 4992cef658a669d514b3469c4b4449ac5826359d 04:51:20 INFO - mozversion application_display_name: Nightly 04:51:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:51:20 INFO - mozversion application_name: Firefox 04:51:20 INFO - mozversion application_remotingname: firefox 04:51:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:51:20 INFO - mozversion application_vendor: Mozilla 04:51:20 INFO - mozversion application_version: 48.0a1 04:51:20 INFO - mozversion platform_buildid: 20160419024446 04:51:20 INFO - mozversion platform_changeset: 4992cef658a669d514b3469c4b4449ac5826359d 04:51:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:51:20 INFO - mozversion platform_version: 48.0a1 04:51:20 INFO - using testdate: 1461066680 04:51:20 INFO - actual date: 1461066680 04:51:20 INFO - starting webserver on 'localhost:49279' 04:51:20 INFO - SUITE-START | Running 2 tests 04:51:20 INFO - TEST-START | dromaeo_css 04:51:20 INFO - operating with platform_type : mac_ 04:51:20 INFO - Initialising browser for dromaeo_css test... 04:51:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp10o3B2/profile http://localhost:49279/getInfo.html) 04:51:23 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:51:23 INFO - PROCESS | 1654 | colorDepth:24 04:51:23 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:51:23 INFO - PROCESS | 1654 | __metrics 04:51:23 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:51:24 INFO - TEST-INFO | 1654: exit 0 04:51:24 INFO - Browser initialized. 04:51:24 INFO - Running cycle 1/1 for dromaeo_css test... 04:51:24 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp10o3B2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:36 INFO - PROCESS | 1657 | RSS: Main: 247676928 04:51:36 INFO - PROCESS | 1657 | 04:52:26 INFO - PROCESS | 1657 | 1461066746073 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] 04:54:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 04:54:37 INFO - PROCESS | 1657 | RSS: Main: 260079616 04:54:37 INFO - PROCESS | 1657 | 04:57:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 04:57:40 INFO - PROCESS | 1657 | RSS: Main: 271728640 04:57:40 INFO - PROCESS | 1657 | 05:00:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 05:00:42 INFO - PROCESS | 1657 | RSS: Main: 273747968 05:00:42 INFO - PROCESS | 1657 | 05:03:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:03:44 INFO - PROCESS | 1657 | RSS: Main: 275275776 05:03:44 INFO - PROCESS | 1657 | 05:06:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 05:06:47 INFO - PROCESS | 1657 | RSS: Main: 278171648 05:06:47 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 05:09:50 INFO - PROCESS | 1657 | RSS: Main: 279384064 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | __start_tp_report 05:09:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 05:09:50 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 05:09:50 INFO - PROCESS | 1657 | |i|pagename|runs| 05:09:50 INFO - PROCESS | 1657 | |0;dojo.html;2868.598396224528;3448.0345503259086;3460.186856088817;3486.407310757171;3534.628863969283;5755.597108202427;5758;5761.020626493496;5781.9421805781985;5792.420757924211;5588.127977127667;5656.604037717361;5698.34469036061;5778.6243894146855;5874.236349274599;5203.9219411708855;5256.632035757499;5299.5230429261355;5313.840584782462;5331.840044798662;59.81068189120384;5265.921011184835;5280.234366016925;5289.788408463657;5305.151175811871;5238.554722848562;5263.526282634543;5265.210218467241;5272.604554658396;5289.7619607117385;5160.638755287131;5406.59450541207;5408.296921400193;5412.97153540826;5416.756245968941;5204.531592156686;5212.478752124795;5283.101872681654;5308.601854860863;5318.627696061278;5569.554435645138;5664.320281566197;5714;5719.456651618089;5727.971360143214;6126.540509461764;6172.043333283348;6236.594621349598;6253.624782513064;6268.811935641938;6209.9068513972325;6260.686965651699;6267.091271765614;6271.278802937675;6296.748130074791;5890.322612899528;5893.381194974509;5897.085951677497;5916.437938395846;5956;6186.938130618636;6259.7496100156395;6332.905006424908;6334.5249106317215;6352.968235158887;6186.690665466798;6216.44052035319;6233.906491402633;6244.75020999164;6247.531435142382;6206.224221972254;6239.970404883235;6243.188385509855;6260.46786023184;6267.122602835516;6132.692058233643;6132.938670613236;6207.720652570555;6229.314775374705;6295.055741638791;6130.398136742002;6173.876522469525;6240.500759939285;6253.468455181361;6269.341719119518;4872.537108974641;4959.7272150032095;4960.206366981338;4961.801527938842;4976.62675299354;4372.103718737651;4387.648988080946;4478.529754375809;4483.618892394183;4503.909921801611;5751.913721294184;5797.565182611321;5803.883922321615;5848.561357898175;5853.2976042875125;6227.719752611143;6232.345603711636;6250.031245157008;6256.43692067707;6271.686415679198;5695.45893140151;5738.77044918207;5841.4450627190345;5861.267341582302;5872.324682661463;5438.646487978269;5455.427180146107;5461.754221060062;5463.31708536433;5482.150266708587;6233.563650544509;6372.171617689671;6410.967945160244;6417.7753778617525;6424.38968298011;6129.725578588854;6202.4417802398;6207.131001659681;6245.5628106032145;6260.812175634738;6377.8724425512155;6384;6394.392532709385;6445;6519.152510173647;6109.799438327977;6137.254412462313;6215.9432896406815;6245.469135123565;6245.906311405423;6165.84585385369;6219.129321894848;6232.407921247474;6258.9374106260175;6269.059641053878;5980.581359304807;6200.596961197508;6204.534659900616;6205.410486003822;6209;6072.423119803611;6112.72492737819;6151.0773383992755;6192.0711893214575;6207.379262073757;4951.282064100613;5132.793793458609;5872.295324560909;6016.939830601638;6090.69546522681;5974.701264936823;5995.850103747442;6167.259928808572;6188.195534580475;6220.315765265726;5031.22016087507;5941.287045554561;6029.487493563096;6035.366286540026;6044.274687037584 05:09:50 INFO - PROCESS | 1657 | |1;ext.html;512.6846989101703;535.1250705097167;556.2046273828423;604.6160687963142;630.5743623054437;30557.541636875438;38214;38222;38391.42412863809;38488.61511384886;39088.218235635344;39409.01477463065;39882.60117398825;39894;39928.80035599825;9428.632848237;9658.275629327793;9679.806403871919;9704;9719.95140024299;9616.134547890688;9777.364471309378;9804.215662747;9825.508724563779;9842.704718858446;9758.170555502791;9782.706518804447;9802.460864652441;9805.1665608423;9845.064718851734;9744.805103897954;9756.707298781012;9799.16707079899;9829.164521015722;9836;9539.52302384878;9568.09103135201;9640.276979226515;9651.806963860754;9686.854697179477;38590.80704596487;38899.805500972594;38987.41518877219;39127.82616521481;39151.412728808806;2723.8229515081653;2747.5741260104714;3032.272254658888;3207.9198020049453;3353.3628610563915;27003.459930801273;27207.86396068007;27644.58533122005;27670;28573.57139642907;2692.7041062741805;2697.4739925714493;2729.863506824651;2871.6410448693914;2913.0823790505924;335636.64363356296;336350;337215;339333;341808;58755.82488350209;58902.11646825301;59143.40856591465;59195;59230.81538369208;67078.32921670818;68520.6573967137;69284.6535767318;69307;69375;16096.356145754038;16290.51128466148;16362.67274654524;16485;16517.256723447474;12281.447334869952;12282.201656892274;12613.87386126127;12676.366181691063;12735.363231838372;19302.32441864528;19578.665440772507;19705.24126622274;20003.399898002794;20008.89995550042;6994.720211191597;8555.786105347292;8643.956780216184;8728.519931403835;8837.867431988525;987.7728122531857;992.5682328187117;994.8209322322051;1050.0759331787979;1054.3779170289413;29700.702992970226;29731.554026689617;30125.94559190418;30225.395492090036;30251.243718906764;998.3211416237028;1097.8133717268083;1108.6729414822646;1139.1797905508022;1179.9705007374712;15353.309101090477;15359.001664891746;15360.231988400536;15719.921400392925;15797.289121989308;645823;648479;653179;653187;654482;60135.39864601298;60864.39135608676;60932.39067609356;60971.39028609657;61305.38694613086;1141.9143564232716;1474.7124310759514;1477.6675248069314;1479.7706355514913;1486.695227478386;29105.563416549194;29166.56250156292;29395;29738.256543585718;29835;52352.47647523476;52434.47565524296;52752.47247527476;52807;53043.46956530385;419697;474689.626551865;487350;492587;494778;26437.942482300492;26770.46459070769;26822.79297431659;26928.9803264752;27003.1899043029;45169.66197365855;45491.772541137085;46091.769541152076;46326.76836615795;46731.76634116808;37626;37917.81041094777;38168.61831381651;38523.107669248064;38753.612463876125;37470.43794343142;37663.43504847484;38120;38323.233535329695;38404.615953841225;32950.50574241436;36321.365538551356;37087.44368834416;38430.42354364743;38568.42147367736;28495.28761780972;30036.549451757804;30999.90000999972;31110.066697999097;31119.910803122686;23462.76537234674;24040.39899002539;24111.879440602686;24157;24464.26607201787 05:09:50 INFO - PROCESS | 1657 | |2;jquery.html;395.11494252873564;398.14796335841305;402.42051445917895;404.81378565859706;407.20391634354837;19704.40886773397;20064.598708025867;20409.795902040958;20454.795452045477;21093.789062109376;18329.450116496493;19969.700454493195;20341;21254.362369128914;21344.679829802564;5514.338279406476;5831.387704291052;5858.384869588695;5924.377940316248;5977.043673012319;5110.770015349318;5694.5729070319685;5793.594448388614;5877.941220587803;5914.822555323326;5698.886022279552;5851.502622277111;5856.267966504187;5860.882782344351;5862.824115276527;4559.3388958601145;5728.68492232927;5838.386969368197;5840.503557197643;5858.707064646772;5024.497550244982;5089.6691715038405;5098.643094983353;5112.795488180468;5133.820316288912;25329.49341013188;25355.873220633963;25715.35711607206;25772;26337.209883703326;1919.5680971781378;2197.912083516673;2339.812814974815;2683.154806236049;2914.446255211524;7780;9513.381630194015;9852.605895764162;10043.89956100437;10049.246306526966;1601.6716573102485;1613.0483015021193;1796.6137280484759;1805.0703887497948;1840.871139020269;86244;104632.95367046384;111907;112047;112842.99999999919;11071.5571377144;11606.76786464266;11655.59205427806;11727.296362218294;11844.763104737856;12251.75496490083;14437.700606945215;14465.204413757345;14667.339969701388;14829.925850370895;7059.329363710439;8423.70517031901;8471.830563388698;8502.532360720219;8533.74398768038;8572;8589.14108589136;8619.267362274277;8638;8775.244951009696;4622.884427889262;4682.929756053662;4686.320483529869;4725.314829349725;4741.122892264942;1951.6779731344452;1990.9502262443555;2017.6670849309994;2019.1014998325604;2048.3240530625158;394.8874570747323;877.8683197520295;921.4563407589554;971.7862070344513;1049.8740151181908;9642.794650668666;10124.595016199417;11098.056665183389;11695.532178712756;11937.761244775056;438.2309047621404;446.84360473833897;448.0367210497391;455.8176729308303;456.0423111465895;5862.912056319158;6002.0996850472775;6005.669688167105;6046.788362407295;6157.876842463125;597337;625936;633911.8304408449;638657;639072;125671.743282566;131392;140469;150020;150047;1050.485262221506;1052.610534102387;1059.0786016165985;1071.8660167479065;1120.8991190792867;17395;17610.559736006704;17744.556386089935;17782.555436114202;18057.638847223243;50504.74747626238;51826.740866295426;51905.96188076142;52321.69195770136;52578.21132682936;69161.65419172872;72957.6352118236;73395;75730.62134689292;77062.22937770774;7621;7915.960420197862;7944.324732397579;8001.9199808003505;8167.79580510492;9110.952240492428;11949.462274197507;12523.499060037497;12656.92416144638;12953.805692914426;11216.719582010515;11255.66233013011;11267.605633802777;11405.885941140814;11418.771624567627;8963.462192268486;11164.944175279072;11299.887001129884;11443.771124577295;11700.186471096653;11209.047230985458;11578.884211157781;11628.825567616661;11706.239094458833;11801.940990294994;8003.919960800317;8182.140875208018;8303.584820758817;8316.085230624505;8526.488410695378;8444.986601607847;10608.310459820086;10788.514516846917;11229.3262404256;11493.712657183638 05:09:50 INFO - PROCESS | 1657 | |3;mootools.html;1382.1292585671029;1632.8040635123784;1760.331074191807;1925.345382569926;1941.7378653881722;8164.755057348274;8782.38523303369;8821.25019373354;9064.365494415393;9093.681721139761;8867.068957759438;8883.689070882521;8885.155910188538;8934.687285944994;9001.864972025409;2484.950300993979;2500.4124030852736;2504.6618706474574;2505.3736565858535;2509.9709119261106;2158.665406861939;2448.755124487555;2489.427431690712;2503.111395454615;2520.7479252074736;2457.201409541897;2459.8770061496944;2505.549001179787;2513.0952856971567;2519;2078.4180429479693;2441.1333976438377;2456.6416636901195;2462.2859370782453;2473.6660550825586;2075.740532433446;2426.46617744096;2461.6799816023804;2462.236706620953;2468.5309791139625;8927.151920567536;8996.77508062297;9008.099190081006;9056.320775941766;9064.32017598676;5322.7338633068575;5456.317960254968;5500;5524.751386187631;5529.502344788948;3484.9128771780656;3486.477028445728;3503.1417302760988;3547.6452354764574;3555.253396786678;1002.5789168549227;1019.5310157327638;1020.8315627921384;1031.0720351683544;1055.519738518972;10489.741231052323;11546.94226528862;11950.28298302096;11984.940075299568;12008.639740807703;4777.8327758528285;4857.198562237262;4878.317035615017;5013.248012798036;5038.3198268234055;10024.899751002396;10041.497925103715;10067.647632332979;10110.646127385653;10136.391816491034;5427.810026649048;5435.782568697208;5469.644473109235;5511.58663100269;5512.944870551243;4464.285714285698;4854.441739200037;5017.147084995559;5029.673071250357;5033.471485494044;3238.9676103238667;3838.347480928193;3901.7073719471155;3968.3253846846105;4060.898477538026;1170.1808733886314;1366.9179849209079;1377.5040985245303;1381.5233744358181;1383.7163381506766;833.2875391540146;837.8408102460513;876.4303202918154;893.1247377570018;945.4847108325979;5713.4000929902595;6001.2498437695285;6013.338532761392;6015.578909476295;6088.634681919099;453.01469304263094;463.4739570587408;468.9846482365708;1002.588938535197;1003.2024540490328;3288.457404528226;3883.728139030241;3950.2889479893897;3958.3468727659615;4041.211963667057;10500.632477863239;10902.890971090188;10954.233203675667;11033;11070.889291107147;10743.51654175564;10857.67426977192;10879.12966962641;10999.395033273087;11064.723381915355;8222.547759873265;8381.539015354092;8410.873836892451;8418.78953026167;8495.277901378453;5418.349798024262;5505.421930697379;5826.329972053268;5957.463828255478;5959.642421454727;9932.602695892372;10028.29801913859;10227.642032528825;10263.23025773085;10290;8852;9277.443353398818;9505.714828555154;10128.442935638315;10487.37075775456;5712;5869.178315035813;7069.893951590628;7080.362767350963;7176.390006849476;8304.128066553167;8333.166683331618;8370.121137280497;8379.70671026511;8425.87361189595;7408.851822963618;7523.059617547807;7541.73603923882;7605.809854753454;7674.002379690604;6464.41820236181;7291.671874765754;7358.632068396666;7398.408127349907;7417.109946806418;6897.3447522485285;6901.827454313682;6902.482313826383;6907.13660792401;7004.754833580801;3864.2271545690414;4684.625229981525;4844.660873738945;4864.100141473838;4972.8508144755715;4851.175300198904;4923.926141107814;4963.726995015309;4984.551390374883;4997.300377947163 05:09:50 INFO - PROCESS | 1657 | |4;prototype.html;261.79841522028033;265.8033055538902;289.2695942744571;328.4531255459659;329.8169515918664;5508.834734957953;6041.063635136561;6195.659238741868;6277.309495955447;6400.679966001699;5188.8702782430455;6239.65681887496;6286.214223222098;6304.96847515763;6349.142865713127;4726.385569875912;4755.619550435957;4758.976205118969;4784.6889952153;4798.4961579034225;3597.80212088335;3672.5776535698337;4173.3114036184;4640.861186685389;4771.880702982418;3852.9807350963206;4657.580817726404;4682.906341873178;4724.551167639069;4739.933514959141;3618.764780289286;4183.673979186524;4726.409198850144;4758.906081324206;4767.4994125617;3362.4283871741864;3866.3427217373114;3920.901977450558;4011.83952641894;4032.899177520556;5946.256717910262;5991.670458124801;6011.218541589609;6033.215681961317;6062.696865156769;4574.359589657451;4847.878803029917;5165.199394093921;5434.212039254326;5520.309961254843;5488.53347465462;5675.489205971441;6204.286507051702;6425.807225783234;6701.765438209688;4397.054633253866;4428.689991700582;4448.3549885266775;4462.977685111554;4470.441194850644;5635.3519345275;7064.752733654297;7077.504574679774;7089.929100708979;7105;3690.704743620504;3989.5611482736876;4364.236258654722;4414.050979039522;4414.205443020223;4147.8963025924595;4356.172327257811;4436.423264975532;4453.287474004143;4483.21543729846;4356.803943822536;5290.68255904647;5328.786848526093;5339.626226164209;5344.171653393731;5950.9107363389585;6257.74969001244;6343.065365063608;6712.664366781642;6734.259231484532;1452.208546342246;1539.4150222915443;1542.3367951780842;1545.590418539088;1552.9689406211812;968.7626531499827;987.6148302162162;1002.7493126718321;1003.708924411927;1007.0080970244303;6144.201253837062;6157;6159.384061593805;6199.287081985539;6231.697200483751;6775.186977562724;6856.725730970706;6875.106236189264;6898.55101438992;6905.827354316082;4946.848121067347;5853.326867410217;6158.414950579688;6167.453714114402;6198.163247961557;3336.7330613550857;3555.6088830228655;3607.22444674391;3658.158623516553;3735.34631439497;6445.838854028687;6991.405730512964;7139.535930164523;7274.5266609353075;7326.3406293433845;5947.613405128653;6320.5891617044745;6777.186737591521;7159;7195.208527062019;5716.742746576414;6457.61254324742;7195.316444937723;7270.382017528464;7336.743213987698;6818.352256535622;6843.144606924135;6847.315268473113;6863.142107236596;6889.173299203927;5936.643801371932;6226.844328891814;6371.5026968661405;6799.490038247251;6817.181938167451;7190.928090719026;7234.516924030666;7251.564906105439;7258.45561582894;7303.5027819297975;3950.7036972227775;4246.129543443525;4388.4733831940375;4415.13904788576;4449.555044495524;6525.119108920235;6609.380701728107;6724.562903411263;6813.318668133147;6826;6137.96931015342;6671.6330601815425;6698.162729658793;6709.228438729413;6724.8991265132045;5195.92206116901;6370.936290637034;6680.198376194693;6681.09805176295;6690.02994565795;6150.815475535741;6233.812985610439;6507.674616269073;6575.769848055295;6665.433438157811;6687.130673012477;6691.899621505779;6731.259561448334;6752.493562982698;6756.763513277012;6217.751289948352;6497.545171837926;6585.769498067736;6586.736530538917;6612.702428390638 05:09:50 INFO - PROCESS | 1657 | |5;yui.html;657.5725778244141;664.8171752767988;697.2539382860341;737.498501019307;781.6091954022988;14036.508722194712;14220.644483887909;14405.279736013184;14442.205678687667;14466.276686165702;13782.448702051906;13815.930920345409;13831.515896943609;13846.30768461578;13905.88752899768;712.3482013957223;886.3086792302015;901.6799036342086;907.2560500389683;911.7264820553808;751.0949306086153;886.4238245140646;894.9731508054737;900.4867225681331;915.4187091197072;935.0602644342426;942.0202988891579;942.9905700942971;947.2043483473881;958.884933807944;905.3526728389234;911.4349103555804;915.4049867586089;941.6468824190929;950.9286803489731;670.4033410264868;689.3588962265092;700.1038670501766;707.6214225389392;714.132329219995;14394.352254148589;14646.41414343425;14659.486917957927;14665.853341466556;14673.486427974969;12361;12434.378281086;12453.377331133406;12553.372331383394;12561.246325220516;9460.621575136985;9604.375715578464;9916.25628077897;10064.547095380653;10107.595696172219;3419.1767766018884;3457.170279133015;3459.1069937577004;3463.8441270142903;3469.1153755792366;82142.58928705378;83258.58370708168;85264.14735852624;85748.57125714331;86043.13956860414;9278.257739380833;9324.300677449219;9376.249900008119;9431.15119639222;9637.132658060667;21682.674759878613;22003.22988695388;22121.66817497739;22175.66736498954;22232.333030009126;6854.417374523122;6977.581345119309;7297.781066568012;7418.258174182646;7475.8504829903095;3593.7242278990584;3719.739618226698;3965.306071437487;3980.522337319482;3986.5614782373914;3285.129440698217;3382.4926261060546;3384.661533846596;3387.3733359328603;3388.034410193083;600.1987346891948;600.6786369292495;604.8487878030493;613.6808859393091;614.6772944204329;557.4230671255232;568.196002656245;582.4699523433655;598.6797063570968;605.0984033789622;6370.77702280418;7790.922090779133;8001.279085453928;8073.9192608074345;8091.433599647969;475.65039695823543;514.341642697348;567.8154599755096;569.7977416552923;591.7455494137477;3626.7642603230706;3732.5894151643297;3763.8118094095184;3804;3846.2307538492414;70786;75617;85197;85696;91378;77406;77502;78296.60851695706;78984.21015789769;80584.59707701541;46891.76554117208;67727;70303.59392812115;71412.2858771416;71423.64288178526;7344.228855970046;7421.51760135611;7455.9627201865815;7667.46327757074;7715.807104822425;44046;45184.77407612941;45598.088038239715;45806.54193458156;45949.54050459452;55156;59491.107633384665;64970.35029649832;65419.67290163519;65594.67202663956;9973.3018688694;9985.45080020606;10056.19550435978;10220.080192782685;10330.638427654996;10870.945645271722;10907.618233361794;11185.440727963733;11241.38172400493;11516.078713703051;10150.289479736348;10201.74495637615;10539.104176144807;10558.260921735406;10602.363858168535;9757.681414249233;10114.544845481823;10158.949205253926;10355.43045132525;11003.889961100287;8156.347492200491;8665.786789849551;8952.925648922172;10723.624673136403;11474.655760327203;8821.867671985054;8862.601182946653;9101.635934562544;9111.498867562348;9245.72262832116;7304.817379565553;8297.502149871027;9358.859617105885;9427.198688111588;9546.52267386614 05:09:50 INFO - PROCESS | 1657 | __end_tp_report 05:09:50 INFO - PROCESS | 1657 | __start_cc_report 05:09:50 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,404 05:09:50 INFO - PROCESS | 1657 | __end_cc_report 05:09:50 INFO - PROCESS | 1657 | __startTimestamp1461067790534__endTimestamp 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | ------- Summary: start ------- 05:09:50 INFO - PROCESS | 1657 | Number of tests: 6 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5741.91 Median:5995.85 stddev:782.09 (13.0%) stddev-sans-first:751.31 05:09:50 INFO - PROCESS | 1657 | Values: 2868.6 3448.0 3460.2 3486.4 3534.6 5755.6 5758.0 5761.0 5781.9 5792.4 5588.1 5656.6 5698.3 5778.6 5874.2 5203.9 5256.6 5299.5 5313.8 5331.8 59.8 5265.9 5280.2 5289.8 5305.2 5238.6 5263.5 5265.2 5272.6 5289.8 5160.6 5406.6 5408.3 5413.0 5416.8 5204.5 5212.5 5283.1 5308.6 5318.6 5569.6 5664.3 5714.0 5719.5 5728.0 6126.5 6172.0 6236.6 6253.6 6268.8 6209.9 6260.7 6267.1 6271.3 6296.7 5890.3 5893.4 5897.1 5916.4 5956.0 6186.9 6259.7 6332.9 6334.5 6353.0 6186.7 6216.4 6233.9 6244.8 6247.5 6206.2 6240.0 6243.2 6260.5 6267.1 6132.7 6132.9 6207.7 6229.3 6295.1 6130.4 6173.9 6240.5 6253.5 6269.3 4872.5 4959.7 4960.2 4961.8 4976.6 4372.1 4387.6 4478.5 4483.6 4503.9 5751.9 5797.6 5803.9 5848.6 5853.3 6227.7 6232.3 6250.0 6256.4 6271.7 5695.5 5738.8 5841.4 5861.3 5872.3 5438.6 5455.4 5461.8 5463.3 5482.2 6233.6 6372.2 6411.0 6417.8 6424.4 6129.7 6202.4 6207.1 6245.6 6260.8 6377.9 6384.0 6394.4 6445.0 6519.2 6109.8 6137.3 6215.9 6245.5 6245.9 6165.8 6219.1 6232.4 6258.9 6269.1 5980.6 6200.6 6204.5 6205.4 6209.0 6072.4 6112.7 6151.1 6192.1 6207.4 4951.3 5132.8 5872.3 6016.9 6090.7 5974.7 5995.9 6167.3 6188.2 6220.3 5031.2 5941.3 6029.5 6035.4 6044.3 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:63267.57 Median:38417.52 stddev:134870.30 (351.1%) stddev-sans-first:135166.34 05:09:50 INFO - PROCESS | 1657 | Values: 512.7 535.1 556.2 604.6 630.6 30557.5 38214.0 38222.0 38391.4 38488.6 39088.2 39409.0 39882.6 39894.0 39928.8 9428.6 9658.3 9679.8 9704.0 9720.0 9616.1 9777.4 9804.2 9825.5 9842.7 9758.2 9782.7 9802.5 9805.2 9845.1 9744.8 9756.7 9799.2 9829.2 9836.0 9539.5 9568.1 9640.3 9651.8 9686.9 38590.8 38899.8 38987.4 39127.8 39151.4 2723.8 2747.6 3032.3 3207.9 3353.4 27003.5 27207.9 27644.6 27670.0 28573.6 2692.7 2697.5 2729.9 2871.6 2913.1 335636.6 336350.0 337215.0 339333.0 341808.0 58755.8 58902.1 59143.4 59195.0 59230.8 67078.3 68520.7 69284.7 69307.0 69375.0 16096.4 16290.5 16362.7 16485.0 16517.3 12281.4 12282.2 12613.9 12676.4 12735.4 19302.3 19578.7 19705.2 20003.4 20008.9 6994.7 8555.8 8644.0 8728.5 8837.9 987.8 992.6 994.8 1050.1 1054.4 29700.7 29731.6 30125.9 30225.4 30251.2 998.3 1097.8 1108.7 1139.2 1180.0 15353.3 15359.0 15360.2 15719.9 15797.3 645823.0 648479.0 653179.0 653187.0 654482.0 60135.4 60864.4 60932.4 60971.4 61305.4 1141.9 1474.7 1477.7 1479.8 1486.7 29105.6 29166.6 29395.0 29738.3 29835.0 52352.5 52434.5 52752.5 52807.0 53043.5 419697.0 474689.6 487350.0 492587.0 494778.0 26437.9 26770.5 26822.8 26929.0 27003.2 45169.7 45491.8 46091.8 46326.8 46731.8 37626.0 37917.8 38168.6 38523.1 38753.6 37470.4 37663.4 38120.0 38323.2 38404.6 32950.5 36321.4 37087.4 38430.4 38568.4 28495.3 30036.5 30999.9 31110.1 31119.9 23462.8 24040.4 24111.9 24157.0 24464.3 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:35122.19 Median:400.28 stddev:104553.72 (26119.9%) stddev-sans-first:104814.50 05:09:50 INFO - PROCESS | 1657 | Values: 395.1 398.1 402.4 404.8 407.2 19704.4 20064.6 20409.8 20454.8 21093.8 18329.5 19969.7 20341.0 21254.4 21344.7 5514.3 5831.4 5858.4 5924.4 5977.0 5110.8 5694.6 5793.6 5877.9 5914.8 5698.9 5851.5 5856.3 5860.9 5862.8 4559.3 5728.7 5838.4 5840.5 5858.7 5024.5 5089.7 5098.6 5112.8 5133.8 25329.5 25355.9 25715.4 25772.0 26337.2 1919.6 2197.9 2339.8 2683.2 2914.4 7780.0 9513.4 9852.6 10043.9 10049.2 1601.7 1613.0 1796.6 1805.1 1840.9 86244.0 104633.0 111907.0 112047.0 112843.0 11071.6 11606.8 11655.6 11727.3 11844.8 12251.8 14437.7 14465.2 14667.3 14829.9 7059.3 8423.7 8471.8 8502.5 8533.7 8572.0 8589.1 8619.3 8638.0 8775.2 4622.9 4682.9 4686.3 4725.3 4741.1 1951.7 1991.0 2017.7 2019.1 2048.3 394.9 877.9 921.5 971.8 1049.9 9642.8 10124.6 11098.1 11695.5 11937.8 438.2 446.8 448.0 455.8 456.0 5862.9 6002.1 6005.7 6046.8 6157.9 597337.0 625936.0 633911.8 638657.0 639072.0 125671.7 131392.0 140469.0 150020.0 150047.0 1050.5 1052.6 1059.1 1071.9 1120.9 17395.0 17610.6 17744.6 17782.6 18057.6 50504.7 51826.7 51906.0 52321.7 52578.2 69161.7 72957.6 73395.0 75730.6 77062.2 7621.0 7916.0 7944.3 8001.9 8167.8 9111.0 11949.5 12523.5 12656.9 12953.8 11216.7 11255.7 11267.6 11405.9 11418.8 8963.5 11164.9 11299.9 11443.8 11700.2 11209.0 11578.9 11628.8 11706.2 11801.9 8003.9 8182.1 8303.6 8316.1 8526.5 8445.0 10608.3 10788.5 11229.3 11493.7 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5721.05 Median:4847.92 stddev:3223.44 (66.5%) stddev-sans-first:3215.98 05:09:50 INFO - PROCESS | 1657 | Values: 1382.1 1632.8 1760.3 1925.3 1941.7 8164.8 8782.4 8821.3 9064.4 9093.7 8867.1 8883.7 8885.2 8934.7 9001.9 2485.0 2500.4 2504.7 2505.4 2510.0 2158.7 2448.8 2489.4 2503.1 2520.7 2457.2 2459.9 2505.5 2513.1 2519.0 2078.4 2441.1 2456.6 2462.3 2473.7 2075.7 2426.5 2461.7 2462.2 2468.5 8927.2 8996.8 9008.1 9056.3 9064.3 5322.7 5456.3 5500.0 5524.8 5529.5 3484.9 3486.5 3503.1 3547.6 3555.3 1002.6 1019.5 1020.8 1031.1 1055.5 10489.7 11546.9 11950.3 11984.9 12008.6 4777.8 4857.2 4878.3 5013.2 5038.3 10024.9 10041.5 10067.6 10110.6 10136.4 5427.8 5435.8 5469.6 5511.6 5512.9 4464.3 4854.4 5017.1 5029.7 5033.5 3239.0 3838.3 3901.7 3968.3 4060.9 1170.2 1366.9 1377.5 1381.5 1383.7 833.3 837.8 876.4 893.1 945.5 5713.4 6001.2 6013.3 6015.6 6088.6 453.0 463.5 469.0 1002.6 1003.2 3288.5 3883.7 3950.3 3958.3 4041.2 10500.6 10902.9 10954.2 11033.0 11070.9 10743.5 10857.7 10879.1 10999.4 11064.7 8222.5 8381.5 8410.9 8418.8 8495.3 5418.3 5505.4 5826.3 5957.5 5959.6 9932.6 10028.3 10227.6 10263.2 10290.0 8852.0 9277.4 9505.7 10128.4 10487.4 5712.0 5869.2 7069.9 7080.4 7176.4 8304.1 8333.2 8370.1 8379.7 8425.9 7408.9 7523.1 7541.7 7605.8 7674.0 6464.4 7291.7 7358.6 7398.4 7417.1 6897.3 6901.8 6902.5 6907.1 7004.8 3864.2 4684.6 4844.7 4864.1 4972.9 4851.2 4923.9 4963.7 4984.6 4997.3 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:5341.76 Median:6100.33 stddev:1725.74 (28.3%) stddev-sans-first:1687.94 05:09:50 INFO - PROCESS | 1657 | Values: 261.8 265.8 289.3 328.5 329.8 5508.8 6041.1 6195.7 6277.3 6400.7 5188.9 6239.7 6286.2 6305.0 6349.1 4726.4 4755.6 4759.0 4784.7 4798.5 3597.8 3672.6 4173.3 4640.9 4771.9 3853.0 4657.6 4682.9 4724.6 4739.9 3618.8 4183.7 4726.4 4758.9 4767.5 3362.4 3866.3 3920.9 4011.8 4032.9 5946.3 5991.7 6011.2 6033.2 6062.7 4574.4 4847.9 5165.2 5434.2 5520.3 5488.5 5675.5 6204.3 6425.8 6701.8 4397.1 4428.7 4448.4 4463.0 4470.4 5635.4 7064.8 7077.5 7089.9 7105.0 3690.7 3989.6 4364.2 4414.1 4414.2 4147.9 4356.2 4436.4 4453.3 4483.2 4356.8 5290.7 5328.8 5339.6 5344.2 5950.9 6257.7 6343.1 6712.7 6734.3 1452.2 1539.4 1542.3 1545.6 1553.0 968.8 987.6 1002.7 1003.7 1007.0 6144.2 6157.0 6159.4 6199.3 6231.7 6775.2 6856.7 6875.1 6898.6 6905.8 4946.8 5853.3 6158.4 6167.5 6198.2 3336.7 3555.6 3607.2 3658.2 3735.3 6445.8 6991.4 7139.5 7274.5 7326.3 5947.6 6320.6 6777.2 7159.0 7195.2 5716.7 6457.6 7195.3 7270.4 7336.7 6818.4 6843.1 6847.3 6863.1 6889.2 5936.6 6226.8 6371.5 6799.5 6817.2 7190.9 7234.5 7251.6 7258.5 7303.5 3950.7 4246.1 4388.5 4415.1 4449.6 6525.1 6609.4 6724.6 6813.3 6826.0 6138.0 6671.6 6698.2 6709.2 6724.9 5195.9 6370.9 6680.2 6681.1 6690.0 6150.8 6233.8 6507.7 6575.8 6665.4 6687.1 6691.9 6731.3 6752.5 6756.8 6217.8 6497.5 6585.8 6586.7 6612.7 05:09:50 INFO - PROCESS | 1657 | 05:09:50 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:17422.27 Median:65195.01 stddev:24673.79 (37.8%) stddev-sans-first:24710.89 05:09:50 INFO - PROCESS | 1657 | Values: 657.6 664.8 697.3 737.5 781.6 14036.5 14220.6 14405.3 14442.2 14466.3 13782.4 13815.9 13831.5 13846.3 13905.9 712.3 886.3 901.7 907.3 911.7 751.1 886.4 895.0 900.5 915.4 935.1 942.0 943.0 947.2 958.9 905.4 911.4 915.4 941.6 950.9 670.4 689.4 700.1 707.6 714.1 14394.4 14646.4 14659.5 14665.9 14673.5 12361.0 12434.4 12453.4 12553.4 12561.2 9460.6 9604.4 9916.3 10064.5 10107.6 3419.2 3457.2 3459.1 3463.8 3469.1 82142.6 83258.6 85264.1 85748.6 86043.1 9278.3 9324.3 9376.2 9431.2 9637.1 21682.7 22003.2 22121.7 22175.7 22232.3 6854.4 6977.6 7297.8 7418.3 7475.9 3593.7 3719.7 3965.3 3980.5 3986.6 3285.1 3382.5 3384.7 3387.4 3388.0 600.2 600.7 604.8 613.7 614.7 557.4 568.2 582.5 598.7 605.1 6370.8 7790.9 8001.3 8073.9 8091.4 475.7 514.3 567.8 569.8 591.7 3626.8 3732.6 3763.8 3804.0 3846.2 70786.0 75617.0 85197.0 85696.0 91378.0 77406.0 77502.0 78296.6 78984.2 80584.6 46891.8 67727.0 70303.6 71412.3 71423.6 7344.2 7421.5 7456.0 7667.5 7715.8 44046.0 45184.8 45598.1 45806.5 45949.5 55156.0 59491.1 64970.4 65419.7 65594.7 9973.3 9985.5 10056.2 10220.1 10330.6 10870.9 10907.6 11185.4 11241.4 11516.1 10150.3 10201.7 10539.1 10558.3 10602.4 9757.7 10114.5 10158.9 10355.4 11003.9 8156.3 8665.8 8952.9 10723.6 11474.7 8821.9 8862.6 9101.6 9111.5 9245.7 7304.8 8297.5 9358.9 9427.2 9546.5 05:09:50 INFO - PROCESS | 1657 | -------- Summary: end -------- 05:09:50 INFO - PROCESS | 1657 | 05:09:51 INFO - TEST-INFO | 1657: exit 0 05:09:51 INFO - TEST-OK | dromaeo_css | took 1110540ms 05:09:51 INFO - TEST-START | kraken 05:09:51 INFO - operating with platform_type : mac_ 05:09:51 INFO - Initialising browser for kraken test... 05:09:51 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpsOLUrM/profile http://localhost:49279/getInfo.html) 05:09:54 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 05:09:54 INFO - PROCESS | 1665 | colorDepth:24 05:09:54 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 05:09:54 INFO - PROCESS | 1665 | __metrics 05:09:54 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:09:54 INFO - TEST-INFO | 1665: exit 0 05:09:54 INFO - Browser initialized. 05:09:54 INFO - Running cycle 1/1 for kraken test... 05:09:54 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpsOLUrM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:06 INFO - PROCESS | 1668 | RSS: Main: 247603200 05:10:06 INFO - PROCESS | 1668 | 05:10:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 05:10:10 INFO - PROCESS | 1668 | RSS: Main: 259641344 05:10:10 INFO - PROCESS | 1668 | 05:10:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 05:10:13 INFO - PROCESS | 1668 | RSS: Main: 268464128 05:10:13 INFO - PROCESS | 1668 | 05:10:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 05:10:17 INFO - PROCESS | 1668 | RSS: Main: 271745024 05:10:17 INFO - PROCESS | 1668 | 05:10:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 05:10:20 INFO - PROCESS | 1668 | RSS: Main: 273788928 05:10:20 INFO - PROCESS | 1668 | 05:10:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:10:23 INFO - PROCESS | 1668 | RSS: Main: 274472960 05:10:23 INFO - PROCESS | 1668 | 05:10:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 05:10:26 INFO - PROCESS | 1668 | RSS: Main: 274444288 05:10:26 INFO - PROCESS | 1668 | 05:10:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 05:10:30 INFO - PROCESS | 1668 | RSS: Main: 274518016 05:10:30 INFO - PROCESS | 1668 | 05:10:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 05:10:33 INFO - PROCESS | 1668 | RSS: Main: 274599936 05:10:33 INFO - PROCESS | 1668 | 05:10:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:10:36 INFO - PROCESS | 1668 | RSS: Main: 274477056 05:10:36 INFO - PROCESS | 1668 | 05:10:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:10:38 INFO - PROCESS | 1668 | RSS: Main: 274538496 05:10:38 INFO - PROCESS | 1668 | 05:10:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:10:41 INFO - PROCESS | 1668 | RSS: Main: 274628608 05:10:41 INFO - PROCESS | 1668 | 05:10:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:10:45 INFO - PROCESS | 1668 | RSS: Main: 274685952 05:10:45 INFO - PROCESS | 1668 | 05:10:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:10:48 INFO - PROCESS | 1668 | RSS: Main: 274780160 05:10:48 INFO - PROCESS | 1668 | 05:10:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 05:10:51 INFO - PROCESS | 1668 | RSS: Main: 276135936 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | __start_tp_report 05:10:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 05:10:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 05:10:51 INFO - PROCESS | 1668 | |i|pagename|runs| 05:10:51 INFO - PROCESS | 1668 | |0;ai-astar;115;109;113;115;116;125;122;116;123;172 05:10:51 INFO - PROCESS | 1668 | |1;audio-beat-detection;151;129;179;165;129;172;165;122;166;119 05:10:51 INFO - PROCESS | 1668 | |2;audio-dft;196;201;207;235;195;198;196;191;201;193 05:10:51 INFO - PROCESS | 1668 | |3;audio-fft;75;107;105;143;86;84;87;73;129;60 05:10:51 INFO - PROCESS | 1668 | |4;audio-oscillator;71;81;131;77;79;86;120;80;83;124 05:10:51 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;81;129;128;92;89;87;89;86;91;86 05:10:51 INFO - PROCESS | 1668 | |6;imaging-darkroom;134;93;95;107;93;96;95;94;103;144 05:10:51 INFO - PROCESS | 1668 | |7;imaging-desaturate;120;149;132;92;90;88;93;90;90;94 05:10:51 INFO - PROCESS | 1668 | |8;json-parse-financial;59;80;91;92;92;90;90;81;61;61 05:10:51 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;63;65;55;96;112;109;88;64;76;64 05:10:51 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;85;95;113;110;109;80;84;84;84;83 05:10:51 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;142;143;147;133;133;133;159;154;186;165 05:10:51 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;170;167;191;224;176;189;223;170;167;172 05:10:51 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;70;65;100;96;101;71;57;99;97;98 05:10:51 INFO - PROCESS | 1668 | __end_tp_report 05:10:51 INFO - PROCESS | 1668 | __start_cc_report 05:10:51 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1049 05:10:51 INFO - PROCESS | 1668 | __end_cc_report 05:10:51 INFO - PROCESS | 1668 | __startTimestamp1461067851113__endTimestamp 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | ------- Summary: start ------- 05:10:51 INFO - PROCESS | 1668 | Number of tests: 14 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:122.60 Median:119.00 stddev:18.03 (15.2%) stddev-sans-first:18.92 05:10:51 INFO - PROCESS | 1668 | Values: 115.0 109.0 113.0 115.0 116.0 125.0 122.0 116.0 123.0 172.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:165.00 stddev:22.75 (13.8%) stddev-sans-first:24.13 05:10:51 INFO - PROCESS | 1668 | Values: 151.0 129.0 179.0 165.0 129.0 172.0 165.0 122.0 166.0 119.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:201.30 Median:199.50 stddev:12.69 (6.4%) stddev-sans-first:13.32 05:10:51 INFO - PROCESS | 1668 | Values: 196.0 201.0 207.0 235.0 195.0 198.0 196.0 191.0 201.0 193.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:94.90 Median:74.00 stddev:25.99 (35.1%) stddev-sans-first:26.55 05:10:51 INFO - PROCESS | 1668 | Values: 75.0 107.0 105.0 143.0 86.0 84.0 87.0 73.0 129.0 60.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:79.50 stddev:22.44 (28.2%) stddev-sans-first:22.32 05:10:51 INFO - PROCESS | 1668 | Values: 71.0 81.0 131.0 77.0 79.0 86.0 120.0 80.0 83.0 124.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:88.00 stddev:17.50 (19.9%) stddev-sans-first:17.73 05:10:51 INFO - PROCESS | 1668 | Values: 81.0 129.0 128.0 92.0 89.0 87.0 89.0 86.0 91.0 86.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:93.50 stddev:18.43 (19.7%) stddev-sans-first:16.39 05:10:51 INFO - PROCESS | 1668 | Values: 134.0 93.0 95.0 107.0 93.0 96.0 95.0 94.0 103.0 144.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:90.00 stddev:21.79 (24.2%) stddev-sans-first:22.31 05:10:51 INFO - PROCESS | 1668 | Values: 120.0 149.0 132.0 92.0 90.0 88.0 93.0 90.0 90.0 94.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:79.70 Median:90.00 stddev:14.03 (15.6%) stddev-sans-first:12.73 05:10:51 INFO - PROCESS | 1668 | Values: 59.0 80.0 91.0 92.0 92.0 90.0 90.0 81.0 61.0 61.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:64.50 stddev:20.67 (32.0%) stddev-sans-first:21.08 05:10:51 INFO - PROCESS | 1668 | Values: 63.0 65.0 55.0 96.0 112.0 109.0 88.0 64.0 76.0 64.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:84.00 stddev:13.01 (15.5%) stddev-sans-first:13.50 05:10:51 INFO - PROCESS | 1668 | Values: 85.0 95.0 113.0 110.0 109.0 80.0 84.0 84.0 84.0 83.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:150.50 stddev:17.01 (11.3%) stddev-sans-first:17.83 05:10:51 INFO - PROCESS | 1668 | Values: 142.0 143.0 147.0 133.0 133.0 133.0 159.0 154.0 186.0 165.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:182.50 stddev:22.02 (12.1%) stddev-sans-first:22.69 05:10:51 INFO - PROCESS | 1668 | Values: 170.0 167.0 191.0 224.0 176.0 189.0 223.0 170.0 167.0 172.0 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.40 Median:83.50 stddev:17.37 (20.8%) stddev-sans-first:17.50 05:10:51 INFO - PROCESS | 1668 | Values: 70.0 65.0 100.0 96.0 101.0 71.0 57.0 99.0 97.0 98.0 05:10:51 INFO - PROCESS | 1668 | -------- Summary: end -------- 05:10:51 INFO - PROCESS | 1668 | 05:10:51 INFO - TEST-INFO | 1668: exit 0 05:10:51 INFO - TEST-OK | kraken | took 60794ms 05:10:51 INFO - SUITE-END | took 1171s 05:10:52 INFO - Completed test suite (00:19:31) 05:10:52 INFO - javascript benchmark 05:10:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.598396224528, 3448.0345503259086, 3460.186856088817, 3486.407310757171, 3534.628863969283, 5755.597108202427, 5758.0, 5761.020626493496, 5781.9421805781985, 5792.420757924211, 5588.127977127667, 5656.604037717361, 5698.34469036061, 5778.6243894146855, 5874.236349274599, 5203.9219411708855, 5256.632035757499, 5299.5230429261355, 5313.840584782462, 5331.840044798662, 59.81068189120384, 5265.921011184835, 5280.234366016925, 5289.788408463657, 5305.151175811871, 5238.554722848562, 5263.526282634543, 5265.210218467241, 5272.604554658396, 5289.7619607117385, 5160.638755287131, 5406.59450541207, 5408.296921400193, 5412.97153540826, 5416.756245968941, 5204.531592156686, 5212.478752124795, 5283.101872681654, 5308.601854860863, 5318.627696061278, 5569.554435645138, 5664.320281566197, 5714.0, 5719.456651618089, 5727.971360143214, 6126.540509461764, 6172.043333283348, 6236.594621349598, 6253.624782513064, 6268.811935641938, 6209.9068513972325, 6260.686965651699, 6267.091271765614, 6271.278802937675, 6296.748130074791, 5890.322612899528, 5893.381194974509, 5897.085951677497, 5916.437938395846, 5956.0, 6186.938130618636, 6259.7496100156395, 6332.905006424908, 6334.5249106317215, 6352.968235158887, 6186.690665466798, 6216.44052035319, 6233.906491402633, 6244.75020999164, 6247.531435142382, 6206.224221972254, 6239.970404883235, 6243.188385509855, 6260.46786023184, 6267.122602835516, 6132.692058233643, 6132.938670613236, 6207.720652570555, 6229.314775374705, 6295.055741638791, 6130.398136742002, 6173.876522469525, 6240.500759939285, 6253.468455181361, 6269.341719119518, 4872.537108974641, 4959.7272150032095, 4960.206366981338, 4961.801527938842, 4976.62675299354, 4372.103718737651, 4387.648988080946, 4478.529754375809, 4483.618892394183, 4503.909921801611, 5751.913721294184, 5797.565182611321, 5803.883922321615, 5848.561357898175, 5853.2976042875125, 6227.719752611143, 6232.345603711636, 6250.031245157008, 6256.43692067707, 6271.686415679198, 5695.45893140151, 5738.77044918207, 5841.4450627190345, 5861.267341582302, 5872.324682661463, 5438.646487978269, 5455.427180146107, 5461.754221060062, 5463.31708536433, 5482.150266708587, 6233.563650544509, 6372.171617689671, 6410.967945160244, 6417.7753778617525, 6424.38968298011, 6129.725578588854, 6202.4417802398, 6207.131001659681, 6245.5628106032145, 6260.812175634738, 6377.8724425512155, 6384.0, 6394.392532709385, 6445.0, 6519.152510173647, 6109.799438327977, 6137.254412462313, 6215.9432896406815, 6245.469135123565, 6245.906311405423, 6165.84585385369, 6219.129321894848, 6232.407921247474, 6258.9374106260175, 6269.059641053878, 5980.581359304807, 6200.596961197508, 6204.534659900616, 6205.410486003822, 6209.0, 6072.423119803611, 6112.72492737819, 6151.0773383992755, 6192.0711893214575, 6207.379262073757, 4951.282064100613, 5132.793793458609, 5872.295324560909, 6016.939830601638, 6090.69546522681, 5974.701264936823, 5995.850103747442, 6167.259928808572, 6188.195534580475, 6220.315765265726, 5031.22016087507, 5941.287045554561, 6029.487493563096, 6035.366286540026, 6044.274687037584], "value": 5694.210360773198, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6846989101703, 535.1250705097167, 556.2046273828423, 604.6160687963142, 630.5743623054437, 30557.541636875438, 38214.0, 38222.0, 38391.42412863809, 38488.61511384886, 39088.218235635344, 39409.01477463065, 39882.60117398825, 39894.0, 39928.80035599825, 9428.632848237, 9658.275629327793, 9679.806403871919, 9704.0, 9719.95140024299, 9616.134547890688, 9777.364471309378, 9804.215662747, 9825.508724563779, 9842.704718858446, 9758.170555502791, 9782.706518804447, 9802.460864652441, 9805.1665608423, 9845.064718851734, 9744.805103897954, 9756.707298781012, 9799.16707079899, 9829.164521015722, 9836.0, 9539.52302384878, 9568.09103135201, 9640.276979226515, 9651.806963860754, 9686.854697179477, 38590.80704596487, 38899.805500972594, 38987.41518877219, 39127.82616521481, 39151.412728808806, 2723.8229515081653, 2747.5741260104714, 3032.272254658888, 3207.9198020049453, 3353.3628610563915, 27003.459930801273, 27207.86396068007, 27644.58533122005, 27670.0, 28573.57139642907, 2692.7041062741805, 2697.4739925714493, 2729.863506824651, 2871.6410448693914, 2913.0823790505924, 335636.64363356296, 336350.0, 337215.0, 339333.0, 341808.0, 58755.82488350209, 58902.11646825301, 59143.40856591465, 59195.0, 59230.81538369208, 67078.32921670818, 68520.6573967137, 69284.6535767318, 69307.0, 69375.0, 16096.356145754038, 16290.51128466148, 16362.67274654524, 16485.0, 16517.256723447474, 12281.447334869952, 12282.201656892274, 12613.87386126127, 12676.366181691063, 12735.363231838372, 19302.32441864528, 19578.665440772507, 19705.24126622274, 20003.399898002794, 20008.89995550042, 6994.720211191597, 8555.786105347292, 8643.956780216184, 8728.519931403835, 8837.867431988525, 987.7728122531857, 992.5682328187117, 994.8209322322051, 1050.0759331787979, 1054.3779170289413, 29700.702992970226, 29731.554026689617, 30125.94559190418, 30225.395492090036, 30251.243718906764, 998.3211416237028, 1097.8133717268083, 1108.6729414822646, 1139.1797905508022, 1179.9705007374712, 15353.309101090477, 15359.001664891746, 15360.231988400536, 15719.921400392925, 15797.289121989308, 645823.0, 648479.0, 653179.0, 653187.0, 654482.0, 60135.39864601298, 60864.39135608676, 60932.39067609356, 60971.39028609657, 61305.38694613086, 1141.9143564232716, 1474.7124310759514, 1477.6675248069314, 1479.7706355514913, 1486.695227478386, 29105.563416549194, 29166.56250156292, 29395.0, 29738.256543585718, 29835.0, 52352.47647523476, 52434.47565524296, 52752.47247527476, 52807.0, 53043.46956530385, 419697.0, 474689.626551865, 487350.0, 492587.0, 494778.0, 26437.942482300492, 26770.46459070769, 26822.79297431659, 26928.9803264752, 27003.1899043029, 45169.66197365855, 45491.772541137085, 46091.769541152076, 46326.76836615795, 46731.76634116808, 37626.0, 37917.81041094777, 38168.61831381651, 38523.107669248064, 38753.612463876125, 37470.43794343142, 37663.43504847484, 38120.0, 38323.233535329695, 38404.615953841225, 32950.50574241436, 36321.365538551356, 37087.44368834416, 38430.42354364743, 38568.42147367736, 28495.28761780972, 30036.549451757804, 30999.90000999972, 31110.066697999097, 31119.910803122686, 23462.76537234674, 24040.39899002539, 24111.879440602686, 24157.0, 24464.26607201787], "value": 19562.112135971343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.11494252873564, 398.14796335841305, 402.42051445917895, 404.81378565859706, 407.20391634354837, 19704.40886773397, 20064.598708025867, 20409.795902040958, 20454.795452045477, 21093.789062109376, 18329.450116496493, 19969.700454493195, 20341.0, 21254.362369128914, 21344.679829802564, 5514.338279406476, 5831.387704291052, 5858.384869588695, 5924.377940316248, 5977.043673012319, 5110.770015349318, 5694.5729070319685, 5793.594448388614, 5877.941220587803, 5914.822555323326, 5698.886022279552, 5851.502622277111, 5856.267966504187, 5860.882782344351, 5862.824115276527, 4559.3388958601145, 5728.68492232927, 5838.386969368197, 5840.503557197643, 5858.707064646772, 5024.497550244982, 5089.6691715038405, 5098.643094983353, 5112.795488180468, 5133.820316288912, 25329.49341013188, 25355.873220633963, 25715.35711607206, 25772.0, 26337.209883703326, 1919.5680971781378, 2197.912083516673, 2339.812814974815, 2683.154806236049, 2914.446255211524, 7780.0, 9513.381630194015, 9852.605895764162, 10043.89956100437, 10049.246306526966, 1601.6716573102485, 1613.0483015021193, 1796.6137280484759, 1805.0703887497948, 1840.871139020269, 86244.0, 104632.95367046384, 111907.0, 112047.0, 112842.99999999919, 11071.5571377144, 11606.76786464266, 11655.59205427806, 11727.296362218294, 11844.763104737856, 12251.75496490083, 14437.700606945215, 14465.204413757345, 14667.339969701388, 14829.925850370895, 7059.329363710439, 8423.70517031901, 8471.830563388698, 8502.532360720219, 8533.74398768038, 8572.0, 8589.14108589136, 8619.267362274277, 8638.0, 8775.244951009696, 4622.884427889262, 4682.929756053662, 4686.320483529869, 4725.314829349725, 4741.122892264942, 1951.6779731344452, 1990.9502262443555, 2017.6670849309994, 2019.1014998325604, 2048.3240530625158, 394.8874570747323, 877.8683197520295, 921.4563407589554, 971.7862070344513, 1049.8740151181908, 9642.794650668666, 10124.595016199417, 11098.056665183389, 11695.532178712756, 11937.761244775056, 438.2309047621404, 446.84360473833897, 448.0367210497391, 455.8176729308303, 456.0423111465895, 5862.912056319158, 6002.0996850472775, 6005.669688167105, 6046.788362407295, 6157.876842463125, 597337.0, 625936.0, 633911.8304408449, 638657.0, 639072.0, 125671.743282566, 131392.0, 140469.0, 150020.0, 150047.0, 1050.485262221506, 1052.610534102387, 1059.0786016165985, 1071.8660167479065, 1120.8991190792867, 17395.0, 17610.559736006704, 17744.556386089935, 17782.555436114202, 18057.638847223243, 50504.74747626238, 51826.740866295426, 51905.96188076142, 52321.69195770136, 52578.21132682936, 69161.65419172872, 72957.6352118236, 73395.0, 75730.62134689292, 77062.22937770774, 7621.0, 7915.960420197862, 7944.324732397579, 8001.9199808003505, 8167.79580510492, 9110.952240492428, 11949.462274197507, 12523.499060037497, 12656.92416144638, 12953.805692914426, 11216.719582010515, 11255.66233013011, 11267.605633802777, 11405.885941140814, 11418.771624567627, 8963.462192268486, 11164.944175279072, 11299.887001129884, 11443.771124577295, 11700.186471096653, 11209.047230985458, 11578.884211157781, 11628.825567616661, 11706.239094458833, 11801.940990294994, 8003.919960800317, 8182.140875208018, 8303.584820758817, 8316.085230624505, 8526.488410695378, 8444.986601607847, 10608.310459820086, 10788.514516846917, 11229.3262404256, 11493.712657183638], "value": 8963.60714788752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.1292585671029, 1632.8040635123784, 1760.331074191807, 1925.345382569926, 1941.7378653881722, 8164.755057348274, 8782.38523303369, 8821.25019373354, 9064.365494415393, 9093.681721139761, 8867.068957759438, 8883.689070882521, 8885.155910188538, 8934.687285944994, 9001.864972025409, 2484.950300993979, 2500.4124030852736, 2504.6618706474574, 2505.3736565858535, 2509.9709119261106, 2158.665406861939, 2448.755124487555, 2489.427431690712, 2503.111395454615, 2520.7479252074736, 2457.201409541897, 2459.8770061496944, 2505.549001179787, 2513.0952856971567, 2519.0, 2078.4180429479693, 2441.1333976438377, 2456.6416636901195, 2462.2859370782453, 2473.6660550825586, 2075.740532433446, 2426.46617744096, 2461.6799816023804, 2462.236706620953, 2468.5309791139625, 8927.151920567536, 8996.77508062297, 9008.099190081006, 9056.320775941766, 9064.32017598676, 5322.7338633068575, 5456.317960254968, 5500.0, 5524.751386187631, 5529.502344788948, 3484.9128771780656, 3486.477028445728, 3503.1417302760988, 3547.6452354764574, 3555.253396786678, 1002.5789168549227, 1019.5310157327638, 1020.8315627921384, 1031.0720351683544, 1055.519738518972, 10489.741231052323, 11546.94226528862, 11950.28298302096, 11984.940075299568, 12008.639740807703, 4777.8327758528285, 4857.198562237262, 4878.317035615017, 5013.248012798036, 5038.3198268234055, 10024.899751002396, 10041.497925103715, 10067.647632332979, 10110.646127385653, 10136.391816491034, 5427.810026649048, 5435.782568697208, 5469.644473109235, 5511.58663100269, 5512.944870551243, 4464.285714285698, 4854.441739200037, 5017.147084995559, 5029.673071250357, 5033.471485494044, 3238.9676103238667, 3838.347480928193, 3901.7073719471155, 3968.3253846846105, 4060.898477538026, 1170.1808733886314, 1366.9179849209079, 1377.5040985245303, 1381.5233744358181, 1383.7163381506766, 833.2875391540146, 837.8408102460513, 876.4303202918154, 893.1247377570018, 945.4847108325979, 5713.4000929902595, 6001.2498437695285, 6013.338532761392, 6015.578909476295, 6088.634681919099, 453.01469304263094, 463.4739570587408, 468.9846482365708, 1002.588938535197, 1003.2024540490328, 3288.457404528226, 3883.728139030241, 3950.2889479893897, 3958.3468727659615, 4041.211963667057, 10500.632477863239, 10902.890971090188, 10954.233203675667, 11033.0, 11070.889291107147, 10743.51654175564, 10857.67426977192, 10879.12966962641, 10999.395033273087, 11064.723381915355, 8222.547759873265, 8381.539015354092, 8410.873836892451, 8418.78953026167, 8495.277901378453, 5418.349798024262, 5505.421930697379, 5826.329972053268, 5957.463828255478, 5959.642421454727, 9932.602695892372, 10028.29801913859, 10227.642032528825, 10263.23025773085, 10290.0, 8852.0, 9277.443353398818, 9505.714828555154, 10128.442935638315, 10487.37075775456, 5712.0, 5869.178315035813, 7069.893951590628, 7080.362767350963, 7176.390006849476, 8304.128066553167, 8333.166683331618, 8370.121137280497, 8379.70671026511, 8425.87361189595, 7408.851822963618, 7523.059617547807, 7541.73603923882, 7605.809854753454, 7674.002379690604, 6464.41820236181, 7291.671874765754, 7358.632068396666, 7398.408127349907, 7417.109946806418, 6897.3447522485285, 6901.827454313682, 6902.482313826383, 6907.13660792401, 7004.754833580801, 3864.2271545690414, 4684.625229981525, 4844.660873738945, 4864.100141473838, 4972.8508144755715, 4851.175300198904, 4923.926141107814, 4963.726995015309, 4984.551390374883, 4997.300377947163], "value": 4571.8515892570895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.79841522028033, 265.8033055538902, 289.2695942744571, 328.4531255459659, 329.8169515918664, 5508.834734957953, 6041.063635136561, 6195.659238741868, 6277.309495955447, 6400.679966001699, 5188.8702782430455, 6239.65681887496, 6286.214223222098, 6304.96847515763, 6349.142865713127, 4726.385569875912, 4755.619550435957, 4758.976205118969, 4784.6889952153, 4798.4961579034225, 3597.80212088335, 3672.5776535698337, 4173.3114036184, 4640.861186685389, 4771.880702982418, 3852.9807350963206, 4657.580817726404, 4682.906341873178, 4724.551167639069, 4739.933514959141, 3618.764780289286, 4183.673979186524, 4726.409198850144, 4758.906081324206, 4767.4994125617, 3362.4283871741864, 3866.3427217373114, 3920.901977450558, 4011.83952641894, 4032.899177520556, 5946.256717910262, 5991.670458124801, 6011.218541589609, 6033.215681961317, 6062.696865156769, 4574.359589657451, 4847.878803029917, 5165.199394093921, 5434.212039254326, 5520.309961254843, 5488.53347465462, 5675.489205971441, 6204.286507051702, 6425.807225783234, 6701.765438209688, 4397.054633253866, 4428.689991700582, 4448.3549885266775, 4462.977685111554, 4470.441194850644, 5635.3519345275, 7064.752733654297, 7077.504574679774, 7089.929100708979, 7105.0, 3690.704743620504, 3989.5611482736876, 4364.236258654722, 4414.050979039522, 4414.205443020223, 4147.8963025924595, 4356.172327257811, 4436.423264975532, 4453.287474004143, 4483.21543729846, 4356.803943822536, 5290.68255904647, 5328.786848526093, 5339.626226164209, 5344.171653393731, 5950.9107363389585, 6257.74969001244, 6343.065365063608, 6712.664366781642, 6734.259231484532, 1452.208546342246, 1539.4150222915443, 1542.3367951780842, 1545.590418539088, 1552.9689406211812, 968.7626531499827, 987.6148302162162, 1002.7493126718321, 1003.708924411927, 1007.0080970244303, 6144.201253837062, 6157.0, 6159.384061593805, 6199.287081985539, 6231.697200483751, 6775.186977562724, 6856.725730970706, 6875.106236189264, 6898.55101438992, 6905.827354316082, 4946.848121067347, 5853.326867410217, 6158.414950579688, 6167.453714114402, 6198.163247961557, 3336.7330613550857, 3555.6088830228655, 3607.22444674391, 3658.158623516553, 3735.34631439497, 6445.838854028687, 6991.405730512964, 7139.535930164523, 7274.5266609353075, 7326.3406293433845, 5947.613405128653, 6320.5891617044745, 6777.186737591521, 7159.0, 7195.208527062019, 5716.742746576414, 6457.61254324742, 7195.316444937723, 7270.382017528464, 7336.743213987698, 6818.352256535622, 6843.144606924135, 6847.315268473113, 6863.142107236596, 6889.173299203927, 5936.643801371932, 6226.844328891814, 6371.5026968661405, 6799.490038247251, 6817.181938167451, 7190.928090719026, 7234.516924030666, 7251.564906105439, 7258.45561582894, 7303.5027819297975, 3950.7036972227775, 4246.129543443525, 4388.4733831940375, 4415.13904788576, 4449.555044495524, 6525.119108920235, 6609.380701728107, 6724.562903411263, 6813.318668133147, 6826.0, 6137.96931015342, 6671.6330601815425, 6698.162729658793, 6709.228438729413, 6724.8991265132045, 5195.92206116901, 6370.936290637034, 6680.198376194693, 6681.09805176295, 6690.02994565795, 6150.815475535741, 6233.812985610439, 6507.674616269073, 6575.769848055295, 6665.433438157811, 6687.130673012477, 6691.899621505779, 6731.259561448334, 6752.493562982698, 6756.763513277012, 6217.751289948352, 6497.545171837926, 6585.769498067736, 6586.736530538917, 6612.702428390638], "value": 4767.428803664043, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.5725778244141, 664.8171752767988, 697.2539382860341, 737.498501019307, 781.6091954022988, 14036.508722194712, 14220.644483887909, 14405.279736013184, 14442.205678687667, 14466.276686165702, 13782.448702051906, 13815.930920345409, 13831.515896943609, 13846.30768461578, 13905.88752899768, 712.3482013957223, 886.3086792302015, 901.6799036342086, 907.2560500389683, 911.7264820553808, 751.0949306086153, 886.4238245140646, 894.9731508054737, 900.4867225681331, 915.4187091197072, 935.0602644342426, 942.0202988891579, 942.9905700942971, 947.2043483473881, 958.884933807944, 905.3526728389234, 911.4349103555804, 915.4049867586089, 941.6468824190929, 950.9286803489731, 670.4033410264868, 689.3588962265092, 700.1038670501766, 707.6214225389392, 714.132329219995, 14394.352254148589, 14646.41414343425, 14659.486917957927, 14665.853341466556, 14673.486427974969, 12361.0, 12434.378281086, 12453.377331133406, 12553.372331383394, 12561.246325220516, 9460.621575136985, 9604.375715578464, 9916.25628077897, 10064.547095380653, 10107.595696172219, 3419.1767766018884, 3457.170279133015, 3459.1069937577004, 3463.8441270142903, 3469.1153755792366, 82142.58928705378, 83258.58370708168, 85264.14735852624, 85748.57125714331, 86043.13956860414, 9278.257739380833, 9324.300677449219, 9376.249900008119, 9431.15119639222, 9637.132658060667, 21682.674759878613, 22003.22988695388, 22121.66817497739, 22175.66736498954, 22232.333030009126, 6854.417374523122, 6977.581345119309, 7297.781066568012, 7418.258174182646, 7475.8504829903095, 3593.7242278990584, 3719.739618226698, 3965.306071437487, 3980.522337319482, 3986.5614782373914, 3285.129440698217, 3382.4926261060546, 3384.661533846596, 3387.3733359328603, 3388.034410193083, 600.1987346891948, 600.6786369292495, 604.8487878030493, 613.6808859393091, 614.6772944204329, 557.4230671255232, 568.196002656245, 582.4699523433655, 598.6797063570968, 605.0984033789622, 6370.77702280418, 7790.922090779133, 8001.279085453928, 8073.9192608074345, 8091.433599647969, 475.65039695823543, 514.341642697348, 567.8154599755096, 569.7977416552923, 591.7455494137477, 3626.7642603230706, 3732.5894151643297, 3763.8118094095184, 3804.0, 3846.2307538492414, 70786.0, 75617.0, 85197.0, 85696.0, 91378.0, 77406.0, 77502.0, 78296.60851695706, 78984.21015789769, 80584.59707701541, 46891.76554117208, 67727.0, 70303.59392812115, 71412.2858771416, 71423.64288178526, 7344.228855970046, 7421.51760135611, 7455.9627201865815, 7667.46327757074, 7715.807104822425, 44046.0, 45184.77407612941, 45598.088038239715, 45806.54193458156, 45949.54050459452, 55156.0, 59491.107633384665, 64970.35029649832, 65419.67290163519, 65594.67202663956, 9973.3018688694, 9985.45080020606, 10056.19550435978, 10220.080192782685, 10330.638427654996, 10870.945645271722, 10907.618233361794, 11185.440727963733, 11241.38172400493, 11516.078713703051, 10150.289479736348, 10201.74495637615, 10539.104176144807, 10558.260921735406, 10602.363858168535, 9757.681414249233, 10114.544845481823, 10158.949205253926, 10355.43045132525, 11003.889961100287, 8156.347492200491, 8665.786789849551, 8952.925648922172, 10723.624673136403, 11474.655760327203, 8821.867671985054, 8862.601182946653, 9101.635934562544, 9111.498867562348, 9245.72262832116, 7304.817379565553, 8297.502149871027, 9358.859617105885, 9427.198688111588, 9546.52267386614], "value": 6586.6832110165205, "unit": "score"}], "name": "dromaeo_css", "value": 7234.396721964463, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 113.0, 115.0, 116.0, 125.0, 122.0, 116.0, 123.0, 172.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 129.0, 179.0, 165.0, 129.0, 172.0, 165.0, 122.0, 166.0, 119.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 201.0, 207.0, 235.0, 195.0, 198.0, 196.0, 191.0, 201.0, 193.0], "value": 201.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 107.0, 105.0, 143.0, 86.0, 84.0, 87.0, 73.0, 129.0, 60.0], "value": 94.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 131.0, 77.0, 79.0, 86.0, 120.0, 80.0, 83.0, 124.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 129.0, 128.0, 92.0, 89.0, 87.0, 89.0, 86.0, 91.0, 86.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 93.0, 95.0, 107.0, 93.0, 96.0, 95.0, 94.0, 103.0, 144.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 149.0, 132.0, 92.0, 90.0, 88.0, 93.0, 90.0, 90.0, 94.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 80.0, 91.0, 92.0, 92.0, 90.0, 90.0, 81.0, 61.0, 61.0], "value": 79.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 55.0, 96.0, 112.0, 109.0, 88.0, 64.0, 76.0, 64.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 113.0, 110.0, 109.0, 80.0, 84.0, 84.0, 84.0, 83.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 143.0, 147.0, 133.0, 133.0, 133.0, 159.0, 154.0, 186.0, 165.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 191.0, 224.0, 176.0, 189.0, 223.0, 170.0, 167.0, 172.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 100.0, 96.0, 101.0, 71.0, 57.0, 99.0, 97.0, 98.0], "value": 85.4, "unit": "score"}], "name": "kraken", "value": 1638.1000000000001, "alertThreshold": 2.0}]} 05:10:52 INFO - Return code: 0 05:10:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:10:52 ERROR - # TBPL SUCCESS # 05:10:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:10:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:10:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:10:52 INFO - 2016-04-19 05:10:52.395 screenresolution[1676:10666] starting screenresolution argv=screenresolution get 05:10:52 INFO - 2016-04-19 05:10:52.398 screenresolution[1676:10666] Display 0: 1600x1200x32@60 05:10:52 INFO - 2016-04-19 05:10:52.404 screenresolution[1677:10668] starting screenresolution argv=screenresolution list 05:10:52 INFO - Available Modes on Display 0 05:10:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:10:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:10:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:10:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:10:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:10:52 INFO - Intel Iris: 05:10:52 INFO - Chipset Model: Intel Iris 05:10:52 INFO - Type: GPU 05:10:52 INFO - Bus: Built-In 05:10:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:10:52 INFO - Vendor: Intel (0x8086) 05:10:52 INFO - Device ID: 0x0a2e 05:10:52 INFO - Revision ID: 0x0009 05:10:52 INFO - Displays: 05:10:52 INFO - Display: 05:10:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:10:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:10:52 INFO - Display Serial Number: 1600x1200 60 05:10:52 INFO - Main Display: Yes 05:10:52 INFO - Mirror: Off 05:10:52 INFO - Online: Yes 05:10:52 INFO - Rotation: Supported 05:10:52 INFO - Return code: 0 05:10:52 INFO - Running post-action listener: _resource_record_post_action 05:10:52 INFO - Running post-run listener: _resource_record_post_run 05:10:53 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 37807616; Write bytes: 694466560; Read time: 399; Write time: 2187 05:10:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381846016; Write bytes: 375481856; Read time: 28187; Write time: 710 05:10:53 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 35927552; Write bytes: 296094208; Read time: 377; Write time: 1424 05:10:53 INFO - Running post-run listener: _upload_blobber_files 05:10:53 INFO - Blob upload gear active. 05:10:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:10:53 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 05:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:10:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:10:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:10:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:10:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:55 INFO - (blobuploader) - INFO - Done attempting. 05:10:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:10:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:10:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:10:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:10:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:10:56 INFO - (blobuploader) - INFO - Done attempting. 05:10:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:10:56 INFO - Return code: 0 05:10:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:10:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:10:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35397ffc70557113e1f22fd535945a1c99a261444ef5e531241d341a16da3b6f4a086d89d41ec777e541980de1167e3291c4887bdc8e9434de85f9085ee253f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50a42880b2cc0022cd664bfe05de91b713debc61803d6c983dd53090660ecd9214d26f17763848964cdd86aa90def56021a7e7f516e1e4374fac8f8ca8a79766"} 05:10:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:10:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:10:56 INFO - Contents: 05:10:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35397ffc70557113e1f22fd535945a1c99a261444ef5e531241d341a16da3b6f4a086d89d41ec777e541980de1167e3291c4887bdc8e9434de85f9085ee253f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50a42880b2cc0022cd664bfe05de91b713debc61803d6c983dd53090660ecd9214d26f17763848964cdd86aa90def56021a7e7f516e1e4374fac8f8ca8a79766"} 05:10:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:10:56 INFO - Copying logs to upload dir... 05:10:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:10:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.004289 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 05:10:56.374724) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 05:10:56.378101) ========= 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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35397ffc70557113e1f22fd535945a1c99a261444ef5e531241d341a16da3b6f4a086d89d41ec777e541980de1167e3291c4887bdc8e9434de85f9085ee253f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50a42880b2cc0022cd664bfe05de91b713debc61803d6c983dd53090660ecd9214d26f17763848964cdd86aa90def56021a7e7f516e1e4374fac8f8ca8a79766"} build_url:https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009407 build_url: 'https://queue.taskcluster.net/v1/task/Bvl4jo8ST6ix9hKFVfXtMA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/35397ffc70557113e1f22fd535945a1c99a261444ef5e531241d341a16da3b6f4a086d89d41ec777e541980de1167e3291c4887bdc8e9434de85f9085ee253f0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50a42880b2cc0022cd664bfe05de91b713debc61803d6c983dd53090660ecd9214d26f17763848964cdd86aa90def56021a7e7f516e1e4374fac8f8ca8a79766"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 05:10:56.402050) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:10:56.402365) ========= 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.Xj1HN6ZHrY/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.Ve3KZUkOzB/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.004776 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 05:10:56.450886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 05:10:56.451211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 05:10:56.451600) ========= ========= Total master_lag: 2.17 =========