builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0145 starttime: 1461639079.61 results: success (0) buildid: 20160425181356 builduid: 5ae703220f69444a904ff843c2df0b7b revision: f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.608746) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.609227) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.609513) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.629460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.629800) ========= 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.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 19:51:19-- 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% 21.3M=0.001s 2016-04-25 19:51:19 (21.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.143431 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.791055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.791395) ========= 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.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.025684 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:19.839954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-25 19:51:19.840304) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 19:51:19,919 truncating revision to first 12 chars 2016-04-25 19:51:19,920 Setting DEBUG logging. 2016-04-25 19:51:19,920 attempt 1/10 2016-04-25 19:51:19,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f4fd92a0496f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 19:51:21,440 unpacking tar archive at: fx-team-f4fd92a0496f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766801 ========= master_lag: 2.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-25 19:51:24.149765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:24.150140) ========= script_repo_revision: f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:24.150577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:24.150871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 19:51:24.167744) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 19:51:24.168167) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:24 INFO - MultiFileLogger online at 20160425 19:51:24 in /builds/slave/test 19:51:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:51:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:51:24 INFO - {'append_to_log': False, 19:51:24 INFO - 'base_work_dir': '/builds/slave/test', 19:51:24 INFO - 'blob_upload_branch': 'Fx-Team', 19:51:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:51:24 INFO - 'branch': 'Fx-Team', 19:51:24 INFO - 'buildbot_json_path': 'buildprops.json', 19:51:24 INFO - 'config_files': ('talos/mac_config.py',), 19:51:24 INFO - 'default_actions': ('clobber', 19:51:24 INFO - 'read-buildbot-config', 19:51:24 INFO - 'download-and-extract', 19:51:24 INFO - 'populate-webroot', 19:51:24 INFO - 'create-virtualenv', 19:51:24 INFO - 'install', 19:51:24 INFO - 'run-tests'), 19:51:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:51:24 INFO - 'download_minidump_stackwalk': True, 19:51:24 INFO - 'download_symbols': 'ondemand', 19:51:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:51:24 INFO - 'tooltool.py': '/tools/tooltool.py', 19:51:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:51:24 INFO - '/tools/misc-python/virtualenv.py')}, 19:51:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:51:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:51:24 INFO - 'installer_path': 'installer.exe', 19:51:24 INFO - 'log_level': 'info', 19:51:24 INFO - 'log_name': 'talos', 19:51:24 INFO - 'log_to_console': True, 19:51:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:51:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:51:24 INFO - 'opt_config_files': (), 19:51:24 INFO - 'pip_index': False, 19:51:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:24 INFO - 'cmd': ('bash', 19:51:24 INFO - '-c', 19:51:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:24 INFO - 'enabled': True, 19:51:24 INFO - 'halt_on_failure': False, 19:51:24 INFO - 'name': 'check_screen_resolution'},), 19:51:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:24 INFO - 'cmd': ('bash', 19:51:24 INFO - '-c', 19:51:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:24 INFO - 'enabled': True, 19:51:24 INFO - 'halt_on_failure': False, 19:51:24 INFO - 'name': 'check_screen_resolution'},), 19:51:24 INFO - 'run_cmd_checks_enabled': True, 19:51:24 INFO - 'sps_profile': False, 19:51:24 INFO - 'sps_profile_interval': 0, 19:51:24 INFO - 'suite': 'dromaeojs', 19:51:24 INFO - 'system_bits': '32', 19:51:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:51:24 INFO - 'title': 't-yosemite-r7-0145', 19:51:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:51:24 INFO - 'use_talos_json': True, 19:51:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:51:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:51:24 INFO - 'work_dir': 'build'} 19:51:24 INFO - ##### 19:51:24 INFO - ##### Running clobber step. 19:51:24 INFO - ##### 19:51:24 INFO - Running pre-action listener: _resource_record_pre_action 19:51:24 INFO - Running main action method: clobber 19:51:24 INFO - rmtree: /builds/slave/test/build 19:51:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:51:26 INFO - Running post-action listener: _resource_record_post_action 19:51:26 INFO - ##### 19:51:26 INFO - ##### Running read-buildbot-config step. 19:51:26 INFO - ##### 19:51:26 INFO - Running pre-action listener: _resource_record_pre_action 19:51:26 INFO - Running main action method: read_buildbot_config 19:51:26 INFO - Using buildbot properties: 19:51:26 INFO - { 19:51:26 INFO - "project": "", 19:51:26 INFO - "product": "firefox", 19:51:26 INFO - "script_repo_revision": "production", 19:51:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:51:26 INFO - "repository": "", 19:51:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:51:26 INFO - "buildid": "20160425181356", 19:51:26 INFO - "pgo_build": "False", 19:51:26 INFO - "basedir": "/builds/slave/test", 19:51:26 INFO - "buildnumber": 669, 19:51:26 INFO - "slavename": "t-yosemite-r7-0145", 19:51:26 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:51:26 INFO - "platform": "yosemite_r7", 19:51:26 INFO - "scheduler": "tests-fx-team-macosx64-talos", 19:51:26 INFO - "branch": "fx-team", 19:51:26 INFO - "repo_path": "integration/fx-team", 19:51:26 INFO - "revision": "f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3", 19:51:26 INFO - "stage_platform": "macosx64", 19:51:26 INFO - "builduid": "5ae703220f69444a904ff843c2df0b7b", 19:51:26 INFO - "slavebuilddir": "test" 19:51:26 INFO - } 19:51:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:51:26 INFO - Running post-action listener: _resource_record_post_action 19:51:26 INFO - ##### 19:51:26 INFO - ##### Running download-and-extract step. 19:51:26 INFO - ##### 19:51:26 INFO - Running pre-action listener: _resource_record_pre_action 19:51:26 INFO - Running main action method: download_and_extract 19:51:26 INFO - mkdir: /builds/slave/test/build/tests 19:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:26 INFO - https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:51:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:51:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:51:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:51:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:51:28 INFO - Downloaded 1199 bytes. 19:51:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:51:28 INFO - Using the following test package requirements: 19:51:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:51:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:51:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:51:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'jsshell-mac.zip'], 19:51:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:51:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:51:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:51:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:51:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:51:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:51:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:51:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:28 INFO - https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:51:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:29 INFO - Downloaded 18113194 bytes. 19:51:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:51:32 INFO - Return code: 0 19:51:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:32 INFO - https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:51:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:33 INFO - Downloaded 18113194 bytes. 19:51:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:51:37 INFO - Return code: 0 19:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:37 INFO - https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:51:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:51:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:51:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:51:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:51:38 INFO - Downloaded 10840145 bytes. 19:51:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:51:38 INFO - Return code: 0 19:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:38 INFO - https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:51:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:51:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:51:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:51:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:51:41 INFO - Downloaded 110097629 bytes. 19:51:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:51:41 INFO - mkdir: /builds/slave/test/properties 19:51:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:51:41 INFO - Writing to file /builds/slave/test/properties/build_url 19:51:41 INFO - Contents: 19:51:41 INFO - build_url:https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:51:42 INFO - Running post-action listener: _resource_record_post_action 19:51:42 INFO - Running post-action listener: set_extra_try_arguments 19:51:42 INFO - ##### 19:51:42 INFO - ##### Running populate-webroot step. 19:51:42 INFO - ##### 19:51:42 INFO - Running pre-action listener: _resource_record_pre_action 19:51:42 INFO - Running main action method: populate_webroot 19:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:51:43 INFO - '--tppagecycles', 19:51:43 INFO - '7'], 19:51:43 INFO - 'tests': ['tsvgm']}}, 19:51:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:51:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:51:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:51:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:51:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['damp', 'tps']}, 19:51:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'tests': ['damp', 'tps']}, 19:51:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['dromaeo_dom']}, 19:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:51:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'other-e10s': {'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['a11yr', 19:51:43 INFO - 'ts_paint', 19:51:43 INFO - 'tpaint', 19:51:43 INFO - 'sessionrestore', 19:51:43 INFO - 'sessionrestore_no_auto_restore', 19:51:43 INFO - 'tabpaint']}, 19:51:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['tsvgx', 19:51:43 INFO - 'tsvgr_opacity', 19:51:43 INFO - 'tart', 19:51:43 INFO - 'tscrollx', 19:51:43 INFO - 'cart']}, 19:51:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:51:43 INFO - 'tsvgr_opacity', 19:51:43 INFO - 'tart', 19:51:43 INFO - 'tscrollx', 19:51:43 INFO - 'cart']}, 19:51:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'talos_options': ['--disable-e10s'], 19:51:43 INFO - 'tests': ['tp5o']}, 19:51:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'tests': ['tp5o']}, 19:51:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'talos_options': ['--disable-e10s', 19:51:43 INFO - '--xperf_path', 19:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:43 INFO - 'tests': ['tp5n']}, 19:51:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:51:43 INFO - 'talos_options': ['--xperf_path', 19:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:43 INFO - 'tests': ['tp5n']}}, 19:51:43 INFO - 'talos.zip': {'path': '', 19:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:51:43 INFO - Running post-action listener: _resource_record_post_action 19:51:43 INFO - ##### 19:51:43 INFO - ##### Running create-virtualenv step. 19:51:43 INFO - ##### 19:51:43 INFO - Running pre-action listener: _resource_record_pre_action 19:51:43 INFO - Running main action method: create_virtualenv 19:51:43 INFO - Creating virtualenv /builds/slave/test/build/venv 19:51:43 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 19:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:51:43 INFO - Using real prefix '/tools/python27' 19:51:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:51:43 INFO - Installing distribute.............................................................................................................................................................................................done. 19:51:46 INFO - Installing pip.................done. 19:51:46 INFO - Return code: 0 19:51:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:51: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')]} 19:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51: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')]} 19:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:46 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:46 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 19:51:46 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 19:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:46 INFO - 'HOME': '/Users/cltbld', 19:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:46 INFO - 'LOGNAME': 'cltbld', 19:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:46 INFO - 'MOZ_NO_REMOTE': '1', 19:51:46 INFO - 'NO_EM_RESTART': '1', 19:51:46 INFO - 'PAGER': '/bin/cat', 19:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:46 INFO - 'PWD': '/builds/slave/test', 19:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:46 INFO - 'SHELL': '/bin/bash', 19:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:51:46 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:46 INFO - 'USER': 'cltbld', 19:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:46 INFO - 'XPC_FLAGS': '0x0', 19:51:46 INFO - 'XPC_SERVICE_NAME': '0', 19:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:46 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 19:51:46 INFO - Cleaning up... 19:51:46 INFO - Return code: 0 19:51:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:51: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')]} 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51: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')]} 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:47 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:47 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 19:51:47 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 19:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:47 INFO - 'HOME': '/Users/cltbld', 19:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:47 INFO - 'LOGNAME': 'cltbld', 19:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:47 INFO - 'MOZ_NO_REMOTE': '1', 19:51:47 INFO - 'NO_EM_RESTART': '1', 19:51:47 INFO - 'PAGER': '/bin/cat', 19:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:47 INFO - 'PWD': '/builds/slave/test', 19:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:47 INFO - 'SHELL': '/bin/bash', 19:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:51:47 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:47 INFO - 'USER': 'cltbld', 19:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:47 INFO - 'XPC_FLAGS': '0x0', 19:51:47 INFO - 'XPC_SERVICE_NAME': '0', 19:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:47 INFO - Downloading/unpacking psutil>=0.7.1 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:51:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:50 INFO - Installing collected packages: psutil 19:51:50 INFO - Running setup.py install for psutil 19:51:50 INFO - building 'psutil._psutil_osx' extension 19:51:50 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 19:51:50 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 19:51:50 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 19:51:51 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 19:51:51 INFO - building 'psutil._psutil_posix' extension 19:51:51 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 19:51:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:51:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:51:51 INFO - ^ 19:51:51 INFO - 1 warning generated. 19:51:51 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 19:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:51 INFO - Successfully installed psutil 19:51:51 INFO - Cleaning up... 19:51:51 INFO - Return code: 0 19:51:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:51 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:51 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 19:51:51 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 19:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:51 INFO - 'HOME': '/Users/cltbld', 19:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:51 INFO - 'LOGNAME': 'cltbld', 19:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:51 INFO - 'MOZ_NO_REMOTE': '1', 19:51:51 INFO - 'NO_EM_RESTART': '1', 19:51:51 INFO - 'PAGER': '/bin/cat', 19:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:51 INFO - 'PWD': '/builds/slave/test', 19:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:51 INFO - 'SHELL': '/bin/bash', 19:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:51:51 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:51 INFO - 'USER': 'cltbld', 19:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:51 INFO - 'XPC_FLAGS': '0x0', 19:51:51 INFO - 'XPC_SERVICE_NAME': '0', 19:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:51:51 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 19:51:51 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 19:51:51 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 19:51:51 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 19:51:51 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 19:51:51 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 19:51:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:51:54 INFO - Installing collected packages: mozsystemmonitor 19:51:54 INFO - Running setup.py install for mozsystemmonitor 19:51:54 INFO - Successfully installed mozsystemmonitor 19:51:54 INFO - Cleaning up... 19:51:54 INFO - Return code: 0 19:51:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:54 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:54 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 19:51:54 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 19:51:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:51:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:54 INFO - 'HOME': '/Users/cltbld', 19:51:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:54 INFO - 'LOGNAME': 'cltbld', 19:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:54 INFO - 'MOZ_NO_REMOTE': '1', 19:51:54 INFO - 'NO_EM_RESTART': '1', 19:51:54 INFO - 'PAGER': '/bin/cat', 19:51:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:54 INFO - 'PWD': '/builds/slave/test', 19:51:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:54 INFO - 'SHELL': '/bin/bash', 19:51:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:51:54 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:51:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:54 INFO - 'USER': 'cltbld', 19:51:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:54 INFO - 'XPC_FLAGS': '0x0', 19:51:54 INFO - 'XPC_SERVICE_NAME': '0', 19:51:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:54 INFO - Downloading/unpacking blobuploader==1.2.4 19:51:54 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 19:51:54 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 19:51:54 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 19:51:54 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 19:51:54 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 19:51:54 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 19:51:57 INFO - Downloading blobuploader-1.2.4.tar.gz 19:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:51:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:51:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:57 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 19:51:58 INFO - Downloading docopt-0.6.1.tar.gz 19:51:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:51:58 INFO - Installing collected packages: blobuploader, requests, docopt 19:51:58 INFO - Running setup.py install for blobuploader 19:51:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:51:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:51:58 INFO - Running setup.py install for requests 19:51:59 INFO - Running setup.py install for docopt 19:51:59 INFO - Successfully installed blobuploader requests docopt 19:51:59 INFO - Cleaning up... 19:51:59 INFO - Return code: 0 19:51:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:59 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')]} 19:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 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')]} 19:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:51:59 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 19:51:59 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 19:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:59 INFO - 'HOME': '/Users/cltbld', 19:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:59 INFO - 'LOGNAME': 'cltbld', 19:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:59 INFO - 'MOZ_NO_REMOTE': '1', 19:51:59 INFO - 'NO_EM_RESTART': '1', 19:51:59 INFO - 'PAGER': '/bin/cat', 19:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:59 INFO - 'PWD': '/builds/slave/test', 19:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:59 INFO - 'SHELL': '/bin/bash', 19:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:51:59 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:59 INFO - 'USER': 'cltbld', 19:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:59 INFO - 'XPC_FLAGS': '0x0', 19:51:59 INFO - 'XPC_SERVICE_NAME': '0', 19:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-h5aSoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-613eUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ZpJqUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-b_7g1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-qWDEjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-3Ufu9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-HqbBTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-k_fnIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-wON5__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-SsXvyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-1OhRsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-LFVXNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Navxmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-1UwMtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-idbxd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-WUNpUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-GszIpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:52:01 INFO - Running setup.py install for manifestparser 19:52:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:52:01 INFO - Running setup.py install for mozcrash 19:52:01 INFO - Running setup.py install for mozdebug 19:52:01 INFO - Running setup.py install for mozdevice 19:52:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:52:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:52:01 INFO - Running setup.py install for mozfile 19:52:02 INFO - Running setup.py install for mozhttpd 19:52:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Running setup.py install for mozinfo 19:52:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Running setup.py install for mozInstall 19:52:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Running setup.py install for mozleak 19:52:02 INFO - Running setup.py install for mozlog 19:52:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Running setup.py install for moznetwork 19:52:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:52:02 INFO - Running setup.py install for mozprocess 19:52:03 INFO - Running setup.py install for mozprofile 19:52:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:52:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:52:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:52:03 INFO - Running setup.py install for mozrunner 19:52:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:52:03 INFO - Running setup.py install for mozscreenshot 19:52:03 INFO - Running setup.py install for moztest 19:52:03 INFO - Running setup.py install for mozversion 19:52:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:52:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:52:03 INFO - Cleaning up... 19:52:03 INFO - Return code: 0 19:52:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:03 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:52:03 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 19:52:03 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 19:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:03 INFO - 'HOME': '/Users/cltbld', 19:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:03 INFO - 'LOGNAME': 'cltbld', 19:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:03 INFO - 'MOZ_NO_REMOTE': '1', 19:52:03 INFO - 'NO_EM_RESTART': '1', 19:52:03 INFO - 'PAGER': '/bin/cat', 19:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:03 INFO - 'PWD': '/builds/slave/test', 19:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:03 INFO - 'SHELL': '/bin/bash', 19:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:52:03 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:03 INFO - 'USER': 'cltbld', 19:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:03 INFO - 'XPC_FLAGS': '0x0', 19:52:03 INFO - 'XPC_SERVICE_NAME': '0', 19:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-bafmmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-CZ9j66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-_0_kYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-CJZLaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-tNIuJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-2nAwuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-JdR4jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:04 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-1i5WwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:04 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)) 19:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-cwsbMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ebMJJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-fPxl__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-eApJN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-BRdH4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-krhRx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-NuRY4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Os0XC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:05 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)) 19:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:05 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Nvv2Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:05 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)) 19:52:05 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)) 19:52:05 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)) 19:52:05 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)) 19:52:05 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)) 19:52:05 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)) 19:52:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:05 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 19:52:08 INFO - Downloading blessings-1.6.tar.gz 19:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:52:08 INFO - Installing collected packages: blessings 19:52:08 INFO - Running setup.py install for blessings 19:52:08 INFO - Successfully installed blessings 19:52:08 INFO - Cleaning up... 19:52:08 INFO - Return code: 0 19:52:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:52:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:08 INFO - Reading from file tmpfile_stdout 19:52:08 INFO - Current package versions: 19:52:08 INFO - blessings == 1.6 19:52:08 INFO - blobuploader == 1.2.4 19:52:08 INFO - docopt == 0.6.1 19:52:08 INFO - manifestparser == 1.1 19:52:08 INFO - mozInstall == 1.12 19:52:08 INFO - mozcrash == 0.17 19:52:08 INFO - mozdebug == 0.1 19:52:08 INFO - mozdevice == 0.48 19:52:08 INFO - mozfile == 1.2 19:52:08 INFO - mozhttpd == 0.7 19:52:08 INFO - mozinfo == 0.9 19:52:08 INFO - mozleak == 0.1 19:52:08 INFO - mozlog == 3.1 19:52:08 INFO - moznetwork == 0.27 19:52:08 INFO - mozprocess == 0.22 19:52:08 INFO - mozprofile == 0.28 19:52:08 INFO - mozrunner == 6.11 19:52:08 INFO - mozscreenshot == 0.1 19:52:08 INFO - mozsystemmonitor == 0.0 19:52:08 INFO - moztest == 0.7 19:52:08 INFO - mozversion == 1.4 19:52:08 INFO - psutil == 3.1.1 19:52:08 INFO - requests == 1.2.3 19:52:08 INFO - wsgiref == 0.1.2 19:52:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:08 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:52:08 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 19:52:08 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 19:52:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:52:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:08 INFO - 'HOME': '/Users/cltbld', 19:52:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:08 INFO - 'LOGNAME': 'cltbld', 19:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:08 INFO - 'MOZ_NO_REMOTE': '1', 19:52:08 INFO - 'NO_EM_RESTART': '1', 19:52:08 INFO - 'PAGER': '/bin/cat', 19:52:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:08 INFO - 'PWD': '/builds/slave/test', 19:52:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:08 INFO - 'SHELL': '/bin/bash', 19:52:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:52:08 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:52:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:08 INFO - 'USER': 'cltbld', 19:52:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:08 INFO - 'XPC_FLAGS': '0x0', 19:52:08 INFO - 'XPC_SERVICE_NAME': '0', 19:52:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 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)) 19:52:09 INFO - Cleaning up... 19:52:09 INFO - Return code: 0 19:52:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:09 INFO - 'HOME': '/Users/cltbld', 19:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:09 INFO - 'LOGNAME': 'cltbld', 19:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:09 INFO - 'MOZ_NO_REMOTE': '1', 19:52:09 INFO - 'NO_EM_RESTART': '1', 19:52:09 INFO - 'PAGER': '/bin/cat', 19:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:09 INFO - 'PWD': '/builds/slave/test', 19:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:09 INFO - 'SHELL': '/bin/bash', 19:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:52:09 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:09 INFO - 'USER': 'cltbld', 19:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:09 INFO - 'XPC_FLAGS': '0x0', 19:52:09 INFO - 'XPC_SERVICE_NAME': '0', 19:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:09 INFO - Downloading/unpacking jsonschema 19:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:52:11 INFO - Downloading/unpacking functools32 (from jsonschema) 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52:12 INFO - Downloading functools32-3.2.3-2.tar.gz 19:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:52:12 INFO - warning: no files found matching '*.txt' 19:52:12 INFO - no previously-included directories found matching 'build' 19:52:12 INFO - no previously-included directories found matching 'dist' 19:52:12 INFO - no previously-included directories found matching '.git*' 19:52:12 INFO - Installing collected packages: jsonschema, functools32 19:52:12 INFO - Running setup.py install for functools32 19:52:12 INFO - warning: no files found matching '*.txt' 19:52:12 INFO - no previously-included directories found matching 'build' 19:52:12 INFO - no previously-included directories found matching 'dist' 19:52:12 INFO - no previously-included directories found matching '.git*' 19:52:12 INFO - Successfully installed jsonschema functools32 19:52:12 INFO - Cleaning up... 19:52:12 INFO - Return code: 0 19:52:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:52:12 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')]} 19:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 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')]} 19:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 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 0x10acaba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acbb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc79dd9ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, '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.qGfQU585XN/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.ijYiVz7KiG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 19:52:12 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 19:52:12 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 19:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:12 INFO - 'HOME': '/Users/cltbld', 19:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:12 INFO - 'LOGNAME': 'cltbld', 19:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:12 INFO - 'MOZ_NO_REMOTE': '1', 19:52:12 INFO - 'NO_EM_RESTART': '1', 19:52:12 INFO - 'PAGER': '/bin/cat', 19:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:12 INFO - 'PWD': '/builds/slave/test', 19:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:12 INFO - 'SHELL': '/bin/bash', 19:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:52:12 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:12 INFO - 'USER': 'cltbld', 19:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:12 INFO - 'XPC_FLAGS': '0x0', 19:52:12 INFO - 'XPC_SERVICE_NAME': '0', 19:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:12 INFO - Downloading/unpacking flake8 19:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:52:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:52:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:52:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:52:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:52:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:52:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:52:17 INFO - no previously-included directories found matching 'docs/_build' 19:52:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:52:17 INFO - Running setup.py install for pep8 19:52:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:52:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:52:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:52:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:52:17 INFO - no previously-included directories found matching 'docs/_build' 19:52:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:52:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:52:17 INFO - Cleaning up... 19:52:18 INFO - Return code: 0 19:52:18 INFO - Running post-action listener: _resource_record_post_action 19:52:18 INFO - Running post-action listener: _start_resource_monitoring 19:52:18 INFO - Starting resource monitoring. 19:52:18 INFO - ##### 19:52:18 INFO - ##### Running install step. 19:52:18 INFO - ##### 19:52:18 INFO - Running pre-action listener: _resource_record_pre_action 19:52:18 INFO - Running main action method: install 19:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:18 INFO - Reading from file tmpfile_stdout 19:52:18 INFO - Detecting whether we're running mozinstall >=1.0... 19:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:52:18 INFO - Reading from file tmpfile_stdout 19:52:18 INFO - Output received: 19:52:18 INFO - Usage: mozinstall [options] installer 19:52:18 INFO - Options: 19:52:18 INFO - -h, --help show this help message and exit 19:52:18 INFO - -d DEST, --destination=DEST 19:52:18 INFO - Directory to install application into. [default: 19:52:18 INFO - "/builds/slave/test"] 19:52:18 INFO - --app=APP Application being installed. [default: firefox] 19:52:18 INFO - mkdir: /builds/slave/test/build/application 19:52:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:52:51 INFO - Reading from file tmpfile_stdout 19:52:51 INFO - Output received: 19:52:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:52:51 INFO - Running post-action listener: _resource_record_post_action 19:52:51 INFO - ##### 19:52:51 INFO - ##### Running run-tests step. 19:52:51 INFO - ##### 19:52:51 INFO - Running pre-action listener: _resource_record_pre_action 19:52:51 INFO - Running main action method: run_tests 19:52:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:52:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:52:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:52:51 INFO - 2016-04-25 19:52:51.687 screenresolution[1661:7438] starting screenresolution argv=screenresolution get 19:52:51 INFO - 2016-04-25 19:52:51.690 screenresolution[1661:7438] Display 0: 1600x1200x32@60 19:52:51 INFO - 2016-04-25 19:52:51.696 screenresolution[1662:7440] starting screenresolution argv=screenresolution list 19:52:51 INFO - Available Modes on Display 0 19:52:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:52:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:52:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:52:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:52:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:52:51 INFO - Intel Iris: 19:52:51 INFO - Chipset Model: Intel Iris 19:52:51 INFO - Type: GPU 19:52:51 INFO - Bus: Built-In 19:52:51 INFO - VRAM (Dynamic, Max): 1536 MB 19:52:51 INFO - Vendor: Intel (0x8086) 19:52:51 INFO - Device ID: 0x0a2e 19:52:51 INFO - Revision ID: 0x0009 19:52:51 INFO - Displays: 19:52:51 INFO - Display: 19:52:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:52:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:52:51 INFO - Display Serial Number: 1600x1200 60 19:52:51 INFO - Main Display: Yes 19:52:51 INFO - Mirror: Off 19:52:51 INFO - Online: Yes 19:52:51 INFO - Rotation: Supported 19:52:51 INFO - Return code: 0 19:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:52:51 INFO - Python 2.7.3 19:52:51 INFO - Return code: 0 19:52:51 INFO - grabbing minidump binary from tooltool 19:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:52:51 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 0x10ab4ae00>, '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 0x10a973db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab45b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:52:51 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 19:52:51 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 19:52:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:52:52 INFO - Return code: 0 19:52:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:52:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:52:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:52:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:52:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:52:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:52:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:52:53 INFO - Return code: 0 19:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 19:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --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 19:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qGfQU585XN/Render', 19:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:53 INFO - 'HOME': '/Users/cltbld', 19:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:53 INFO - 'LOGNAME': 'cltbld', 19:52:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:52:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:53 INFO - 'MOZ_NO_REMOTE': '1', 19:52:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:52:53 INFO - 'NO_EM_RESTART': '1', 19:52:53 INFO - 'PAGER': '/bin/cat', 19:52:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:53 INFO - 'PWD': '/builds/slave/test', 19:52:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:53 INFO - 'SHELL': '/bin/bash', 19:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ijYiVz7KiG/Listeners', 19:52:53 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 19:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:53 INFO - 'USER': 'cltbld', 19:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:53 INFO - 'XPC_FLAGS': '0x0', 19:52:53 INFO - 'XPC_SERVICE_NAME': '0', 19:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 19:52:53 INFO - mozversion application_buildid: 20160425181356 19:52:53 INFO - mozversion application_changeset: f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 19:52:53 INFO - mozversion application_display_name: Nightly 19:52:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:52:53 INFO - mozversion application_name: Firefox 19:52:53 INFO - mozversion application_remotingname: firefox 19:52:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:52:53 INFO - mozversion application_vendor: Mozilla 19:52:53 INFO - mozversion application_version: 49.0a1 19:52:53 INFO - mozversion platform_buildid: 20160425181356 19:52:53 INFO - mozversion platform_changeset: f4fd92a0496fd6d1ef7fe87ebaeb002cc3d79ec3 19:52:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:52:53 INFO - mozversion platform_version: 49.0a1 19:52:53 INFO - using testdate: 1461639173 19:52:53 INFO - actual date: 1461639173 19:52:53 INFO - starting webserver on 'localhost:49270' 19:52:53 INFO - SUITE-START | Running 2 tests 19:52:53 INFO - TEST-START | dromaeo_css 19:52:53 INFO - operating with platform_type : mac_ 19:52:53 INFO - Initialising browser for dromaeo_css test... 19:52:53 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpwei7kd/profile http://localhost:49270/getInfo.html) 19:52:55 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 19:52:55 INFO - PROCESS | 1680 | colorDepth:24 19:52:55 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 19:52:55 INFO - PROCESS | 1680 | __metrics 19:52:55 INFO - PROCESS | 1680 | 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" 19:52:55 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:52:56 INFO - TEST-INFO | 1680: exit 0 19:52:56 INFO - Browser initialized. 19:52:56 INFO - Running cycle 1/1 for dromaeo_css test... 19:52:56 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpwei7kd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:08 INFO - PROCESS | 1682 | RSS: Main: 239325184 19:53:08 INFO - PROCESS | 1682 | 19:53:59 INFO - PROCESS | 1682 | 1461639239622 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] 19:56:09 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 19:56:11 INFO - PROCESS | 1682 | RSS: Main: 390463488 19:56:11 INFO - PROCESS | 1682 | 19:59:14 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 19:59:15 INFO - PROCESS | 1682 | RSS: Main: 378036224 19:59:15 INFO - PROCESS | 1682 | 20:02:17 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 20:02:17 INFO - PROCESS | 1682 | RSS: Main: 376360960 20:02:17 INFO - PROCESS | 1682 | 20:05:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 20:05:20 INFO - PROCESS | 1682 | RSS: Main: 378724352 20:05:20 INFO - PROCESS | 1682 | 20:08:24 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 20:08:24 INFO - PROCESS | 1682 | RSS: Main: 345677824 20:08:24 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 20:11:28 INFO - PROCESS | 1682 | RSS: Main: 383164416 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | __start_tp_report 20:11:28 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 20:11:28 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 20:11:28 INFO - PROCESS | 1682 | |i|pagename|runs| 20:11:28 INFO - PROCESS | 1682 | |0;dojo.html;3363.2264579146795;3414.7438942079334;3426.1434641339656;3450.1029732269603;3484.947725784112;5521.613487055908;5631.18347839563;5670.518005969492;5692.174634677975;5709.314882214135;5624.634398764078;5705.77176912923;5708.942910570893;5868.501177399926;5869.442402971711;5032.6477146599755;5039.143345631252;5067.49325067494;5080.390353157626;5160.174372100465;4551.817927282921;4936.407631084275;5145.228215767646;5163.845084647466;5247.816326428576;5017.197248440232;5072.670276432039;5112.156494178456;5126.846194614168;5177.990291893101;5104;5117.462666420047;5118.181091025418;5127.234699467143;5127.410347810012;5038.773255203525;5088.287639730441;5102.285680004802;5123.359580052494;5125.6155788315655;5465.453454654543;5580.358258800249;5614.522765564931;5713.057345537981;5718.056520674084;6113.777244551107;6140.140380346755;6144.723487443076;6156.045812899008;6168.599041062362;6148.815535533941;6208.441240288395;6221.782237621706;6224.221972253468;6228.906566401508;5564.304461942257;5593.496585307342;5642.463965923231;5670.574706896958;5690.573207009491;6260.280067792262;6265.216847894013;6271.529635277372;6281.59169653971;6284.088807122942;6054.727537260834;6113.877722445526;6121.693915304217;6127.5404344674325;6162.075688646739;6079.209702738615;6125.815438131368;6139.522803442411;6221.688915554295;6251.593646412968;6047.365026672224;6051.7579296827635;6062.9090563641585;6078.513718902477;6134.386561343919;6098.207233059674;6099.756009759649;6107.908381374283;6109.7861574844665;6121.540884433685;4784;4796.952030479721;4861.854144375674;4866.391701037371;4873.975630121898;4367.213901497697;4393.384926110347;4423.049044455393;4428.512863585003;4430.645548356124;5572.3870374258795;5622.184783206494;5628.324601047901;5658.321001479767;5697.202391665171;6147.415995480422;6226.813195604139;6255.593386429868;6273.780417685359;6275.121482992385;5496.642718223303;5586.91619625706;5593.8321850344555;5625.409332020161;5626.043572592669;5243.659162154448;5280.735963201824;5325.307709997675;5326.760295786699;5349.358077030781;5232.947670523246;6229.408206220402;6237.656928868956;6258.9374106260175;6279.215098112736;6070.180525628984;6131.233595800525;6241.43827055549;6242.625442473466;6270.717817698123;6198.256209254918;6225.28409232944;6243.250809902841;6263.123162757127;6309.305976342691;6098.63244893404;6108.949222962717;6112.047575716935;6129.817282374446;6144.341027922525;4778.737169455713;6159.753609855556;6164.7842325518395;6176.258848938156;6201.379862013943;5792.073268277056;5937.554683398849;5938.910916336345;5955.196048533393;5959.970200148971;5903.852403689943;5910.083936989773;5915.08316210988;5931.792387266425;5933.2880054393745;5694;5840.766369345179;5841.1238314253205;5846.473817356458;5846.970765146147;6025.66858822769;6050.42520960508;6056.333803281724;6091.0558863376245;6093.878122437438;5874.765009399747;5879.353271140257;5886.05823068307;5888.087346461305;5890.764369425175 20:11:28 INFO - PROCESS | 1682 | |1;ext.html;505.23456962701505;530.0353356890461;545.4055079962842;559.5076332827111;568.0626965506916;29791.55312670312;30242.697573024263;30261.848690756517;37256.81371593145;38417.80791096047;42428;42868;43025.709228723186;43096.92257693559;43137.78431107848;8988.370814043019;9231.492267925296;9538.856917146231;9787.021297870227;9807.215422766161;8424.620892059873;8646.740597782045;9562.995885432034;11147.665570032872;11412.71468213293;11307.151963602722;11440.542378304857;11443.141764367663;11456;11459;8443.83112337756;11432.657020289364;11487.770244595104;11532.94233528827;11535;11124.888751112467;11423.428828558539;11423.657290281222;11425.942870285595;11433.885661143366;37299.81350093232;37637.24725505502;37755;37808.810955945046;37875.621243787486;3210.807351558914;3302.488114342281;3430.142464383904;3478.0001499138098;3550.0769799851964;27102.5934610981;27329.726702732918;27801;28096.297592560146;28178.577321340195;2710.241132482908;2721.931951701223;2764.433291175304;2816.2818481287345;2842.360468894503;493237;494027;494703;495904;503458;60281.69859150676;60418.697906511065;60746;60923.69538152281;60931.6953415239;60815;62017.75964480775;62093;62138.68930655408;62223.06665400023;16412.83587164137;16551.503454896374;16667.3333066676;16680.83319166817;16684.416045438353;11637.418129093512;11840.763184736257;12795.744085118431;12868.163569367962;12881.484740610458;16682.33270669184;16885.83114168867;16911.23899424529;18480.445586632424;18998.71501927472;6741.494387920926;7804.141544430108;8007.159248278964;8008.7597372078935;8051.315638170821;915.3821170709745;928.800307933784;935.4434111703525;963.1235575626147;1061.7717190804015;27951.44097118087;28183.71816281852;28658;28775.136745897657;28800.567991480144;1070.4326706845384;1088.8475613414128;1125.2517076144436;1126.1272513801869;1143.0512674480162;15078.54764357071;15098.698026039418;15417.383304667912;15555.766663500057;15787.28957196929;627520;637472;637645;640278;641350;58681.5329616763;59201.703991479764;59273;59352.703236484405;59369.70315148397;1255.7174635706892;1440.2438719672257;1481.3630139040101;1484.6882154747625;1486.7026594681106;28612.141635750962;28971.42057158887;28996.710032899402;29447.85276073606;29479.26301842471;50666.493335066174;51094;51107.488925111764;51159;51211.97576048533;547079;564463;564825.175874118;565753;566209;22077.668834967808;22516.88741556282;22537.211197608005;26649.60025599579;27157.456850863266;40632.39051414172;40781.79609101936;41208.79395603003;41593.37609935793;42646;34786.82606586951;35328.29343413169;36039.91880243597;36848.81575592105;37029.07427314231;36197.914062578166;36303.273934520636;36701.81649091737;37080.44379334258;37092.44361334636;28488.715112848608;34241.828790855885;35520.28959420849;35596.82201589079;35614;22448.77551224467;28888.566671500364;30564.541531876595;30901.072967811;31041.379172416877;21599.568008639424;21670.783292166878;21773.67339489877;21948.561028779015;22166.667499987834 20:11:28 INFO - PROCESS | 1682 | |2;jquery.html;386.9690424766018;390.145581177222;395.42268288299084;396.06528592521653;407.7797989085894;11219.640713888433;20378.69431958522;20401.897990510028;21031.68452473212;21045.26341578045;14098.365573549188;16526.586835329123;20091.29680461184;20234.69647955282;21176.188286108496;4941.407031156266;5953.3451320354725;6005.279366476029;6007.549433792483;6007.6996150192535;4958.355413796201;5867.354590994987;5884.440978107094;5926.051831706928;5940.287165540141;5878.794242201524;6038.637681739087;6087.391260873921;6091.695415229243;6096.695165241742;5588.86027849303;5951.166836642873;6052.939470605282;6069.241344831896;6079.574429789916;4991.151504244287;5145.125328694094;5172.379314482287;5181.766820493087;5189.351331083614;25559.61660575093;25964.480710385877;27172.913083476637;27234.31914202141;27264.72735272642;2199.681046248301;2207.4812419081504;2255.875926824024;2304.896279667419;2360.1267531013464;14906.701865962728;15211.315490802941;15356.846431535765;15380;15754.527364179094;1715.5968347438343;1752.6407086547226;1804.3865085870736;1827.1321122466854;1918.8177123173277;113668;118079;118256;119791;123053.00155489864;10990.835137472945;11163.218574699693;11442.256253343507;11461.255018423775;11601.76796464066;14288.071275367069;14370.778483828783;14380.424783008772;14401.639959000899;14414.351354189086;7445.478816482794;7870.252326029018;8754.430961987451;8887.644494220158;8929.419587726778;8633.007204171476;8711.39020268575;8925.285977121815;9036.141566551298;9072.591733372014;6213.254409470893;6342.175517182736;6780.593164410053;6851.383375496229;6890.1741038624805;1852.1387554787088;1888.1408958923932;1888.7355770192185;1890.2627975089727;1918.6642337590865;936.9625214991461;962.6245764151986;966.7486453522135;1014.1582486536157;1052.379096333152;17985.370512032016;18857.24571017144;21256.25603103884;21898.89050554737;21943.561128777656;907.9909200908038;992.3004281981185;1035.2649618770606;1059.8463222832645;1076.6769969009265;11324.886751132548;11586.304821710724;11685;11721.296722196696;11745.765084698258;550510;556242;564451;570425;571132;99329;115384.42307788575;120242;127272.36363818121;131064;431.0903992575663;440.3900597672183;453.0712040317336;454.1076784119156;464.3359995206819;10746.677599672023;10776;11294.774104517699;11474.655760327203;11586.304821710724;36591;50342.993140137725;51219.97560048853;52158.95682086261;52472.43685215394;53539;58736.412635873094;73834;75937;78317.4336513278;7915.366770658218;8019.558924258989;8069.193080692119;8403.327733781405;8407.747767566983;9103.226225770884;11945.223560468541;12639.873601264237;13052.934735326262;13096.214227146402;10967.616133435291;11215;11522.777700041883;11544.538218471167;11573.421328933688;10784.676459706221;10905.672829815117;10963.561457541928;11296.49165787525;11335.26320789179;11115.777264500737;11437.59968401102;11456.312621242752;11621.47703353368;11622.82565761531;8049;8346.749597512084;8368.372372071997;8402.915970840213;8502.36232282569;10038.949805251219;11264.0425563828;11385.9183377579;11432.94283528577;11549.88450115488 20:11:28 INFO - PROCESS | 1682 | |3;mootools.html;1287.770179478598;1596.1699916043654;1599.3922309522382;1835.6145209506003;1963.4993076765422;7217.747378841742;8560.785980350494;8819.955900220506;8824.073472285414;8845.601947912352;8496.447730897487;8592.183742544465;8709.86935195971;8709.91290087099;8722.345824063188;2105.7157283766724;2198.7141671582754;2387.9403014924637;2422.2248880358206;2424.4787370715294;2196.8901554922268;2233.3411643565187;2397.9040838366445;2468.987655061731;2473.5423946569854;2408.8072954163713;2416.395901024744;2416.66166736657;2417.5890098683267;2419.080749315258;2392.497575509145;2434.0993832281993;2444.486657801855;2445.400876785181;2448.510297940419;2116.2381542644634;2365.727941286757;2447.963280550793;2447.9755202447927;2453.288546321565;8471.279941205004;8543.658253669846;8548.358873084482;8551;8560.785980350478;5108.233764935252;5322.441143679896;5361.81233656824;5369.355677318707;5381.246625472437;3551.6448355164534;3563.875264365735;3607.458881167846;3671.908202294964;3710.3135919854913;1036.9014943580423;1051.8106740786654;1052.2581579986092;1053.9156867450586;1055.482813421418;11641.534338626445;11653.766924661459;11671.474783634756;11675.065994720402;11727.88272117285;4754.405699287589;4769.136703418128;4809.278608208797;4832;4893.437254715754;9906.405615663083;9933.50332483373;9937.155341795884;9938.354006989523;9952.00479951999;5369.4630536946;5390.16452449863;5404.729763511808;5409.107497262986;5422.322209723785;4336.522982471925;4690.742009189527;4850.660453768273;4916.631252655993;4943.431505376856;3512.682743971011;3694.0026192927758;3756.154865155318;3840.5007349044445;3844.0774214188395;962.9374090684083;1217.8782121787751;1220.0239808153442;1232.0451649971344;1232.8890399864056;789.9368050555942;883.1212943121635;893.7229458867641;933.0249888866151;941.9670311539063;5703.344115426696;5707.571932105108;5821.039528477754;5905.232319798484;5962.469583045108;943.8159558886089;950.8668786369914;964.1081999150757;966.970990870261;992.1269283030887;3806.4290356446754;3847.9230415392094;3934.626210509997;3940.1134744682217;3962.4650672159464;8891.599878005505;9978.351407158512;10166.745831354126;10559.683209503573;10616.416097114732;9080.727578172666;10253.384796912209;10572.788544229072;10581.735456613646;10676.572937082585;7739.029482254451;8080.39397045224;8110.716124935599;8131.552764598004;8146.959265203636;5545.140503222007;5791.391903850204;5941.31674857397;6031.457168854824;6034.969825150846;9910.15763660097;9986;10073.89926100759;10094.54574544162;10096;9282.953585232031;9719.85420218683;9786.266030047633;9883.752906177404;10077.992200780156;6707.295733947865;7279.890801638086;7523;7539.019927409402;7552.735654252075;7487.700491980259;7893.842123157619;8144.714934977247;8145.226203510894;8146.348292136733;7128.144622645315;7219.061522002106;7221;7346.11846578414;7377.8155546111775;7144.285571442814;7157.892631610634;7202.423806095395;7259.709611615476;7314.743983960536;6844.076049733222;6865.210500792473;6905.827354316182;6960.060391847036;7005.929940700732;4853.490383509822;4885.828995985124;4893.192623217129;5003.574696150868;5078.111330517218;4906.3376444181;4926.433460152129;4947.703137811743;4994.925076123934;5152.175651895679 20:11:28 INFO - PROCESS | 1682 | |4;prototype.html;293.3034044145155;312.865467848825;313.38889166126046;321.50488248097923;321.93400352927654;5315.229291752694;5344.331958505187;5346.743515273913;5352.464753524646;5408.513233808956;4980.726060066695;5021.648484606079;5221.451747566498;5267.736613169345;5296.557237795435;3812.0660438192544;3887.2031233597045;3987.5046857428465;4092.1815636872598;4111.856085037024;3247.9350412991726;3993.921641156886;4090.836366545335;4094.34490481523;4128.97935510322;3296.8516416761304;3605.296967091418;3823.9044023899346;4030.9395359069636;4035.1122752994293;3339.5157702133174;3555.4311310190506;3847.942280865789;4059.106996460774;4100.774457404841;3536.328097661436;3558.3061303045915;3564.144605294711;3565.536480257576;3573.356795776759;4750.738709370983;4859.635527335428;5125.720991785033;5133.974330128362;5150.61370397218;4471.128130014648;4677.695949763254;4694.037722266927;4694.967107236403;4707.364505791743;5490.313710786152;5513.09034009388;5546.334439867202;5571.164325351189;5616.107704532169;3104.4101620691995;3256.8697252109887;3538.5576802899636;3927.8821635350987;3958.4260282259197;5386.003589335985;5583.972080139573;5888.676122813244;5890.705464726747;5920.703964801743;3253.5119732040384;3281.343731253759;3884.2425726983533;3884.9316437979783;3915.784631845219;3234.8677962712864;3373.2916087621384;3981.1838573092446;3985.720999530005;3988.720789544762;4073.327900896378;4421.845235416809;4457.197704413236;4599.287110497811;4612.446506419251;5536.090048933112;5541.639793413416;5577.860553486195;5635.718214089279;5693.686847223443;1158.5423757615695;1311.8556958734532;1362.9709569275133;1400.0899415380084;1446.4431193990179;809.720646376999;811.7524155132623;917.6237742525533;944.8677185194078;966.7389804752818;4411.2941929291155;4666.789994450258;5008.398992120896;5156.756810787319;5182.663126896815;4797.363098359414;5064.139096353629;5399;5731.828045158652;5893.379320686846;4584.922543202345;4935.703857768545;5248.396434410091;5261.053010458154;5265.921011184835;3203.2952750394875;3273.2798784267425;3306.93386132276;3313.7017668409476;3327.667233276653;5539.473749993744;6499.935000649933;6502.512311576556;6535.248446428626;6543.5419520633095;5972.552058595623;6209.5407579218845;6408.7756928508215;6413.16628838248;6514.250861150934;5464.748474657572;6559.6064236145985;6600.174978127734;6610.8347291318105;6622.867542649216;5339.626226164131;6025.698715064317;6319.8104056878365;6336.20797400325;6343.746250149942;6149.938500614937;6181.5363847710705;6187.628742275478;6189.381061893775;6225.3774622537385;5512.503874651301;5744.454276843693;5789.56578256624;6528.640924749185;6593.538452308292;3529.5588051493564;3587.820608969593;3615.6565126312958;4062.695297852732;4079.8572049978106;4923.212286034217;5773.451522105393;5783.074708046693;5793.652380857162;5804.564657650609;5407.378151512469;5500.42245564226;5516.91724624139;5588.12211710961;5648.717564121695;4917.557419832232;5559.749811258423;5580;5581.846256864858;5598.636088654224;5196.9731804595485;5469.617126801086;5491.670499770026;5493.340799104133;5509.787846673726;4205.76868272248;4669.7898594564;5287.867803304948;5581.74882130297;5655.485901246417;5101.928594995093;5144.739538813014;5516.834494965157;5544.750486228048;5578.191162281528 20:11:28 INFO - PROCESS | 1682 | |5;yui.html;663.6980174020821;667.9398854103135;702.8524009957912;704.925982771809;749.8427509422561;14673.70652586949;15138.772918406232;15139;15159.46941857034;15162.545123646281;13863.098898571587;13911.513097041605;13926.442942282296;13946.02377833552;13956.441742330295;718.4791026505794;740.4335683202354;889.5730049576207;892.1168043636801;932.2448816458656;896.551724137931;914.7438717159173;915.6566287642135;918.9908100919006;920.4891285336636;958.1328897347911;958.9041095890425;978.838491648877;979.5298256836722;979.8138353712808;736.74950516824;924.5562130177485;960.3901522533172;967.970960871175;970.3062310448029;713.3401603516778;713.9393151582121;717.7057406463325;719.6186021408662;723.5513981331555;14876.479323223635;14939.70120597582;14986.850131498655;14990.550283491513;15074.698506029928;12191.390430478441;12351.19717218378;12358.258504489759;12684.492620295177;12774.552890648876;9745.12293893553;10141.290109692325;10303.587856485732;10447.738806529822;10927.562897484091;3197.232664160608;3535.063208249816;3572.2498275362204;3598.6636103495225;3611.3138503684218;80610;84346.89132771669;86121;87184.56407717984;87455;9282;9340.299477539213;9498.762530936781;9553.04469553039;9563.856542151872;20853.89573052125;20907.58184836295;20948.266810661553;20960.47598810042;20968.685469717966;7536.521734825725;7592.164861865191;7612.467127301035;7799.220077992155;7841.659186375773;3434.6050204226835;4112.451230863681;4157.605027522381;4201.243776120266;4241.893952651147;3533.94699079514;3600.6219346968714;3601.4597810328137;3605.5853576839;3608.1701208722143;603.8158361699636;606.6238931862274;617.4628073575927;643.3052303512195;659.7097277198018;504.1681225977094;542.533421257718;544.4430119896413;552.3316786688072;580.9099589563624;7886.369090472748;8164.918350816534;8341.541215233221;8497.320214382835;8682.913170868336;528.8281308574651;565.5673409841393;581.2327727399792;619.0868469008267;622.1445512420422;3719.497867787868;3781.9810900945317;3807.200487897517;3999.3626513702857;4047.2714911316248;102736.97263027423;103096.45355319676;105090.4745476283;107327;107786.383204252;82739;82979.17020829715;83498.16501834904;84478;85268.72096918551;72079;72487;72859;73082;73270.26729732848;8090.474119182234;8103.473274237156;8263.338932885476;8267.214614611603;8270.172982701682;45382.31926521171;45948;46289.76855115703;46714;47446;60077.39922600893;61113.69443152934;62161.43255646641;64469;64649.67675161594;11515.366654834064;11566;11631.6510504685;11876.109291802977;12347.506099755909;10137.797244055226;12090.153689241668;12222.694432639255;12599.43302551369;12710.046746493865;9756.756081098029;11564.24832385926;11782.705432364259;11809.759975202824;12206.465575007067;11353.829692554453;11656.708582285171;11995.160338776202;11999.1600587958;12001.63995080149;11417.543298267976;11495.54017839277;11662.300261984308;11777.528898844294;11899.167058305835;9646.662366817127;9655.468949207861;9703.660371887232;9823.508824558601;10643.09533689614;9261.120193581599;9680.981288565734;10039.466582154115;10042.545604033614;10087.010163622956 20:11:28 INFO - PROCESS | 1682 | __end_tp_report 20:11:28 INFO - PROCESS | 1682 | __start_cc_report 20:11:28 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1549 20:11:28 INFO - PROCESS | 1682 | __end_cc_report 20:11:28 INFO - PROCESS | 1682 | __startTimestamp1461640288352__endTimestamp 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | ------- Summary: start ------- 20:11:28 INFO - PROCESS | 1682 | Number of tests: 6 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#0] dojo.html Cycles:165 Average:5656.70 Median:5869.44 stddev:633.26 (10.8%) stddev-sans-first:609.11 20:11:28 INFO - PROCESS | 1682 | Values: 3363.2 3414.7 3426.1 3450.1 3484.9 5521.6 5631.2 5670.5 5692.2 5709.3 5624.6 5705.8 5708.9 5868.5 5869.4 5032.6 5039.1 5067.5 5080.4 5160.2 4551.8 4936.4 5145.2 5163.8 5247.8 5017.2 5072.7 5112.2 5126.8 5178.0 5104.0 5117.5 5118.2 5127.2 5127.4 5038.8 5088.3 5102.3 5123.4 5125.6 5465.5 5580.4 5614.5 5713.1 5718.1 6113.8 6140.1 6144.7 6156.0 6168.6 6148.8 6208.4 6221.8 6224.2 6228.9 5564.3 5593.5 5642.5 5670.6 5690.6 6260.3 6265.2 6271.5 6281.6 6284.1 6054.7 6113.9 6121.7 6127.5 6162.1 6079.2 6125.8 6139.5 6221.7 6251.6 6047.4 6051.8 6062.9 6078.5 6134.4 6098.2 6099.8 6107.9 6109.8 6121.5 4784.0 4797.0 4861.9 4866.4 4874.0 4367.2 4393.4 4423.0 4428.5 4430.6 5572.4 5622.2 5628.3 5658.3 5697.2 6147.4 6226.8 6255.6 6273.8 6275.1 5496.6 5586.9 5593.8 5625.4 5626.0 5243.7 5280.7 5325.3 5326.8 5349.4 5232.9 6229.4 6237.7 6258.9 6279.2 6070.2 6131.2 6241.4 6242.6 6270.7 6198.3 6225.3 6243.3 6263.1 6309.3 6098.6 6108.9 6112.0 6129.8 6144.3 4778.7 6159.8 6164.8 6176.3 6201.4 5792.1 5937.6 5938.9 5955.2 5960.0 5903.9 5910.1 5915.1 5931.8 5933.3 5694.0 5840.8 5841.1 5846.5 5847.0 6025.7 6050.4 6056.3 6091.1 6093.9 5874.8 5879.4 5886.1 5888.1 5890.8 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#1] ext.html Cycles:180 Average:68950.46 Median:19132.41 stddev:151872.15 (793.8%) stddev-sans-first:152211.25 20:11:28 INFO - PROCESS | 1682 | Values: 505.2 530.0 545.4 559.5 568.1 29791.6 30242.7 30261.8 37256.8 38417.8 42428.0 42868.0 43025.7 43096.9 43137.8 8988.4 9231.5 9538.9 9787.0 9807.2 8424.6 8646.7 9563.0 11147.7 11412.7 11307.2 11440.5 11443.1 11456.0 11459.0 8443.8 11432.7 11487.8 11532.9 11535.0 11124.9 11423.4 11423.7 11425.9 11433.9 37299.8 37637.2 37755.0 37808.8 37875.6 3210.8 3302.5 3430.1 3478.0 3550.1 27102.6 27329.7 27801.0 28096.3 28178.6 2710.2 2721.9 2764.4 2816.3 2842.4 493237.0 494027.0 494703.0 495904.0 503458.0 60281.7 60418.7 60746.0 60923.7 60931.7 60815.0 62017.8 62093.0 62138.7 62223.1 16412.8 16551.5 16667.3 16680.8 16684.4 11637.4 11840.8 12795.7 12868.2 12881.5 16682.3 16885.8 16911.2 18480.4 18998.7 6741.5 7804.1 8007.2 8008.8 8051.3 915.4 928.8 935.4 963.1 1061.8 27951.4 28183.7 28658.0 28775.1 28800.6 1070.4 1088.8 1125.3 1126.1 1143.1 15078.5 15098.7 15417.4 15555.8 15787.3 627520.0 637472.0 637645.0 640278.0 641350.0 58681.5 59201.7 59273.0 59352.7 59369.7 1255.7 1440.2 1481.4 1484.7 1486.7 28612.1 28971.4 28996.7 29447.9 29479.3 50666.5 51094.0 51107.5 51159.0 51212.0 547079.0 564463.0 564825.2 565753.0 566209.0 22077.7 22516.9 22537.2 26649.6 27157.5 40632.4 40781.8 41208.8 41593.4 42646.0 34786.8 35328.3 36039.9 36848.8 37029.1 36197.9 36303.3 36701.8 37080.4 37092.4 28488.7 34241.8 35520.3 35596.8 35614.0 22448.8 28888.6 30564.5 30901.1 31041.4 21599.6 21670.8 21773.7 21948.6 22166.7 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#2] jquery.html Cycles:180 Average:33312.62 Median:2280.39 stddev:93923.18 (4118.7%) stddev-sans-first:94154.12 20:11:28 INFO - PROCESS | 1682 | Values: 387.0 390.1 395.4 396.1 407.8 11219.6 20378.7 20401.9 21031.7 21045.3 14098.4 16526.6 20091.3 20234.7 21176.2 4941.4 5953.3 6005.3 6007.5 6007.7 4958.4 5867.4 5884.4 5926.1 5940.3 5878.8 6038.6 6087.4 6091.7 6096.7 5588.9 5951.2 6052.9 6069.2 6079.6 4991.2 5145.1 5172.4 5181.8 5189.4 25559.6 25964.5 27172.9 27234.3 27264.7 2199.7 2207.5 2255.9 2304.9 2360.1 14906.7 15211.3 15356.8 15380.0 15754.5 1715.6 1752.6 1804.4 1827.1 1918.8 113668.0 118079.0 118256.0 119791.0 123053.0 10990.8 11163.2 11442.3 11461.3 11601.8 14288.1 14370.8 14380.4 14401.6 14414.4 7445.5 7870.3 8754.4 8887.6 8929.4 8633.0 8711.4 8925.3 9036.1 9072.6 6213.3 6342.2 6780.6 6851.4 6890.2 1852.1 1888.1 1888.7 1890.3 1918.7 937.0 962.6 966.7 1014.2 1052.4 17985.4 18857.2 21256.3 21898.9 21943.6 908.0 992.3 1035.3 1059.8 1076.7 11324.9 11586.3 11685.0 11721.3 11745.8 550510.0 556242.0 564451.0 570425.0 571132.0 99329.0 115384.4 120242.0 127272.4 131064.0 431.1 440.4 453.1 454.1 464.3 10746.7 10776.0 11294.8 11474.7 11586.3 36591.0 50343.0 51220.0 52159.0 52472.4 53539.0 58736.4 73834.0 75937.0 78317.4 7915.4 8019.6 8069.2 8403.3 8407.7 9103.2 11945.2 12639.9 13052.9 13096.2 10967.6 11215.0 11522.8 11544.5 11573.4 10784.7 10905.7 10963.6 11296.5 11335.3 11115.8 11437.6 11456.3 11621.5 11622.8 8049.0 8346.7 8368.4 8402.9 8502.4 10038.9 11264.0 11385.9 11432.9 11549.9 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#3] mootools.html Cycles:180 Average:5632.16 Median:5237.31 stddev:3114.63 (59.5%) stddev-sans-first:3106.25 20:11:28 INFO - PROCESS | 1682 | Values: 1287.8 1596.2 1599.4 1835.6 1963.5 7217.7 8560.8 8820.0 8824.1 8845.6 8496.4 8592.2 8709.9 8709.9 8722.3 2105.7 2198.7 2387.9 2422.2 2424.5 2196.9 2233.3 2397.9 2469.0 2473.5 2408.8 2416.4 2416.7 2417.6 2419.1 2392.5 2434.1 2444.5 2445.4 2448.5 2116.2 2365.7 2448.0 2448.0 2453.3 8471.3 8543.7 8548.4 8551.0 8560.8 5108.2 5322.4 5361.8 5369.4 5381.2 3551.6 3563.9 3607.5 3671.9 3710.3 1036.9 1051.8 1052.3 1053.9 1055.5 11641.5 11653.8 11671.5 11675.1 11727.9 4754.4 4769.1 4809.3 4832.0 4893.4 9906.4 9933.5 9937.2 9938.4 9952.0 5369.5 5390.2 5404.7 5409.1 5422.3 4336.5 4690.7 4850.7 4916.6 4943.4 3512.7 3694.0 3756.2 3840.5 3844.1 962.9 1217.9 1220.0 1232.0 1232.9 789.9 883.1 893.7 933.0 942.0 5703.3 5707.6 5821.0 5905.2 5962.5 943.8 950.9 964.1 967.0 992.1 3806.4 3847.9 3934.6 3940.1 3962.5 8891.6 9978.4 10166.7 10559.7 10616.4 9080.7 10253.4 10572.8 10581.7 10676.6 7739.0 8080.4 8110.7 8131.6 8147.0 5545.1 5791.4 5941.3 6031.5 6035.0 9910.2 9986.0 10073.9 10094.5 10096.0 9283.0 9719.9 9786.3 9883.8 10078.0 6707.3 7279.9 7523.0 7539.0 7552.7 7487.7 7893.8 8144.7 8145.2 8146.3 7128.1 7219.1 7221.0 7346.1 7377.8 7144.3 7157.9 7202.4 7259.7 7314.7 6844.1 6865.2 6905.8 6960.1 7005.9 4853.5 4885.8 4893.2 5003.6 5078.1 4906.3 4926.4 4947.7 4994.9 5152.2 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#4] prototype.html Cycles:180 Average:4613.22 Median:5169.71 stddev:1483.93 (28.7%) stddev-sans-first:1452.24 20:11:28 INFO - PROCESS | 1682 | Values: 293.3 312.9 313.4 321.5 321.9 5315.2 5344.3 5346.7 5352.5 5408.5 4980.7 5021.6 5221.5 5267.7 5296.6 3812.1 3887.2 3987.5 4092.2 4111.9 3247.9 3993.9 4090.8 4094.3 4129.0 3296.9 3605.3 3823.9 4030.9 4035.1 3339.5 3555.4 3847.9 4059.1 4100.8 3536.3 3558.3 3564.1 3565.5 3573.4 4750.7 4859.6 5125.7 5134.0 5150.6 4471.1 4677.7 4694.0 4695.0 4707.4 5490.3 5513.1 5546.3 5571.2 5616.1 3104.4 3256.9 3538.6 3927.9 3958.4 5386.0 5584.0 5888.7 5890.7 5920.7 3253.5 3281.3 3884.2 3884.9 3915.8 3234.9 3373.3 3981.2 3985.7 3988.7 4073.3 4421.8 4457.2 4599.3 4612.4 5536.1 5541.6 5577.9 5635.7 5693.7 1158.5 1311.9 1363.0 1400.1 1446.4 809.7 811.8 917.6 944.9 966.7 4411.3 4666.8 5008.4 5156.8 5182.7 4797.4 5064.1 5399.0 5731.8 5893.4 4584.9 4935.7 5248.4 5261.1 5265.9 3203.3 3273.3 3306.9 3313.7 3327.7 5539.5 6499.9 6502.5 6535.2 6543.5 5972.6 6209.5 6408.8 6413.2 6514.3 5464.7 6559.6 6600.2 6610.8 6622.9 5339.6 6025.7 6319.8 6336.2 6343.7 6149.9 6181.5 6187.6 6189.4 6225.4 5512.5 5744.5 5789.6 6528.6 6593.5 3529.6 3587.8 3615.7 4062.7 4079.9 4923.2 5773.5 5783.1 5793.7 5804.6 5407.4 5500.4 5516.9 5588.1 5648.7 4917.6 5559.7 5580.0 5581.8 5598.6 5197.0 5469.6 5491.7 5493.3 5509.8 4205.8 4669.8 5287.9 5581.7 5655.5 5101.9 5144.7 5516.8 5544.8 5578.2 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - PROCESS | 1682 | [#5] yui.html Cycles:180 Average:18810.41 Median:548.39 stddev:27227.36 (4965.0%) stddev-sans-first:27269.65 20:11:28 INFO - PROCESS | 1682 | Values: 663.7 667.9 702.9 704.9 749.8 14673.7 15138.8 15139.0 15159.5 15162.5 13863.1 13911.5 13926.4 13946.0 13956.4 718.5 740.4 889.6 892.1 932.2 896.6 914.7 915.7 919.0 920.5 958.1 958.9 978.8 979.5 979.8 736.7 924.6 960.4 968.0 970.3 713.3 713.9 717.7 719.6 723.6 14876.5 14939.7 14986.9 14990.6 15074.7 12191.4 12351.2 12358.3 12684.5 12774.6 9745.1 10141.3 10303.6 10447.7 10927.6 3197.2 3535.1 3572.2 3598.7 3611.3 80610.0 84346.9 86121.0 87184.6 87455.0 9282.0 9340.3 9498.8 9553.0 9563.9 20853.9 20907.6 20948.3 20960.5 20968.7 7536.5 7592.2 7612.5 7799.2 7841.7 3434.6 4112.5 4157.6 4201.2 4241.9 3533.9 3600.6 3601.5 3605.6 3608.2 603.8 606.6 617.5 643.3 659.7 504.2 542.5 544.4 552.3 580.9 7886.4 8164.9 8341.5 8497.3 8682.9 528.8 565.6 581.2 619.1 622.1 3719.5 3782.0 3807.2 3999.4 4047.3 102737.0 103096.5 105090.5 107327.0 107786.4 82739.0 82979.2 83498.2 84478.0 85268.7 72079.0 72487.0 72859.0 73082.0 73270.3 8090.5 8103.5 8263.3 8267.2 8270.2 45382.3 45948.0 46289.8 46714.0 47446.0 60077.4 61113.7 62161.4 64469.0 64649.7 11515.4 11566.0 11631.7 11876.1 12347.5 10137.8 12090.2 12222.7 12599.4 12710.0 9756.8 11564.2 11782.7 11809.8 12206.5 11353.8 11656.7 11995.2 11999.2 12001.6 11417.5 11495.5 11662.3 11777.5 11899.2 9646.7 9655.5 9703.7 9823.5 10643.1 9261.1 9681.0 10039.5 10042.5 10087.0 20:11:28 INFO - PROCESS | 1682 | -------- Summary: end -------- 20:11:28 INFO - PROCESS | 1682 | 20:11:28 INFO - TEST-INFO | 1682: exit 0 20:11:28 INFO - TEST-OK | dromaeo_css | took 1115567ms 20:11:28 INFO - TEST-START | kraken 20:11:28 INFO - operating with platform_type : mac_ 20:11:28 INFO - Initialising browser for kraken test... 20:11:28 INFO - TEST-INFO | started process 1803 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpsRUXac/profile http://localhost:49270/getInfo.html) 20:11:31 INFO - PROCESS | 1803 | __metrics Screen width/height:1600/1200 20:11:31 INFO - PROCESS | 1803 | colorDepth:24 20:11:31 INFO - PROCESS | 1803 | Browser inner width/height: 1024/689 20:11:31 INFO - PROCESS | 1803 | __metrics 20:11:31 INFO - PROCESS | 1803 | 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" 20:11:31 ERROR - PROCESS | 1803 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:11:32 INFO - TEST-INFO | 1803: exit 0 20:11:32 INFO - Browser initialized. 20:11:32 INFO - Running cycle 1/1 for kraken test... 20:11:32 INFO - TEST-INFO | started process 1806 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpsRUXac/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:44 INFO - PROCESS | 1806 | RSS: Main: 239484928 20:11:44 INFO - PROCESS | 1806 | 20:11:46 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 20:11:47 INFO - PROCESS | 1806 | RSS: Main: 286908416 20:11:47 INFO - PROCESS | 1806 | 20:11:50 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 20:11:50 INFO - PROCESS | 1806 | RSS: Main: 300195840 20:11:50 INFO - PROCESS | 1806 | 20:11:54 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 20:11:55 INFO - PROCESS | 1806 | RSS: Main: 387076096 20:11:55 INFO - PROCESS | 1806 | 20:11:57 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 20:11:57 INFO - PROCESS | 1806 | RSS: Main: 288485376 20:11:57 INFO - PROCESS | 1806 | 20:11:59 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:12:00 INFO - PROCESS | 1806 | RSS: Main: 292622336 20:12:00 INFO - PROCESS | 1806 | 20:12:03 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 20:12:03 INFO - PROCESS | 1806 | RSS: Main: 329711616 20:12:03 INFO - PROCESS | 1806 | 20:12:06 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 20:12:07 INFO - PROCESS | 1806 | RSS: Main: 331505664 20:12:07 INFO - PROCESS | 1806 | 20:12:10 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 20:12:10 INFO - PROCESS | 1806 | RSS: Main: 329441280 20:12:10 INFO - PROCESS | 1806 | 20:12:12 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:12:12 INFO - PROCESS | 1806 | RSS: Main: 322109440 20:12:12 INFO - PROCESS | 1806 | 20:12:14 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:12:15 INFO - PROCESS | 1806 | RSS: Main: 329256960 20:12:15 INFO - PROCESS | 1806 | 20:12:17 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:12:18 INFO - PROCESS | 1806 | RSS: Main: 330465280 20:12:18 INFO - PROCESS | 1806 | 20:12:21 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:12:21 INFO - PROCESS | 1806 | RSS: Main: 331751424 20:12:21 INFO - PROCESS | 1806 | 20:12:24 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:12:25 INFO - PROCESS | 1806 | RSS: Main: 327835648 20:12:25 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 20:12:27 INFO - PROCESS | 1806 | RSS: Main: 392192000 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | __start_tp_report 20:12:27 INFO - PROCESS | 1806 | _x_x_mozilla_page_load 20:12:27 INFO - PROCESS | 1806 | _x_x_mozilla_page_load_details 20:12:27 INFO - PROCESS | 1806 | |i|pagename|runs| 20:12:27 INFO - PROCESS | 1806 | |0;ai-astar;111;112;109;112;112;112;113;112;103;113 20:12:27 INFO - PROCESS | 1806 | |1;audio-beat-detection;158;124;146;170;120;140;161;101;194;148 20:12:27 INFO - PROCESS | 1806 | |2;audio-dft;187;183;184;185;185;196;263;180;185;188 20:12:27 INFO - PROCESS | 1806 | |3;audio-fft;76;70;90;92;55;58;58;83;86;56 20:12:27 INFO - PROCESS | 1806 | |4;audio-oscillator;89;97;138;77;80;83;128;80;81;130 20:12:27 INFO - PROCESS | 1806 | |5;imaging-gaussian-blur;88;84;87;84;88;85;87;85;87;83 20:12:27 INFO - PROCESS | 1806 | |6;imaging-darkroom;93;92;93;94;92;92;92;92;101;147 20:12:27 INFO - PROCESS | 1806 | |7;imaging-desaturate;86;85;85;86;86;84;84;86;86;85 20:12:27 INFO - PROCESS | 1806 | |8;json-parse-financial;61;62;62;60;59;60;61;78;60;58 20:12:27 INFO - PROCESS | 1806 | |9;json-stringify-tinderbox;65;65;73;81;69;69;68;69;67;68 20:12:27 INFO - PROCESS | 1806 | |10;stanford-crypto-aes;86;105;105;106;82;81;81;80;82;81 20:12:27 INFO - PROCESS | 1806 | |11;stanford-crypto-ccm;138;136;139;135;135;132;139;150;242;158 20:12:27 INFO - PROCESS | 1806 | |12;stanford-crypto-pbkdf2;171;172;170;172;171;168;169;169;169;170 20:12:27 INFO - PROCESS | 1806 | |13;stanford-crypto-sha256-iterative;69;68;69;68;66;70;68;69;68;70 20:12:27 INFO - PROCESS | 1806 | __end_tp_report 20:12:27 INFO - PROCESS | 1806 | __start_cc_report 20:12:27 INFO - PROCESS | 1806 | _x_x_mozilla_cycle_collect,1955 20:12:27 INFO - PROCESS | 1806 | __end_cc_report 20:12:27 INFO - PROCESS | 1806 | __startTimestamp1461640347352__endTimestamp 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | ------- Summary: start ------- 20:12:27 INFO - PROCESS | 1806 | Number of tests: 14 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.00 (2.7%) stddev-sans-first:3.18 20:12:27 INFO - PROCESS | 1806 | Values: 111.0 112.0 109.0 112.0 112.0 112.0 113.0 112.0 103.0 113.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:153.00 stddev:26.82 (17.5%) stddev-sans-first:28.10 20:12:27 INFO - PROCESS | 1806 | Values: 158.0 124.0 146.0 170.0 120.0 140.0 161.0 101.0 194.0 148.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#2] audio-dft Cycles:10 Average:193.60 Median:186.00 stddev:24.74 (13.3%) stddev-sans-first:26.12 20:12:27 INFO - PROCESS | 1806 | Values: 187.0 183.0 184.0 185.0 185.0 196.0 263.0 180.0 185.0 188.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#3] audio-fft Cycles:10 Average:72.40 Median:79.50 stddev:14.89 (18.7%) stddev-sans-first:15.74 20:12:27 INFO - PROCESS | 1806 | Values: 76.0 70.0 90.0 92.0 55.0 58.0 58.0 83.0 86.0 56.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:80.50 stddev:24.06 (29.9%) stddev-sans-first:25.28 20:12:27 INFO - PROCESS | 1806 | Values: 89.0 97.0 138.0 77.0 80.0 83.0 128.0 80.0 81.0 130.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.74 20:12:27 INFO - PROCESS | 1806 | Values: 88.0 84.0 87.0 84.0 88.0 85.0 87.0 85.0 87.0 83.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:92.00 stddev:17.16 (18.7%) stddev-sans-first:18.07 20:12:27 INFO - PROCESS | 1806 | Values: 93.0 92.0 93.0 94.0 92.0 92.0 92.0 92.0 101.0 147.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:0.82 (1.0%) stddev-sans-first:0.83 20:12:27 INFO - PROCESS | 1806 | Values: 86.0 85.0 85.0 86.0 86.0 84.0 84.0 86.0 86.0 85.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:61.00 stddev:5.72 (9.4%) stddev-sans-first:6.06 20:12:27 INFO - PROCESS | 1806 | Values: 61.0 62.0 62.0 60.0 59.0 60.0 61.0 78.0 60.0 58.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:4.67 (6.8%) stddev-sans-first:4.68 20:12:27 INFO - PROCESS | 1806 | Values: 65.0 65.0 73.0 81.0 69.0 69.0 68.0 69.0 67.0 68.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:81.00 stddev:11.45 (14.1%) stddev-sans-first:12.10 20:12:27 INFO - PROCESS | 1806 | Values: 86.0 105.0 105.0 106.0 82.0 81.0 81.0 80.0 82.0 81.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:139.00 stddev:33.14 (23.8%) stddev-sans-first:34.84 20:12:27 INFO - PROCESS | 1806 | Values: 138.0 136.0 139.0 135.0 135.0 132.0 139.0 150.0 242.0 158.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:170.50 stddev:1.37 (0.8%) stddev-sans-first:1.41 20:12:27 INFO - PROCESS | 1806 | Values: 171.0 172.0 170.0 172.0 171.0 168.0 169.0 169.0 169.0 170.0 20:12:27 INFO - PROCESS | 1806 | 20:12:27 INFO - PROCESS | 1806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.18 (1.7%) stddev-sans-first:1.24 20:12:27 INFO - PROCESS | 1806 | Values: 69.0 68.0 69.0 68.0 66.0 70.0 68.0 69.0 68.0 70.0 20:12:27 INFO - PROCESS | 1806 | -------- Summary: end -------- 20:12:27 INFO - PROCESS | 1806 | 20:12:28 INFO - TEST-INFO | 1806: exit 0 20:12:28 INFO - TEST-OK | kraken | took 59251ms 20:12:28 INFO - SUITE-END | took 1174s 20:12:28 INFO - Completed test suite (00:19:35) 20:12:28 INFO - javascript benchmark 20:12:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.2264579146795, 3414.7438942079334, 3426.1434641339656, 3450.1029732269603, 3484.947725784112, 5521.613487055908, 5631.18347839563, 5670.518005969492, 5692.174634677975, 5709.314882214135, 5624.634398764078, 5705.77176912923, 5708.942910570893, 5868.501177399926, 5869.442402971711, 5032.6477146599755, 5039.143345631252, 5067.49325067494, 5080.390353157626, 5160.174372100465, 4551.817927282921, 4936.407631084275, 5145.228215767646, 5163.845084647466, 5247.816326428576, 5017.197248440232, 5072.670276432039, 5112.156494178456, 5126.846194614168, 5177.990291893101, 5104.0, 5117.462666420047, 5118.181091025418, 5127.234699467143, 5127.410347810012, 5038.773255203525, 5088.287639730441, 5102.285680004802, 5123.359580052494, 5125.6155788315655, 5465.453454654543, 5580.358258800249, 5614.522765564931, 5713.057345537981, 5718.056520674084, 6113.777244551107, 6140.140380346755, 6144.723487443076, 6156.045812899008, 6168.599041062362, 6148.815535533941, 6208.441240288395, 6221.782237621706, 6224.221972253468, 6228.906566401508, 5564.304461942257, 5593.496585307342, 5642.463965923231, 5670.574706896958, 5690.573207009491, 6260.280067792262, 6265.216847894013, 6271.529635277372, 6281.59169653971, 6284.088807122942, 6054.727537260834, 6113.877722445526, 6121.693915304217, 6127.5404344674325, 6162.075688646739, 6079.209702738615, 6125.815438131368, 6139.522803442411, 6221.688915554295, 6251.593646412968, 6047.365026672224, 6051.7579296827635, 6062.9090563641585, 6078.513718902477, 6134.386561343919, 6098.207233059674, 6099.756009759649, 6107.908381374283, 6109.7861574844665, 6121.540884433685, 4784.0, 4796.952030479721, 4861.854144375674, 4866.391701037371, 4873.975630121898, 4367.213901497697, 4393.384926110347, 4423.049044455393, 4428.512863585003, 4430.645548356124, 5572.3870374258795, 5622.184783206494, 5628.324601047901, 5658.321001479767, 5697.202391665171, 6147.415995480422, 6226.813195604139, 6255.593386429868, 6273.780417685359, 6275.121482992385, 5496.642718223303, 5586.91619625706, 5593.8321850344555, 5625.409332020161, 5626.043572592669, 5243.659162154448, 5280.735963201824, 5325.307709997675, 5326.760295786699, 5349.358077030781, 5232.947670523246, 6229.408206220402, 6237.656928868956, 6258.9374106260175, 6279.215098112736, 6070.180525628984, 6131.233595800525, 6241.43827055549, 6242.625442473466, 6270.717817698123, 6198.256209254918, 6225.28409232944, 6243.250809902841, 6263.123162757127, 6309.305976342691, 6098.63244893404, 6108.949222962717, 6112.047575716935, 6129.817282374446, 6144.341027922525, 4778.737169455713, 6159.753609855556, 6164.7842325518395, 6176.258848938156, 6201.379862013943, 5792.073268277056, 5937.554683398849, 5938.910916336345, 5955.196048533393, 5959.970200148971, 5903.852403689943, 5910.083936989773, 5915.08316210988, 5931.792387266425, 5933.2880054393745, 5694.0, 5840.766369345179, 5841.1238314253205, 5846.473817356458, 5846.970765146147, 6025.66858822769, 6050.42520960508, 6056.333803281724, 6091.0558863376245, 6093.878122437438, 5874.765009399747, 5879.353271140257, 5886.05823068307, 5888.087346461305, 5890.764369425175], "value": 5617.466505775736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.23456962701505, 530.0353356890461, 545.4055079962842, 559.5076332827111, 568.0626965506916, 29791.55312670312, 30242.697573024263, 30261.848690756517, 37256.81371593145, 38417.80791096047, 42428.0, 42868.0, 43025.709228723186, 43096.92257693559, 43137.78431107848, 8988.370814043019, 9231.492267925296, 9538.856917146231, 9787.021297870227, 9807.215422766161, 8424.620892059873, 8646.740597782045, 9562.995885432034, 11147.665570032872, 11412.71468213293, 11307.151963602722, 11440.542378304857, 11443.141764367663, 11456.0, 11459.0, 8443.83112337756, 11432.657020289364, 11487.770244595104, 11532.94233528827, 11535.0, 11124.888751112467, 11423.428828558539, 11423.657290281222, 11425.942870285595, 11433.885661143366, 37299.81350093232, 37637.24725505502, 37755.0, 37808.810955945046, 37875.621243787486, 3210.807351558914, 3302.488114342281, 3430.142464383904, 3478.0001499138098, 3550.0769799851964, 27102.5934610981, 27329.726702732918, 27801.0, 28096.297592560146, 28178.577321340195, 2710.241132482908, 2721.931951701223, 2764.433291175304, 2816.2818481287345, 2842.360468894503, 493237.0, 494027.0, 494703.0, 495904.0, 503458.0, 60281.69859150676, 60418.697906511065, 60746.0, 60923.69538152281, 60931.6953415239, 60815.0, 62017.75964480775, 62093.0, 62138.68930655408, 62223.06665400023, 16412.83587164137, 16551.503454896374, 16667.3333066676, 16680.83319166817, 16684.416045438353, 11637.418129093512, 11840.763184736257, 12795.744085118431, 12868.163569367962, 12881.484740610458, 16682.33270669184, 16885.83114168867, 16911.23899424529, 18480.445586632424, 18998.71501927472, 6741.494387920926, 7804.141544430108, 8007.159248278964, 8008.7597372078935, 8051.315638170821, 915.3821170709745, 928.800307933784, 935.4434111703525, 963.1235575626147, 1061.7717190804015, 27951.44097118087, 28183.71816281852, 28658.0, 28775.136745897657, 28800.567991480144, 1070.4326706845384, 1088.8475613414128, 1125.2517076144436, 1126.1272513801869, 1143.0512674480162, 15078.54764357071, 15098.698026039418, 15417.383304667912, 15555.766663500057, 15787.28957196929, 627520.0, 637472.0, 637645.0, 640278.0, 641350.0, 58681.5329616763, 59201.703991479764, 59273.0, 59352.703236484405, 59369.70315148397, 1255.7174635706892, 1440.2438719672257, 1481.3630139040101, 1484.6882154747625, 1486.7026594681106, 28612.141635750962, 28971.42057158887, 28996.710032899402, 29447.85276073606, 29479.26301842471, 50666.493335066174, 51094.0, 51107.488925111764, 51159.0, 51211.97576048533, 547079.0, 564463.0, 564825.175874118, 565753.0, 566209.0, 22077.668834967808, 22516.88741556282, 22537.211197608005, 26649.60025599579, 27157.456850863266, 40632.39051414172, 40781.79609101936, 41208.79395603003, 41593.37609935793, 42646.0, 34786.82606586951, 35328.29343413169, 36039.91880243597, 36848.81575592105, 37029.07427314231, 36197.914062578166, 36303.273934520636, 36701.81649091737, 37080.44379334258, 37092.44361334636, 28488.715112848608, 34241.828790855885, 35520.28959420849, 35596.82201589079, 35614.0, 22448.77551224467, 28888.566671500364, 30564.541531876595, 30901.072967811, 31041.379172416877, 21599.568008639424, 21670.783292166878, 21773.67339489877, 21948.561028779015, 22166.667499987834], "value": 19560.313124915443, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.9690424766018, 390.145581177222, 395.42268288299084, 396.06528592521653, 407.7797989085894, 11219.640713888433, 20378.69431958522, 20401.897990510028, 21031.68452473212, 21045.26341578045, 14098.365573549188, 16526.586835329123, 20091.29680461184, 20234.69647955282, 21176.188286108496, 4941.407031156266, 5953.3451320354725, 6005.279366476029, 6007.549433792483, 6007.6996150192535, 4958.355413796201, 5867.354590994987, 5884.440978107094, 5926.051831706928, 5940.287165540141, 5878.794242201524, 6038.637681739087, 6087.391260873921, 6091.695415229243, 6096.695165241742, 5588.86027849303, 5951.166836642873, 6052.939470605282, 6069.241344831896, 6079.574429789916, 4991.151504244287, 5145.125328694094, 5172.379314482287, 5181.766820493087, 5189.351331083614, 25559.61660575093, 25964.480710385877, 27172.913083476637, 27234.31914202141, 27264.72735272642, 2199.681046248301, 2207.4812419081504, 2255.875926824024, 2304.896279667419, 2360.1267531013464, 14906.701865962728, 15211.315490802941, 15356.846431535765, 15380.0, 15754.527364179094, 1715.5968347438343, 1752.6407086547226, 1804.3865085870736, 1827.1321122466854, 1918.8177123173277, 113668.0, 118079.0, 118256.0, 119791.0, 123053.00155489864, 10990.835137472945, 11163.218574699693, 11442.256253343507, 11461.255018423775, 11601.76796464066, 14288.071275367069, 14370.778483828783, 14380.424783008772, 14401.639959000899, 14414.351354189086, 7445.478816482794, 7870.252326029018, 8754.430961987451, 8887.644494220158, 8929.419587726778, 8633.007204171476, 8711.39020268575, 8925.285977121815, 9036.141566551298, 9072.591733372014, 6213.254409470893, 6342.175517182736, 6780.593164410053, 6851.383375496229, 6890.1741038624805, 1852.1387554787088, 1888.1408958923932, 1888.7355770192185, 1890.2627975089727, 1918.6642337590865, 936.9625214991461, 962.6245764151986, 966.7486453522135, 1014.1582486536157, 1052.379096333152, 17985.370512032016, 18857.24571017144, 21256.25603103884, 21898.89050554737, 21943.561128777656, 907.9909200908038, 992.3004281981185, 1035.2649618770606, 1059.8463222832645, 1076.6769969009265, 11324.886751132548, 11586.304821710724, 11685.0, 11721.296722196696, 11745.765084698258, 550510.0, 556242.0, 564451.0, 570425.0, 571132.0, 99329.0, 115384.42307788575, 120242.0, 127272.36363818121, 131064.0, 431.0903992575663, 440.3900597672183, 453.0712040317336, 454.1076784119156, 464.3359995206819, 10746.677599672023, 10776.0, 11294.774104517699, 11474.655760327203, 11586.304821710724, 36591.0, 50342.993140137725, 51219.97560048853, 52158.95682086261, 52472.43685215394, 53539.0, 58736.412635873094, 73834.0, 75937.0, 78317.4336513278, 7915.366770658218, 8019.558924258989, 8069.193080692119, 8403.327733781405, 8407.747767566983, 9103.226225770884, 11945.223560468541, 12639.873601264237, 13052.934735326262, 13096.214227146402, 10967.616133435291, 11215.0, 11522.777700041883, 11544.538218471167, 11573.421328933688, 10784.676459706221, 10905.672829815117, 10963.561457541928, 11296.49165787525, 11335.26320789179, 11115.777264500737, 11437.59968401102, 11456.312621242752, 11621.47703353368, 11622.82565761531, 8049.0, 8346.749597512084, 8368.372372071997, 8402.915970840213, 8502.36232282569, 10038.949805251219, 11264.0425563828, 11385.9183377579, 11432.94283528577, 11549.88450115488], "value": 9341.288693637835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.770179478598, 1596.1699916043654, 1599.3922309522382, 1835.6145209506003, 1963.4993076765422, 7217.747378841742, 8560.785980350494, 8819.955900220506, 8824.073472285414, 8845.601947912352, 8496.447730897487, 8592.183742544465, 8709.86935195971, 8709.91290087099, 8722.345824063188, 2105.7157283766724, 2198.7141671582754, 2387.9403014924637, 2422.2248880358206, 2424.4787370715294, 2196.8901554922268, 2233.3411643565187, 2397.9040838366445, 2468.987655061731, 2473.5423946569854, 2408.8072954163713, 2416.395901024744, 2416.66166736657, 2417.5890098683267, 2419.080749315258, 2392.497575509145, 2434.0993832281993, 2444.486657801855, 2445.400876785181, 2448.510297940419, 2116.2381542644634, 2365.727941286757, 2447.963280550793, 2447.9755202447927, 2453.288546321565, 8471.279941205004, 8543.658253669846, 8548.358873084482, 8551.0, 8560.785980350478, 5108.233764935252, 5322.441143679896, 5361.81233656824, 5369.355677318707, 5381.246625472437, 3551.6448355164534, 3563.875264365735, 3607.458881167846, 3671.908202294964, 3710.3135919854913, 1036.9014943580423, 1051.8106740786654, 1052.2581579986092, 1053.9156867450586, 1055.482813421418, 11641.534338626445, 11653.766924661459, 11671.474783634756, 11675.065994720402, 11727.88272117285, 4754.405699287589, 4769.136703418128, 4809.278608208797, 4832.0, 4893.437254715754, 9906.405615663083, 9933.50332483373, 9937.155341795884, 9938.354006989523, 9952.00479951999, 5369.4630536946, 5390.16452449863, 5404.729763511808, 5409.107497262986, 5422.322209723785, 4336.522982471925, 4690.742009189527, 4850.660453768273, 4916.631252655993, 4943.431505376856, 3512.682743971011, 3694.0026192927758, 3756.154865155318, 3840.5007349044445, 3844.0774214188395, 962.9374090684083, 1217.8782121787751, 1220.0239808153442, 1232.0451649971344, 1232.8890399864056, 789.9368050555942, 883.1212943121635, 893.7229458867641, 933.0249888866151, 941.9670311539063, 5703.344115426696, 5707.571932105108, 5821.039528477754, 5905.232319798484, 5962.469583045108, 943.8159558886089, 950.8668786369914, 964.1081999150757, 966.970990870261, 992.1269283030887, 3806.4290356446754, 3847.9230415392094, 3934.626210509997, 3940.1134744682217, 3962.4650672159464, 8891.599878005505, 9978.351407158512, 10166.745831354126, 10559.683209503573, 10616.416097114732, 9080.727578172666, 10253.384796912209, 10572.788544229072, 10581.735456613646, 10676.572937082585, 7739.029482254451, 8080.39397045224, 8110.716124935599, 8131.552764598004, 8146.959265203636, 5545.140503222007, 5791.391903850204, 5941.31674857397, 6031.457168854824, 6034.969825150846, 9910.15763660097, 9986.0, 10073.89926100759, 10094.54574544162, 10096.0, 9282.953585232031, 9719.85420218683, 9786.266030047633, 9883.752906177404, 10077.992200780156, 6707.295733947865, 7279.890801638086, 7523.0, 7539.019927409402, 7552.735654252075, 7487.700491980259, 7893.842123157619, 8144.714934977247, 8145.226203510894, 8146.348292136733, 7128.144622645315, 7219.061522002106, 7221.0, 7346.11846578414, 7377.8155546111775, 7144.285571442814, 7157.892631610634, 7202.423806095395, 7259.709611615476, 7314.743983960536, 6844.076049733222, 6865.210500792473, 6905.827354316182, 6960.060391847036, 7005.929940700732, 4853.490383509822, 4885.828995985124, 4893.192623217129, 5003.574696150868, 5078.111330517218, 4906.3376444181, 4926.433460152129, 4947.703137811743, 4994.925076123934, 5152.175651895679], "value": 4546.381107927906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3034044145155, 312.865467848825, 313.38889166126046, 321.50488248097923, 321.93400352927654, 5315.229291752694, 5344.331958505187, 5346.743515273913, 5352.464753524646, 5408.513233808956, 4980.726060066695, 5021.648484606079, 5221.451747566498, 5267.736613169345, 5296.557237795435, 3812.0660438192544, 3887.2031233597045, 3987.5046857428465, 4092.1815636872598, 4111.856085037024, 3247.9350412991726, 3993.921641156886, 4090.836366545335, 4094.34490481523, 4128.97935510322, 3296.8516416761304, 3605.296967091418, 3823.9044023899346, 4030.9395359069636, 4035.1122752994293, 3339.5157702133174, 3555.4311310190506, 3847.942280865789, 4059.106996460774, 4100.774457404841, 3536.328097661436, 3558.3061303045915, 3564.144605294711, 3565.536480257576, 3573.356795776759, 4750.738709370983, 4859.635527335428, 5125.720991785033, 5133.974330128362, 5150.61370397218, 4471.128130014648, 4677.695949763254, 4694.037722266927, 4694.967107236403, 4707.364505791743, 5490.313710786152, 5513.09034009388, 5546.334439867202, 5571.164325351189, 5616.107704532169, 3104.4101620691995, 3256.8697252109887, 3538.5576802899636, 3927.8821635350987, 3958.4260282259197, 5386.003589335985, 5583.972080139573, 5888.676122813244, 5890.705464726747, 5920.703964801743, 3253.5119732040384, 3281.343731253759, 3884.2425726983533, 3884.9316437979783, 3915.784631845219, 3234.8677962712864, 3373.2916087621384, 3981.1838573092446, 3985.720999530005, 3988.720789544762, 4073.327900896378, 4421.845235416809, 4457.197704413236, 4599.287110497811, 4612.446506419251, 5536.090048933112, 5541.639793413416, 5577.860553486195, 5635.718214089279, 5693.686847223443, 1158.5423757615695, 1311.8556958734532, 1362.9709569275133, 1400.0899415380084, 1446.4431193990179, 809.720646376999, 811.7524155132623, 917.6237742525533, 944.8677185194078, 966.7389804752818, 4411.2941929291155, 4666.789994450258, 5008.398992120896, 5156.756810787319, 5182.663126896815, 4797.363098359414, 5064.139096353629, 5399.0, 5731.828045158652, 5893.379320686846, 4584.922543202345, 4935.703857768545, 5248.396434410091, 5261.053010458154, 5265.921011184835, 3203.2952750394875, 3273.2798784267425, 3306.93386132276, 3313.7017668409476, 3327.667233276653, 5539.473749993744, 6499.935000649933, 6502.512311576556, 6535.248446428626, 6543.5419520633095, 5972.552058595623, 6209.5407579218845, 6408.7756928508215, 6413.16628838248, 6514.250861150934, 5464.748474657572, 6559.6064236145985, 6600.174978127734, 6610.8347291318105, 6622.867542649216, 5339.626226164131, 6025.698715064317, 6319.8104056878365, 6336.20797400325, 6343.746250149942, 6149.938500614937, 6181.5363847710705, 6187.628742275478, 6189.381061893775, 6225.3774622537385, 5512.503874651301, 5744.454276843693, 5789.56578256624, 6528.640924749185, 6593.538452308292, 3529.5588051493564, 3587.820608969593, 3615.6565126312958, 4062.695297852732, 4079.8572049978106, 4923.212286034217, 5773.451522105393, 5783.074708046693, 5793.652380857162, 5804.564657650609, 5407.378151512469, 5500.42245564226, 5516.91724624139, 5588.12211710961, 5648.717564121695, 4917.557419832232, 5559.749811258423, 5580.0, 5581.846256864858, 5598.636088654224, 5196.9731804595485, 5469.617126801086, 5491.670499770026, 5493.340799104133, 5509.787846673726, 4205.76868272248, 4669.7898594564, 5287.867803304948, 5581.74882130297, 5655.485901246417, 5101.928594995093, 5144.739538813014, 5516.834494965157, 5544.750486228048, 5578.191162281528], "value": 4145.342822945127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.6980174020821, 667.9398854103135, 702.8524009957912, 704.925982771809, 749.8427509422561, 14673.70652586949, 15138.772918406232, 15139.0, 15159.46941857034, 15162.545123646281, 13863.098898571587, 13911.513097041605, 13926.442942282296, 13946.02377833552, 13956.441742330295, 718.4791026505794, 740.4335683202354, 889.5730049576207, 892.1168043636801, 932.2448816458656, 896.551724137931, 914.7438717159173, 915.6566287642135, 918.9908100919006, 920.4891285336636, 958.1328897347911, 958.9041095890425, 978.838491648877, 979.5298256836722, 979.8138353712808, 736.74950516824, 924.5562130177485, 960.3901522533172, 967.970960871175, 970.3062310448029, 713.3401603516778, 713.9393151582121, 717.7057406463325, 719.6186021408662, 723.5513981331555, 14876.479323223635, 14939.70120597582, 14986.850131498655, 14990.550283491513, 15074.698506029928, 12191.390430478441, 12351.19717218378, 12358.258504489759, 12684.492620295177, 12774.552890648876, 9745.12293893553, 10141.290109692325, 10303.587856485732, 10447.738806529822, 10927.562897484091, 3197.232664160608, 3535.063208249816, 3572.2498275362204, 3598.6636103495225, 3611.3138503684218, 80610.0, 84346.89132771669, 86121.0, 87184.56407717984, 87455.0, 9282.0, 9340.299477539213, 9498.762530936781, 9553.04469553039, 9563.856542151872, 20853.89573052125, 20907.58184836295, 20948.266810661553, 20960.47598810042, 20968.685469717966, 7536.521734825725, 7592.164861865191, 7612.467127301035, 7799.220077992155, 7841.659186375773, 3434.6050204226835, 4112.451230863681, 4157.605027522381, 4201.243776120266, 4241.893952651147, 3533.94699079514, 3600.6219346968714, 3601.4597810328137, 3605.5853576839, 3608.1701208722143, 603.8158361699636, 606.6238931862274, 617.4628073575927, 643.3052303512195, 659.7097277198018, 504.1681225977094, 542.533421257718, 544.4430119896413, 552.3316786688072, 580.9099589563624, 7886.369090472748, 8164.918350816534, 8341.541215233221, 8497.320214382835, 8682.913170868336, 528.8281308574651, 565.5673409841393, 581.2327727399792, 619.0868469008267, 622.1445512420422, 3719.497867787868, 3781.9810900945317, 3807.200487897517, 3999.3626513702857, 4047.2714911316248, 102736.97263027423, 103096.45355319676, 105090.4745476283, 107327.0, 107786.383204252, 82739.0, 82979.17020829715, 83498.16501834904, 84478.0, 85268.72096918551, 72079.0, 72487.0, 72859.0, 73082.0, 73270.26729732848, 8090.474119182234, 8103.473274237156, 8263.338932885476, 8267.214614611603, 8270.172982701682, 45382.31926521171, 45948.0, 46289.76855115703, 46714.0, 47446.0, 60077.39922600893, 61113.69443152934, 62161.43255646641, 64469.0, 64649.67675161594, 11515.366654834064, 11566.0, 11631.6510504685, 11876.109291802977, 12347.506099755909, 10137.797244055226, 12090.153689241668, 12222.694432639255, 12599.43302551369, 12710.046746493865, 9756.756081098029, 11564.24832385926, 11782.705432364259, 11809.759975202824, 12206.465575007067, 11353.829692554453, 11656.708582285171, 11995.160338776202, 11999.1600587958, 12001.63995080149, 11417.543298267976, 11495.54017839277, 11662.300261984308, 11777.528898844294, 11899.167058305835, 9646.662366817127, 9655.468949207861, 9703.660371887232, 9823.508824558601, 10643.09533689614, 9261.120193581599, 9680.981288565734, 10039.466582154115, 10042.545604033614, 10087.010163622956], "value": 6934.860868732922, "unit": "score"}], "name": "dromaeo_css", "value": 7154.928671424608, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 109.0, 112.0, 112.0, 112.0, 113.0, 112.0, 103.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 146.0, 170.0, 120.0, 140.0, 161.0, 101.0, 194.0, 148.0], "value": 146.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 185.0, 185.0, 196.0, 263.0, 180.0, 185.0, 188.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 90.0, 92.0, 55.0, 58.0, 58.0, 83.0, 86.0, 56.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 97.0, 138.0, 77.0, 80.0, 83.0, 128.0, 80.0, 81.0, 130.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 84.0, 88.0, 85.0, 87.0, 85.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 94.0, 92.0, 92.0, 92.0, 92.0, 101.0, 147.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 86.0, 86.0, 84.0, 84.0, 86.0, 86.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 60.0, 59.0, 60.0, 61.0, 78.0, 60.0, 58.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 73.0, 81.0, 69.0, 69.0, 68.0, 69.0, 67.0, 68.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 105.0, 105.0, 106.0, 82.0, 81.0, 81.0, 80.0, 82.0, 81.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 139.0, 135.0, 135.0, 132.0, 139.0, 150.0, 242.0, 158.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 170.0, 172.0, 171.0, 168.0, 169.0, 169.0, 169.0, 170.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 66.0, 70.0, 68.0, 69.0, 68.0, 70.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1500.6999999999998, "alertThreshold": 2.0}]} 20:12:28 INFO - Return code: 0 20:12:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:12:28 ERROR - # TBPL SUCCESS # 20:12:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:12:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:12:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:12:28 INFO - 2016-04-25 20:12:28.687 screenresolution[1815:9876] starting screenresolution argv=screenresolution get 20:12:28 INFO - 2016-04-25 20:12:28.689 screenresolution[1815:9876] Display 0: 1600x1200x32@60 20:12:28 INFO - 2016-04-25 20:12:28.695 screenresolution[1816:9879] starting screenresolution argv=screenresolution list 20:12:28 INFO - Available Modes on Display 0 20:12:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:12:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:12:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:12:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:12:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:12:28 INFO - Intel Iris: 20:12:28 INFO - Chipset Model: Intel Iris 20:12:28 INFO - Type: GPU 20:12:28 INFO - Bus: Built-In 20:12:28 INFO - VRAM (Dynamic, Max): 1536 MB 20:12:28 INFO - Vendor: Intel (0x8086) 20:12:28 INFO - Device ID: 0x0a2e 20:12:28 INFO - Revision ID: 0x0009 20:12:28 INFO - Displays: 20:12:28 INFO - Display: 20:12:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:12:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:12:28 INFO - Display Serial Number: 1600x1200 60 20:12:28 INFO - Main Display: Yes 20:12:28 INFO - Mirror: Off 20:12:28 INFO - Online: Yes 20:12:28 INFO - Rotation: Supported 20:12:28 INFO - Return code: 0 20:12:28 INFO - Running post-action listener: _resource_record_post_action 20:12:28 INFO - Running post-run listener: _resource_record_post_run 20:12:29 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37692928; Write bytes: 696756736; Read time: 423; Write time: 2045 20:12:29 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382650880; Write bytes: 376690176; Read time: 28436; Write time: 657 20:12:29 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35902976; Write bytes: 298124288; Read time: 405; Write time: 1334 20:12:29 INFO - Running post-run listener: _upload_blobber_files 20:12:29 INFO - Blob upload gear active. 20:12:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:12:29 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 20:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:12:30 INFO - (blobuploader) - INFO - Open directory for files ... 20:12:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:12:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:12:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:32 INFO - (blobuploader) - INFO - Done attempting. 20:12:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:12:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:12:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:33 INFO - (blobuploader) - INFO - Done attempting. 20:12:33 INFO - (blobuploader) - INFO - Iteration through files over. 20:12:33 INFO - Return code: 0 20:12:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:12:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:12:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba9b7f646ef9381133a4df7f8f831d4d792702f29bb9b5bd985f143d122986fe997a72527b36db50dfaaf4a6f21ee3aaacc0fd620c8ee861ee63a1f1e49cb58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/914d4148b2daea3d14ba3a31112e898259f6e7b535bb814f76bcf297b9d61a3271e135b5f1fb983404861b6d3db314f487e909b21235461d5f28b8c08967e5e7"} 20:12:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:12:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:12:33 INFO - Contents: 20:12:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba9b7f646ef9381133a4df7f8f831d4d792702f29bb9b5bd985f143d122986fe997a72527b36db50dfaaf4a6f21ee3aaacc0fd620c8ee861ee63a1f1e49cb58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/914d4148b2daea3d14ba3a31112e898259f6e7b535bb814f76bcf297b9d61a3271e135b5f1fb983404861b6d3db314f487e909b21235461d5f28b8c08967e5e7"} 20:12:33 INFO - Running post-run listener: copy_logs_to_upload_dir 20:12:33 INFO - Copying logs to upload dir... 20:12:33 INFO - mkdir: /builds/slave/test/build/upload/logs 20:12:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.507247 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-25 20:12:34.219499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 20:12:34.223221) ========= 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.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba9b7f646ef9381133a4df7f8f831d4d792702f29bb9b5bd985f143d122986fe997a72527b36db50dfaaf4a6f21ee3aaacc0fd620c8ee861ee63a1f1e49cb58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/914d4148b2daea3d14ba3a31112e898259f6e7b535bb814f76bcf297b9d61a3271e135b5f1fb983404861b6d3db314f487e909b21235461d5f28b8c08967e5e7"} build_url:https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/VH3hQQR6ScCGcgb5nmH-Tg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ba9b7f646ef9381133a4df7f8f831d4d792702f29bb9b5bd985f143d122986fe997a72527b36db50dfaaf4a6f21ee3aaacc0fd620c8ee861ee63a1f1e49cb58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/914d4148b2daea3d14ba3a31112e898259f6e7b535bb814f76bcf297b9d61a3271e135b5f1fb983404861b6d3db314f487e909b21235461d5f28b8c08967e5e7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 20:12:34.261430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 20:12:34.261787) ========= 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.qGfQU585XN/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.ijYiVz7KiG/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005165 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 20:12:34.312602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 20:12:34.312923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 20:12:34.313295) ========= ========= Total master_lag: 3.20 =========