builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1463824153.84 results: success (0) buildid: 20160521004015 builduid: 16b1b2ee1cdb45d6bdd1dd0861d9f51f revision: 8b81a6c988c496aaff85df718646e17239c1e278 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:13.841541) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:13.841982) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:13.842272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:13.886686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:13.886964) ========= 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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-05-21 02:49:13-- 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% 15.0M=0.001s 2016-05-21 02:49:14 (15.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.635487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:14.541223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:14.541541) ========= 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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.026785 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:14.592674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 02:49:14.593488) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8b81a6c988c496aaff85df718646e17239c1e278 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8b81a6c988c496aaff85df718646e17239c1e278 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-05-21 02:49:14,672 truncating revision to first 12 chars 2016-05-21 02:49:14,672 Setting DEBUG logging. 2016-05-21 02:49:14,672 attempt 1/10 2016-05-21 02:49:14,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8b81a6c988c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-21 02:49:15,596 unpacking tar archive at: mozilla-aurora-8b81a6c988c4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.174903 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 02:49:15.788313) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:15.788620) ========= script_repo_revision: 8b81a6c988c496aaff85df718646e17239c1e278 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:15.789099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:15.789366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 02:49:15.803867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-21 02:49:15.804228) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:49:15 INFO - MultiFileLogger online at 20160521 02:49:15 in /builds/slave/test 02:49:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:49:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:49:15 INFO - {'append_to_log': False, 02:49:15 INFO - 'base_work_dir': '/builds/slave/test', 02:49:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:49:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:49:15 INFO - 'branch': 'Mozilla-Aurora', 02:49:15 INFO - 'buildbot_json_path': 'buildprops.json', 02:49:15 INFO - 'config_files': ('talos/mac_config.py',), 02:49:15 INFO - 'default_actions': ('clobber', 02:49:15 INFO - 'read-buildbot-config', 02:49:15 INFO - 'download-and-extract', 02:49:15 INFO - 'populate-webroot', 02:49:15 INFO - 'create-virtualenv', 02:49:15 INFO - 'install', 02:49:15 INFO - 'run-tests'), 02:49:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:49:15 INFO - 'download_minidump_stackwalk': True, 02:49:15 INFO - 'download_symbols': 'ondemand', 02:49:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:49:15 INFO - 'tooltool.py': '/tools/tooltool.py', 02:49:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:49:15 INFO - '/tools/misc-python/virtualenv.py')}, 02:49:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:49:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:49:15 INFO - 'installer_path': 'installer.exe', 02:49:15 INFO - 'log_level': 'info', 02:49:15 INFO - 'log_name': 'talos', 02:49:15 INFO - 'log_to_console': True, 02:49:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:49:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:49:15 INFO - 'opt_config_files': (), 02:49:15 INFO - 'pip_index': False, 02:49:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:15 INFO - 'cmd': ('bash', 02:49:15 INFO - '-c', 02:49:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:15 INFO - 'enabled': True, 02:49:15 INFO - 'halt_on_failure': False, 02:49:15 INFO - 'name': 'check_screen_resolution'},), 02:49:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:15 INFO - 'cmd': ('bash', 02:49:15 INFO - '-c', 02:49:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:15 INFO - 'enabled': True, 02:49:15 INFO - 'halt_on_failure': False, 02:49:15 INFO - 'name': 'check_screen_resolution'},), 02:49:15 INFO - 'run_cmd_checks_enabled': True, 02:49:15 INFO - 'sps_profile': False, 02:49:15 INFO - 'sps_profile_interval': 0, 02:49:15 INFO - 'suite': 'dromaeojs', 02:49:15 INFO - 'system_bits': '32', 02:49:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:49:15 INFO - 'title': 't-yosemite-r7-0039', 02:49:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:49:15 INFO - 'use_talos_json': True, 02:49:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:49:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:49:15 INFO - 'work_dir': 'build'} 02:49:15 INFO - ##### 02:49:15 INFO - ##### Running clobber step. 02:49:15 INFO - ##### 02:49:15 INFO - Running pre-action listener: _resource_record_pre_action 02:49:15 INFO - Running main action method: clobber 02:49:15 INFO - rmtree: /builds/slave/test/build 02:49:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:49:17 INFO - Running post-action listener: _resource_record_post_action 02:49:17 INFO - ##### 02:49:17 INFO - ##### Running read-buildbot-config step. 02:49:17 INFO - ##### 02:49:17 INFO - Running pre-action listener: _resource_record_pre_action 02:49:17 INFO - Running main action method: read_buildbot_config 02:49:17 INFO - Using buildbot properties: 02:49:17 INFO - { 02:49:17 INFO - "project": "", 02:49:17 INFO - "product": "firefox", 02:49:17 INFO - "script_repo_revision": "production", 02:49:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:49:17 INFO - "repository": "", 02:49:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:49:17 INFO - "buildid": "20160521004015", 02:49:17 INFO - "stage_platform": "macosx64", 02:49:17 INFO - "basedir": "/builds/slave/test", 02:49:17 INFO - "nightly_build": "True", 02:49:17 INFO - "buildnumber": 9, 02:49:17 INFO - "slavename": "t-yosemite-r7-0039", 02:49:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:49:17 INFO - "platform": "yosemite_r7", 02:49:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:49:17 INFO - "branch": "mozilla-aurora", 02:49:17 INFO - "repo_path": "releases/mozilla-aurora", 02:49:17 INFO - "revision": "8b81a6c988c496aaff85df718646e17239c1e278", 02:49:17 INFO - "pgo_build": "False", 02:49:17 INFO - "builduid": "16b1b2ee1cdb45d6bdd1dd0861d9f51f", 02:49:17 INFO - "slavebuilddir": "test" 02:49:17 INFO - } 02:49:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:49:17 INFO - Running post-action listener: _resource_record_post_action 02:49:17 INFO - ##### 02:49:17 INFO - ##### Running download-and-extract step. 02:49:17 INFO - ##### 02:49:17 INFO - Running pre-action listener: _resource_record_pre_action 02:49:17 INFO - Running main action method: download_and_extract 02:49:17 INFO - mkdir: /builds/slave/test/build/tests 02:49:17 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')]} 02:49:17 INFO - https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:49:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:49:23 INFO - Downloaded 1199 bytes. 02:49:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:23 INFO - Using the following test package requirements: 02:49:23 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:49:23 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:49:23 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:49:23 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'jsshell-mac.zip'], 02:49:23 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:49:23 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:49:23 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:49:23 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:49:23 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:49:23 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:49:23 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:23 INFO - https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:49:33 INFO - Downloaded 18102567 bytes. 02:49:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:49:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:49:36 INFO - Return code: 0 02:49:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:49: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')]} 02:49:36 INFO - https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:49:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:49:43 INFO - Downloaded 18102567 bytes. 02:49:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:49:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:49:46 INFO - Return code: 0 02:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:46 INFO - https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:49:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:49:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:49:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:49:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:49:47 INFO - Downloaded 10840145 bytes. 02:49:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:49:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:49:47 INFO - Return code: 0 02:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:47 INFO - https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:49:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:49:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:50:01 INFO - Downloaded 114249612 bytes. 02:50:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:50:01 INFO - mkdir: /builds/slave/test/properties 02:50:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:50:01 INFO - Writing to file /builds/slave/test/properties/build_url 02:50:01 INFO - Contents: 02:50:01 INFO - build_url:https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:50:03 INFO - Running post-action listener: _resource_record_post_action 02:50:03 INFO - Running post-action listener: set_extra_try_arguments 02:50:03 INFO - ##### 02:50:03 INFO - ##### Running populate-webroot step. 02:50:03 INFO - ##### 02:50:03 INFO - Running pre-action listener: _resource_record_pre_action 02:50:03 INFO - Running main action method: populate_webroot 02:50:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:50:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:50:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:50:03 INFO - '--tppagecycles', 02:50:03 INFO - '7'], 02:50:03 INFO - 'tests': ['tsvgm']}}, 02:50:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:50:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:50:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:50:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:50:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:50:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:50:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['damp', 'tps']}, 02:50:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'tests': ['damp', 'tps']}, 02:50:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['dromaeo_dom']}, 02:50:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:50:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'other-e10s': {'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['a11yr', 02:50:03 INFO - 'ts_paint', 02:50:03 INFO - 'tpaint', 02:50:03 INFO - 'sessionrestore', 02:50:03 INFO - 'sessionrestore_no_auto_restore', 02:50:03 INFO - 'tabpaint']}, 02:50:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['tsvgx', 02:50:03 INFO - 'tsvgr_opacity', 02:50:03 INFO - 'tart', 02:50:03 INFO - 'tscrollx', 02:50:03 INFO - 'cart']}, 02:50:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:50:03 INFO - 'tsvgr_opacity', 02:50:03 INFO - 'tart', 02:50:03 INFO - 'tscrollx', 02:50:03 INFO - 'cart']}, 02:50:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'talos_options': ['--disable-e10s'], 02:50:03 INFO - 'tests': ['tp5o']}, 02:50:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'tests': ['tp5o']}, 02:50:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'talos_options': ['--disable-e10s', 02:50:03 INFO - '--xperf_path', 02:50:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:50:03 INFO - 'tests': ['tp5n']}, 02:50:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:50:03 INFO - 'talos_options': ['--xperf_path', 02:50:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:50:03 INFO - 'tests': ['tp5n']}}, 02:50:03 INFO - 'talos.zip': {'path': '', 02:50:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:50:03 INFO - Running post-action listener: _resource_record_post_action 02:50:03 INFO - ##### 02:50:03 INFO - ##### Running create-virtualenv step. 02:50:03 INFO - ##### 02:50:03 INFO - Running pre-action listener: _resource_record_pre_action 02:50:03 INFO - Running main action method: create_virtualenv 02:50:03 INFO - Creating virtualenv /builds/slave/test/build/venv 02:50:03 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 02:50:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:50:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:50:03 INFO - Using real prefix '/tools/python27' 02:50:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:50:03 INFO - Installing distribute.............................................................................................................................................................................................done. 02:50:06 INFO - Installing pip.................done. 02:50:06 INFO - Return code: 0 02:50:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:50:06 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')]} 02:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:06 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')]} 02:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:06 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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:06 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 02:50:06 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 02:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:06 INFO - 'HOME': '/Users/cltbld', 02:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:06 INFO - 'LOGNAME': 'cltbld', 02:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:06 INFO - 'MOZ_NO_REMOTE': '1', 02:50:06 INFO - 'NO_EM_RESTART': '1', 02:50:06 INFO - 'PAGER': '/bin/cat', 02:50:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:06 INFO - 'PWD': '/builds/slave/test', 02:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:06 INFO - 'SHELL': '/bin/bash', 02:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:06 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:06 INFO - 'USER': 'cltbld', 02:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:06 INFO - 'XPC_FLAGS': '0x0', 02:50:06 INFO - 'XPC_SERVICE_NAME': '0', 02:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:07 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 02:50:07 INFO - Cleaning up... 02:50:07 INFO - Return code: 0 02:50:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:07 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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:07 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 02:50:07 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 02:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:07 INFO - 'HOME': '/Users/cltbld', 02:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:07 INFO - 'LOGNAME': 'cltbld', 02:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:07 INFO - 'MOZ_NO_REMOTE': '1', 02:50:07 INFO - 'NO_EM_RESTART': '1', 02:50:07 INFO - 'PAGER': '/bin/cat', 02:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:07 INFO - 'PWD': '/builds/slave/test', 02:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:07 INFO - 'SHELL': '/bin/bash', 02:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:07 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:07 INFO - 'USER': 'cltbld', 02:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:07 INFO - 'XPC_FLAGS': '0x0', 02:50:07 INFO - 'XPC_SERVICE_NAME': '0', 02:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:07 INFO - Downloading/unpacking psutil>=0.7.1 02:50:07 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 02:50:07 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 02:50:07 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 02:50:07 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 02:50:07 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 02:50:07 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 02:50:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:50:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:50:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:50:10 INFO - Installing collected packages: psutil 02:50:10 INFO - Running setup.py install for psutil 02:50:10 INFO - building 'psutil._psutil_osx' extension 02:50:10 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 02:50:11 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 02:50:11 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 02:50:11 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 02:50:11 INFO - building 'psutil._psutil_posix' extension 02:50:11 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 02:50:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:50:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:50:11 INFO - ^ 02:50:11 INFO - 1 warning generated. 02:50:11 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 02:50:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:50:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:50:11 INFO - Successfully installed psutil 02:50:11 INFO - Cleaning up... 02:50:11 INFO - Return code: 0 02:50:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:50:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:11 INFO - 'HOME': '/Users/cltbld', 02:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:11 INFO - 'LOGNAME': 'cltbld', 02:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:11 INFO - 'MOZ_NO_REMOTE': '1', 02:50:11 INFO - 'NO_EM_RESTART': '1', 02:50:11 INFO - 'PAGER': '/bin/cat', 02:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:11 INFO - 'PWD': '/builds/slave/test', 02:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:11 INFO - 'SHELL': '/bin/bash', 02:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:11 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:11 INFO - 'USER': 'cltbld', 02:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:11 INFO - 'XPC_FLAGS': '0x0', 02:50:11 INFO - 'XPC_SERVICE_NAME': '0', 02:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:50:11 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 02:50:11 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 02:50:11 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 02:50:11 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 02:50:11 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 02:50:11 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 02:50:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:50:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:50:14 INFO - Installing collected packages: mozsystemmonitor 02:50:14 INFO - Running setup.py install for mozsystemmonitor 02:50:14 INFO - Successfully installed mozsystemmonitor 02:50:14 INFO - Cleaning up... 02:50:14 INFO - Return code: 0 02:50:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02: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')]} 02:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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', '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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02: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 blobuploader==1.2.4 02:50:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:14 INFO - 'HOME': '/Users/cltbld', 02:50:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:14 INFO - 'LOGNAME': 'cltbld', 02:50:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:14 INFO - 'MOZ_NO_REMOTE': '1', 02:50:14 INFO - 'NO_EM_RESTART': '1', 02:50:14 INFO - 'PAGER': '/bin/cat', 02:50:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:14 INFO - 'PWD': '/builds/slave/test', 02:50:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:14 INFO - 'SHELL': '/bin/bash', 02:50:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:14 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:14 INFO - 'USER': 'cltbld', 02:50:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:14 INFO - 'XPC_FLAGS': '0x0', 02:50:14 INFO - 'XPC_SERVICE_NAME': '0', 02:50:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:14 INFO - Downloading/unpacking blobuploader==1.2.4 02: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 02: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 02: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 02: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 02: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 02: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 02:50:17 INFO - Downloading blobuploader-1.2.4.tar.gz 02:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:50:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:50:17 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 02:50:17 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 02:50:17 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 02:50:17 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 02:50:17 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 02:50:17 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 02:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:50:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:18 INFO - Downloading docopt-0.6.1.tar.gz 02:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:50:18 INFO - Installing collected packages: blobuploader, requests, docopt 02:50:18 INFO - Running setup.py install for blobuploader 02:50:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:50:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:50:18 INFO - Running setup.py install for requests 02:50:19 INFO - Running setup.py install for docopt 02:50:19 INFO - Successfully installed blobuploader requests docopt 02:50:19 INFO - Cleaning up... 02:50:19 INFO - Return code: 0 02:50:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:19 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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:19 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 02:50:19 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 02:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:19 INFO - 'HOME': '/Users/cltbld', 02:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:19 INFO - 'LOGNAME': 'cltbld', 02:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:19 INFO - 'MOZ_NO_REMOTE': '1', 02:50:19 INFO - 'NO_EM_RESTART': '1', 02:50:19 INFO - 'PAGER': '/bin/cat', 02:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:19 INFO - 'PWD': '/builds/slave/test', 02:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:19 INFO - 'SHELL': '/bin/bash', 02:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:19 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:19 INFO - 'USER': 'cltbld', 02:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:19 INFO - 'XPC_FLAGS': '0x0', 02:50:19 INFO - 'XPC_SERVICE_NAME': '0', 02:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-JqXOGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-KvhFjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-BwkTxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-LJWqo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-9RbG8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-iQVvQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-EMN3_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-boLz2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-xRDldc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:20 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-VEsEQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-AWziQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-epCv7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-0y3H7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-AZ_Lfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jDB_Km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-87IZmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:21 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pfF40c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:50:21 INFO - Running setup.py install for manifestparser 02:50:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:50:21 INFO - Running setup.py install for mozcrash 02:50:22 INFO - Running setup.py install for mozdebug 02:50:22 INFO - Running setup.py install for mozdevice 02:50:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Running setup.py install for mozfile 02:50:22 INFO - Running setup.py install for mozhttpd 02:50:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Running setup.py install for mozinfo 02:50:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Running setup.py install for mozInstall 02:50:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:50:22 INFO - Running setup.py install for mozleak 02:50:23 INFO - Running setup.py install for mozlog 02:50:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Running setup.py install for moznetwork 02:50:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Running setup.py install for mozprocess 02:50:23 INFO - Running setup.py install for mozprofile 02:50:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Running setup.py install for mozrunner 02:50:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:50:23 INFO - Running setup.py install for mozscreenshot 02:50:24 INFO - Running setup.py install for moztest 02:50:24 INFO - Running setup.py install for mozversion 02:50:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:50:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:50:24 INFO - Cleaning up... 02:50:24 INFO - Return code: 0 02:50:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:24 INFO - 'HOME': '/Users/cltbld', 02:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:24 INFO - 'LOGNAME': 'cltbld', 02:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:24 INFO - 'MOZ_NO_REMOTE': '1', 02:50:24 INFO - 'NO_EM_RESTART': '1', 02:50:24 INFO - 'PAGER': '/bin/cat', 02:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:24 INFO - 'PWD': '/builds/slave/test', 02:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:24 INFO - 'SHELL': '/bin/bash', 02:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:24 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:24 INFO - 'USER': 'cltbld', 02:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:24 INFO - 'XPC_FLAGS': '0x0', 02:50:24 INFO - 'XPC_SERVICE_NAME': '0', 02:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-6uJO6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:24 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)) 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jPEfPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:24 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)) 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-5EGrCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:24 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)) 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:24 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-GT07Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-l3H9RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-30fIxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ifi3DY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZJ6cWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-qwCJuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:25 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)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-VEto8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:25 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)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YwUXe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:25 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)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tNCU95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:25 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)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:25 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yFEC8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:25 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)) 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:26 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-4C0JPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:26 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)) 02:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:26 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-fHtyvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:26 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FcmrJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:26 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-jYt0hG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:50:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:28 INFO - Downloading blessings-1.6.tar.gz 02:50:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:50:28 INFO - Installing collected packages: blessings 02:50:28 INFO - Running setup.py install for blessings 02:50:29 INFO - Successfully installed blessings 02:50:29 INFO - Cleaning up... 02:50:29 INFO - Return code: 0 02:50:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:29 INFO - Reading from file tmpfile_stdout 02:50:29 INFO - Current package versions: 02:50:29 INFO - blessings == 1.6 02:50:29 INFO - blobuploader == 1.2.4 02:50:29 INFO - docopt == 0.6.1 02:50:29 INFO - manifestparser == 1.1 02:50:29 INFO - mozInstall == 1.12 02:50:29 INFO - mozcrash == 0.17 02:50:29 INFO - mozdebug == 0.1 02:50:29 INFO - mozdevice == 0.48 02:50:29 INFO - mozfile == 1.2 02:50:29 INFO - mozhttpd == 0.7 02:50:29 INFO - mozinfo == 0.9 02:50:29 INFO - mozleak == 0.1 02:50:29 INFO - mozlog == 3.1 02:50:29 INFO - moznetwork == 0.27 02:50:29 INFO - mozprocess == 0.22 02:50:29 INFO - mozprofile == 0.28 02:50:29 INFO - mozrunner == 6.11 02:50:29 INFO - mozscreenshot == 0.1 02:50:29 INFO - mozsystemmonitor == 0.0 02:50:29 INFO - moztest == 0.7 02:50:29 INFO - mozversion == 1.4 02:50:29 INFO - psutil == 3.1.1 02:50:29 INFO - requests == 1.2.3 02:50:29 INFO - wsgiref == 0.1.2 02:50:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:29 INFO - 'HOME': '/Users/cltbld', 02:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:29 INFO - 'LOGNAME': 'cltbld', 02:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:29 INFO - 'MOZ_NO_REMOTE': '1', 02:50:29 INFO - 'NO_EM_RESTART': '1', 02:50:29 INFO - 'PAGER': '/bin/cat', 02:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:29 INFO - 'PWD': '/builds/slave/test', 02:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:29 INFO - 'SHELL': '/bin/bash', 02:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:29 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:29 INFO - 'USER': 'cltbld', 02:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:29 INFO - 'XPC_FLAGS': '0x0', 02:50:29 INFO - 'XPC_SERVICE_NAME': '0', 02:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 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)) 02:50:29 INFO - Cleaning up... 02:50:29 INFO - Return code: 0 02:50:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:29 INFO - 'HOME': '/Users/cltbld', 02:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:29 INFO - 'LOGNAME': 'cltbld', 02:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:29 INFO - 'MOZ_NO_REMOTE': '1', 02:50:29 INFO - 'NO_EM_RESTART': '1', 02:50:29 INFO - 'PAGER': '/bin/cat', 02:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:29 INFO - 'PWD': '/builds/slave/test', 02:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:29 INFO - 'SHELL': '/bin/bash', 02:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:29 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:29 INFO - 'USER': 'cltbld', 02:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:29 INFO - 'XPC_FLAGS': '0x0', 02:50:29 INFO - 'XPC_SERVICE_NAME': '0', 02:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:29 INFO - Downloading/unpacking jsonschema 02:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:50:32 INFO - Downloading/unpacking functools32 (from jsonschema) 02:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:50:32 INFO - warning: no files found matching '*.txt' 02:50:32 INFO - no previously-included directories found matching 'build' 02:50:32 INFO - no previously-included directories found matching 'dist' 02:50:32 INFO - no previously-included directories found matching '.git*' 02:50:32 INFO - Installing collected packages: jsonschema, functools32 02:50:33 INFO - Running setup.py install for functools32 02:50:33 INFO - warning: no files found matching '*.txt' 02:50:33 INFO - no previously-included directories found matching 'build' 02:50:33 INFO - no previously-included directories found matching 'dist' 02:50:33 INFO - no previously-included directories found matching '.git*' 02:50:33 INFO - Successfully installed jsonschema functools32 02:50:33 INFO - Cleaning up... 02:50:33 INFO - Return code: 0 02:50:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:50:33 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')]} 02:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:33 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')]} 02:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:33 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 0x10c79fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbd056e5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, '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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 02:50:33 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 02:50:33 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 02:50:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:50:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:33 INFO - 'HOME': '/Users/cltbld', 02:50:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:33 INFO - 'LOGNAME': 'cltbld', 02:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:33 INFO - 'MOZ_NO_REMOTE': '1', 02:50:33 INFO - 'NO_EM_RESTART': '1', 02:50:33 INFO - 'PAGER': '/bin/cat', 02:50:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:33 INFO - 'PWD': '/builds/slave/test', 02:50:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:33 INFO - 'SHELL': '/bin/bash', 02:50:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:50:33 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:50:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:33 INFO - 'USER': 'cltbld', 02:50:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:33 INFO - 'XPC_FLAGS': '0x0', 02:50:33 INFO - 'XPC_SERVICE_NAME': '0', 02:50:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:33 INFO - Downloading/unpacking flake8 02: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 02: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 02: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 02: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 02: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 02: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 02:50:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:50:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:50:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:36 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 02:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:50:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:50:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:50:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:50:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:50:37 INFO - no previously-included directories found matching 'docs/_build' 02:50:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:50:37 INFO - Running setup.py install for pep8 02:50:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:50:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:50:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:50:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:50:37 INFO - no previously-included directories found matching 'docs/_build' 02:50:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:50:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:50:37 INFO - Cleaning up... 02:50:37 INFO - Return code: 0 02:50:37 INFO - Running post-action listener: _resource_record_post_action 02:50:37 INFO - Running post-action listener: _start_resource_monitoring 02:50:37 INFO - Starting resource monitoring. 02:50:37 INFO - ##### 02:50:37 INFO - ##### Running install step. 02:50:37 INFO - ##### 02:50:37 INFO - Running pre-action listener: _resource_record_pre_action 02:50:37 INFO - Running main action method: install 02:50:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:38 INFO - Reading from file tmpfile_stdout 02:50:38 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:50:38 INFO - Reading from file tmpfile_stdout 02:50:38 INFO - Output received: 02:50:38 INFO - Usage: mozinstall [options] installer 02:50:38 INFO - Options: 02:50:38 INFO - -h, --help show this help message and exit 02:50:38 INFO - -d DEST, --destination=DEST 02:50:38 INFO - Directory to install application into. [default: 02:50:38 INFO - "/builds/slave/test"] 02:50:38 INFO - --app=APP Application being installed. [default: firefox] 02:50:38 INFO - mkdir: /builds/slave/test/build/application 02:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:51:10 INFO - Reading from file tmpfile_stdout 02:51:10 INFO - Output received: 02:51:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:51:10 INFO - Running post-action listener: _resource_record_post_action 02:51:10 INFO - ##### 02:51:10 INFO - ##### Running run-tests step. 02:51:10 INFO - ##### 02:51:10 INFO - Running pre-action listener: _resource_record_pre_action 02:51:10 INFO - Running main action method: run_tests 02:51:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:51:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:51:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:51:10 INFO - 2016-05-21 02:51:10.651 screenresolution[1653:8253] starting screenresolution argv=screenresolution get 02:51:10 INFO - 2016-05-21 02:51:10.654 screenresolution[1653:8253] Display 0: 1600x1200x32@60 02:51:10 INFO - 2016-05-21 02:51:10.659 screenresolution[1654:8258] starting screenresolution argv=screenresolution list 02:51:10 INFO - Available Modes on Display 0 02:51:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:51:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:51:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:51:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:51:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:51:10 INFO - Intel Iris: 02:51:10 INFO - Chipset Model: Intel Iris 02:51:10 INFO - Type: GPU 02:51:10 INFO - Bus: Built-In 02:51:10 INFO - VRAM (Dynamic, Max): 1536 MB 02:51:10 INFO - Vendor: Intel (0x8086) 02:51:10 INFO - Device ID: 0x0a2e 02:51:10 INFO - Revision ID: 0x0009 02:51:10 INFO - Displays: 02:51:10 INFO - Display: 02:51:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:51:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:51:10 INFO - Display Serial Number: 1600x1200 60 02:51:10 INFO - Main Display: Yes 02:51:10 INFO - Mirror: Off 02:51:10 INFO - Online: Yes 02:51:10 INFO - Rotation: Supported 02:51:10 INFO - Return code: 0 02:51:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:51:10 INFO - Python 2.7.3 02:51:10 INFO - Return code: 0 02:51:10 INFO - grabbing minidump binary from tooltool 02:51:10 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')]} 02:51:10 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 0x10c63ee00>, '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 0x10c4a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c639b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:51:10 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 02:51:10 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 02:51:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:51:11 INFO - Return code: 0 02:51:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:51:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:51:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:51:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:51:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:51:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:51:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:51:12 INFO - Return code: 0 02:51:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:51:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PUmpxl7sIx/Render', 02:51:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:12 INFO - 'HOME': '/Users/cltbld', 02:51:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:12 INFO - 'LOGNAME': 'cltbld', 02:51:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:51:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:12 INFO - 'MOZ_NO_REMOTE': '1', 02:51:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:51:12 INFO - 'NO_EM_RESTART': '1', 02:51:12 INFO - 'PAGER': '/bin/cat', 02:51:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:12 INFO - 'PWD': '/builds/slave/test', 02:51:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:51:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:12 INFO - 'SHELL': '/bin/bash', 02:51:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3wKQzvrxYr/Listeners', 02:51:12 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:51:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:12 INFO - 'USER': 'cltbld', 02:51:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:12 INFO - 'XPC_FLAGS': '0x0', 02:51:12 INFO - 'XPC_SERVICE_NAME': '0', 02:51:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:51:12 INFO - mozversion application_buildid: 20160521004015 02:51:12 INFO - mozversion application_changeset: 8b81a6c988c496aaff85df718646e17239c1e278 02:51:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:51:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:51:12 INFO - mozversion application_name: Firefox 02:51:12 INFO - mozversion application_remotingname: firefox-dev 02:51:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:51:12 INFO - mozversion application_vendor: Mozilla 02:51:12 INFO - mozversion application_version: 48.0a2 02:51:12 INFO - mozversion platform_buildid: 20160521004015 02:51:12 INFO - mozversion platform_changeset: 8b81a6c988c496aaff85df718646e17239c1e278 02:51:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:51:12 INFO - mozversion platform_version: 48.0a2 02:51:12 INFO - using testdate: 1463824272 02:51:12 INFO - actual date: 1463824272 02:51:12 INFO - starting webserver on 'localhost:49280' 02:51:12 INFO - SUITE-START | Running 2 tests 02:51:12 INFO - TEST-START | dromaeo_css 02:51:12 INFO - operating with platform_type : mac_ 02:51:12 INFO - Initialising browser for dromaeo_css test... 02:51:12 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpylQnCH/profile http://localhost:49280/getInfo.html) 02:51:14 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 02:51:14 INFO - PROCESS | 1672 | colorDepth:24 02:51:14 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 02:51:14 INFO - PROCESS | 1672 | __metrics 02:51:14 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:51:14 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:51:15 INFO - TEST-INFO | 1672: exit 0 02:51:15 INFO - Browser initialized. 02:51:15 INFO - Running cycle 1/1 for dromaeo_css test... 02:51:15 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpylQnCH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:27 INFO - PROCESS | 1676 | RSS: Main: 211341312 02:51:27 INFO - PROCESS | 1676 | 02:52:19 INFO - PROCESS | 1676 | 1463824339023 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] 02:54:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 02:54:31 INFO - PROCESS | 1676 | RSS: Main: 395636736 02:54:31 INFO - PROCESS | 1676 | 02:57:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 02:57:35 INFO - PROCESS | 1676 | RSS: Main: 355696640 02:57:35 INFO - PROCESS | 1676 | 03:00:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 03:00:37 INFO - PROCESS | 1676 | RSS: Main: 359256064 03:00:37 INFO - PROCESS | 1676 | 03:03:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 03:03:40 INFO - PROCESS | 1676 | RSS: Main: 356323328 03:03:40 INFO - PROCESS | 1676 | 03:06:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 03:06:44 INFO - PROCESS | 1676 | RSS: Main: 326852608 03:06:44 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 03:09:47 INFO - PROCESS | 1676 | RSS: Main: 365514752 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | __start_tp_report 03:09:47 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:09:47 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:09:47 INFO - PROCESS | 1676 | |i|pagename|runs| 03:09:47 INFO - PROCESS | 1676 | |0;dojo.html;3383.627800941896;3445.965540344596;3452.2750222453283;3481.6344283850212;3510.789352638842;5702.857428564277;5703.857403564913;5722.570807189461;5722.942770572298;5726.198332233485;5664.376918538957;5713.657180569169;5723.656580605167;5738.282714660668;5752.482276595106;5019.397672279331;5084.694918304895;5134.358205224356;5255.237990491377;5271.367435907697;5226.05930932432;5226.320578324812;5229.607779416559;5235.738213089349;5238.266642670029;5138.023775482665;5168.767405466744;5196.92204616931;5200.531952124308;5221.321228240324;5221.712805795699;5279.392869819982;5280.445553216895;5290.338707661543;5298.549623282026;5058.4435712071645;5088.771005304733;5115.335006449175;5204.661696989683;5209.817656382046;5544.91682624761;5573.470520300565;5582.078956972095;5593.440655934414;5630;6210.813675589739;6210.844728881814;6216.129741836146;6223.408776166257;6227.159333489966;6119.418655227746;6176.752929882799;6258.71835767387;6273.592216505913;6309.621422714652;5795.072788353843;5822.7088645567555;5830.533557315404;5835.708214589296;5837.6497410155525;6371.458426033746;6385.648789316632;6389.3291204423795;6394.104825324458;6405.935940640627;6013.098035294741;6119.2350956130485;6122.204113465221;6163.7842675506145;6217.502599792006;6319.178506794087;6332.905006424908;6341;6343.1436756038265;6363.077353783768;6201.069839524017;6240.032794916795;6252.781152659635;6257.186565746424;6270.090836828635;6101.298350689639;6161.815145545641;6162.106494558264;6167.444929956326;6271.27880293763;4841.685290456109;4875.731834749123;4890.217565189552;4895.143349913751;4903.092927808367;4134.64855487281;4247.341662042327;4427.535108813592;4469.374287599739;4554.772261386917;5714.771409143671;5746.339170995389;5747.137929310553;5759.913601295984;5761.8559536011935;6132.906245166512;6296.464800492009;6330.05049242608;6357.841053973595;6371.96814015927;5687.459691329317;5701.173329867146;5737.426257374229;5766.913496297559;5770.740316685759;5356.276902618097;5384.91922621161;5387.4073851876265;5390.0836857734275;5391.137418013099;6231.626102433868;6304.936950630435;6334.778282760081;6348.809535713936;6368.044793281138;5094.5160209780015;6179;6220.626762394271;6231.75072997075;6235.095911092775;6329.90505142414;6330.841728956813;6332.113504109337;6333.556651034383;6358.332375100548;6062.575619706578;6105.786297479567;6107.9083813741945;6110.847228819315;6139.032198454696;6215.223097112861;6319.368063193644;6324.462420694293;6329.651869147241;6354.93645063562;6107.206063211754;6137.662428566293;6140.8771824564155;6147.108669242845;6158.32258451561;4249.511306199826;5877.471027607536;5943.52451803846;6052.848678783065;6117.908231376622;5888.381719919518;5900.350961394157;5903.793367232126;5906.26171728534;5906.350301466921;6003.7898673546215;6101.7559297627595;6109.755609775737;6111.419415155553;6124.938750612615;4953.23224900141;5287.762050707651;5791.7683292667825;5892.94107058924;5900.967330717193 03:09:47 INFO - PROCESS | 1676 | |1;ext.html;522.8431470558833;540.0899484368837;557.3005877623582;566.5297802823657;663.8780461020874;30366.696333036663;38157.80921095394;38878;39005.21989560207;39098.41352379709;41371.58628413715;41883;41934;42001.78999105008;42057;8392.916070839305;9332.160105590512;9551.283653725963;9570.330076894621;9573.760655983586;8457.53483558404;11337.659870203906;11500.884991150107;11505.3096814191;11521.539138434453;11298.983091521803;11378.032867206297;11379.601713940014;11390.25963312382;11449.427528623577;8475.49147051179;11144.21990460664;11222.775544489146;11351.602693905756;11562.76874462506;9359.251259899193;11293.322400655987;11303.830442543287;11304.886951130466;11318.26431281964;38376.04059898497;38897;38985.83042508729;39054.80472597619;39105.804470977746;3167.952480712791;3333.3000069985333;3483.860645574174;3534.222471056389;3829.329867273244;27194.5920811188;27955.58066629002;28424.857875710695;28436;28721.13836584906;2704.418550011757;2717.91846244611;2720.5919112133142;2728.0876494023905;2751.2434080627786;441669;442796;445584;446390.7680461609;446463;63241.68379158075;63757.724845503755;63802.0429693555;63821;63870;69234.30765692379;70550;70567.29432705608;70568;70599;17607.471775846745;18001.18994645244;18029.459116226535;18045.00752458601;18082.728759068625;12603.243805371523;12749.42627581761;13343.933280333731;13599.59201223965;13709.931450342685;19417.205785242997;19992.90003550002;20027.198912043354;20074.498137546678;20089.397318080482;8153.79615509617;8344;8368.079511253758;8390.412671113085;8465.619047142893;985.9211263098935;1073.4632683658172;1125.2348403085816;1145.8052131137727;1176.6352430746497;29059.418811624073;29625.703742962294;29687.406251875273;30499.237519061757;30500;1127.515975635291;1131.1346819682785;1140.372794962767;1162.3781277016756;1286.3118231746157;15309.9234503829;15381.30784114695;15424.614384640474;15540.766888496682;15610.375584976473;590315;597097;600756;601831;602032;60636.78726425535;61214;61257.69371153116;61271.08093378603;61287.38712612817;1439.417036100381;1522.7182971150373;1525.3212320517707;1591.8089829220798;1615.0148409470214;28644.856775715987;28851.422971540873;29256;29311;29402.35378815917;54753.17870232029;56455.71772141113;57983.42016579949;60548.06437581543;60572.697136514034;453372;464930;465217;465405;465489;34886.82556587201;35295.823520883256;35430;36113;38334.42498362471;41663.58336416597;43337;43667.78166109149;43710;43821;34466.48300275548;35687.64312356843;36587.902362929155;37201.81399092987;37375.62624373721;35226;35402.29195416129;35428.64571354254;36179.45730813987;36200;36708.632913670524;37598.43602345912;37670.8116459416;38433.2313353737;39263.60736392599;29915.55126673058;31370.52944205793;31579.210519736273;32494;32632.673673262965;24334.513309733353;24635.891384887364;24690.18778312144;25063.99744010219;25104.246872593852 03:09:47 INFO - PROCESS | 1676 | |2;jquery.html;391.96276353746384;393.7617741266534;397.1104725415069;398.4262661767057;408.08181591419304;14300.642483937907;16991.745123823122;20128.59742805143;20839.895800520993;20845.47886302843;16991;20014.399568012985;20200.393988180378;21598.784012159915;21606.243781467652;5632.662040277576;5825.242718446596;5938.52491800657;5940.287165540141;6048;4170.228507726065;4405.05291362357;5956.076808094731;5957.731902064395;6005.849853753647;4381.452318460192;4382.583654552828;4385.451818522684;6100.664463454508;6527.869442611169;5583.804566840141;5909.025010873201;6499.862524058294;6501.707423165969;6515.837104072412;5721.599488035839;5739.081746920514;5743.224664670258;5746.396628354005;5749.568782341299;13466.192028478323;16522.669546609002;24611.38471538208;25615.359616009562;25692.357691057685;2196.7144271244797;2320.2459200759654;2356.964645530318;2751.257160566656;2815.1413818785263;9745.269104817096;9846.507674616241;10082.9495852521;10148.391096534158;10423.583056677724;1700.228627337432;1740.2864825421516;1751.3870145449118;1788.8211178882138;1814.3105619864366;102887;109337.90662093436;111017;111756;111821.44089279664;11733.237339572901;11788.23376480543;11789.050668026308;11794.233374830608;11811.704707382385;13666.155069065748;14235.715285694228;15330.386784528717;15371.385144594315;15383.38466461329;8530.87203691945;8804.559772011375;8834.602442890086;8906.153915378029;8961;7246.89129663045;8955.41789783662;8962.238209752113;8978.86531702025;9068.909310906807;4714.339992401066;4721.834735784231;4725.9527404726205;4726.456457507431;4762.738049407316;1775.6359946210996;1825.4614888607894;1908.1508728615634;1963.5974625202084;1966.3805901141095;882.6028287270753;999.0509016434416;1018.7300365403174;1023.9795204095876;1106.3417266726285;20407.693884591743;21316.467088322606;21381.893090534446;21414.143434262456;21464.570708586052;865.8787769712245;977.041032729373;993.5429702337005;1025.415513157493;1029.330934892311;11179.273347232403;11486.712832179262;11487.310761354345;11487.770244595229;11490.253133546294;539981;548035;562795;563813;565111;119090;120642.46700952928;124017;124749;125376;1000.1049061090283;1002.4336250018862;1016.9847452288221;1018.3635227982511;1023.0076825479273;14519.92740036293;18204.544886377946;18260.904345739298;18417.17122729507;18584.163712633235;38259;51266;51913.22130168126;52194.739026304625;52667;75973.62013189899;76083.85874211781;76983.2301676976;77407.06482337987;79266.81099783392;6852.794416167523;8225.35492901434;8341.374396920377;8469.61886715087;8780.209781119729;8240.876386854321;10373.481325933522;12255.448504817483;12445.937770311091;12708.555200567936;8053.476524025919;10611.575536978435;10762.246642734934;11031.944840275748;11114.833277501006;8441.577921103797;9181.265498759949;10777.784444311226;10983.231173817756;11080.5567777288;5927.496162826208;10984;11140.997310242117;11444.969952704296;11466;8007.119216886254;8224.465409748347;8369.49783013021;8453.619587118717;8678.783030424038;9909.55407006672;11201.66395008151;11206;11212.103031757602;11240.718982025515 03:09:47 INFO - PROCESS | 1676 | |3;mootools.html;1424;1589.0942162967108;1603.1262961685884;1864.8321651051403;1993.3820515640143;7819.452638315321;8003.279704826565;8006.919930800683;8013.799655008627;8463;8437.873431898526;8484.406091573592;8577.699780507683;8596.054434012269;8599.785005374868;2393.8563686178886;2436.293474892279;2438.0247900839627;2441.6215486599604;2444.69441319835;2402.1232250228627;2446.217210492643;2463.1748364297982;2463.59350707134;2467.222824810188;2395.64065390192;2421.9757802422014;2433.0024689877155;2435.5372479228977;2449.7917676997477;2398.6521954316527;2419.0444774314137;2437.683101196842;2439.2926051445147;2448.5714999875127;2430.161594249981;2474.542209691213;2484.2050543825985;2487.141936032066;2487.5895477246413;8624.913750862474;8647.264982476498;8648.697295594688;8672.176143266379;8699.826003479959;5149.639525233235;5244.60665450089;5335.813246536352;5338.733063346817;5345.144776835727;3429.2112814052907;3439.501272315525;3462.1344663834043;3498.1954150545516;3512.8243587820507;994.751312171957;999.5651891427252;1012.5139932832208;1016.8678071850612;1019.2559431614889;11019.559217631286;11149.163812713998;11222.102231821435;11232.775344493064;11239.325640461599;4533.84131555394;4635.142498637762;4718.127146477913;4726.834560790425;4748.406449193851;9914.900850991542;9974.501274936225;9981.650642227487;9982.351147175556;9986.950065249628;5293.179557168645;5319.069162896478;5325.600579956441;5329.680219186861;5334.039872822929;4320.157569274024;4482.843100491533;4718.504557021532;4777.235642297216;4830.686005409637;3989.4414781930554;3995.9400608990886;4058.9188216236103;4084.1627466369323;4126.051008268055;1315.7368526294779;1321.973560528784;1324.38416136497;1328.7408955253634;1333.4799428223;666.6799936030733;820.8235229425583;863.8920134983127;903.9412438191497;945.9169251207329;5578.609497335228;5610.551155907518;5809.564282678732;5897.852553686106;5968.83739512578;486.48189677992826;502.8139588352333;504.52574579895514;506.1117738369212;665.6405541007802;3686.4101743720876;3789.1853251551047;3853.807309634507;3919.666828319625;3930.76415415076;9912.207023438108;10195.082442580204;10472.109870660926;10480.213983951235;10495.580176792842;10004.199664027006;10209.081182693444;10330;10350.637727679496;10354.689359319082;6891.896621550681;8117.959410202911;8135.3898457615915;8187.590620468953;8219.342452603778;5223;5497.202905578749;5611.859703507445;5628.380878103323;5639.407862174413;8974.19232269099;8977.640894364413;9110.544472776468;9352.53237338144;9357.766055848386;9163;9495.572699228689;9515.524223788922;9519.428834269966;9694.709158725249;7028.718851245892;7283.380912622486;7295.379892709179;7297.525660832029;7309.488335816442;7942.166072562298;7961.641726122222;8026.438149329724;8034.598270086589;8037.156098609562;6232.844178895564;7005;7224.855502890018;7352.941176470631;7444.627768611656;7035.824104397431;7265.600391978492;7280.526765760209;7348.70605175787;7395.7041718330665;6873.759418420332;6942.409895158766;6947.235804061448;6949.235584085847;6954;3957.9604203957592;4718.740469274223;4759.23852183649;4793.0413917216;4838.491958344323;4763.095011947719;4831.347768051268;4855.975720121518;4929.728864912321;5013.724245166551 03:09:47 INFO - PROCESS | 1676 | |4;prototype.html;309.28400752258534;314.3508654628193;322.54843219492705;324.85706289232724;326.76309675485277;6454.837629394153;6479.319671434497;6495.772647957322;6501.38236867498;6620.668966551677;4596.839110631128;6193.1948846649875;6389.936100638992;6484.546081774278;6601.768938087168;4612.19286624841;4720.386349774524;4734.810607575693;4743.691660042104;4798.376211092571;4766.713997160165;4791;4815.566599006106;4827.25177597473;4845.878853028685;4752.215884379073;4757.04859028194;4769.833055843046;4772.069446457925;4772.451168115676;4526.79629416677;4599.540045995407;4727.787249573777;4744.312074749177;4750.524947505256;4056.594340565949;4077.9397356687177;4085.2442298174933;4091.0386059276047;4094;5555.194496797942;5623.818998010423;6139.1712118863825;6173.753049878;6179.783707570214;5599.6640201587625;5611.382747897728;5616.410276920946;5621.662700237999;5628.746706398181;6101.938980610226;6412.6533427980185;6897.068895699117;6917.377436030731;6952.374286314205;4301.698881078326;4435.445569303837;4471.441069866267;4476.686631935766;4599.034202817413;6449.968005119158;6753.15585551806;7148.428125749928;7226.421886249087;7349.963250183769;4576.359309696645;4579.17574836526;4581.015081757439;4587.1513769952;4592.265237562041;4623.283391074388;4627.097715945426;4633.536646335407;4640.187967105743;4641.303804429295;5495.505404513613;5499.972500137474;5500.4224556420995;5505.284313039359;5510.145927381182;6713;6954.130733658293;6985.476089293324;6989.30106989297;7018.613976231356;1595.505393328072;1596.6567188054623;1612.8387161283779;1618.150471002733;1627.4873414874278;954.1365064616533;957.411192116857;1002.2633364477103;1007.4710776842217;1031.7884833609091;5541.450932570981;6062.938985677489;6500.674966251669;6517.034749189203;6522.119513865662;7239.89140162898;7240.746573869995;7242.565446073252;7247.673854676446;7254.92745072553;6476.967615161894;6487.6756162191705;6494.188226471691;6502.284748677642;6506.674666266667;3098.7675924305768;3790.905227369338;3837.654611085016;3840.135969406861;3840.9615903841163;6871.484638652121;6979.546329488567;7275.745348912868;7578.924210757932;7584.241575842482;7397.778066658009;7453.440991925628;7456.85086298271;7456.96271518639;7464.888026679604;6692.86614267712;6974.721011159599;7545.6981720730555;7578.09062912454;7602.1257555380735;5784.768609255582;7218.675159617725;7230.819229519304;7231.783046508614;7244.565326080452;6859.931400686129;7075.71697132109;7137.500374973702;7145.249413893629;7162.355388015104;7535.510191837513;7568;7573;7580.734674286374;7590;3527.5766907971206;4529.773511324486;4556.088782243498;4573.474050484237;4678.806904239534;7252;7254.404031113147;7255.4921155520715;7264.055672762507;7267.091613548307;6762.898556521558;6980.022796808349;6998.545094568837;7044.682989265393;7072.328128827753;6928.757493487704;6968.477364197807;6972.825679357854;6982.371586557234;7021.859562808818;6830.829229269308;6843.110395648534;6864.759733409307;6873.793786186422;6887.449004079762;5972.641641501524;6478.093066970722;7003.369696727319;7009.474289428212;7017.7192912282935;5367.275417818544;6631.33686631333;6777.966110169417;6839.726410943506;6882.483813714091 03:09:47 INFO - PROCESS | 1676 | |5;yui.html;700.9614471204085;729.956202627842;739.8261408568985;745.5601195294774;759.772068379486;14611.85388146121;14676.77984830227;15044.323005464754;15057.096574205556;15092.547223583297;13887.652808679788;13965.790513142285;13991;13994.020418570704;14037.929810350959;746.6714645555938;902.5848109869468;904.1952662130692;905.8912930448355;906.3429013964889;751.2187325181832;910.4673765846954;935.1583574782682;935.6117211357279;936.180841763457;837.3050368194414;980.7646164920403;981.7987312600935;985.8028394321128;986.3736527305141;766.6703317573441;950.4202436513721;977.0669011094387;978.8629591857145;979.0356498848664;717.379466761253;720.0531301338723;720.4992530191519;720.5640587444625;721.9639018049077;13571.59285221445;13656.863431365658;13692.178469291874;13705.040647154703;13756.037077404586;12580.308083055428;12847.164934279243;13017.349132543335;13041.934790325988;13083.280419576919;9746.220302375792;9811.215102791759;9971.700848974542;10137.087662110445;10517.894821051768;3466.4280393735257;3493.63316851732;3544.8936531904083;3567.5362202913716;3606.4229723244157;69635.3036469634;69705;70645;70778;70812;9392.201662858597;9497.382670126419;9516.857247141299;9552.856707149398;9597.712068637951;21821.127154913625;22157.335279941628;22183.778162218496;22200.77799221987;22201.777982220294;7761.72833950809;7773.92226077732;7860.449768516263;8008;8121.512709237347;4131.091159944807;4134.91730165395;4157.625813676784;4195.664346852244;4444.977775111169;3530.752847300664;3599.6940260077663;3600.513930619385;3601.549806274216;3608.783472991629;632.3992207402986;639.2201514152727;646.1309538670574;646.8350570604559;648.7502311609969;567.625367257608;578.9942100579025;582.2983305117408;610.9755609775648;617.7528988404584;7826.178251283647;8448.450850694686;8602.784930376665;8638.740837774876;8760.605772740242;500.38702589327784;570.2956848292356;570.3270141233302;609.9725512351955;614.8770245950827;3346.849391777376;3636.3454578176184;3848.2111167210655;3857.1514266861245;3878.5927477614446;84066;84981.15018849856;86114.27771444661;86202.13797861942;86437.1356286429;66201;68137.31862681409;68508.31491685119;68930.65534672294;69311;58833;58927;59639;60045.69977150086;60472.39527604756;7487.625618718933;7698.769036928901;7720.3437707795465;7732.806679833049;7863.292303692695;42354.576454235066;43419.56580434155;44039.55960440355;45665.543344566126;46371.76814116043;64439.67780161069;67127.32872671405;67318.3268167312;68494;68867.31132688609;10627.628033018807;10833.349999000086;10963.671089867317;11221.495032723384;11330.206885517935;11693.532258709556;11931.821022684493;12030.819537706766;12168.695782605506;12237.877621223673;9399.483028433502;10258.128059115059;11231.831522527333;11354.943225283821;11569.942150289195;11127.276727012719;11132.220744548127;11475.713107172387;11527.308361498337;11711.76576468483;10704.228150252384;10895.836562451728;11111.333320000826;11219.439028048402;11637.825432618349;9409.905900940903;9460.95269523648;9521.333506654468;9866.358686685342;9966.750831229278;9344.95327523358;9569.90430095718;9571.61713531451;9687.564059617192;9748 03:09:47 INFO - PROCESS | 1676 | __end_tp_report 03:09:47 INFO - PROCESS | 1676 | __start_cc_report 03:09:47 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1844 03:09:47 INFO - PROCESS | 1676 | __end_cc_report 03:09:47 INFO - PROCESS | 1676 | __startTimestamp1463825387522__endTimestamp 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:09:47 INFO - PROCESS | 1676 | Number of tests: 6 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5721.39 Median:5892.94 stddev:655.89 (11.1%) stddev-sans-first:631.74 03:09:47 INFO - PROCESS | 1676 | Values: 3383.6 3446.0 3452.3 3481.6 3510.8 5702.9 5703.9 5722.6 5722.9 5726.2 5664.4 5713.7 5723.7 5738.3 5752.5 5019.4 5084.7 5134.4 5255.2 5271.4 5226.1 5226.3 5229.6 5235.7 5238.3 5138.0 5168.8 5196.9 5200.5 5221.3 5221.7 5279.4 5280.4 5290.3 5298.5 5058.4 5088.8 5115.3 5204.7 5209.8 5544.9 5573.5 5582.1 5593.4 5630.0 6210.8 6210.8 6216.1 6223.4 6227.2 6119.4 6176.8 6258.7 6273.6 6309.6 5795.1 5822.7 5830.5 5835.7 5837.6 6371.5 6385.6 6389.3 6394.1 6405.9 6013.1 6119.2 6122.2 6163.8 6217.5 6319.2 6332.9 6341.0 6343.1 6363.1 6201.1 6240.0 6252.8 6257.2 6270.1 6101.3 6161.8 6162.1 6167.4 6271.3 4841.7 4875.7 4890.2 4895.1 4903.1 4134.6 4247.3 4427.5 4469.4 4554.8 5714.8 5746.3 5747.1 5759.9 5761.9 6132.9 6296.5 6330.1 6357.8 6372.0 5687.5 5701.2 5737.4 5766.9 5770.7 5356.3 5384.9 5387.4 5390.1 5391.1 6231.6 6304.9 6334.8 6348.8 6368.0 5094.5 6179.0 6220.6 6231.8 6235.1 6329.9 6330.8 6332.1 6333.6 6358.3 6062.6 6105.8 6107.9 6110.8 6139.0 6215.2 6319.4 6324.5 6329.7 6354.9 6107.2 6137.7 6140.9 6147.1 6158.3 4249.5 5877.5 5943.5 6052.8 6117.9 5888.4 5900.4 5903.8 5906.3 5906.4 6003.8 6101.8 6109.8 6111.4 6124.9 4953.2 5287.8 5791.8 5892.9 5901.0 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:65226.67 Median:35415.47 stddev:134827.99 (380.7%) stddev-sans-first:135118.69 03:09:47 INFO - PROCESS | 1676 | Values: 522.8 540.1 557.3 566.5 663.9 30366.7 38157.8 38878.0 39005.2 39098.4 41371.6 41883.0 41934.0 42001.8 42057.0 8392.9 9332.2 9551.3 9570.3 9573.8 8457.5 11337.7 11500.9 11505.3 11521.5 11299.0 11378.0 11379.6 11390.3 11449.4 8475.5 11144.2 11222.8 11351.6 11562.8 9359.3 11293.3 11303.8 11304.9 11318.3 38376.0 38897.0 38985.8 39054.8 39105.8 3168.0 3333.3 3483.9 3534.2 3829.3 27194.6 27955.6 28424.9 28436.0 28721.1 2704.4 2717.9 2720.6 2728.1 2751.2 441669.0 442796.0 445584.0 446390.8 446463.0 63241.7 63757.7 63802.0 63821.0 63870.0 69234.3 70550.0 70567.3 70568.0 70599.0 17607.5 18001.2 18029.5 18045.0 18082.7 12603.2 12749.4 13343.9 13599.6 13709.9 19417.2 19992.9 20027.2 20074.5 20089.4 8153.8 8344.0 8368.1 8390.4 8465.6 985.9 1073.5 1125.2 1145.8 1176.6 29059.4 29625.7 29687.4 30499.2 30500.0 1127.5 1131.1 1140.4 1162.4 1286.3 15309.9 15381.3 15424.6 15540.8 15610.4 590315.0 597097.0 600756.0 601831.0 602032.0 60636.8 61214.0 61257.7 61271.1 61287.4 1439.4 1522.7 1525.3 1591.8 1615.0 28644.9 28851.4 29256.0 29311.0 29402.4 54753.2 56455.7 57983.4 60548.1 60572.7 453372.0 464930.0 465217.0 465405.0 465489.0 34886.8 35295.8 35430.0 36113.0 38334.4 41663.6 43337.0 43667.8 43710.0 43821.0 34466.5 35687.6 36587.9 37201.8 37375.6 35226.0 35402.3 35428.6 36179.5 36200.0 36708.6 37598.4 37670.8 38433.2 39263.6 29915.6 31370.5 31579.2 32494.0 32632.7 24334.5 24635.9 24690.2 25064.0 25104.2 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:33067.98 Median:2338.61 stddev:92892.46 (3972.1%) stddev-sans-first:93120.64 03:09:47 INFO - PROCESS | 1676 | Values: 392.0 393.8 397.1 398.4 408.1 14300.6 16991.7 20128.6 20839.9 20845.5 16991.0 20014.4 20200.4 21598.8 21606.2 5632.7 5825.2 5938.5 5940.3 6048.0 4170.2 4405.1 5956.1 5957.7 6005.8 4381.5 4382.6 4385.5 6100.7 6527.9 5583.8 5909.0 6499.9 6501.7 6515.8 5721.6 5739.1 5743.2 5746.4 5749.6 13466.2 16522.7 24611.4 25615.4 25692.4 2196.7 2320.2 2357.0 2751.3 2815.1 9745.3 9846.5 10082.9 10148.4 10423.6 1700.2 1740.3 1751.4 1788.8 1814.3 102887.0 109337.9 111017.0 111756.0 111821.4 11733.2 11788.2 11789.1 11794.2 11811.7 13666.2 14235.7 15330.4 15371.4 15383.4 8530.9 8804.6 8834.6 8906.2 8961.0 7246.9 8955.4 8962.2 8978.9 9068.9 4714.3 4721.8 4726.0 4726.5 4762.7 1775.6 1825.5 1908.2 1963.6 1966.4 882.6 999.1 1018.7 1024.0 1106.3 20407.7 21316.5 21381.9 21414.1 21464.6 865.9 977.0 993.5 1025.4 1029.3 11179.3 11486.7 11487.3 11487.8 11490.3 539981.0 548035.0 562795.0 563813.0 565111.0 119090.0 120642.5 124017.0 124749.0 125376.0 1000.1 1002.4 1017.0 1018.4 1023.0 14519.9 18204.5 18260.9 18417.2 18584.2 38259.0 51266.0 51913.2 52194.7 52667.0 75973.6 76083.9 76983.2 77407.1 79266.8 6852.8 8225.4 8341.4 8469.6 8780.2 8240.9 10373.5 12255.4 12445.9 12708.6 8053.5 10611.6 10762.2 11031.9 11114.8 8441.6 9181.3 10777.8 10983.2 11080.6 5927.5 10984.0 11141.0 11445.0 11466.0 8007.1 8224.5 8369.5 8453.6 8678.8 9909.6 11201.7 11206.0 11212.1 11240.7 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5545.80 Median:2827.88 stddev:3051.53 (107.9%) stddev-sans-first:3044.37 03:09:47 INFO - PROCESS | 1676 | Values: 1424.0 1589.1 1603.1 1864.8 1993.4 7819.5 8003.3 8006.9 8013.8 8463.0 8437.9 8484.4 8577.7 8596.1 8599.8 2393.9 2436.3 2438.0 2441.6 2444.7 2402.1 2446.2 2463.2 2463.6 2467.2 2395.6 2422.0 2433.0 2435.5 2449.8 2398.7 2419.0 2437.7 2439.3 2448.6 2430.2 2474.5 2484.2 2487.1 2487.6 8624.9 8647.3 8648.7 8672.2 8699.8 5149.6 5244.6 5335.8 5338.7 5345.1 3429.2 3439.5 3462.1 3498.2 3512.8 994.8 999.6 1012.5 1016.9 1019.3 11019.6 11149.2 11222.1 11232.8 11239.3 4533.8 4635.1 4718.1 4726.8 4748.4 9914.9 9974.5 9981.7 9982.4 9987.0 5293.2 5319.1 5325.6 5329.7 5334.0 4320.2 4482.8 4718.5 4777.2 4830.7 3989.4 3995.9 4058.9 4084.2 4126.1 1315.7 1322.0 1324.4 1328.7 1333.5 666.7 820.8 863.9 903.9 945.9 5578.6 5610.6 5809.6 5897.9 5968.8 486.5 502.8 504.5 506.1 665.6 3686.4 3789.2 3853.8 3919.7 3930.8 9912.2 10195.1 10472.1 10480.2 10495.6 10004.2 10209.1 10330.0 10350.6 10354.7 6891.9 8118.0 8135.4 8187.6 8219.3 5223.0 5497.2 5611.9 5628.4 5639.4 8974.2 8977.6 9110.5 9352.5 9357.8 9163.0 9495.6 9515.5 9519.4 9694.7 7028.7 7283.4 7295.4 7297.5 7309.5 7942.2 7961.6 8026.4 8034.6 8037.2 6232.8 7005.0 7224.9 7352.9 7444.6 7035.8 7265.6 7280.5 7348.7 7395.7 6873.8 6942.4 6947.2 6949.2 6954.0 3958.0 4718.7 4759.2 4793.0 4838.5 4763.1 4831.3 4856.0 4929.7 5013.7 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5630.11 Median:6477.53 stddev:1815.25 (28.0%) stddev-sans-first:1775.87 03:09:47 INFO - PROCESS | 1676 | Values: 309.3 314.4 322.5 324.9 326.8 6454.8 6479.3 6495.8 6501.4 6620.7 4596.8 6193.2 6389.9 6484.5 6601.8 4612.2 4720.4 4734.8 4743.7 4798.4 4766.7 4791.0 4815.6 4827.3 4845.9 4752.2 4757.0 4769.8 4772.1 4772.5 4526.8 4599.5 4727.8 4744.3 4750.5 4056.6 4077.9 4085.2 4091.0 4094.0 5555.2 5623.8 6139.2 6173.8 6179.8 5599.7 5611.4 5616.4 5621.7 5628.7 6101.9 6412.7 6897.1 6917.4 6952.4 4301.7 4435.4 4471.4 4476.7 4599.0 6450.0 6753.2 7148.4 7226.4 7350.0 4576.4 4579.2 4581.0 4587.2 4592.3 4623.3 4627.1 4633.5 4640.2 4641.3 5495.5 5500.0 5500.4 5505.3 5510.1 6713.0 6954.1 6985.5 6989.3 7018.6 1595.5 1596.7 1612.8 1618.2 1627.5 954.1 957.4 1002.3 1007.5 1031.8 5541.5 6062.9 6500.7 6517.0 6522.1 7239.9 7240.7 7242.6 7247.7 7254.9 6477.0 6487.7 6494.2 6502.3 6506.7 3098.8 3790.9 3837.7 3840.1 3841.0 6871.5 6979.5 7275.7 7578.9 7584.2 7397.8 7453.4 7456.9 7457.0 7464.9 6692.9 6974.7 7545.7 7578.1 7602.1 5784.8 7218.7 7230.8 7231.8 7244.6 6859.9 7075.7 7137.5 7145.2 7162.4 7535.5 7568.0 7573.0 7580.7 7590.0 3527.6 4529.8 4556.1 4573.5 4678.8 7252.0 7254.4 7255.5 7264.1 7267.1 6762.9 6980.0 6998.5 7044.7 7072.3 6928.8 6968.5 6972.8 6982.4 7021.9 6830.8 6843.1 6864.8 6873.8 6887.4 5972.6 6478.1 7003.4 7009.5 7017.7 5367.3 6631.3 6778.0 6839.7 6882.5 03:09:47 INFO - PROCESS | 1676 | 03:09:47 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:17054.27 Median:625.08 stddev:23052.08 (3687.9%) stddev-sans-first:23084.04 03:09:47 INFO - PROCESS | 1676 | Values: 701.0 730.0 739.8 745.6 759.8 14611.9 14676.8 15044.3 15057.1 15092.5 13887.7 13965.8 13991.0 13994.0 14037.9 746.7 902.6 904.2 905.9 906.3 751.2 910.5 935.2 935.6 936.2 837.3 980.8 981.8 985.8 986.4 766.7 950.4 977.1 978.9 979.0 717.4 720.1 720.5 720.6 722.0 13571.6 13656.9 13692.2 13705.0 13756.0 12580.3 12847.2 13017.3 13041.9 13083.3 9746.2 9811.2 9971.7 10137.1 10517.9 3466.4 3493.6 3544.9 3567.5 3606.4 69635.3 69705.0 70645.0 70778.0 70812.0 9392.2 9497.4 9516.9 9552.9 9597.7 21821.1 22157.3 22183.8 22200.8 22201.8 7761.7 7773.9 7860.4 8008.0 8121.5 4131.1 4134.9 4157.6 4195.7 4445.0 3530.8 3599.7 3600.5 3601.5 3608.8 632.4 639.2 646.1 646.8 648.8 567.6 579.0 582.3 611.0 617.8 7826.2 8448.5 8602.8 8638.7 8760.6 500.4 570.3 570.3 610.0 614.9 3346.8 3636.3 3848.2 3857.2 3878.6 84066.0 84981.2 86114.3 86202.1 86437.1 66201.0 68137.3 68508.3 68930.7 69311.0 58833.0 58927.0 59639.0 60045.7 60472.4 7487.6 7698.8 7720.3 7732.8 7863.3 42354.6 43419.6 44039.6 45665.5 46371.8 64439.7 67127.3 67318.3 68494.0 68867.3 10627.6 10833.3 10963.7 11221.5 11330.2 11693.5 11931.8 12030.8 12168.7 12237.9 9399.5 10258.1 11231.8 11354.9 11569.9 11127.3 11132.2 11475.7 11527.3 11711.8 10704.2 10895.8 11111.3 11219.4 11637.8 9409.9 9461.0 9521.3 9866.4 9966.8 9345.0 9569.9 9571.6 9687.6 9748.0 03:09:47 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:09:47 INFO - PROCESS | 1676 | 03:09:48 INFO - TEST-INFO | 1676: exit 0 03:09:48 INFO - TEST-OK | dromaeo_css | took 1115776ms 03:09:48 INFO - TEST-START | kraken 03:09:48 INFO - operating with platform_type : mac_ 03:09:48 INFO - Initialising browser for kraken test... 03:09:48 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpdD0uIN/profile http://localhost:49280/getInfo.html) 03:09:50 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 03:09:50 INFO - PROCESS | 1681 | colorDepth:24 03:09:50 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 03:09:50 INFO - PROCESS | 1681 | __metrics 03:09:50 INFO - PROCESS | 1681 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:09:50 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:09:50 INFO - TEST-INFO | 1681: exit 0 03:09:50 INFO - Browser initialized. 03:09:50 INFO - Running cycle 1/1 for kraken test... 03:09:50 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpdD0uIN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:02 INFO - PROCESS | 1684 | RSS: Main: 211636224 03:10:02 INFO - PROCESS | 1684 | 03:10:05 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 03:10:06 INFO - PROCESS | 1684 | RSS: Main: 271511552 03:10:06 INFO - PROCESS | 1684 | 03:10:09 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 03:10:10 INFO - PROCESS | 1684 | RSS: Main: 287449088 03:10:10 INFO - PROCESS | 1684 | 03:10:13 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 03:10:14 INFO - PROCESS | 1684 | RSS: Main: 374312960 03:10:14 INFO - PROCESS | 1684 | 03:10:16 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 03:10:16 INFO - PROCESS | 1684 | RSS: Main: 278855680 03:10:16 INFO - PROCESS | 1684 | 03:10:18 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:10:19 INFO - PROCESS | 1684 | RSS: Main: 280797184 03:10:19 INFO - PROCESS | 1684 | 03:10:22 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 03:10:23 INFO - PROCESS | 1684 | RSS: Main: 317743104 03:10:23 INFO - PROCESS | 1684 | 03:10:25 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 03:10:26 INFO - PROCESS | 1684 | RSS: Main: 319025152 03:10:26 INFO - PROCESS | 1684 | 03:10:29 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 03:10:30 INFO - PROCESS | 1684 | RSS: Main: 317456384 03:10:30 INFO - PROCESS | 1684 | 03:10:31 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:10:32 INFO - PROCESS | 1684 | RSS: Main: 309415936 03:10:32 INFO - PROCESS | 1684 | 03:10:34 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:10:35 INFO - PROCESS | 1684 | RSS: Main: 316252160 03:10:35 INFO - PROCESS | 1684 | 03:10:37 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:10:37 INFO - PROCESS | 1684 | RSS: Main: 318705664 03:10:37 INFO - PROCESS | 1684 | 03:10:40 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:10:41 INFO - PROCESS | 1684 | RSS: Main: 320847872 03:10:41 INFO - PROCESS | 1684 | 03:10:44 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:10:44 INFO - PROCESS | 1684 | RSS: Main: 318697472 03:10:44 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 03:10:46 INFO - PROCESS | 1684 | RSS: Main: 382627840 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | __start_tp_report 03:10:46 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 03:10:46 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 03:10:46 INFO - PROCESS | 1684 | |i|pagename|runs| 03:10:46 INFO - PROCESS | 1684 | |0;ai-astar;113;117;170;168;112;113;114;115;113;135 03:10:46 INFO - PROCESS | 1684 | |1;audio-beat-detection;153;121;159;179;147;147;164;149;171;137 03:10:46 INFO - PROCESS | 1684 | |2;audio-dft;183;187;185;185;184;226;261;184;184;185 03:10:46 INFO - PROCESS | 1684 | |3;audio-fft;71;80;74;150;58;56;68;93;159;83 03:10:46 INFO - PROCESS | 1684 | |4;audio-oscillator;94;95;142;77;89;86;127;79;78;125 03:10:46 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;134;87;90;87;90;86;90;87;89;85 03:10:46 INFO - PROCESS | 1684 | |6;imaging-darkroom;138;150;96;95;94;94;95;95;93;95 03:10:46 INFO - PROCESS | 1684 | |7;imaging-desaturate;86;100;136;86;83;82;83;83;107;145 03:10:46 INFO - PROCESS | 1684 | |8;json-parse-financial;56;64;85;62;61;63;64;83;60;58 03:10:46 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;63;65;73;79;76;102;108;100;68;70 03:10:46 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;82;82;80;81;80;80;77;80;80;86 03:10:46 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;150;133;117;141;151;148;132;139;184;200 03:10:46 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;170;167;165;165;160;148;191;220;169;165 03:10:46 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;70;68;68;69;68;68;72;69;67;69 03:10:46 INFO - PROCESS | 1684 | __end_tp_report 03:10:46 INFO - PROCESS | 1684 | __start_cc_report 03:10:46 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1984 03:10:46 INFO - PROCESS | 1684 | __end_cc_report 03:10:46 INFO - PROCESS | 1684 | __startTimestamp1463825446835__endTimestamp 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | ------- Summary: start ------- 03:10:46 INFO - PROCESS | 1684 | Number of tests: 14 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:127.00 Median:116.00 stddev:23.14 (20.0%) stddev-sans-first:23.98 03:10:46 INFO - PROCESS | 1684 | Values: 113.0 117.0 170.0 168.0 112.0 113.0 114.0 115.0 113.0 135.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:156.00 stddev:16.75 (10.7%) stddev-sans-first:17.76 03:10:46 INFO - PROCESS | 1684 | Values: 153.0 121.0 159.0 179.0 147.0 147.0 164.0 149.0 171.0 137.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:196.40 Median:185.00 stddev:26.18 (14.2%) stddev-sans-first:27.32 03:10:46 INFO - PROCESS | 1684 | Values: 183.0 187.0 185.0 185.0 184.0 226.0 261.0 184.0 184.0 185.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:89.20 Median:72.50 stddev:36.20 (49.9%) stddev-sans-first:37.79 03:10:46 INFO - PROCESS | 1684 | Values: 71.0 80.0 74.0 150.0 58.0 56.0 68.0 93.0 159.0 83.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:82.50 stddev:23.44 (28.4%) stddev-sans-first:24.78 03:10:46 INFO - PROCESS | 1684 | Values: 94.0 95.0 142.0 77.0 89.0 86.0 127.0 79.0 78.0 125.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:88.00 stddev:14.69 (16.7%) stddev-sans-first:1.90 03:10:46 INFO - PROCESS | 1684 | Values: 134.0 87.0 90.0 87.0 90.0 86.0 90.0 87.0 89.0 85.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:95.00 stddev:21.03 (22.1%) stddev-sans-first:18.48 03:10:46 INFO - PROCESS | 1684 | Values: 138.0 150.0 96.0 95.0 94.0 94.0 95.0 95.0 93.0 95.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:99.10 Median:83.00 stddev:23.44 (28.2%) stddev-sans-first:24.38 03:10:46 INFO - PROCESS | 1684 | Values: 86.0 100.0 136.0 86.0 83.0 82.0 83.0 83.0 107.0 145.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:63.50 stddev:10.04 (15.8%) stddev-sans-first:10.02 03:10:46 INFO - PROCESS | 1684 | Values: 56.0 64.0 85.0 62.0 61.0 63.0 64.0 83.0 60.0 58.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:69.00 stddev:16.63 (24.1%) stddev-sans-first:16.41 03:10:46 INFO - PROCESS | 1684 | Values: 63.0 65.0 73.0 79.0 76.0 102.0 108.0 100.0 68.0 70.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:80.50 stddev:2.30 (2.9%) stddev-sans-first:2.40 03:10:46 INFO - PROCESS | 1684 | Values: 82.0 82.0 80.0 81.0 80.0 80.0 77.0 80.0 80.0 86.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:149.00 stddev:24.86 (16.7%) stddev-sans-first:26.37 03:10:46 INFO - PROCESS | 1684 | Values: 150.0 133.0 117.0 141.0 151.0 148.0 132.0 139.0 184.0 200.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:168.00 stddev:19.92 (11.9%) stddev-sans-first:21.11 03:10:46 INFO - PROCESS | 1684 | Values: 170.0 167.0 165.0 165.0 160.0 148.0 191.0 220.0 169.0 165.0 03:10:46 INFO - PROCESS | 1684 | 03:10:46 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.40 (2.0%) stddev-sans-first:1.41 03:10:46 INFO - PROCESS | 1684 | Values: 70.0 68.0 68.0 69.0 68.0 68.0 72.0 69.0 67.0 69.0 03:10:46 INFO - PROCESS | 1684 | -------- Summary: end -------- 03:10:46 INFO - PROCESS | 1684 | 03:10:47 INFO - TEST-INFO | 1684: exit 0 03:10:47 INFO - TEST-OK | kraken | took 59524ms 03:10:47 INFO - SUITE-END | took 1175s 03:10:47 INFO - Completed test suite (00:19:35) 03:10:47 INFO - javascript benchmark 03:10:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3383.627800941896, 3445.965540344596, 3452.2750222453283, 3481.6344283850212, 3510.789352638842, 5702.857428564277, 5703.857403564913, 5722.570807189461, 5722.942770572298, 5726.198332233485, 5664.376918538957, 5713.657180569169, 5723.656580605167, 5738.282714660668, 5752.482276595106, 5019.397672279331, 5084.694918304895, 5134.358205224356, 5255.237990491377, 5271.367435907697, 5226.05930932432, 5226.320578324812, 5229.607779416559, 5235.738213089349, 5238.266642670029, 5138.023775482665, 5168.767405466744, 5196.92204616931, 5200.531952124308, 5221.321228240324, 5221.712805795699, 5279.392869819982, 5280.445553216895, 5290.338707661543, 5298.549623282026, 5058.4435712071645, 5088.771005304733, 5115.335006449175, 5204.661696989683, 5209.817656382046, 5544.91682624761, 5573.470520300565, 5582.078956972095, 5593.440655934414, 5630.0, 6210.813675589739, 6210.844728881814, 6216.129741836146, 6223.408776166257, 6227.159333489966, 6119.418655227746, 6176.752929882799, 6258.71835767387, 6273.592216505913, 6309.621422714652, 5795.072788353843, 5822.7088645567555, 5830.533557315404, 5835.708214589296, 5837.6497410155525, 6371.458426033746, 6385.648789316632, 6389.3291204423795, 6394.104825324458, 6405.935940640627, 6013.098035294741, 6119.2350956130485, 6122.204113465221, 6163.7842675506145, 6217.502599792006, 6319.178506794087, 6332.905006424908, 6341.0, 6343.1436756038265, 6363.077353783768, 6201.069839524017, 6240.032794916795, 6252.781152659635, 6257.186565746424, 6270.090836828635, 6101.298350689639, 6161.815145545641, 6162.106494558264, 6167.444929956326, 6271.27880293763, 4841.685290456109, 4875.731834749123, 4890.217565189552, 4895.143349913751, 4903.092927808367, 4134.64855487281, 4247.341662042327, 4427.535108813592, 4469.374287599739, 4554.772261386917, 5714.771409143671, 5746.339170995389, 5747.137929310553, 5759.913601295984, 5761.8559536011935, 6132.906245166512, 6296.464800492009, 6330.05049242608, 6357.841053973595, 6371.96814015927, 5687.459691329317, 5701.173329867146, 5737.426257374229, 5766.913496297559, 5770.740316685759, 5356.276902618097, 5384.91922621161, 5387.4073851876265, 5390.0836857734275, 5391.137418013099, 6231.626102433868, 6304.936950630435, 6334.778282760081, 6348.809535713936, 6368.044793281138, 5094.5160209780015, 6179.0, 6220.626762394271, 6231.75072997075, 6235.095911092775, 6329.90505142414, 6330.841728956813, 6332.113504109337, 6333.556651034383, 6358.332375100548, 6062.575619706578, 6105.786297479567, 6107.9083813741945, 6110.847228819315, 6139.032198454696, 6215.223097112861, 6319.368063193644, 6324.462420694293, 6329.651869147241, 6354.93645063562, 6107.206063211754, 6137.662428566293, 6140.8771824564155, 6147.108669242845, 6158.32258451561, 4249.511306199826, 5877.471027607536, 5943.52451803846, 6052.848678783065, 6117.908231376622, 5888.381719919518, 5900.350961394157, 5903.793367232126, 5906.26171728534, 5906.350301466921, 6003.7898673546215, 6101.7559297627595, 6109.755609775737, 6111.419415155553, 6124.938750612615, 4953.23224900141, 5287.762050707651, 5791.7683292667825, 5892.94107058924, 5900.967330717193], "value": 5680.645760581507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.8431470558833, 540.0899484368837, 557.3005877623582, 566.5297802823657, 663.8780461020874, 30366.696333036663, 38157.80921095394, 38878.0, 39005.21989560207, 39098.41352379709, 41371.58628413715, 41883.0, 41934.0, 42001.78999105008, 42057.0, 8392.916070839305, 9332.160105590512, 9551.283653725963, 9570.330076894621, 9573.760655983586, 8457.53483558404, 11337.659870203906, 11500.884991150107, 11505.3096814191, 11521.539138434453, 11298.983091521803, 11378.032867206297, 11379.601713940014, 11390.25963312382, 11449.427528623577, 8475.49147051179, 11144.21990460664, 11222.775544489146, 11351.602693905756, 11562.76874462506, 9359.251259899193, 11293.322400655987, 11303.830442543287, 11304.886951130466, 11318.26431281964, 38376.04059898497, 38897.0, 38985.83042508729, 39054.80472597619, 39105.804470977746, 3167.952480712791, 3333.3000069985333, 3483.860645574174, 3534.222471056389, 3829.329867273244, 27194.5920811188, 27955.58066629002, 28424.857875710695, 28436.0, 28721.13836584906, 2704.418550011757, 2717.91846244611, 2720.5919112133142, 2728.0876494023905, 2751.2434080627786, 441669.0, 442796.0, 445584.0, 446390.7680461609, 446463.0, 63241.68379158075, 63757.724845503755, 63802.0429693555, 63821.0, 63870.0, 69234.30765692379, 70550.0, 70567.29432705608, 70568.0, 70599.0, 17607.471775846745, 18001.18994645244, 18029.459116226535, 18045.00752458601, 18082.728759068625, 12603.243805371523, 12749.42627581761, 13343.933280333731, 13599.59201223965, 13709.931450342685, 19417.205785242997, 19992.90003550002, 20027.198912043354, 20074.498137546678, 20089.397318080482, 8153.79615509617, 8344.0, 8368.079511253758, 8390.412671113085, 8465.619047142893, 985.9211263098935, 1073.4632683658172, 1125.2348403085816, 1145.8052131137727, 1176.6352430746497, 29059.418811624073, 29625.703742962294, 29687.406251875273, 30499.237519061757, 30500.0, 1127.515975635291, 1131.1346819682785, 1140.372794962767, 1162.3781277016756, 1286.3118231746157, 15309.9234503829, 15381.30784114695, 15424.614384640474, 15540.766888496682, 15610.375584976473, 590315.0, 597097.0, 600756.0, 601831.0, 602032.0, 60636.78726425535, 61214.0, 61257.69371153116, 61271.08093378603, 61287.38712612817, 1439.417036100381, 1522.7182971150373, 1525.3212320517707, 1591.8089829220798, 1615.0148409470214, 28644.856775715987, 28851.422971540873, 29256.0, 29311.0, 29402.35378815917, 54753.17870232029, 56455.71772141113, 57983.42016579949, 60548.06437581543, 60572.697136514034, 453372.0, 464930.0, 465217.0, 465405.0, 465489.0, 34886.82556587201, 35295.823520883256, 35430.0, 36113.0, 38334.42498362471, 41663.58336416597, 43337.0, 43667.78166109149, 43710.0, 43821.0, 34466.48300275548, 35687.64312356843, 36587.902362929155, 37201.81399092987, 37375.62624373721, 35226.0, 35402.29195416129, 35428.64571354254, 36179.45730813987, 36200.0, 36708.632913670524, 37598.43602345912, 37670.8116459416, 38433.2313353737, 39263.60736392599, 29915.55126673058, 31370.52944205793, 31579.210519736273, 32494.0, 32632.673673262965, 24334.513309733353, 24635.891384887364, 24690.18778312144, 25063.99744010219, 25104.246872593852], "value": 20446.64799237688, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.96276353746384, 393.7617741266534, 397.1104725415069, 398.4262661767057, 408.08181591419304, 14300.642483937907, 16991.745123823122, 20128.59742805143, 20839.895800520993, 20845.47886302843, 16991.0, 20014.399568012985, 20200.393988180378, 21598.784012159915, 21606.243781467652, 5632.662040277576, 5825.242718446596, 5938.52491800657, 5940.287165540141, 6048.0, 4170.228507726065, 4405.05291362357, 5956.076808094731, 5957.731902064395, 6005.849853753647, 4381.452318460192, 4382.583654552828, 4385.451818522684, 6100.664463454508, 6527.869442611169, 5583.804566840141, 5909.025010873201, 6499.862524058294, 6501.707423165969, 6515.837104072412, 5721.599488035839, 5739.081746920514, 5743.224664670258, 5746.396628354005, 5749.568782341299, 13466.192028478323, 16522.669546609002, 24611.38471538208, 25615.359616009562, 25692.357691057685, 2196.7144271244797, 2320.2459200759654, 2356.964645530318, 2751.257160566656, 2815.1413818785263, 9745.269104817096, 9846.507674616241, 10082.9495852521, 10148.391096534158, 10423.583056677724, 1700.228627337432, 1740.2864825421516, 1751.3870145449118, 1788.8211178882138, 1814.3105619864366, 102887.0, 109337.90662093436, 111017.0, 111756.0, 111821.44089279664, 11733.237339572901, 11788.23376480543, 11789.050668026308, 11794.233374830608, 11811.704707382385, 13666.155069065748, 14235.715285694228, 15330.386784528717, 15371.385144594315, 15383.38466461329, 8530.87203691945, 8804.559772011375, 8834.602442890086, 8906.153915378029, 8961.0, 7246.89129663045, 8955.41789783662, 8962.238209752113, 8978.86531702025, 9068.909310906807, 4714.339992401066, 4721.834735784231, 4725.9527404726205, 4726.456457507431, 4762.738049407316, 1775.6359946210996, 1825.4614888607894, 1908.1508728615634, 1963.5974625202084, 1966.3805901141095, 882.6028287270753, 999.0509016434416, 1018.7300365403174, 1023.9795204095876, 1106.3417266726285, 20407.693884591743, 21316.467088322606, 21381.893090534446, 21414.143434262456, 21464.570708586052, 865.8787769712245, 977.041032729373, 993.5429702337005, 1025.415513157493, 1029.330934892311, 11179.273347232403, 11486.712832179262, 11487.310761354345, 11487.770244595229, 11490.253133546294, 539981.0, 548035.0, 562795.0, 563813.0, 565111.0, 119090.0, 120642.46700952928, 124017.0, 124749.0, 125376.0, 1000.1049061090283, 1002.4336250018862, 1016.9847452288221, 1018.3635227982511, 1023.0076825479273, 14519.92740036293, 18204.544886377946, 18260.904345739298, 18417.17122729507, 18584.163712633235, 38259.0, 51266.0, 51913.22130168126, 52194.739026304625, 52667.0, 75973.62013189899, 76083.85874211781, 76983.2301676976, 77407.06482337987, 79266.81099783392, 6852.794416167523, 8225.35492901434, 8341.374396920377, 8469.61886715087, 8780.209781119729, 8240.876386854321, 10373.481325933522, 12255.448504817483, 12445.937770311091, 12708.555200567936, 8053.476524025919, 10611.575536978435, 10762.246642734934, 11031.944840275748, 11114.833277501006, 8441.577921103797, 9181.265498759949, 10777.784444311226, 10983.231173817756, 11080.5567777288, 5927.496162826208, 10984.0, 11140.997310242117, 11444.969952704296, 11466.0, 8007.119216886254, 8224.465409748347, 8369.49783013021, 8453.619587118717, 8678.783030424038, 9909.55407006672, 11201.66395008151, 11206.0, 11212.103031757602, 11240.718982025515], "value": 9381.945801659003, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.0, 1589.0942162967108, 1603.1262961685884, 1864.8321651051403, 1993.3820515640143, 7819.452638315321, 8003.279704826565, 8006.919930800683, 8013.799655008627, 8463.0, 8437.873431898526, 8484.406091573592, 8577.699780507683, 8596.054434012269, 8599.785005374868, 2393.8563686178886, 2436.293474892279, 2438.0247900839627, 2441.6215486599604, 2444.69441319835, 2402.1232250228627, 2446.217210492643, 2463.1748364297982, 2463.59350707134, 2467.222824810188, 2395.64065390192, 2421.9757802422014, 2433.0024689877155, 2435.5372479228977, 2449.7917676997477, 2398.6521954316527, 2419.0444774314137, 2437.683101196842, 2439.2926051445147, 2448.5714999875127, 2430.161594249981, 2474.542209691213, 2484.2050543825985, 2487.141936032066, 2487.5895477246413, 8624.913750862474, 8647.264982476498, 8648.697295594688, 8672.176143266379, 8699.826003479959, 5149.639525233235, 5244.60665450089, 5335.813246536352, 5338.733063346817, 5345.144776835727, 3429.2112814052907, 3439.501272315525, 3462.1344663834043, 3498.1954150545516, 3512.8243587820507, 994.751312171957, 999.5651891427252, 1012.5139932832208, 1016.8678071850612, 1019.2559431614889, 11019.559217631286, 11149.163812713998, 11222.102231821435, 11232.775344493064, 11239.325640461599, 4533.84131555394, 4635.142498637762, 4718.127146477913, 4726.834560790425, 4748.406449193851, 9914.900850991542, 9974.501274936225, 9981.650642227487, 9982.351147175556, 9986.950065249628, 5293.179557168645, 5319.069162896478, 5325.600579956441, 5329.680219186861, 5334.039872822929, 4320.157569274024, 4482.843100491533, 4718.504557021532, 4777.235642297216, 4830.686005409637, 3989.4414781930554, 3995.9400608990886, 4058.9188216236103, 4084.1627466369323, 4126.051008268055, 1315.7368526294779, 1321.973560528784, 1324.38416136497, 1328.7408955253634, 1333.4799428223, 666.6799936030733, 820.8235229425583, 863.8920134983127, 903.9412438191497, 945.9169251207329, 5578.609497335228, 5610.551155907518, 5809.564282678732, 5897.852553686106, 5968.83739512578, 486.48189677992826, 502.8139588352333, 504.52574579895514, 506.1117738369212, 665.6405541007802, 3686.4101743720876, 3789.1853251551047, 3853.807309634507, 3919.666828319625, 3930.76415415076, 9912.207023438108, 10195.082442580204, 10472.109870660926, 10480.213983951235, 10495.580176792842, 10004.199664027006, 10209.081182693444, 10330.0, 10350.637727679496, 10354.689359319082, 6891.896621550681, 8117.959410202911, 8135.3898457615915, 8187.590620468953, 8219.342452603778, 5223.0, 5497.202905578749, 5611.859703507445, 5628.380878103323, 5639.407862174413, 8974.19232269099, 8977.640894364413, 9110.544472776468, 9352.53237338144, 9357.766055848386, 9163.0, 9495.572699228689, 9515.524223788922, 9519.428834269966, 9694.709158725249, 7028.718851245892, 7283.380912622486, 7295.379892709179, 7297.525660832029, 7309.488335816442, 7942.166072562298, 7961.641726122222, 8026.438149329724, 8034.598270086589, 8037.156098609562, 6232.844178895564, 7005.0, 7224.855502890018, 7352.941176470631, 7444.627768611656, 7035.824104397431, 7265.600391978492, 7280.526765760209, 7348.70605175787, 7395.7041718330665, 6873.759418420332, 6942.409895158766, 6947.235804061448, 6949.235584085847, 6954.0, 3957.9604203957592, 4718.740469274223, 4759.23852183649, 4793.0413917216, 4838.491958344323, 4763.095011947719, 4831.347768051268, 4855.975720121518, 4929.728864912321, 5013.724245166551], "value": 4442.041477647261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.28400752258534, 314.3508654628193, 322.54843219492705, 324.85706289232724, 326.76309675485277, 6454.837629394153, 6479.319671434497, 6495.772647957322, 6501.38236867498, 6620.668966551677, 4596.839110631128, 6193.1948846649875, 6389.936100638992, 6484.546081774278, 6601.768938087168, 4612.19286624841, 4720.386349774524, 4734.810607575693, 4743.691660042104, 4798.376211092571, 4766.713997160165, 4791.0, 4815.566599006106, 4827.25177597473, 4845.878853028685, 4752.215884379073, 4757.04859028194, 4769.833055843046, 4772.069446457925, 4772.451168115676, 4526.79629416677, 4599.540045995407, 4727.787249573777, 4744.312074749177, 4750.524947505256, 4056.594340565949, 4077.9397356687177, 4085.2442298174933, 4091.0386059276047, 4094.0, 5555.194496797942, 5623.818998010423, 6139.1712118863825, 6173.753049878, 6179.783707570214, 5599.6640201587625, 5611.382747897728, 5616.410276920946, 5621.662700237999, 5628.746706398181, 6101.938980610226, 6412.6533427980185, 6897.068895699117, 6917.377436030731, 6952.374286314205, 4301.698881078326, 4435.445569303837, 4471.441069866267, 4476.686631935766, 4599.034202817413, 6449.968005119158, 6753.15585551806, 7148.428125749928, 7226.421886249087, 7349.963250183769, 4576.359309696645, 4579.17574836526, 4581.015081757439, 4587.1513769952, 4592.265237562041, 4623.283391074388, 4627.097715945426, 4633.536646335407, 4640.187967105743, 4641.303804429295, 5495.505404513613, 5499.972500137474, 5500.4224556420995, 5505.284313039359, 5510.145927381182, 6713.0, 6954.130733658293, 6985.476089293324, 6989.30106989297, 7018.613976231356, 1595.505393328072, 1596.6567188054623, 1612.8387161283779, 1618.150471002733, 1627.4873414874278, 954.1365064616533, 957.411192116857, 1002.2633364477103, 1007.4710776842217, 1031.7884833609091, 5541.450932570981, 6062.938985677489, 6500.674966251669, 6517.034749189203, 6522.119513865662, 7239.89140162898, 7240.746573869995, 7242.565446073252, 7247.673854676446, 7254.92745072553, 6476.967615161894, 6487.6756162191705, 6494.188226471691, 6502.284748677642, 6506.674666266667, 3098.7675924305768, 3790.905227369338, 3837.654611085016, 3840.135969406861, 3840.9615903841163, 6871.484638652121, 6979.546329488567, 7275.745348912868, 7578.924210757932, 7584.241575842482, 7397.778066658009, 7453.440991925628, 7456.85086298271, 7456.96271518639, 7464.888026679604, 6692.86614267712, 6974.721011159599, 7545.6981720730555, 7578.09062912454, 7602.1257555380735, 5784.768609255582, 7218.675159617725, 7230.819229519304, 7231.783046508614, 7244.565326080452, 6859.931400686129, 7075.71697132109, 7137.500374973702, 7145.249413893629, 7162.355388015104, 7535.510191837513, 7568.0, 7573.0, 7580.734674286374, 7590.0, 3527.5766907971206, 4529.773511324486, 4556.088782243498, 4573.474050484237, 4678.806904239534, 7252.0, 7254.404031113147, 7255.4921155520715, 7264.055672762507, 7267.091613548307, 6762.898556521558, 6980.022796808349, 6998.545094568837, 7044.682989265393, 7072.328128827753, 6928.757493487704, 6968.477364197807, 6972.825679357854, 6982.371586557234, 7021.859562808818, 6830.829229269308, 6843.110395648534, 6864.759733409307, 6873.793786186422, 6887.449004079762, 5972.641641501524, 6478.093066970722, 7003.369696727319, 7009.474289428212, 7017.7192912282935, 5367.275417818544, 6631.33686631333, 6777.966110169417, 6839.726410943506, 6882.483813714091], "value": 5020.858993531166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.9614471204085, 729.956202627842, 739.8261408568985, 745.5601195294774, 759.772068379486, 14611.85388146121, 14676.77984830227, 15044.323005464754, 15057.096574205556, 15092.547223583297, 13887.652808679788, 13965.790513142285, 13991.0, 13994.020418570704, 14037.929810350959, 746.6714645555938, 902.5848109869468, 904.1952662130692, 905.8912930448355, 906.3429013964889, 751.2187325181832, 910.4673765846954, 935.1583574782682, 935.6117211357279, 936.180841763457, 837.3050368194414, 980.7646164920403, 981.7987312600935, 985.8028394321128, 986.3736527305141, 766.6703317573441, 950.4202436513721, 977.0669011094387, 978.8629591857145, 979.0356498848664, 717.379466761253, 720.0531301338723, 720.4992530191519, 720.5640587444625, 721.9639018049077, 13571.59285221445, 13656.863431365658, 13692.178469291874, 13705.040647154703, 13756.037077404586, 12580.308083055428, 12847.164934279243, 13017.349132543335, 13041.934790325988, 13083.280419576919, 9746.220302375792, 9811.215102791759, 9971.700848974542, 10137.087662110445, 10517.894821051768, 3466.4280393735257, 3493.63316851732, 3544.8936531904083, 3567.5362202913716, 3606.4229723244157, 69635.3036469634, 69705.0, 70645.0, 70778.0, 70812.0, 9392.201662858597, 9497.382670126419, 9516.857247141299, 9552.856707149398, 9597.712068637951, 21821.127154913625, 22157.335279941628, 22183.778162218496, 22200.77799221987, 22201.777982220294, 7761.72833950809, 7773.92226077732, 7860.449768516263, 8008.0, 8121.512709237347, 4131.091159944807, 4134.91730165395, 4157.625813676784, 4195.664346852244, 4444.977775111169, 3530.752847300664, 3599.6940260077663, 3600.513930619385, 3601.549806274216, 3608.783472991629, 632.3992207402986, 639.2201514152727, 646.1309538670574, 646.8350570604559, 648.7502311609969, 567.625367257608, 578.9942100579025, 582.2983305117408, 610.9755609775648, 617.7528988404584, 7826.178251283647, 8448.450850694686, 8602.784930376665, 8638.740837774876, 8760.605772740242, 500.38702589327784, 570.2956848292356, 570.3270141233302, 609.9725512351955, 614.8770245950827, 3346.849391777376, 3636.3454578176184, 3848.2111167210655, 3857.1514266861245, 3878.5927477614446, 84066.0, 84981.15018849856, 86114.27771444661, 86202.13797861942, 86437.1356286429, 66201.0, 68137.31862681409, 68508.31491685119, 68930.65534672294, 69311.0, 58833.0, 58927.0, 59639.0, 60045.69977150086, 60472.39527604756, 7487.625618718933, 7698.769036928901, 7720.3437707795465, 7732.806679833049, 7863.292303692695, 42354.576454235066, 43419.56580434155, 44039.55960440355, 45665.543344566126, 46371.76814116043, 64439.67780161069, 67127.32872671405, 67318.3268167312, 68494.0, 68867.31132688609, 10627.628033018807, 10833.349999000086, 10963.671089867317, 11221.495032723384, 11330.206885517935, 11693.532258709556, 11931.821022684493, 12030.819537706766, 12168.695782605506, 12237.877621223673, 9399.483028433502, 10258.128059115059, 11231.831522527333, 11354.943225283821, 11569.942150289195, 11127.276727012719, 11132.220744548127, 11475.713107172387, 11527.308361498337, 11711.76576468483, 10704.228150252384, 10895.836562451728, 11111.333320000826, 11219.439028048402, 11637.825432618349, 9409.905900940903, 9460.95269523648, 9521.333506654468, 9866.358686685342, 9966.750831229278, 9344.95327523358, 9569.90430095718, 9571.61713531451, 9687.564059617192, 9748.0], "value": 6765.355238574072, "unit": "score"}], "name": "dromaeo_css", "value": 7401.744032608742, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 170.0, 168.0, 112.0, 113.0, 114.0, 115.0, 113.0, 135.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 159.0, 179.0, 147.0, 147.0, 164.0, 149.0, 171.0, 137.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 185.0, 185.0, 184.0, 226.0, 261.0, 184.0, 184.0, 185.0], "value": 196.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 74.0, 150.0, 58.0, 56.0, 68.0, 93.0, 159.0, 83.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 142.0, 77.0, 89.0, 86.0, 127.0, 79.0, 78.0, 125.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 150.0, 96.0, 95.0, 94.0, 94.0, 95.0, 95.0, 93.0, 95.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 100.0, 136.0, 86.0, 83.0, 82.0, 83.0, 83.0, 107.0, 145.0], "value": 99.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 85.0, 62.0, 61.0, 63.0, 64.0, 83.0, 60.0, 58.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 73.0, 79.0, 76.0, 102.0, 108.0, 100.0, 68.0, 70.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 80.0, 81.0, 80.0, 80.0, 77.0, 80.0, 80.0, 86.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 133.0, 117.0, 141.0, 151.0, 148.0, 132.0, 139.0, 184.0, 200.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 165.0, 165.0, 160.0, 148.0, 191.0, 220.0, 169.0, 165.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 72.0, 69.0, 67.0, 69.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1577.7, "alertThreshold": 2.0}]} 03:10:48 INFO - Return code: 0 03:10:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:10:48 ERROR - # TBPL SUCCESS # 03:10:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:48 INFO - 2016-05-21 03:10:48.137 screenresolution[1688:10490] starting screenresolution argv=screenresolution get 03:10:48 INFO - 2016-05-21 03:10:48.139 screenresolution[1688:10490] Display 0: 1600x1200x32@60 03:10:48 INFO - 2016-05-21 03:10:48.145 screenresolution[1689:10493] starting screenresolution argv=screenresolution list 03:10:48 INFO - Available Modes on Display 0 03:10:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:48 INFO - Intel Iris: 03:10:48 INFO - Chipset Model: Intel Iris 03:10:48 INFO - Type: GPU 03:10:48 INFO - Bus: Built-In 03:10:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:48 INFO - Vendor: Intel (0x8086) 03:10:48 INFO - Device ID: 0x0a2e 03:10:48 INFO - Revision ID: 0x0009 03:10:48 INFO - Displays: 03:10:48 INFO - Display: 03:10:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:48 INFO - Display Serial Number: 1600x1200 60 03:10:48 INFO - Main Display: Yes 03:10:48 INFO - Mirror: Off 03:10:48 INFO - Online: Yes 03:10:48 INFO - Rotation: Supported 03:10:48 INFO - Return code: 0 03:10:48 INFO - Running post-action listener: _resource_record_post_action 03:10:48 INFO - Running post-run listener: _resource_record_post_run 03:10:49 INFO - Total resource usage - Wall time: 1210s; CPU: 52.0%; Read bytes: 32689664; Write bytes: 654904832; Read time: 321; Write time: 1898 03:10:49 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 345682432; Write bytes: 346408960; Read time: 27010; Write time: 719 03:10:49 INFO - run-tests - Wall time: 1178s; CPU: 52.0%; Read bytes: 31763968; Write bytes: 298538496; Read time: 306; Write time: 1146 03:10:49 INFO - Running post-run listener: _upload_blobber_files 03:10:49 INFO - Blob upload gear active. 03:10:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:10:49 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 03:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:10:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:10:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:10:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:10:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:51 INFO - (blobuploader) - INFO - Done attempting. 03:10:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:10:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:10:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:52 INFO - (blobuploader) - INFO - Done attempting. 03:10:52 INFO - (blobuploader) - INFO - Iteration through files over. 03:10:52 INFO - Return code: 0 03:10:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:10:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:10:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a1f2b4df669b97c05c586b20385a8193aab5e2a353101caee3a618cb3d59376bb0713973199639b5fe18d959c83eeee8e545cd45e479a259ab4c169469bc05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9f89f716379823866ec2f42fbe62d63b4e3e5473bf51e204fa81206f3ef43db1b739c9a0c8e5e1b417b8c4ccfc898ef096c8aad38c28f7c089884c273a733f8"} 03:10:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:10:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:10:52 INFO - Contents: 03:10:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a1f2b4df669b97c05c586b20385a8193aab5e2a353101caee3a618cb3d59376bb0713973199639b5fe18d959c83eeee8e545cd45e479a259ab4c169469bc05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9f89f716379823866ec2f42fbe62d63b4e3e5473bf51e204fa81206f3ef43db1b739c9a0c8e5e1b417b8c4ccfc898ef096c8aad38c28f7c089884c273a733f8"} 03:10:52 INFO - Running post-run listener: copy_logs_to_upload_dir 03:10:52 INFO - Copying logs to upload dir... 03:10:52 INFO - mkdir: /builds/slave/test/build/upload/logs 03:10:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.437807 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-21 03:10:52.273903) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 03:10:52.277486) ========= 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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a1f2b4df669b97c05c586b20385a8193aab5e2a353101caee3a618cb3d59376bb0713973199639b5fe18d959c83eeee8e545cd45e479a259ab4c169469bc05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9f89f716379823866ec2f42fbe62d63b4e3e5473bf51e204fa81206f3ef43db1b739c9a0c8e5e1b417b8c4ccfc898ef096c8aad38c28f7c089884c273a733f8"} build_url:https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/DuIE92jMSMGNK_vGSRyAMg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10a1f2b4df669b97c05c586b20385a8193aab5e2a353101caee3a618cb3d59376bb0713973199639b5fe18d959c83eeee8e545cd45e479a259ab4c169469bc05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9f89f716379823866ec2f42fbe62d63b4e3e5473bf51e204fa81206f3ef43db1b739c9a0c8e5e1b417b8c4ccfc898ef096c8aad38c28f7c089884c273a733f8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 03:10:52.306802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 03:10:52.307148) ========= 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.PUmpxl7sIx/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.3wKQzvrxYr/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005061 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 03:10:52.356001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 03:10:52.356328) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 03:10:52.356680) ========= ========= Total master_lag: 0.16 =========