builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0290 starttime: 1463774713.68 results: success (0) buildid: 20160520105741 builduid: 77153f17d4a54554ba738427aa2c8d92 revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.680536) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.681319) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.681597) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.825389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:13.825639) ========= 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.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-20 13:05:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.0M=0.001s 2016-05-20 13:05:14 (20.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.595781 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:14.475686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:14.475992) ========= 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.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.025015 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:14.565332) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 13:05:14.565631) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-20 13:05:14,641 truncating revision to first 12 chars 2016-05-20 13:05:14,641 Setting DEBUG logging. 2016-05-20 13:05:14,641 attempt 1/10 2016-05-20 13:05:14,641 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b4ae8c5edb7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 13:05:15,727 unpacking tar archive at: larch-b4ae8c5edb7e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.336591 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 13:05:15.921212) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.921518) ========= script_repo_revision: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.921892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.922171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 13:05:15.936707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-20 13:05:15.937006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:16 INFO - MultiFileLogger online at 20160520 13:05:16 in /builds/slave/test 13:05:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 13:05:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:05:16 INFO - {'append_to_log': False, 13:05:16 INFO - 'base_work_dir': '/builds/slave/test', 13:05:16 INFO - 'blob_upload_branch': 'Larch', 13:05:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:05:16 INFO - 'branch': 'Larch', 13:05:16 INFO - 'buildbot_json_path': 'buildprops.json', 13:05:16 INFO - 'config_files': ('talos/mac_config.py',), 13:05:16 INFO - 'default_actions': ('clobber', 13:05:16 INFO - 'read-buildbot-config', 13:05:16 INFO - 'download-and-extract', 13:05:16 INFO - 'populate-webroot', 13:05:16 INFO - 'create-virtualenv', 13:05:16 INFO - 'install', 13:05:16 INFO - 'run-tests'), 13:05:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:05:16 INFO - 'download_minidump_stackwalk': True, 13:05:16 INFO - 'download_symbols': 'ondemand', 13:05:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:05:16 INFO - 'tooltool.py': '/tools/tooltool.py', 13:05:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:05:16 INFO - '/tools/misc-python/virtualenv.py')}, 13:05:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:05:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:05:16 INFO - 'installer_path': 'installer.exe', 13:05:16 INFO - 'log_level': 'info', 13:05:16 INFO - 'log_name': 'talos', 13:05:16 INFO - 'log_to_console': True, 13:05:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:05:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:05:16 INFO - 'opt_config_files': (), 13:05:16 INFO - 'pip_index': False, 13:05:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:05:16 INFO - 'cmd': ('bash', 13:05:16 INFO - '-c', 13:05:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:05:16 INFO - 'enabled': True, 13:05:16 INFO - 'halt_on_failure': False, 13:05:16 INFO - 'name': 'check_screen_resolution'},), 13:05:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:05:16 INFO - 'cmd': ('bash', 13:05:16 INFO - '-c', 13:05:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:05:16 INFO - 'enabled': True, 13:05:16 INFO - 'halt_on_failure': False, 13:05:16 INFO - 'name': 'check_screen_resolution'},), 13:05:16 INFO - 'run_cmd_checks_enabled': True, 13:05:16 INFO - 'sps_profile': False, 13:05:16 INFO - 'sps_profile_interval': 0, 13:05:16 INFO - 'suite': 'dromaeojs', 13:05:16 INFO - 'system_bits': '32', 13:05:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:05:16 INFO - 'title': 't-yosemite-r7-0290', 13:05:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:05:16 INFO - 'use_talos_json': True, 13:05:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:05:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:05:16 INFO - 'work_dir': 'build'} 13:05:16 INFO - ##### 13:05:16 INFO - ##### Running clobber step. 13:05:16 INFO - ##### 13:05:16 INFO - Running pre-action listener: _resource_record_pre_action 13:05:16 INFO - Running main action method: clobber 13:05:16 INFO - rmtree: /builds/slave/test/build 13:05:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:05:17 INFO - Running post-action listener: _resource_record_post_action 13:05:17 INFO - ##### 13:05:17 INFO - ##### Finished clobber step (success) 13:05:17 INFO - ##### 13:05:17 INFO - ##### 13:05:17 INFO - ##### Running read-buildbot-config step. 13:05:17 INFO - ##### 13:05:17 INFO - Running pre-action listener: _resource_record_pre_action 13:05:17 INFO - Running main action method: read_buildbot_config 13:05:17 INFO - Using buildbot properties: 13:05:17 INFO - { 13:05:17 INFO - "project": "", 13:05:17 INFO - "product": "firefox", 13:05:17 INFO - "script_repo_revision": "production", 13:05:17 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 13:05:17 INFO - "repository": "", 13:05:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 13:05:17 INFO - "buildid": "20160520105741", 13:05:17 INFO - "pgo_build": "False", 13:05:17 INFO - "basedir": "/builds/slave/test", 13:05:17 INFO - "buildnumber": 0, 13:05:17 INFO - "slavename": "t-yosemite-r7-0290", 13:05:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 13:05:17 INFO - "platform": "yosemite_r7", 13:05:17 INFO - "scheduler": "tests-larch-macosx64-talos", 13:05:17 INFO - "branch": "larch", 13:05:17 INFO - "repo_path": "projects/larch", 13:05:17 INFO - "revision": "b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19", 13:05:17 INFO - "stage_platform": "macosx64", 13:05:17 INFO - "builduid": "77153f17d4a54554ba738427aa2c8d92", 13:05:17 INFO - "slavebuilddir": "test" 13:05:17 INFO - } 13:05:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:05:17 INFO - Running post-action listener: _resource_record_post_action 13:05:17 INFO - ##### 13:05:17 INFO - ##### Finished read-buildbot-config step (success) 13:05:17 INFO - ##### 13:05:17 INFO - ##### 13:05:17 INFO - ##### Running download-and-extract step. 13:05:17 INFO - ##### 13:05:17 INFO - Running pre-action listener: _resource_record_pre_action 13:05:17 INFO - Running main action method: download_and_extract 13:05:17 INFO - mkdir: /builds/slave/test/build/tests 13:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:17 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:05:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:18 INFO - Downloaded 1199 bytes. 13:05:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:05:18 INFO - Using the following test package requirements: 13:05:18 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:05:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:05:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:05:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'jsshell-mac.zip'], 13:05:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:05:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:05:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:05:18 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:05:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:05:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:05:18 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:05:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:05:18 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')]} 13:05:18 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:05:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:20 INFO - Downloaded 18150533 bytes. 13:05:20 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'] 13:05:20 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 13:05:23 INFO - Return code: 0 13:05:23 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 13:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:23 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:05:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:05:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:27 INFO - Downloaded 18150533 bytes. 13:05:27 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'] 13:05:27 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 13:05:31 INFO - Return code: 0 13:05:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:31 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:05:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:05:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:05:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:32 INFO - Downloaded 10840160 bytes. 13:05:32 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'] 13:05:32 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 13:05:33 INFO - Return code: 0 13:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:33 INFO - https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:05:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:05:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dHVPF4YWSGe76N6x1qLb5g/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 13:05:35 INFO - Downloaded 110060727 bytes. 13:05:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:35 INFO - mkdir: /builds/slave/test/properties 13:05:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:05:35 INFO - Writing to file /builds/slave/test/properties/build_url 13:05:35 INFO - Contents: 13:05:35 INFO - build_url:https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:05:36 INFO - Running post-action listener: _resource_record_post_action 13:05:36 INFO - Running post-action listener: set_extra_try_arguments 13:05:36 INFO - ##### 13:05:36 INFO - ##### Finished download-and-extract step (success) 13:05:36 INFO - ##### 13:05:36 INFO - ##### 13:05:36 INFO - ##### Running populate-webroot step. 13:05:36 INFO - ##### 13:05:36 INFO - Running pre-action listener: _resource_record_pre_action 13:05:36 INFO - Running main action method: populate_webroot 13:05:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:05:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:05:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:05:36 INFO - '--tppagecycles', 13:05:36 INFO - '7'], 13:05:36 INFO - 'tests': ['tsvgm']}}, 13:05:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:05:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:05:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:05:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:05:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:05:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['damp', 'tps']}, 13:05:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'tests': ['damp', 'tps']}, 13:05:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['dromaeo_dom']}, 13:05:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:05:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'other-e10s': {'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['a11yr', 13:05:36 INFO - 'ts_paint', 13:05:36 INFO - 'tpaint', 13:05:36 INFO - 'sessionrestore', 13:05:36 INFO - 'sessionrestore_no_auto_restore', 13:05:36 INFO - 'tabpaint']}, 13:05:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['tsvgx', 13:05:36 INFO - 'tsvgr_opacity', 13:05:36 INFO - 'tart', 13:05:36 INFO - 'tscrollx', 13:05:36 INFO - 'cart']}, 13:05:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:05:36 INFO - 'tsvgr_opacity', 13:05:36 INFO - 'tart', 13:05:36 INFO - 'tscrollx', 13:05:36 INFO - 'cart']}, 13:05:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'talos_options': ['--disable-e10s'], 13:05:36 INFO - 'tests': ['tp5o']}, 13:05:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'tests': ['tp5o']}, 13:05:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'talos_options': ['--disable-e10s', 13:05:36 INFO - '--xperf_path', 13:05:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:36 INFO - 'tests': ['tp5n']}, 13:05:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:05:36 INFO - 'talos_options': ['--xperf_path', 13:05:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:05:36 INFO - 'tests': ['tp5n']}}, 13:05:36 INFO - 'talos.zip': {'path': '', 13:05:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:05:36 INFO - Running post-action listener: _resource_record_post_action 13:05:36 INFO - ##### 13:05:36 INFO - ##### Finished populate-webroot step (success) 13:05:36 INFO - ##### 13:05:36 INFO - ##### 13:05:36 INFO - ##### Running create-virtualenv step. 13:05:36 INFO - ##### 13:05:36 INFO - Running pre-action listener: _resource_record_pre_action 13:05:36 INFO - Running main action method: create_virtualenv 13:05:36 INFO - Creating virtualenv /builds/slave/test/build/venv 13:05:36 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 13:05:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:05:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:05:36 INFO - Using real prefix '/tools/python27' 13:05:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:05:37 INFO - Installing distribute.............................................................................................................................................................................................done. 13:05:40 INFO - Installing pip.................done. 13:05:40 INFO - Return code: 0 13:05:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:05:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:40 INFO - 'HOME': '/Users/cltbld', 13:05:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:40 INFO - 'LOGNAME': 'cltbld', 13:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:40 INFO - 'MOZ_NO_REMOTE': '1', 13:05:40 INFO - 'NO_EM_RESTART': '1', 13:05:40 INFO - 'PAGER': '/bin/cat', 13:05:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:40 INFO - 'PWD': '/builds/slave/test', 13:05:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:40 INFO - 'SHELL': '/bin/bash', 13:05:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:40 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:40 INFO - 'USER': 'cltbld', 13:05:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:40 INFO - 'XPC_FLAGS': '0x0', 13:05:40 INFO - 'XPC_SERVICE_NAME': '0', 13:05:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:40 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 13:05:40 INFO - Cleaning up... 13:05:40 INFO - Return code: 0 13:05:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:05:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:40 INFO - 'HOME': '/Users/cltbld', 13:05:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:40 INFO - 'LOGNAME': 'cltbld', 13:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:40 INFO - 'MOZ_NO_REMOTE': '1', 13:05:40 INFO - 'NO_EM_RESTART': '1', 13:05:40 INFO - 'PAGER': '/bin/cat', 13:05:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:40 INFO - 'PWD': '/builds/slave/test', 13:05:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:40 INFO - 'SHELL': '/bin/bash', 13:05:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:40 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:40 INFO - 'USER': 'cltbld', 13:05:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:40 INFO - 'XPC_FLAGS': '0x0', 13:05:40 INFO - 'XPC_SERVICE_NAME': '0', 13:05:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:40 INFO - Downloading/unpacking psutil>=0.7.1 13:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:05:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:44 INFO - Installing collected packages: psutil 13:05:44 INFO - Running setup.py install for psutil 13:05:44 INFO - building 'psutil._psutil_osx' extension 13:05:44 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 13:05:44 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 13:05:44 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 13:05:44 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 13:05:44 INFO - building 'psutil._psutil_posix' extension 13:05:44 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 13:05:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:05:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:05:44 INFO - ^ 13:05:44 INFO - 1 warning generated. 13:05:45 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 13:05:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:05:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:05:45 INFO - Successfully installed psutil 13:05:45 INFO - Cleaning up... 13:05:45 INFO - Return code: 0 13:05:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:45 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:45 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 13:05:45 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 13:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:45 INFO - 'HOME': '/Users/cltbld', 13:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:45 INFO - 'LOGNAME': 'cltbld', 13:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:45 INFO - 'MOZ_NO_REMOTE': '1', 13:05:45 INFO - 'NO_EM_RESTART': '1', 13:05:45 INFO - 'PAGER': '/bin/cat', 13:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:45 INFO - 'PWD': '/builds/slave/test', 13:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:45 INFO - 'SHELL': '/bin/bash', 13:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:45 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:45 INFO - 'USER': 'cltbld', 13:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:45 INFO - 'XPC_FLAGS': '0x0', 13:05:45 INFO - 'XPC_SERVICE_NAME': '0', 13:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:05:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:05:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:05:48 INFO - Installing collected packages: mozsystemmonitor 13:05:48 INFO - Running setup.py install for mozsystemmonitor 13:05:48 INFO - Successfully installed mozsystemmonitor 13:05:48 INFO - Cleaning up... 13:05:48 INFO - Return code: 0 13:05:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:05:48 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')]} 13:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:48 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')]} 13:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:48 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:48 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 13:05:48 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 13:05:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:48 INFO - 'HOME': '/Users/cltbld', 13:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:48 INFO - 'LOGNAME': 'cltbld', 13:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:48 INFO - 'MOZ_NO_REMOTE': '1', 13:05:48 INFO - 'NO_EM_RESTART': '1', 13:05:48 INFO - 'PAGER': '/bin/cat', 13:05:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:48 INFO - 'PWD': '/builds/slave/test', 13:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:48 INFO - 'SHELL': '/bin/bash', 13:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:48 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:48 INFO - 'USER': 'cltbld', 13:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:48 INFO - 'XPC_FLAGS': '0x0', 13:05:48 INFO - 'XPC_SERVICE_NAME': '0', 13:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:05:48 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 13:05:48 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 13:05:48 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 13:05:48 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 13:05:48 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 13:05:48 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 13:05:51 INFO - Downloading blobuploader-1.2.4.tar.gz 13:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:05:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:05:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05: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 13:05:52 INFO - Downloading docopt-0.6.1.tar.gz 13:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:05:52 INFO - Installing collected packages: blobuploader, requests, docopt 13:05:52 INFO - Running setup.py install for blobuploader 13:05:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:05:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:05:52 INFO - Running setup.py install for requests 13:05:52 INFO - Running setup.py install for docopt 13:05:53 INFO - Successfully installed blobuploader requests docopt 13:05:53 INFO - Cleaning up... 13:05:53 INFO - Return code: 0 13:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05:53 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')]} 13:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:53 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')]} 13:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:53 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:53 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 13:05:53 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 13:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:53 INFO - 'HOME': '/Users/cltbld', 13:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:53 INFO - 'LOGNAME': 'cltbld', 13:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:53 INFO - 'MOZ_NO_REMOTE': '1', 13:05:53 INFO - 'NO_EM_RESTART': '1', 13:05:53 INFO - 'PAGER': '/bin/cat', 13:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:53 INFO - 'PWD': '/builds/slave/test', 13:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:53 INFO - 'SHELL': '/bin/bash', 13:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:53 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:53 INFO - 'USER': 'cltbld', 13:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:53 INFO - 'XPC_FLAGS': '0x0', 13:05:53 INFO - 'XPC_SERVICE_NAME': '0', 13:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-KmsLrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-fLtApj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gcjZU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-KNyQjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-8dxchR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:05:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Zsw537-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-JUxjxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-BviEGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-jriolH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-nfGoRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-kQlt9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-whsObf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-bG2w1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-_GV8p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:54 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-qsO9Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:05:55 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-BdGed4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:05:55 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3Zggi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:05:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:05:55 INFO - Running setup.py install for manifestparser 13:05:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:05:55 INFO - Running setup.py install for mozcrash 13:05:55 INFO - Running setup.py install for mozdebug 13:05:55 INFO - Running setup.py install for mozdevice 13:05:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:05:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:05:55 INFO - Running setup.py install for mozfile 13:05:55 INFO - Running setup.py install for mozhttpd 13:05:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Running setup.py install for mozinfo 13:05:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Running setup.py install for mozInstall 13:05:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Running setup.py install for mozleak 13:05:56 INFO - Running setup.py install for mozlog 13:05:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Running setup.py install for moznetwork 13:05:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:05:56 INFO - Running setup.py install for mozprocess 13:05:57 INFO - Running setup.py install for mozprofile 13:05:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:05:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:05:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:05:57 INFO - Running setup.py install for mozrunner 13:05:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:05:57 INFO - Running setup.py install for mozscreenshot 13:05:57 INFO - Running setup.py install for moztest 13:05:57 INFO - Running setup.py install for mozversion 13:05:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:05:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:05:57 INFO - Cleaning up... 13:05:57 INFO - Return code: 0 13:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:05:57 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:05:57 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 13:05:57 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 13:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:05:57 INFO - 'HOME': '/Users/cltbld', 13:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:05:57 INFO - 'LOGNAME': 'cltbld', 13:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:05:57 INFO - 'MOZ_NO_REMOTE': '1', 13:05:57 INFO - 'NO_EM_RESTART': '1', 13:05:57 INFO - 'PAGER': '/bin/cat', 13:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:05:57 INFO - 'PWD': '/builds/slave/test', 13:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:05:57 INFO - 'SHELL': '/bin/bash', 13:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:05:57 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:05:57 INFO - 'USER': 'cltbld', 13:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:05:57 INFO - 'XPC_FLAGS': '0x0', 13:05:57 INFO - 'XPC_SERVICE_NAME': '0', 13:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-947E1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EUJRSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-wtwam2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gUchFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Km5pUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-r0uOA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-CDf5um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EETNGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:05:58 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)) 13:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:05:58 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-cgEBNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-YPxi_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-dXcHaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-9sgilS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Q10VRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-NRBQlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Yl6gL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-4KDcI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:05:59 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)) 13:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:05:59 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-2QPFxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:05:59 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)) 13:05:59 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)) 13:05:59 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)) 13:05:59 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)) 13:05:59 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)) 13:05:59 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)) 13:05:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:05:59 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 13:05:59 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 13:05:59 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 13:05:59 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 13:05:59 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 13:05:59 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 13:06:02 INFO - Downloading blessings-1.6.tar.gz 13:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:02 INFO - Installing collected packages: blessings 13:06:02 INFO - Running setup.py install for blessings 13:06:02 INFO - Successfully installed blessings 13:06:02 INFO - Cleaning up... 13:06:02 INFO - Return code: 0 13:06:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:02 INFO - Reading from file tmpfile_stdout 13:06:02 INFO - Current package versions: 13:06:02 INFO - blessings == 1.6 13:06:02 INFO - blobuploader == 1.2.4 13:06:02 INFO - docopt == 0.6.1 13:06:02 INFO - manifestparser == 1.1 13:06:02 INFO - mozInstall == 1.12 13:06:02 INFO - mozcrash == 0.17 13:06:02 INFO - mozdebug == 0.1 13:06:02 INFO - mozdevice == 0.48 13:06:02 INFO - mozfile == 1.2 13:06:02 INFO - mozhttpd == 0.7 13:06:02 INFO - mozinfo == 0.9 13:06:02 INFO - mozleak == 0.1 13:06:02 INFO - mozlog == 3.1 13:06:02 INFO - moznetwork == 0.27 13:06:02 INFO - mozprocess == 0.22 13:06:02 INFO - mozprofile == 0.28 13:06:02 INFO - mozrunner == 6.11 13:06:02 INFO - mozscreenshot == 0.1 13:06:02 INFO - mozsystemmonitor == 0.0 13:06:02 INFO - moztest == 0.7 13:06:02 INFO - mozversion == 1.4 13:06:02 INFO - psutil == 3.1.1 13:06:02 INFO - requests == 1.2.3 13:06:02 INFO - wsgiref == 0.1.2 13:06:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:02 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:06:02 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 13:06:02 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 13:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:02 INFO - 'HOME': '/Users/cltbld', 13:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:02 INFO - 'LOGNAME': 'cltbld', 13:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:02 INFO - 'MOZ_NO_REMOTE': '1', 13:06:02 INFO - 'NO_EM_RESTART': '1', 13:06:02 INFO - 'PAGER': '/bin/cat', 13:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:02 INFO - 'PWD': '/builds/slave/test', 13:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:02 INFO - 'SHELL': '/bin/bash', 13:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:06:02 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:02 INFO - 'USER': 'cltbld', 13:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:02 INFO - 'XPC_FLAGS': '0x0', 13:06:02 INFO - 'XPC_SERVICE_NAME': '0', 13:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 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)) 13:06:03 INFO - Cleaning up... 13:06:03 INFO - Return code: 0 13:06:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06: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')]} 13:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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')]} 13:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:03 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 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:06:03 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 13:06:03 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 13:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:03 INFO - 'HOME': '/Users/cltbld', 13:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:03 INFO - 'LOGNAME': 'cltbld', 13:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:03 INFO - 'MOZ_NO_REMOTE': '1', 13:06:03 INFO - 'NO_EM_RESTART': '1', 13:06:03 INFO - 'PAGER': '/bin/cat', 13:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:03 INFO - 'PWD': '/builds/slave/test', 13:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:03 INFO - 'SHELL': '/bin/bash', 13:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:06:03 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:03 INFO - 'USER': 'cltbld', 13:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:03 INFO - 'XPC_FLAGS': '0x0', 13:06:03 INFO - 'XPC_SERVICE_NAME': '0', 13:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:03 INFO - Downloading/unpacking jsonschema 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:03 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 13:06:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:05 INFO - Downloading/unpacking functools32 (from jsonschema) 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06:06 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:06 INFO - warning: no files found matching '*.txt' 13:06:06 INFO - no previously-included directories found matching 'build' 13:06:06 INFO - no previously-included directories found matching 'dist' 13:06:06 INFO - no previously-included directories found matching '.git*' 13:06:06 INFO - Installing collected packages: jsonschema, functools32 13:06:06 INFO - Running setup.py install for functools32 13:06:06 INFO - warning: no files found matching '*.txt' 13:06:06 INFO - no previously-included directories found matching 'build' 13:06:06 INFO - no previously-included directories found matching 'dist' 13:06:06 INFO - no previously-included directories found matching '.git*' 13:06:06 INFO - Successfully installed jsonschema functools32 13:06:06 INFO - Cleaning up... 13:06:06 INFO - Return code: 0 13:06:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8078d68740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, '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.1IFniFPGPX/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.UFKaifENMR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 13:06:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:06 INFO - 'HOME': '/Users/cltbld', 13:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:06 INFO - 'LOGNAME': 'cltbld', 13:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:06 INFO - 'MOZ_NO_REMOTE': '1', 13:06:06 INFO - 'NO_EM_RESTART': '1', 13:06:06 INFO - 'PAGER': '/bin/cat', 13:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:06 INFO - 'PWD': '/builds/slave/test', 13:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:06 INFO - 'SHELL': '/bin/bash', 13:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:06:06 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:06 INFO - 'USER': 'cltbld', 13:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:06 INFO - 'XPC_FLAGS': '0x0', 13:06:06 INFO - 'XPC_SERVICE_NAME': '0', 13:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:06 INFO - Downloading/unpacking flake8 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:06 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 13:06:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:06:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:06:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:06:10 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 13:06:10 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 13:06:10 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 13:06:10 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 13:06:10 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 13:06:10 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 13:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:06:10 INFO - no previously-included directories found matching 'docs/_build' 13:06:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:06:10 INFO - Running setup.py install for pep8 13:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:06:10 INFO - no previously-included directories found matching 'docs/_build' 13:06:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:06:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:06:10 INFO - Cleaning up... 13:06:10 INFO - Return code: 0 13:06:10 INFO - Running post-action listener: _resource_record_post_action 13:06:10 INFO - Running post-action listener: _start_resource_monitoring 13:06:10 INFO - Starting resource monitoring. 13:06:10 INFO - ##### 13:06:10 INFO - ##### Finished create-virtualenv step (success) 13:06:10 INFO - ##### 13:06:10 INFO - ##### 13:06:10 INFO - ##### Running install step. 13:06:10 INFO - ##### 13:06:10 INFO - Running pre-action listener: _resource_record_pre_action 13:06:10 INFO - Running main action method: install 13:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:11 INFO - Reading from file tmpfile_stdout 13:06:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:11 INFO - Reading from file tmpfile_stdout 13:06:11 INFO - Output received: 13:06:11 INFO - Usage: mozinstall [options] installer 13:06:11 INFO - Options: 13:06:11 INFO - -h, --help show this help message and exit 13:06:11 INFO - -d DEST, --destination=DEST 13:06:11 INFO - Directory to install application into. [default: 13:06:11 INFO - "/builds/slave/test"] 13:06:11 INFO - --app=APP Application being installed. [default: firefox] 13:06:11 INFO - mkdir: /builds/slave/test/build/application 13:06:11 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'] 13:06:11 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 13:06:44 INFO - Reading from file tmpfile_stdout 13:06:44 INFO - Output received: 13:06:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:06:44 INFO - Running post-action listener: _resource_record_post_action 13:06:44 INFO - ##### 13:06:44 INFO - ##### Finished install step (success) 13:06:44 INFO - ##### 13:06:44 INFO - ##### 13:06:44 INFO - ##### Running run-tests step. 13:06:44 INFO - ##### 13:06:44 INFO - Running pre-action listener: _resource_record_pre_action 13:06:44 INFO - Running main action method: run_tests 13:06:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:06:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:06:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:06:45 INFO - 2016-05-20 13:06:45.008 screenresolution[1648:8128] starting screenresolution argv=screenresolution get 13:06:45 INFO - 2016-05-20 13:06:45.010 screenresolution[1648:8128] Display 0: 1600x1200x32@60 13:06:45 INFO - 2016-05-20 13:06:45.016 screenresolution[1649:8130] starting screenresolution argv=screenresolution list 13:06:45 INFO - Available Modes on Display 0 13:06:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:06:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:06:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:06:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:06:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:06:45 INFO - Intel Iris: 13:06:45 INFO - Chipset Model: Intel Iris 13:06:45 INFO - Type: GPU 13:06:45 INFO - Bus: Built-In 13:06:45 INFO - VRAM (Dynamic, Max): 1536 MB 13:06:45 INFO - Vendor: Intel (0x8086) 13:06:45 INFO - Device ID: 0x0a2e 13:06:45 INFO - Revision ID: 0x0009 13:06:45 INFO - Displays: 13:06:45 INFO - Display: 13:06:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:06:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:06:45 INFO - Display Serial Number: 1600x1200 60 13:06:45 INFO - Main Display: Yes 13:06:45 INFO - Mirror: Off 13:06:45 INFO - Online: Yes 13:06:45 INFO - Rotation: Supported 13:06:45 INFO - Return code: 0 13:06:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:06:45 INFO - Python 2.7.3 13:06:45 INFO - Return code: 0 13:06:45 INFO - grabbing minidump binary from tooltool 13:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:06:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f84d30>, '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 0x103da8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:06:45 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 13:06:45 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 13:06:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:06:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:06:45 INFO - Return code: 0 13:06:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:06:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:06:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:06:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:06:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:06:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:06:46 INFO - Return code: 0 13:06:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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 13:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0290 --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 13:06:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1IFniFPGPX/Render', 13:06:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:46 INFO - 'HOME': '/Users/cltbld', 13:06:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:46 INFO - 'LOGNAME': 'cltbld', 13:06:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:06:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:46 INFO - 'MOZ_NO_REMOTE': '1', 13:06:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:06:46 INFO - 'NO_EM_RESTART': '1', 13:06:46 INFO - 'PAGER': '/bin/cat', 13:06:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:46 INFO - 'PWD': '/builds/slave/test', 13:06:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:06:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:46 INFO - 'SHELL': '/bin/bash', 13:06:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFKaifENMR/Listeners', 13:06:46 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 13:06:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:46 INFO - 'USER': 'cltbld', 13:06:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:46 INFO - 'XPC_FLAGS': '0x0', 13:06:46 INFO - 'XPC_SERVICE_NAME': '0', 13:06:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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 13:06:46 INFO - mozversion application_buildid: 20160520105741 13:06:46 INFO - mozversion application_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 13:06:46 INFO - mozversion application_display_name: Nightly 13:06:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:06:46 INFO - mozversion application_name: Firefox 13:06:46 INFO - mozversion application_remotingname: firefox 13:06:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:06:46 INFO - mozversion application_vendor: Mozilla 13:06:46 INFO - mozversion application_version: 49.0a1 13:06:46 INFO - mozversion platform_buildid: 20160520105741 13:06:46 INFO - mozversion platform_changeset: b4ae8c5edb7e67e85a6f83380d03bd9f3db1ba19 13:06:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:06:46 INFO - mozversion platform_version: 49.0a1 13:06:46 INFO - using testdate: 1463774806 13:06:46 INFO - actual date: 1463774806 13:06:46 INFO - starting webserver on 'localhost:49275' 13:06:46 INFO - SUITE-START | Running 2 tests 13:06:46 INFO - TEST-START | dromaeo_css 13:06:46 INFO - operating with platform_type : mac_ 13:06:46 INFO - Initialising browser for dromaeo_css test... 13:06:46 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmphJkwUu/profile http://localhost:49275/getInfo.html) 13:06:49 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 13:06:49 INFO - PROCESS | 1667 | colorDepth:24 13:06:49 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 13:06:49 INFO - PROCESS | 1667 | __metrics 13:06:49 INFO - PROCESS | 1667 | 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" 13:06:49 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:06:49 INFO - TEST-INFO | 1667: exit 0 13:06:49 INFO - Browser initialized. 13:06:49 INFO - Running cycle 1/1 for dromaeo_css test... 13:06:49 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmphJkwUu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:01 INFO - PROCESS | 1670 | RSS: Main: 239120384 13:07:01 INFO - PROCESS | 1670 | 13:07:52 INFO - PROCESS | 1670 | 1463774872946 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] 13:10:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:10:05 INFO - PROCESS | 1670 | RSS: Main: 482463744 13:10:05 INFO - PROCESS | 1670 | 13:13:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:13:09 INFO - PROCESS | 1670 | RSS: Main: 376111104 13:13:09 INFO - PROCESS | 1670 | 13:16:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:16:12 INFO - PROCESS | 1670 | RSS: Main: 370073600 13:16:12 INFO - PROCESS | 1670 | 13:19:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:19:14 INFO - PROCESS | 1670 | RSS: Main: 370393088 13:19:14 INFO - PROCESS | 1670 | 13:22:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:22:18 INFO - PROCESS | 1670 | RSS: Main: 344408064 13:22:18 INFO - PROCESS | 1670 | 13:25:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:25:22 INFO - PROCESS | 1670 | RSS: Main: 376332288 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | __start_tp_report 13:25:22 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 13:25:22 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 13:25:22 INFO - PROCESS | 1670 | |i|pagename|runs| 13:25:22 INFO - PROCESS | 1670 | |0;dojo.html;3227.6443893564706;3346.33073385323;3387.525746395505;3416.0264324667473;3452.3958307296216;5545.805896793613;5571.609987300891;5596.664200147994;5616.522595579376;5664.951983882979;5541.556675465963;5619.353774315945;5649.039663257246;5660.688662123582;5741.885162296751;5003.849884503471;5030.170021946375;5110.28456016158;5118.667286626357;5214.895702085956;5189.299444574972;5203.531682148606;5227.581793456515;5254.4220135785035;5279;5014.147594908874;5054.090263752523;5083.6441449098575;5113.846584602468;5131.461196574343;5035.395752509686;5081.974590127025;5104.923426148611;5123.487651234884;5137.460566640486;5011.523905228997;5019.222020586815;5025.472325405816;5028;5031.4465408805;5484.61607687462;5528.281323427928;5581.832545023656;5592.412796656374;5595.1886976388605;6003.129546215818;6066.8786624267705;6144.139820425144;6192.287886892975;6247.969085100998;5221.477852214786;6226.377362263783;6253.218347706536;6277.183966084425;6277.7175027123885;5737.799177028825;5791.044477661223;5792.710364481759;5796.072628379439;5808.477237048686;6165.475934545524;6206.06908963659;6250.15622890913;6264.749410023639;6282.591631543935;6089.732416321046;6102.877942441126;6110.511159107261;6116.755329786848;6126.203593532812;6222.377762223832;6251.28110267316;6253.6873156342;6256.562040657111;6265.028920517327;5980.760769569169;6020.759169633166;6055.7274922628585;6137.079438084323;6160.383961603804;6126.417990290915;6191.690415479208;6202.503799696014;6218.875622487615;6221.595596286227;4899.216125419915;4907.141250281186;4911.607071434277;4912;4928.507149285043;4300.569943005675;4322;4351.477822661238;4358.346248062752;4375.037491751809;5548.472895074961;5557.638753481011;5557.638753481011;5562.3881373048935;5563.693996830213;6142.662153581507;6180.721867515883;6199.225096862892;6200.193974783249;6208.348123447064;5138.871528211825;5153.433122356538;5434.592405569519;5534.695591742493;5538.030844602178;5321.973390133025;5329.546988505943;5346.331708536433;5348.491893270133;5381.408045115112;6272.2159730033745;6274;6275.215598050335;6279.152314437492;6291.748330066746;6061.121137435135;6098.099428985257;6147.446729794161;6193.659348735863;6216.2540495140875;6275.184226050585;6276.529260305404;6300;6308.053791931064;6314.084457753692;6112.205413296243;6133.202683651097;6161.753529858935;6185.84535386619;6190.721417536131;6054.39456054391;6128;6132.693365331805;6157.384261573807;6267.9971204607045;5818.185454036354;5987.6108052976415;5991.370906054802;6003;6005.309389420273;5972.134040564007;5977.701114944322;5978.372270911492;5986.850328741816;5987.221661184019;5766.855828604318;5793.8551536211935;5799.82600521985;5822.243108395882;5855.3266374367495;5993.190919225849;6003.249593800775;6045.063015232646;6046.123312119806;6060.939390606038;5829.970850145892;5837.29952405714;5842.5325973921135;5843.853903652443;5854.414558544111 13:25:22 INFO - PROCESS | 1670 | |1;ext.html;494.44868971097225;498.9825356112537;531.0812294730117;542.430448029569;554.4843295734964;27920.86039569802;41790.16419671608;42702.78648606756;43004.784976075076;46151;44417.66746997587;44428.555714442846;44490;44566.331505027454;44730;8540.74377768668;9619.470929098898;9820.214382849355;9825;9831.557579908922;8570.314374850028;9846.458444785534;11425.257358271727;11676.416179191048;11814.645560633195;9902.950485247564;11745.236559623598;11788.823167652492;11797.70505737359;11814.350210738407;8652.3510736695;8844.38089333747;8848.203661670415;11680.883191168065;11782.469788859478;8567.871481927776;11401.486933087948;11692.649220523397;11709.70725731855;11891.881081189164;40315.992100197436;40578.594214057775;40616.593834061576;40650.79674601608;40660.390094148315;3204.247001954539;3261.5107733839877;3394.8811791587423;3447.3622379859808;3476.582810062783;28492.857535712188;29026.27434314138;29027.838886444515;29164.854175729197;30181.0945671628;2720.1295585412677;2738.4126793900055;2754.807163498556;2812.057960583207;2961.03766275959;530492.9999999962;536999;538252;675212.6239368821;675682;64336.678316608115;64742.67628661827;65414;65520.67239663771;65539.67230163913;36266;37104.25791484209;37117.81441092831;38827.80586097051;69318.65340673264;17247;17414.95510269388;17427.73858392125;17458.738118928228;17500.037497937734;12041.337726424956;12306;12315.568955086528;12567.93716031414;13211.735765284833;17542.92998725218;19432.33065920476;19819.702704459156;20295.391138265877;20354.28759993423;7597.430192735456;7629.809254768676;8176.7955801105445;8197.836043279101;8224.342052635895;981.4356744871727;1036.7719101797593;1050.726811029138;1088.2871719024051;1176.7822952753768;29766.851165744032;30214.848925755232;30295.545566816516;30560.388792224032;30705.078847634606;1036.2228328753436;1086.6905379018265;1148.4028305281206;1151.7984352738406;1173.2901594535354;14768.18774967387;15124.54626361211;15352.692946141015;15374.461893833663;15677.1400865732;665927;995854;1013198;1015231;1015254.3898243988;60105.699471503234;60602.39397606056;61257.69371153205;61417.07874381888;61547.07679384813;1260.836091308124;1317.1767645221737;1347.5014244729514;1484.3913995261892;1484.5397926642775;29270.26824329409;29811.850940745157;30580.694193058676;31234.375312494078;31501.527477087402;55165.34503964888;57194.142087867884;57826.55433614025;57846.710766445896;57947;531425;549719;617993;618921;619725;23673.763262367844;26235.737642623328;26698.733012669625;26889.058882939786;27283;45157;45322.86692832706;45660;46015.84960376017;46537.06925861396;35867;37198.070048249014;37524;37925.431118532695;38283;36263.456048159824;36727.449088263165;36801;36886;37614.05964850901;36872;37177;37659.43510847395;37943.430848536744;38020;31355.05934821959;32915.34169316648;32918;33018.84434044797;33037.00888973335;23921.401964951016;24176.153834615703;24332.270031899072;24538;24585.262442126765 13:25:22 INFO - PROCESS | 1670 | |2;jquery.html;347.6332469244947;393.03705920494787;404.01458777733;406.7417190084296;419.8236740568961;13862.861371386283;19887.701684474727;20739.481512962182;21261.14955401784;21274.787252127473;15889.841101589009;17043.233054512544;19827.702584461244;20000.89999550004;20899.686504702404;5529.115341545364;5721.170430287627;5959.940400595982;5973.671448070354;6045;5779.711014449261;5856.436920756521;5978.282606087276;6025.819225423223;6076.878462430749;4091.8363265469493;4319.935200971972;4327.545607711192;4331.285337919239;4332.436783218177;4319.352097185416;6211.6273023618505;6492.253390860064;6559.34406559345;6561.573497722633;5491.148871924858;5664.490195882349;5667.886642267173;5670.376258611549;5672.461116193955;10088.798224035552;24753.133640322503;25273.747262527322;25800.967961281527;26058;2067.462459760458;2171.9239826605994;2450.4976479821594;2518.496300739859;2617.3575783993865;9709.368891022132;9833.360831545926;10325.380477171393;11595.594154204562;15263.236838158047;1644.868410527155;1676.6419200447676;1747.0827815396892;1826.607279434928;1845.1788953915514;79923.60038199772;120321;129677;130459;133469;9907.603695852229;10884.802671706106;11596;11600.279524807705;11731.41342932867;14674.192919389536;14767.92616036913;14790.556283311518;14800.925995369955;14811.703765924622;8458.788530286669;8663.740087797376;8665.480071195749;8783.5169065702;8837.64649414029;8598.785030374165;8612.502374311505;8612.698555550525;8684.8697269541;8704.651813927372;4482.350059241392;4561.361409402686;4577.244754615451;4618.56123668251;4623.745693986796;1979.8614097013071;2005.328215047946;2008.1866843928233;2011.637905177082;2035.2571311471206;746.4923851780733;810.6076071640205;832.24681663095;956.3879117364892;1008.9646862359782;17900.720195226517;20322.882241476862;20334.9832508374;20504.487387815425;20508.487287817625;960.7646126699003;964.7154089543601;1005.9295849290626;1009.1674368645896;1043.7703705184847;9793.363431376938;10380.169586433067;10403.595514605431;10506.422146782;10537.683869483928;602961;603301;620400;621884;621951;73040.17399565053;73396;120445.39777301057;121575;138378.6162138386;1001.1790331927895;1013.2248829645328;1032.256775121926;1039.8232300508932;1041.2919214933918;17590.29638814484;17916.55208619795;17940.55148621295;17968.101594920463;18217.180226890086;50574.747126264134;52458;52714.73642631762;54004.72997634987;54008;74708;77194.45611087859;81279.18720812953;82253.17746822695;85716;6904.240533541407;7360.382099057361;7417.851642967003;7504.8499030018;7591.848163036819;11821.349825759666;12620.432080556213;12652.620421387373;12932.741345172855;13038.217706937614;9886.752831179278;10962.451877405938;11091.72270693239;11253.7749245013;11446.94276528612;10643.361398316125;10776.622818201326;10947.83578246311;11044.558217671203;11288.435578220891;11123.990706791266;11282.887171128184;11394.772104558027;11690.123240756808;11777.705557360792;7344.889826652711;7415.221401752954;7470.8505829882015;7473.701051957861;7665.578393188428;10039.598416063276;11220.382878941737;11441.885581144415;11473.36896470702;11486.82769758471 13:25:22 INFO - PROCESS | 1670 | |3;mootools.html;1424.0103128325818;1582.0902980786043;1606.1166358502824;1861.8417434518065;1999.7400337956065;7877.921220787791;8625;8890.733278001655;8923.419977701453;8987.505687187202;8595.484270943733;8851.822963540726;8861.291096712264;8862.113788621135;8866.512341821197;2136.305700647288;2200.9119635214574;2203.0416768705586;2390.820688448369;2426.211481268586;2209.149477451185;2378.108209421467;2459.987700061497;2466.6793316868784;2471.1845091119976;2011.5272910865936;2026.3718247343297;2428.101602407103;2429.5991161458337;2431.0761910473993;2117.947051323714;2405.9759402405925;2411.6141417373137;2411.8914648840846;2414.28778510339;2086.62440760663;2361;2432.221689059502;2439.7926176275037;2443.718972318188;8685.174908383693;8757.737267881974;8781.121887811232;8786.428882122642;8788.252998495136;5280.260763493114;5427.104527752956;5461.972690136564;5462.153366228241;5463.890722185573;3451.9654803451895;3506.193575477629;3609.2961872434885;3639.108418437473;3646.835892384849;1036.0830664861576;1054.1619412567027;1054.3884546962743;1055.7255113670424;1057.9682609521726;11872.643820685307;11884.58403955866;11898.46456909441;11905.821412678817;11914.880851191378;4769.523047695272;4791.943227300336;4911.263310503453;4916.532929371704;4925.236588328743;10138.239632027628;10197.694069177936;10200.234982376203;10200.744981375377;10217.13154381886;5096.770645320969;5478.041342765;5483.506484416342;5484.424135465719;5491.2312276281355;4858.222684370484;4898.24077268024;4940.752962351868;4978.2034874419915;4985.725785081855;3984;4006.6794656427414;4056.8985775355854;4084.9387259191135;4085.5914408558906;1323.2202687385452;1352.4387379237505;1353.6886516101156;1361.4145917255478;1373.8145350377772;735.2757533829174;901.756525738047;929.400536653868;942.5664194470484;944.9480278584743;5781.392953739881;5889.911651325234;5953.672548009901;6039.275286965504;6121.051237058174;450.0863247607362;460.5762698317556;464.69794633488505;465.7205676594084;466.3447855762659;3888.6889048876023;3946.743461674982;3959.940600890989;4047.919041619151;4048.3927410888014;10549.84175237372;10865.674029779118;10910.727231819268;11024;11075.11399088071;10827.133829293794;10850.131989440826;10853.457327133612;10996.28524145928;11046.723831904266;7822.765317040498;8164.469309494863;8213;8321.209485098905;8322.084570697218;5990.221271234712;6072.969635151796;6163.630182189083;6167.568270221221;6173.135760993375;9739.220862331138;9921.107100361001;9972.50137493137;9983.550740216853;10357.482125893826;10201.183905287411;10201.38791672502;10236.436995965294;10345.896541034494;10496.52765625564;7555.773326800205;7635.121960974559;7712.884306735291;7743.225677432211;7749.845003099794;8290.792730181794;8363.372747043873;8418.200270974248;8419.073901870912;8425.873611895704;7436.070491188601;7538.585377804054;7554.3201111898;7631.084269887649;7696;6384.808455746335;7382.741604043833;7394.593297368697;7458.291462311072;7524.2851929066655;7049.471289653399;7147;7175.138983322035;7179.389751871149;7200.135983681992;4706.62347012245;4890.731009794353;4988.152014157675;5025.974870125626;5182.196759502283;4936.851894443029;4975.328330675313;5056.115179843586;5066.341375621146;5140.4859514049795 13:25:22 INFO - PROCESS | 1670 | |4;prototype.html;309.4769838972137;321.89860194038874;322.15755798586696;325.85336598530665;331.8971118953123;4395.8901027474285;4918.581920536754;5356.625036247464;5375.408705042442;5379.408265090842;4643.674942754009;4857.562819346258;4968.2547617857335;5306.655067420625;5333.759980800864;3560.572731272251;3629.310431018098;3814.3324918139283;3995.4805875236325;4026.4161696553983;3392.6776956189124;3607.783532988016;3975.1056012397125;4033.37482690182;4044.47421835161;3945.3687410014404;3961.6038396160443;4028.6374226319626;4049.3318602430563;4083.2854250506193;3548.3258180945536;3742.8958457255176;3836.55879573847;4037.858674946363;4055.1889622075705;3418.7606867519285;3536.8585256589704;3538.5576802899636;3577.3918433866047;3581.6239294874185;4227.598378154071;4872.634552408548;5004.84985450437;5063.961623026192;5154.304168937183;4175.478065241845;4481.736722954919;4708.317293992386;4709.788059537294;4717.292406139072;4469.620082292977;4477.48508921475;5464.7540860660965;5534.529564986981;5595.209532949458;3733.869314574004;3850.7689538627774;3906.554574807311;3948.065447930522;3951.980240098781;5214.9478505214465;5419.5393391561765;5533;5789.594728369016;5915.408459154093;3470.583529976419;3729.9929019164674;3944.4280579315837;3978.900527486778;4002.199010445311;3443.5695538057744;3720.534933133358;3758.267137908136;4048.67610591152;4064.4919385076864;4472.897738063105;4576.8626941191815;4599.425071866017;4654.325122857167;4674.57928786404;4638.675292729544;5352.652077614943;5583.180493850579;5625.803096891589;5632.436756324335;1236.6104677026888;1345.9192448452927;1357.450232655776;1385.8705155298398;1386;814.3892080939296;848.8599381102173;854.6495936666062;866.540733411293;869.9652013919499;4068.389741538793;4203.991042149862;4336.913261734748;4353.7115940798285;4373.671974601918;4883.853484395474;4907.852764417002;4908.4846091160625;4928.236123400879;4941.357623508921;3817.6564109230303;3931.9017024574614;4041.6968727345566;4326.697131200849;4338.60952514269;1834.5230240137394;2312.9306120816404;2577.711144427786;2580.4839032193636;2587.456634106821;4752.008313017082;4833.879153021132;4854.757262136879;4999.250262408201;5071.670341427795;4499.707519011254;4793.760311984456;4855.1018061658;4871.561559459666;5008.499150085036;4529.625429055309;4692.859214223578;5067.41724701664;5070.923936140961;5078.619103567321;4243.490781106287;4334.761588112684;4346.174226896879;4572.039871626996;4921.630877684116;3846.7114966377076;4408.735475871458;4435.290353543418;4624.85509853303;4795.232762757942;4316.784160791885;5536.058869992192;5645.209670646195;5706.172604972338;6014.3989847765615;3244.3511297740074;3932.9126635590924;3964.821583028828;3966.345552983725;3971.185906889023;5758.568107391879;5760.33756118035;5773.884522309446;5787.768489260382;5791.7972870951;4633.366633366633;5246.868828279324;5252;5673.383085820521;5696.515796157373;4779.95220047809;4823.1800593898415;5048;5643.407442218672;5704.914426283526;5353;5366.651167674088;5602.52378547828;5615.8876822464135;5634.830955071355;5349.956758432068;5603.131514615241;5670.9432905672065;5698.943010570007;5701.971490142523;5210.6613070150315;5547.611667183259;5575.2473416088305;5616.38219795814;5622.3815380307315 13:25:22 INFO - PROCESS | 1670 | |5;yui.html;713.3365969648227;716.7204790131847;748.9625518724066;750.5684231566847;761.1057008015587;15004.699906001873;15262.313195906183;15504.224788760574;15516.379344826208;15571.454999075035;14083.436662533486;14130.364133613986;14219.502317418892;14237.572872813833;14247.002709810318;720.4668545276484;907.2469850024464;924.7364501117183;926.2867591954191;927.5918595817818;740.3336996702957;915.638322862469;946.8532377481501;950.8098380323928;953.0231512699504;988.8269552828262;989.3816364772017;994.2642444590987;994.6121012804975;995.7012896131168;757.7991832164481;787.8896954426385;976.1409959235898;981.3915372469079;982.3418309732497;583.8919799837043;585.3181044083638;713.4755954373531;737.2406421385981;741.8108382362517;14899.77650335246;14986.550403487912;15009.324580393908;15011.024283421651;15150.848491515055;11779.823302650468;12249.877501224963;12531.31077790721;12579.119461637689;12661.493540258378;10812.513436895359;11024.393658348788;11045;11343.773124537463;11635.825462618068;3588.9282214355826;3640.526731524911;4022.2960981828355;4210.052738133895;4240.109576988836;81850.59074704647;83101.58449207715;83483.16516834815;86797.56601217017;86960;9106.225970792557;9495.14543691071;9551.0926461986;9831.85252221203;9921.40471571708;21434;22333.888330558242;22342.55314893693;22372;22417.215397461012;7393.55638661682;7475.102987641518;7478.626068696543;7482.513636613603;7553.811154721176;3946.3883098119836;4036.414719865662;4040.4747382840633;4046.3323551613653;4085.4076158957378;3350.1959529712694;3439.5528581284275;3439.7420193485586;3443.517907492953;3443.741719371057;627.1815281058263;632.2886752403546;636.2810024672091;640.6476437959104;649.1690635985946;534.8448949804591;539.8893226888479;568.9573282003867;575.246427180395;581.9272590926134;7626;7635.31282184595;7679.731209407644;7935.56354400502;8138.633761480747;385.55083327923217;507.89080347725417;507.9847604571869;540.1303900719838;562.6764610349065;3287.671232876693;3755.061234691327;3756.2675278320467;3763.2285381496727;3769.566499852497;85111.57444212864;87313;87679;88295.67556486657;89473;52395.952080958166;70930;74593.6270318645;76693.23306766861;79193.81209281865;66984.99522507166;68495.97256041164;68540.6572967132;68698.65650671715;69266.65366673135;7120.145582530129;7168.354848063491;7194.532355396883;7421.2207718190975;7621.885671714817;43601;43753.90615234645;44319.33520997252;44647.77676111599;46066.53933460623;57321;62157.689211553654;62484;62516.37483625105;62764.05853912286;9740.561674724513;9840.163586095263;10128.34165779222;10135.290529662852;10234.437105959243;8987.19115279628;10583.941605839354;10749.570017199225;10780.838287425539;11291.604793832505;9523.904760952302;9607.497374730197;9700.805983880142;9728.610855565697;9857.753556161153;9743.512824358611;9791.216702663627;9805.296531145095;9917.45454000037;9949.452780097165;9491.66779162726;9508.889717767002;9555.90444095578;9802.460864652227;10463.11063559606;9388.061193880558;9455.574499147417;9484.288678349014;9756.268279878896;9833.950830245803;9399;9630.518474076409;9721.368111072758;9774.142912846537;9833.213342932691 13:25:22 INFO - PROCESS | 1670 | __end_tp_report 13:25:22 INFO - PROCESS | 1670 | __start_cc_report 13:25:22 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2718 13:25:22 INFO - PROCESS | 1670 | __end_cc_report 13:25:22 INFO - PROCESS | 1670 | __startTimestamp1463775922046__endTimestamp 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | ------- Summary: start ------- 13:25:22 INFO - PROCESS | 1670 | Number of tests: 6 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5662.35 Median:5837.30 stddev:641.24 (11.0%) stddev-sans-first:614.10 13:25:22 INFO - PROCESS | 1670 | Values: 3227.6 3346.3 3387.5 3416.0 3452.4 5545.8 5571.6 5596.7 5616.5 5665.0 5541.6 5619.4 5649.0 5660.7 5741.9 5003.8 5030.2 5110.3 5118.7 5214.9 5189.3 5203.5 5227.6 5254.4 5279.0 5014.1 5054.1 5083.6 5113.8 5131.5 5035.4 5082.0 5104.9 5123.5 5137.5 5011.5 5019.2 5025.5 5028.0 5031.4 5484.6 5528.3 5581.8 5592.4 5595.2 6003.1 6066.9 6144.1 6192.3 6248.0 5221.5 6226.4 6253.2 6277.2 6277.7 5737.8 5791.0 5792.7 5796.1 5808.5 6165.5 6206.1 6250.2 6264.7 6282.6 6089.7 6102.9 6110.5 6116.8 6126.2 6222.4 6251.3 6253.7 6256.6 6265.0 5980.8 6020.8 6055.7 6137.1 6160.4 6126.4 6191.7 6202.5 6218.9 6221.6 4899.2 4907.1 4911.6 4912.0 4928.5 4300.6 4322.0 4351.5 4358.3 4375.0 5548.5 5557.6 5557.6 5562.4 5563.7 6142.7 6180.7 6199.2 6200.2 6208.3 5138.9 5153.4 5434.6 5534.7 5538.0 5322.0 5329.5 5346.3 5348.5 5381.4 6272.2 6274.0 6275.2 6279.2 6291.7 6061.1 6098.1 6147.4 6193.7 6216.3 6275.2 6276.5 6300.0 6308.1 6314.1 6112.2 6133.2 6161.8 6185.8 6190.7 6054.4 6128.0 6132.7 6157.4 6268.0 5818.2 5987.6 5991.4 6003.0 6005.3 5972.1 5977.7 5978.4 5986.9 5987.2 5766.9 5793.9 5799.8 5822.2 5855.3 5993.2 6003.2 6045.1 6046.1 6060.9 5830.0 5837.3 5842.5 5843.9 5854.4 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:81623.57 Median:19671.79 stddev:197578.69 (1004.4%) stddev-sans-first:198039.05 13:25:22 INFO - PROCESS | 1670 | Values: 494.4 499.0 531.1 542.4 554.5 27920.9 41790.2 42702.8 43004.8 46151.0 44417.7 44428.6 44490.0 44566.3 44730.0 8540.7 9619.5 9820.2 9825.0 9831.6 8570.3 9846.5 11425.3 11676.4 11814.6 9903.0 11745.2 11788.8 11797.7 11814.4 8652.4 8844.4 8848.2 11680.9 11782.5 8567.9 11401.5 11692.6 11709.7 11891.9 40316.0 40578.6 40616.6 40650.8 40660.4 3204.2 3261.5 3394.9 3447.4 3476.6 28492.9 29026.3 29027.8 29164.9 30181.1 2720.1 2738.4 2754.8 2812.1 2961.0 530493.0 536999.0 538252.0 675212.6 675682.0 64336.7 64742.7 65414.0 65520.7 65539.7 36266.0 37104.3 37117.8 38827.8 69318.7 17247.0 17415.0 17427.7 17458.7 17500.0 12041.3 12306.0 12315.6 12567.9 13211.7 17542.9 19432.3 19819.7 20295.4 20354.3 7597.4 7629.8 8176.8 8197.8 8224.3 981.4 1036.8 1050.7 1088.3 1176.8 29766.9 30214.8 30295.5 30560.4 30705.1 1036.2 1086.7 1148.4 1151.8 1173.3 14768.2 15124.5 15352.7 15374.5 15677.1 665927.0 995854.0 1013198.0 1015231.0 1015254.4 60105.7 60602.4 61257.7 61417.1 61547.1 1260.8 1317.2 1347.5 1484.4 1484.5 29270.3 29811.9 30580.7 31234.4 31501.5 55165.3 57194.1 57826.6 57846.7 57947.0 531425.0 549719.0 617993.0 618921.0 619725.0 23673.8 26235.7 26698.7 26889.1 27283.0 45157.0 45322.9 45660.0 46015.8 46537.1 35867.0 37198.1 37524.0 37925.4 38283.0 36263.5 36727.4 36801.0 36886.0 37614.1 36872.0 37177.0 37659.4 37943.4 38020.0 31355.1 32915.3 32918.0 33018.8 33037.0 23921.4 24176.2 24332.3 24538.0 24585.3 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34669.73 Median:13635.81 stddev:102098.86 (748.8%) stddev-sans-first:102352.75 13:25:22 INFO - PROCESS | 1670 | Values: 347.6 393.0 404.0 406.7 419.8 13862.9 19887.7 20739.5 21261.1 21274.8 15889.8 17043.2 19827.7 20000.9 20899.7 5529.1 5721.2 5959.9 5973.7 6045.0 5779.7 5856.4 5978.3 6025.8 6076.9 4091.8 4319.9 4327.5 4331.3 4332.4 4319.4 6211.6 6492.3 6559.3 6561.6 5491.1 5664.5 5667.9 5670.4 5672.5 10088.8 24753.1 25273.7 25801.0 26058.0 2067.5 2171.9 2450.5 2518.5 2617.4 9709.4 9833.4 10325.4 11595.6 15263.2 1644.9 1676.6 1747.1 1826.6 1845.2 79923.6 120321.0 129677.0 130459.0 133469.0 9907.6 10884.8 11596.0 11600.3 11731.4 14674.2 14767.9 14790.6 14800.9 14811.7 8458.8 8663.7 8665.5 8783.5 8837.6 8598.8 8612.5 8612.7 8684.9 8704.7 4482.4 4561.4 4577.2 4618.6 4623.7 1979.9 2005.3 2008.2 2011.6 2035.3 746.5 810.6 832.2 956.4 1009.0 17900.7 20322.9 20335.0 20504.5 20508.5 960.8 964.7 1005.9 1009.2 1043.8 9793.4 10380.2 10403.6 10506.4 10537.7 602961.0 603301.0 620400.0 621884.0 621951.0 73040.2 73396.0 120445.4 121575.0 138378.6 1001.2 1013.2 1032.3 1039.8 1041.3 17590.3 17916.6 17940.6 17968.1 18217.2 50574.7 52458.0 52714.7 54004.7 54008.0 74708.0 77194.5 81279.2 82253.2 85716.0 6904.2 7360.4 7417.9 7504.8 7591.8 11821.3 12620.4 12652.6 12932.7 13038.2 9886.8 10962.5 11091.7 11253.8 11446.9 10643.4 10776.6 10947.8 11044.6 11288.4 11124.0 11282.9 11394.8 11690.1 11777.7 7344.9 7415.2 7470.9 7473.7 7665.6 10039.6 11220.4 11441.9 11473.4 11486.8 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5776.54 Median:4894.49 stddev:3269.10 (66.8%) stddev-sans-first:3261.91 13:25:22 INFO - PROCESS | 1670 | Values: 1424.0 1582.1 1606.1 1861.8 1999.7 7877.9 8625.0 8890.7 8923.4 8987.5 8595.5 8851.8 8861.3 8862.1 8866.5 2136.3 2200.9 2203.0 2390.8 2426.2 2209.1 2378.1 2460.0 2466.7 2471.2 2011.5 2026.4 2428.1 2429.6 2431.1 2117.9 2406.0 2411.6 2411.9 2414.3 2086.6 2361.0 2432.2 2439.8 2443.7 8685.2 8757.7 8781.1 8786.4 8788.3 5280.3 5427.1 5462.0 5462.2 5463.9 3452.0 3506.2 3609.3 3639.1 3646.8 1036.1 1054.2 1054.4 1055.7 1058.0 11872.6 11884.6 11898.5 11905.8 11914.9 4769.5 4791.9 4911.3 4916.5 4925.2 10138.2 10197.7 10200.2 10200.7 10217.1 5096.8 5478.0 5483.5 5484.4 5491.2 4858.2 4898.2 4940.8 4978.2 4985.7 3984.0 4006.7 4056.9 4084.9 4085.6 1323.2 1352.4 1353.7 1361.4 1373.8 735.3 901.8 929.4 942.6 944.9 5781.4 5889.9 5953.7 6039.3 6121.1 450.1 460.6 464.7 465.7 466.3 3888.7 3946.7 3959.9 4047.9 4048.4 10549.8 10865.7 10910.7 11024.0 11075.1 10827.1 10850.1 10853.5 10996.3 11046.7 7822.8 8164.5 8213.0 8321.2 8322.1 5990.2 6073.0 6163.6 6167.6 6173.1 9739.2 9921.1 9972.5 9983.6 10357.5 10201.2 10201.4 10236.4 10345.9 10496.5 7555.8 7635.1 7712.9 7743.2 7749.8 8290.8 8363.4 8418.2 8419.1 8425.9 7436.1 7538.6 7554.3 7631.1 7696.0 6384.8 7382.7 7394.6 7458.3 7524.3 7049.5 7147.0 7175.1 7179.4 7200.1 4706.6 4890.7 4988.2 5026.0 5182.2 4936.9 4975.3 5056.1 5066.3 5140.5 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4292.42 Median:4636.02 stddev:1306.47 (28.2%) stddev-sans-first:1275.48 13:25:22 INFO - PROCESS | 1670 | Values: 309.5 321.9 322.2 325.9 331.9 4395.9 4918.6 5356.6 5375.4 5379.4 4643.7 4857.6 4968.3 5306.7 5333.8 3560.6 3629.3 3814.3 3995.5 4026.4 3392.7 3607.8 3975.1 4033.4 4044.5 3945.4 3961.6 4028.6 4049.3 4083.3 3548.3 3742.9 3836.6 4037.9 4055.2 3418.8 3536.9 3538.6 3577.4 3581.6 4227.6 4872.6 5004.8 5064.0 5154.3 4175.5 4481.7 4708.3 4709.8 4717.3 4469.6 4477.5 5464.8 5534.5 5595.2 3733.9 3850.8 3906.6 3948.1 3952.0 5214.9 5419.5 5533.0 5789.6 5915.4 3470.6 3730.0 3944.4 3978.9 4002.2 3443.6 3720.5 3758.3 4048.7 4064.5 4472.9 4576.9 4599.4 4654.3 4674.6 4638.7 5352.7 5583.2 5625.8 5632.4 1236.6 1345.9 1357.5 1385.9 1386.0 814.4 848.9 854.6 866.5 870.0 4068.4 4204.0 4336.9 4353.7 4373.7 4883.9 4907.9 4908.5 4928.2 4941.4 3817.7 3931.9 4041.7 4326.7 4338.6 1834.5 2312.9 2577.7 2580.5 2587.5 4752.0 4833.9 4854.8 4999.3 5071.7 4499.7 4793.8 4855.1 4871.6 5008.5 4529.6 4692.9 5067.4 5070.9 5078.6 4243.5 4334.8 4346.2 4572.0 4921.6 3846.7 4408.7 4435.3 4624.9 4795.2 4316.8 5536.1 5645.2 5706.2 6014.4 3244.4 3932.9 3964.8 3966.3 3971.2 5758.6 5760.3 5773.9 5787.8 5791.8 4633.4 5246.9 5252.0 5673.4 5696.5 4780.0 4823.2 5048.0 5643.4 5704.9 5353.0 5366.7 5602.5 5615.9 5634.8 5350.0 5603.1 5670.9 5698.9 5702.0 5210.7 5547.6 5575.2 5616.4 5622.4 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:17511.80 Median:70098.33 stddev:24643.89 (35.2%) stddev-sans-first:24680.74 13:25:22 INFO - PROCESS | 1670 | Values: 713.3 716.7 749.0 750.6 761.1 15004.7 15262.3 15504.2 15516.4 15571.5 14083.4 14130.4 14219.5 14237.6 14247.0 720.5 907.2 924.7 926.3 927.6 740.3 915.6 946.9 950.8 953.0 988.8 989.4 994.3 994.6 995.7 757.8 787.9 976.1 981.4 982.3 583.9 585.3 713.5 737.2 741.8 14899.8 14986.6 15009.3 15011.0 15150.8 11779.8 12249.9 12531.3 12579.1 12661.5 10812.5 11024.4 11045.0 11343.8 11635.8 3588.9 3640.5 4022.3 4210.1 4240.1 81850.6 83101.6 83483.2 86797.6 86960.0 9106.2 9495.1 9551.1 9831.9 9921.4 21434.0 22333.9 22342.6 22372.0 22417.2 7393.6 7475.1 7478.6 7482.5 7553.8 3946.4 4036.4 4040.5 4046.3 4085.4 3350.2 3439.6 3439.7 3443.5 3443.7 627.2 632.3 636.3 640.6 649.2 534.8 539.9 569.0 575.2 581.9 7626.0 7635.3 7679.7 7935.6 8138.6 385.6 507.9 508.0 540.1 562.7 3287.7 3755.1 3756.3 3763.2 3769.6 85111.6 87313.0 87679.0 88295.7 89473.0 52396.0 70930.0 74593.6 76693.2 79193.8 66985.0 68496.0 68540.7 68698.7 69266.7 7120.1 7168.4 7194.5 7421.2 7621.9 43601.0 43753.9 44319.3 44647.8 46066.5 57321.0 62157.7 62484.0 62516.4 62764.1 9740.6 9840.2 10128.3 10135.3 10234.4 8987.2 10583.9 10749.6 10780.8 11291.6 9523.9 9607.5 9700.8 9728.6 9857.8 9743.5 9791.2 9805.3 9917.5 9949.5 9491.7 9508.9 9555.9 9802.5 10463.1 9388.1 9455.6 9484.3 9756.3 9834.0 9399.0 9630.5 9721.4 9774.1 9833.2 13:25:22 INFO - PROCESS | 1670 | -------- Summary: end -------- 13:25:22 INFO - PROCESS | 1670 | 13:25:22 INFO - TEST-INFO | 1670: exit 0 13:25:22 INFO - TEST-OK | dromaeo_css | took 1115946ms 13:25:22 INFO - TEST-START | kraken 13:25:22 INFO - operating with platform_type : mac_ 13:25:22 INFO - Initialising browser for kraken test... 13:25:22 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpHIbzyr/profile http://localhost:49275/getInfo.html) 13:25:24 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 13:25:24 INFO - PROCESS | 1675 | colorDepth:24 13:25:24 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 13:25:24 INFO - PROCESS | 1675 | __metrics 13:25:25 INFO - PROCESS | 1675 | 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" 13:25:25 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:25:25 INFO - TEST-INFO | 1675: exit 0 13:25:25 INFO - Browser initialized. 13:25:25 INFO - Running cycle 1/1 for kraken test... 13:25:25 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpHIbzyr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:25:37 INFO - PROCESS | 1677 | RSS: Main: 239386624 13:25:37 INFO - PROCESS | 1677 | 13:25:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:25:41 INFO - PROCESS | 1677 | RSS: Main: 287305728 13:25:41 INFO - PROCESS | 1677 | 13:25:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:25:44 INFO - PROCESS | 1677 | RSS: Main: 296222720 13:25:44 INFO - PROCESS | 1677 | 13:25:48 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:25:48 INFO - PROCESS | 1677 | RSS: Main: 385073152 13:25:48 INFO - PROCESS | 1677 | 13:25:50 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:25:51 INFO - PROCESS | 1677 | RSS: Main: 283422720 13:25:51 INFO - PROCESS | 1677 | 13:25:53 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:25:54 INFO - PROCESS | 1677 | RSS: Main: 287432704 13:25:54 INFO - PROCESS | 1677 | 13:25:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:25:57 INFO - PROCESS | 1677 | RSS: Main: 326209536 13:25:57 INFO - PROCESS | 1677 | 13:26:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:26:01 INFO - PROCESS | 1677 | RSS: Main: 329232384 13:26:01 INFO - PROCESS | 1677 | 13:26:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:26:05 INFO - PROCESS | 1677 | RSS: Main: 328486912 13:26:05 INFO - PROCESS | 1677 | 13:26:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:26:07 INFO - PROCESS | 1677 | RSS: Main: 316301312 13:26:07 INFO - PROCESS | 1677 | 13:26:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:26:10 INFO - PROCESS | 1677 | RSS: Main: 323379200 13:26:10 INFO - PROCESS | 1677 | 13:26:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:26:13 INFO - PROCESS | 1677 | RSS: Main: 325185536 13:26:13 INFO - PROCESS | 1677 | 13:26:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:26:16 INFO - PROCESS | 1677 | RSS: Main: 329478144 13:26:16 INFO - PROCESS | 1677 | 13:26:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:26:20 INFO - PROCESS | 1677 | RSS: Main: 327258112 13:26:20 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:26:22 INFO - PROCESS | 1677 | RSS: Main: 373637120 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | __start_tp_report 13:26:22 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 13:26:22 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 13:26:22 INFO - PROCESS | 1677 | |i|pagename|runs| 13:26:22 INFO - PROCESS | 1677 | |0;ai-astar;111;105;112;113;111;112;111;113;112;111 13:26:22 INFO - PROCESS | 1677 | |1;audio-beat-detection;153;115;142;197;117;137;138;131;163;121 13:26:22 INFO - PROCESS | 1677 | |2;audio-dft;187;185;184;183;184;182;183;185;187;187 13:26:22 INFO - PROCESS | 1677 | |3;audio-fft;75;75;56;149;82;89;60;61;108;56 13:26:22 INFO - PROCESS | 1677 | |4;audio-oscillator;90;95;133;78;80;77;100;80;78;100 13:26:22 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;85;87;85;87;83;88;84;87;83 13:26:22 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;93;93;93;93;110;119;93;92;92 13:26:22 INFO - PROCESS | 1677 | |7;imaging-desaturate;86;85;85;84;84;82;83;83;83;86 13:26:22 INFO - PROCESS | 1677 | |8;json-parse-financial;58;58;59;58;58;59;60;123;56;57 13:26:22 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;63;66;78;72;71;67;67;69;69;67 13:26:22 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;83;85;85;85;84;84;82;78;85;84 13:26:22 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;145;148;137;136;137;137;165;140;211;142 13:26:22 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;153;204;229;199;169;168;164;166;173;163 13:26:22 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;67;71;85;86;85;73;65;66;66 13:26:22 INFO - PROCESS | 1677 | __end_tp_report 13:26:22 INFO - PROCESS | 1677 | __start_cc_report 13:26:22 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2333 13:26:22 INFO - PROCESS | 1677 | __end_cc_report 13:26:22 INFO - PROCESS | 1677 | __startTimestamp1463775982457__endTimestamp 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | ------- Summary: start ------- 13:26:22 INFO - PROCESS | 1677 | Number of tests: 14 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.28 (2.0%) stddev-sans-first:2.42 13:26:22 INFO - PROCESS | 1677 | Values: 111.0 105.0 112.0 113.0 111.0 112.0 111.0 113.0 112.0 111.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:140.00 stddev:24.81 (17.7%) stddev-sans-first:25.96 13:26:22 INFO - PROCESS | 1677 | Values: 153.0 115.0 142.0 197.0 117.0 137.0 138.0 131.0 163.0 121.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.83 (1.0%) stddev-sans-first:1.74 13:26:22 INFO - PROCESS | 1677 | Values: 187.0 185.0 184.0 183.0 184.0 182.0 183.0 185.0 187.0 187.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:81.10 Median:68.00 stddev:29.02 (42.7%) stddev-sans-first:30.70 13:26:22 INFO - PROCESS | 1677 | Values: 75.0 75.0 56.0 149.0 82.0 89.0 60.0 61.0 108.0 56.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:79.00 stddev:17.38 (22.0%) stddev-sans-first:18.43 13:26:22 INFO - PROCESS | 1677 | Values: 90.0 95.0 133.0 78.0 80.0 77.0 100.0 80.0 78.0 100.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:2.10 (2.4%) stddev-sans-first:1.88 13:26:22 INFO - PROCESS | 1677 | Values: 89.0 85.0 87.0 85.0 87.0 83.0 88.0 84.0 87.0 83.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:93.00 stddev:9.42 (10.1%) stddev-sans-first:9.88 13:26:22 INFO - PROCESS | 1677 | Values: 93.0 93.0 93.0 93.0 93.0 110.0 119.0 93.0 92.0 92.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:1.37 (1.6%) stddev-sans-first:1.27 13:26:22 INFO - PROCESS | 1677 | Values: 86.0 85.0 85.0 84.0 84.0 82.0 83.0 83.0 83.0 86.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:58.00 stddev:20.55 (35.4%) stddev-sans-first:21.66 13:26:22 INFO - PROCESS | 1677 | Values: 58.0 58.0 59.0 58.0 58.0 59.0 60.0 123.0 56.0 57.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:4.09 (5.9%) stddev-sans-first:3.75 13:26:22 INFO - PROCESS | 1677 | Values: 63.0 66.0 78.0 72.0 71.0 67.0 67.0 69.0 69.0 67.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.50 stddev:2.17 (2.6%) stddev-sans-first:2.30 13:26:22 INFO - PROCESS | 1677 | Values: 83.0 85.0 85.0 85.0 84.0 84.0 82.0 78.0 85.0 84.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:143.50 stddev:23.19 (16.2%) stddev-sans-first:24.54 13:26:22 INFO - PROCESS | 1677 | Values: 145.0 148.0 137.0 136.0 137.0 137.0 165.0 140.0 211.0 142.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:171.00 stddev:23.82 (13.9%) stddev-sans-first:23.37 13:26:22 INFO - PROCESS | 1677 | Values: 153.0 204.0 229.0 199.0 169.0 168.0 164.0 166.0 173.0 163.0 13:26:22 INFO - PROCESS | 1677 | 13:26:22 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.00 stddev:8.79 (12.2%) stddev-sans-first:9.04 13:26:22 INFO - PROCESS | 1677 | Values: 67.0 67.0 71.0 85.0 86.0 85.0 73.0 65.0 66.0 66.0 13:26:22 INFO - PROCESS | 1677 | -------- Summary: end -------- 13:26:22 INFO - PROCESS | 1677 | 13:26:23 INFO - TEST-INFO | 1677: exit 0 13:26:23 INFO - TEST-OK | kraken | took 60673ms 13:26:23 INFO - SUITE-END | took 1176s 13:26:23 INFO - Completed test suite (00:19:36) 13:26:23 INFO - javascript benchmark 13:26:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.6443893564706, 3346.33073385323, 3387.525746395505, 3416.0264324667473, 3452.3958307296216, 5545.805896793613, 5571.609987300891, 5596.664200147994, 5616.522595579376, 5664.951983882979, 5541.556675465963, 5619.353774315945, 5649.039663257246, 5660.688662123582, 5741.885162296751, 5003.849884503471, 5030.170021946375, 5110.28456016158, 5118.667286626357, 5214.895702085956, 5189.299444574972, 5203.531682148606, 5227.581793456515, 5254.4220135785035, 5279.0, 5014.147594908874, 5054.090263752523, 5083.6441449098575, 5113.846584602468, 5131.461196574343, 5035.395752509686, 5081.974590127025, 5104.923426148611, 5123.487651234884, 5137.460566640486, 5011.523905228997, 5019.222020586815, 5025.472325405816, 5028.0, 5031.4465408805, 5484.61607687462, 5528.281323427928, 5581.832545023656, 5592.412796656374, 5595.1886976388605, 6003.129546215818, 6066.8786624267705, 6144.139820425144, 6192.287886892975, 6247.969085100998, 5221.477852214786, 6226.377362263783, 6253.218347706536, 6277.183966084425, 6277.7175027123885, 5737.799177028825, 5791.044477661223, 5792.710364481759, 5796.072628379439, 5808.477237048686, 6165.475934545524, 6206.06908963659, 6250.15622890913, 6264.749410023639, 6282.591631543935, 6089.732416321046, 6102.877942441126, 6110.511159107261, 6116.755329786848, 6126.203593532812, 6222.377762223832, 6251.28110267316, 6253.6873156342, 6256.562040657111, 6265.028920517327, 5980.760769569169, 6020.759169633166, 6055.7274922628585, 6137.079438084323, 6160.383961603804, 6126.417990290915, 6191.690415479208, 6202.503799696014, 6218.875622487615, 6221.595596286227, 4899.216125419915, 4907.141250281186, 4911.607071434277, 4912.0, 4928.507149285043, 4300.569943005675, 4322.0, 4351.477822661238, 4358.346248062752, 4375.037491751809, 5548.472895074961, 5557.638753481011, 5557.638753481011, 5562.3881373048935, 5563.693996830213, 6142.662153581507, 6180.721867515883, 6199.225096862892, 6200.193974783249, 6208.348123447064, 5138.871528211825, 5153.433122356538, 5434.592405569519, 5534.695591742493, 5538.030844602178, 5321.973390133025, 5329.546988505943, 5346.331708536433, 5348.491893270133, 5381.408045115112, 6272.2159730033745, 6274.0, 6275.215598050335, 6279.152314437492, 6291.748330066746, 6061.121137435135, 6098.099428985257, 6147.446729794161, 6193.659348735863, 6216.2540495140875, 6275.184226050585, 6276.529260305404, 6300.0, 6308.053791931064, 6314.084457753692, 6112.205413296243, 6133.202683651097, 6161.753529858935, 6185.84535386619, 6190.721417536131, 6054.39456054391, 6128.0, 6132.693365331805, 6157.384261573807, 6267.9971204607045, 5818.185454036354, 5987.6108052976415, 5991.370906054802, 6003.0, 6005.309389420273, 5972.134040564007, 5977.701114944322, 5978.372270911492, 5986.850328741816, 5987.221661184019, 5766.855828604318, 5793.8551536211935, 5799.82600521985, 5822.243108395882, 5855.3266374367495, 5993.190919225849, 6003.249593800775, 6045.063015232646, 6046.123312119806, 6060.939390606038, 5829.970850145892, 5837.29952405714, 5842.5325973921135, 5843.853903652443, 5854.414558544111], "value": 5620.795727036787, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.44868971097225, 498.9825356112537, 531.0812294730117, 542.430448029569, 554.4843295734964, 27920.86039569802, 41790.16419671608, 42702.78648606756, 43004.784976075076, 46151.0, 44417.66746997587, 44428.555714442846, 44490.0, 44566.331505027454, 44730.0, 8540.74377768668, 9619.470929098898, 9820.214382849355, 9825.0, 9831.557579908922, 8570.314374850028, 9846.458444785534, 11425.257358271727, 11676.416179191048, 11814.645560633195, 9902.950485247564, 11745.236559623598, 11788.823167652492, 11797.70505737359, 11814.350210738407, 8652.3510736695, 8844.38089333747, 8848.203661670415, 11680.883191168065, 11782.469788859478, 8567.871481927776, 11401.486933087948, 11692.649220523397, 11709.70725731855, 11891.881081189164, 40315.992100197436, 40578.594214057775, 40616.593834061576, 40650.79674601608, 40660.390094148315, 3204.247001954539, 3261.5107733839877, 3394.8811791587423, 3447.3622379859808, 3476.582810062783, 28492.857535712188, 29026.27434314138, 29027.838886444515, 29164.854175729197, 30181.0945671628, 2720.1295585412677, 2738.4126793900055, 2754.807163498556, 2812.057960583207, 2961.03766275959, 530492.9999999962, 536999.0, 538252.0, 675212.6239368821, 675682.0, 64336.678316608115, 64742.67628661827, 65414.0, 65520.67239663771, 65539.67230163913, 36266.0, 37104.25791484209, 37117.81441092831, 38827.80586097051, 69318.65340673264, 17247.0, 17414.95510269388, 17427.73858392125, 17458.738118928228, 17500.037497937734, 12041.337726424956, 12306.0, 12315.568955086528, 12567.93716031414, 13211.735765284833, 17542.92998725218, 19432.33065920476, 19819.702704459156, 20295.391138265877, 20354.28759993423, 7597.430192735456, 7629.809254768676, 8176.7955801105445, 8197.836043279101, 8224.342052635895, 981.4356744871727, 1036.7719101797593, 1050.726811029138, 1088.2871719024051, 1176.7822952753768, 29766.851165744032, 30214.848925755232, 30295.545566816516, 30560.388792224032, 30705.078847634606, 1036.2228328753436, 1086.6905379018265, 1148.4028305281206, 1151.7984352738406, 1173.2901594535354, 14768.18774967387, 15124.54626361211, 15352.692946141015, 15374.461893833663, 15677.1400865732, 665927.0, 995854.0, 1013198.0, 1015231.0, 1015254.3898243988, 60105.699471503234, 60602.39397606056, 61257.69371153205, 61417.07874381888, 61547.07679384813, 1260.836091308124, 1317.1767645221737, 1347.5014244729514, 1484.3913995261892, 1484.5397926642775, 29270.26824329409, 29811.850940745157, 30580.694193058676, 31234.375312494078, 31501.527477087402, 55165.34503964888, 57194.142087867884, 57826.55433614025, 57846.710766445896, 57947.0, 531425.0, 549719.0, 617993.0, 618921.0, 619725.0, 23673.763262367844, 26235.737642623328, 26698.733012669625, 26889.058882939786, 27283.0, 45157.0, 45322.86692832706, 45660.0, 46015.84960376017, 46537.06925861396, 35867.0, 37198.070048249014, 37524.0, 37925.431118532695, 38283.0, 36263.456048159824, 36727.449088263165, 36801.0, 36886.0, 37614.05964850901, 36872.0, 37177.0, 37659.43510847395, 37943.430848536744, 38020.0, 31355.05934821959, 32915.34169316648, 32918.0, 33018.84434044797, 33037.00888973335, 23921.401964951016, 24176.153834615703, 24332.270031899072, 24538.0, 24585.262442126765], "value": 20501.113840402395, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.6332469244947, 393.03705920494787, 404.01458777733, 406.7417190084296, 419.8236740568961, 13862.861371386283, 19887.701684474727, 20739.481512962182, 21261.14955401784, 21274.787252127473, 15889.841101589009, 17043.233054512544, 19827.702584461244, 20000.89999550004, 20899.686504702404, 5529.115341545364, 5721.170430287627, 5959.940400595982, 5973.671448070354, 6045.0, 5779.711014449261, 5856.436920756521, 5978.282606087276, 6025.819225423223, 6076.878462430749, 4091.8363265469493, 4319.935200971972, 4327.545607711192, 4331.285337919239, 4332.436783218177, 4319.352097185416, 6211.6273023618505, 6492.253390860064, 6559.34406559345, 6561.573497722633, 5491.148871924858, 5664.490195882349, 5667.886642267173, 5670.376258611549, 5672.461116193955, 10088.798224035552, 24753.133640322503, 25273.747262527322, 25800.967961281527, 26058.0, 2067.462459760458, 2171.9239826605994, 2450.4976479821594, 2518.496300739859, 2617.3575783993865, 9709.368891022132, 9833.360831545926, 10325.380477171393, 11595.594154204562, 15263.236838158047, 1644.868410527155, 1676.6419200447676, 1747.0827815396892, 1826.607279434928, 1845.1788953915514, 79923.60038199772, 120321.0, 129677.0, 130459.0, 133469.0, 9907.603695852229, 10884.802671706106, 11596.0, 11600.279524807705, 11731.41342932867, 14674.192919389536, 14767.92616036913, 14790.556283311518, 14800.925995369955, 14811.703765924622, 8458.788530286669, 8663.740087797376, 8665.480071195749, 8783.5169065702, 8837.64649414029, 8598.785030374165, 8612.502374311505, 8612.698555550525, 8684.8697269541, 8704.651813927372, 4482.350059241392, 4561.361409402686, 4577.244754615451, 4618.56123668251, 4623.745693986796, 1979.8614097013071, 2005.328215047946, 2008.1866843928233, 2011.637905177082, 2035.2571311471206, 746.4923851780733, 810.6076071640205, 832.24681663095, 956.3879117364892, 1008.9646862359782, 17900.720195226517, 20322.882241476862, 20334.9832508374, 20504.487387815425, 20508.487287817625, 960.7646126699003, 964.7154089543601, 1005.9295849290626, 1009.1674368645896, 1043.7703705184847, 9793.363431376938, 10380.169586433067, 10403.595514605431, 10506.422146782, 10537.683869483928, 602961.0, 603301.0, 620400.0, 621884.0, 621951.0, 73040.17399565053, 73396.0, 120445.39777301057, 121575.0, 138378.6162138386, 1001.1790331927895, 1013.2248829645328, 1032.256775121926, 1039.8232300508932, 1041.2919214933918, 17590.29638814484, 17916.55208619795, 17940.55148621295, 17968.101594920463, 18217.180226890086, 50574.747126264134, 52458.0, 52714.73642631762, 54004.72997634987, 54008.0, 74708.0, 77194.45611087859, 81279.18720812953, 82253.17746822695, 85716.0, 6904.240533541407, 7360.382099057361, 7417.851642967003, 7504.8499030018, 7591.848163036819, 11821.349825759666, 12620.432080556213, 12652.620421387373, 12932.741345172855, 13038.217706937614, 9886.752831179278, 10962.451877405938, 11091.72270693239, 11253.7749245013, 11446.94276528612, 10643.361398316125, 10776.622818201326, 10947.83578246311, 11044.558217671203, 11288.435578220891, 11123.990706791266, 11282.887171128184, 11394.772104558027, 11690.123240756808, 11777.705557360792, 7344.889826652711, 7415.221401752954, 7470.8505829882015, 7473.701051957861, 7665.578393188428, 10039.598416063276, 11220.382878941737, 11441.885581144415, 11473.36896470702, 11486.82769758471], "value": 9374.857180829544, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.0103128325818, 1582.0902980786043, 1606.1166358502824, 1861.8417434518065, 1999.7400337956065, 7877.921220787791, 8625.0, 8890.733278001655, 8923.419977701453, 8987.505687187202, 8595.484270943733, 8851.822963540726, 8861.291096712264, 8862.113788621135, 8866.512341821197, 2136.305700647288, 2200.9119635214574, 2203.0416768705586, 2390.820688448369, 2426.211481268586, 2209.149477451185, 2378.108209421467, 2459.987700061497, 2466.6793316868784, 2471.1845091119976, 2011.5272910865936, 2026.3718247343297, 2428.101602407103, 2429.5991161458337, 2431.0761910473993, 2117.947051323714, 2405.9759402405925, 2411.6141417373137, 2411.8914648840846, 2414.28778510339, 2086.62440760663, 2361.0, 2432.221689059502, 2439.7926176275037, 2443.718972318188, 8685.174908383693, 8757.737267881974, 8781.121887811232, 8786.428882122642, 8788.252998495136, 5280.260763493114, 5427.104527752956, 5461.972690136564, 5462.153366228241, 5463.890722185573, 3451.9654803451895, 3506.193575477629, 3609.2961872434885, 3639.108418437473, 3646.835892384849, 1036.0830664861576, 1054.1619412567027, 1054.3884546962743, 1055.7255113670424, 1057.9682609521726, 11872.643820685307, 11884.58403955866, 11898.46456909441, 11905.821412678817, 11914.880851191378, 4769.523047695272, 4791.943227300336, 4911.263310503453, 4916.532929371704, 4925.236588328743, 10138.239632027628, 10197.694069177936, 10200.234982376203, 10200.744981375377, 10217.13154381886, 5096.770645320969, 5478.041342765, 5483.506484416342, 5484.424135465719, 5491.2312276281355, 4858.222684370484, 4898.24077268024, 4940.752962351868, 4978.2034874419915, 4985.725785081855, 3984.0, 4006.6794656427414, 4056.8985775355854, 4084.9387259191135, 4085.5914408558906, 1323.2202687385452, 1352.4387379237505, 1353.6886516101156, 1361.4145917255478, 1373.8145350377772, 735.2757533829174, 901.756525738047, 929.400536653868, 942.5664194470484, 944.9480278584743, 5781.392953739881, 5889.911651325234, 5953.672548009901, 6039.275286965504, 6121.051237058174, 450.0863247607362, 460.5762698317556, 464.69794633488505, 465.7205676594084, 466.3447855762659, 3888.6889048876023, 3946.743461674982, 3959.940600890989, 4047.919041619151, 4048.3927410888014, 10549.84175237372, 10865.674029779118, 10910.727231819268, 11024.0, 11075.11399088071, 10827.133829293794, 10850.131989440826, 10853.457327133612, 10996.28524145928, 11046.723831904266, 7822.765317040498, 8164.469309494863, 8213.0, 8321.209485098905, 8322.084570697218, 5990.221271234712, 6072.969635151796, 6163.630182189083, 6167.568270221221, 6173.135760993375, 9739.220862331138, 9921.107100361001, 9972.50137493137, 9983.550740216853, 10357.482125893826, 10201.183905287411, 10201.38791672502, 10236.436995965294, 10345.896541034494, 10496.52765625564, 7555.773326800205, 7635.121960974559, 7712.884306735291, 7743.225677432211, 7749.845003099794, 8290.792730181794, 8363.372747043873, 8418.200270974248, 8419.073901870912, 8425.873611895704, 7436.070491188601, 7538.585377804054, 7554.3201111898, 7631.084269887649, 7696.0, 6384.808455746335, 7382.741604043833, 7394.593297368697, 7458.291462311072, 7524.2851929066655, 7049.471289653399, 7147.0, 7175.138983322035, 7179.389751871149, 7200.135983681992, 4706.62347012245, 4890.731009794353, 4988.152014157675, 5025.974870125626, 5182.196759502283, 4936.851894443029, 4975.328330675313, 5056.115179843586, 5066.341375621146, 5140.4859514049795], "value": 4555.115003786387, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.4769838972137, 321.89860194038874, 322.15755798586696, 325.85336598530665, 331.8971118953123, 4395.8901027474285, 4918.581920536754, 5356.625036247464, 5375.408705042442, 5379.408265090842, 4643.674942754009, 4857.562819346258, 4968.2547617857335, 5306.655067420625, 5333.759980800864, 3560.572731272251, 3629.310431018098, 3814.3324918139283, 3995.4805875236325, 4026.4161696553983, 3392.6776956189124, 3607.783532988016, 3975.1056012397125, 4033.37482690182, 4044.47421835161, 3945.3687410014404, 3961.6038396160443, 4028.6374226319626, 4049.3318602430563, 4083.2854250506193, 3548.3258180945536, 3742.8958457255176, 3836.55879573847, 4037.858674946363, 4055.1889622075705, 3418.7606867519285, 3536.8585256589704, 3538.5576802899636, 3577.3918433866047, 3581.6239294874185, 4227.598378154071, 4872.634552408548, 5004.84985450437, 5063.961623026192, 5154.304168937183, 4175.478065241845, 4481.736722954919, 4708.317293992386, 4709.788059537294, 4717.292406139072, 4469.620082292977, 4477.48508921475, 5464.7540860660965, 5534.529564986981, 5595.209532949458, 3733.869314574004, 3850.7689538627774, 3906.554574807311, 3948.065447930522, 3951.980240098781, 5214.9478505214465, 5419.5393391561765, 5533.0, 5789.594728369016, 5915.408459154093, 3470.583529976419, 3729.9929019164674, 3944.4280579315837, 3978.900527486778, 4002.199010445311, 3443.5695538057744, 3720.534933133358, 3758.267137908136, 4048.67610591152, 4064.4919385076864, 4472.897738063105, 4576.8626941191815, 4599.425071866017, 4654.325122857167, 4674.57928786404, 4638.675292729544, 5352.652077614943, 5583.180493850579, 5625.803096891589, 5632.436756324335, 1236.6104677026888, 1345.9192448452927, 1357.450232655776, 1385.8705155298398, 1386.0, 814.3892080939296, 848.8599381102173, 854.6495936666062, 866.540733411293, 869.9652013919499, 4068.389741538793, 4203.991042149862, 4336.913261734748, 4353.7115940798285, 4373.671974601918, 4883.853484395474, 4907.852764417002, 4908.4846091160625, 4928.236123400879, 4941.357623508921, 3817.6564109230303, 3931.9017024574614, 4041.6968727345566, 4326.697131200849, 4338.60952514269, 1834.5230240137394, 2312.9306120816404, 2577.711144427786, 2580.4839032193636, 2587.456634106821, 4752.008313017082, 4833.879153021132, 4854.757262136879, 4999.250262408201, 5071.670341427795, 4499.707519011254, 4793.760311984456, 4855.1018061658, 4871.561559459666, 5008.499150085036, 4529.625429055309, 4692.859214223578, 5067.41724701664, 5070.923936140961, 5078.619103567321, 4243.490781106287, 4334.761588112684, 4346.174226896879, 4572.039871626996, 4921.630877684116, 3846.7114966377076, 4408.735475871458, 4435.290353543418, 4624.85509853303, 4795.232762757942, 4316.784160791885, 5536.058869992192, 5645.209670646195, 5706.172604972338, 6014.3989847765615, 3244.3511297740074, 3932.9126635590924, 3964.821583028828, 3966.345552983725, 3971.185906889023, 5758.568107391879, 5760.33756118035, 5773.884522309446, 5787.768489260382, 5791.7972870951, 4633.366633366633, 5246.868828279324, 5252.0, 5673.383085820521, 5696.515796157373, 4779.95220047809, 4823.1800593898415, 5048.0, 5643.407442218672, 5704.914426283526, 5353.0, 5366.651167674088, 5602.52378547828, 5615.8876822464135, 5634.830955071355, 5349.956758432068, 5603.131514615241, 5670.9432905672065, 5698.943010570007, 5701.971490142523, 5210.6613070150315, 5547.611667183259, 5575.2473416088305, 5616.38219795814, 5622.3815380307315], "value": 3889.354417377102, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.3365969648227, 716.7204790131847, 748.9625518724066, 750.5684231566847, 761.1057008015587, 15004.699906001873, 15262.313195906183, 15504.224788760574, 15516.379344826208, 15571.454999075035, 14083.436662533486, 14130.364133613986, 14219.502317418892, 14237.572872813833, 14247.002709810318, 720.4668545276484, 907.2469850024464, 924.7364501117183, 926.2867591954191, 927.5918595817818, 740.3336996702957, 915.638322862469, 946.8532377481501, 950.8098380323928, 953.0231512699504, 988.8269552828262, 989.3816364772017, 994.2642444590987, 994.6121012804975, 995.7012896131168, 757.7991832164481, 787.8896954426385, 976.1409959235898, 981.3915372469079, 982.3418309732497, 583.8919799837043, 585.3181044083638, 713.4755954373531, 737.2406421385981, 741.8108382362517, 14899.77650335246, 14986.550403487912, 15009.324580393908, 15011.024283421651, 15150.848491515055, 11779.823302650468, 12249.877501224963, 12531.31077790721, 12579.119461637689, 12661.493540258378, 10812.513436895359, 11024.393658348788, 11045.0, 11343.773124537463, 11635.825462618068, 3588.9282214355826, 3640.526731524911, 4022.2960981828355, 4210.052738133895, 4240.109576988836, 81850.59074704647, 83101.58449207715, 83483.16516834815, 86797.56601217017, 86960.0, 9106.225970792557, 9495.14543691071, 9551.0926461986, 9831.85252221203, 9921.40471571708, 21434.0, 22333.888330558242, 22342.55314893693, 22372.0, 22417.215397461012, 7393.55638661682, 7475.102987641518, 7478.626068696543, 7482.513636613603, 7553.811154721176, 3946.3883098119836, 4036.414719865662, 4040.4747382840633, 4046.3323551613653, 4085.4076158957378, 3350.1959529712694, 3439.5528581284275, 3439.7420193485586, 3443.517907492953, 3443.741719371057, 627.1815281058263, 632.2886752403546, 636.2810024672091, 640.6476437959104, 649.1690635985946, 534.8448949804591, 539.8893226888479, 568.9573282003867, 575.246427180395, 581.9272590926134, 7626.0, 7635.31282184595, 7679.731209407644, 7935.56354400502, 8138.633761480747, 385.55083327923217, 507.89080347725417, 507.9847604571869, 540.1303900719838, 562.6764610349065, 3287.671232876693, 3755.061234691327, 3756.2675278320467, 3763.2285381496727, 3769.566499852497, 85111.57444212864, 87313.0, 87679.0, 88295.67556486657, 89473.0, 52395.952080958166, 70930.0, 74593.6270318645, 76693.23306766861, 79193.81209281865, 66984.99522507166, 68495.97256041164, 68540.6572967132, 68698.65650671715, 69266.65366673135, 7120.145582530129, 7168.354848063491, 7194.532355396883, 7421.2207718190975, 7621.885671714817, 43601.0, 43753.90615234645, 44319.33520997252, 44647.77676111599, 46066.53933460623, 57321.0, 62157.689211553654, 62484.0, 62516.37483625105, 62764.05853912286, 9740.561674724513, 9840.163586095263, 10128.34165779222, 10135.290529662852, 10234.437105959243, 8987.19115279628, 10583.941605839354, 10749.570017199225, 10780.838287425539, 11291.604793832505, 9523.904760952302, 9607.497374730197, 9700.805983880142, 9728.610855565697, 9857.753556161153, 9743.512824358611, 9791.216702663627, 9805.296531145095, 9917.45454000037, 9949.452780097165, 9491.66779162726, 9508.889717767002, 9555.90444095578, 9802.460864652227, 10463.11063559606, 9388.061193880558, 9455.574499147417, 9484.288678349014, 9756.268279878896, 9833.950830245803, 9399.0, 9630.518474076409, 9721.368111072758, 9774.142912846537, 9833.213342932691], "value": 6666.698811440598, "unit": "score"}], "name": "dromaeo_css", "value": 7095.443523325108, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 112.0, 113.0, 111.0, 112.0, 111.0, 113.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 115.0, 142.0, 197.0, 117.0, 137.0, 138.0, 131.0, 163.0, 121.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 184.0, 183.0, 184.0, 182.0, 183.0, 185.0, 187.0, 187.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 56.0, 149.0, 82.0, 89.0, 60.0, 61.0, 108.0, 56.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 133.0, 78.0, 80.0, 77.0, 100.0, 80.0, 78.0, 100.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 85.0, 87.0, 83.0, 88.0, 84.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 110.0, 119.0, 93.0, 92.0, 92.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 84.0, 82.0, 83.0, 83.0, 83.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 60.0, 123.0, 56.0, 57.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 78.0, 72.0, 71.0, 67.0, 67.0, 69.0, 69.0, 67.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 85.0, 84.0, 84.0, 82.0, 78.0, 85.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 148.0, 137.0, 136.0, 137.0, 137.0, 165.0, 140.0, 211.0, 142.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 204.0, 229.0, 199.0, 169.0, 168.0, 164.0, 166.0, 173.0, 163.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 85.0, 86.0, 85.0, 73.0, 65.0, 66.0, 66.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1495.1, "alertThreshold": 2.0}]} 13:26:23 INFO - Return code: 0 13:26:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:26:23 ERROR - # TBPL SUCCESS # 13:26:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:26:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:26:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:26:23 INFO - 2016-05-20 13:26:23.746 screenresolution[1681:10315] starting screenresolution argv=screenresolution get 13:26:23 INFO - 2016-05-20 13:26:23.749 screenresolution[1681:10315] Display 0: 1600x1200x32@60 13:26:23 INFO - 2016-05-20 13:26:23.755 screenresolution[1682:10318] starting screenresolution argv=screenresolution list 13:26:23 INFO - Available Modes on Display 0 13:26:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:26:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:26:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:26:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:26:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:26:23 INFO - Intel Iris: 13:26:23 INFO - Chipset Model: Intel Iris 13:26:23 INFO - Type: GPU 13:26:23 INFO - Bus: Built-In 13:26:23 INFO - VRAM (Dynamic, Max): 1536 MB 13:26:23 INFO - Vendor: Intel (0x8086) 13:26:23 INFO - Device ID: 0x0a2e 13:26:23 INFO - Revision ID: 0x0009 13:26:23 INFO - Displays: 13:26:23 INFO - Display: 13:26:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:26:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:26:23 INFO - Display Serial Number: 1600x1200 60 13:26:23 INFO - Main Display: Yes 13:26:23 INFO - Mirror: Off 13:26:23 INFO - Online: Yes 13:26:23 INFO - Rotation: Supported 13:26:23 INFO - Return code: 0 13:26:23 INFO - Running post-action listener: _resource_record_post_action 13:26:23 INFO - ##### 13:26:23 INFO - ##### Finished run-tests step (success) 13:26:23 INFO - ##### 13:26:23 INFO - Running post-run listener: _resource_record_post_run 13:26:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31477248; Write bytes: 695104512; Read time: 195; Write time: 1934 13:26:25 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395698688; Write bytes: 397117440; Read time: 30065; Write time: 848 13:26:25 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29691392; Write bytes: 297139200; Read time: 181; Write time: 1083 13:26:25 INFO - Running post-run listener: _upload_blobber_files 13:26:25 INFO - Blob upload gear active. 13:26:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:26:25 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 13:26:25 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:26:25 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:26:25 INFO - (blobuploader) - INFO - Open directory for files ... 13:26:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:26:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:26:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:26 INFO - (blobuploader) - INFO - Done attempting. 13:26:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:26:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:26:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:29 INFO - (blobuploader) - INFO - Done attempting. 13:26:29 INFO - (blobuploader) - INFO - Iteration through files over. 13:26:29 INFO - Return code: 0 13:26:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:26:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:26:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/922f3fc5b95c48cd6528e40dbaaeb9adb2167ca2c9733618bba399af32c48755e96a045ba7e1a2e835d700b1d136edbfd1d32d1baab3e492e46ccf1149d63722", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73eaa6dabd2c143102391ca41ec7b3460e0367cbe31dd97ece042062f10f6a39bf322a56045f4bde3c4e57df5fbe158f895ac996dcf7dfc8664b89b3ecb34ed9"} 13:26:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:26:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:26:29 INFO - Contents: 13:26:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/922f3fc5b95c48cd6528e40dbaaeb9adb2167ca2c9733618bba399af32c48755e96a045ba7e1a2e835d700b1d136edbfd1d32d1baab3e492e46ccf1149d63722", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73eaa6dabd2c143102391ca41ec7b3460e0367cbe31dd97ece042062f10f6a39bf322a56045f4bde3c4e57df5fbe158f895ac996dcf7dfc8664b89b3ecb34ed9"} 13:26:29 INFO - Running post-run listener: copy_logs_to_upload_dir 13:26:29 INFO - Copying logs to upload dir... 13:26:29 INFO - mkdir: /builds/slave/test/build/upload/logs 13:26:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.862328 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-20 13:26:29.861983) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:29.865485) ========= 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.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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/Larch/sha512/922f3fc5b95c48cd6528e40dbaaeb9adb2167ca2c9733618bba399af32c48755e96a045ba7e1a2e835d700b1d136edbfd1d32d1baab3e492e46ccf1149d63722", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73eaa6dabd2c143102391ca41ec7b3460e0367cbe31dd97ece042062f10f6a39bf322a56045f4bde3c4e57df5fbe158f895ac996dcf7dfc8664b89b3ecb34ed9"} build_url:https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/dHVPF4YWSGe76N6x1qLb5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/922f3fc5b95c48cd6528e40dbaaeb9adb2167ca2c9733618bba399af32c48755e96a045ba7e1a2e835d700b1d136edbfd1d32d1baab3e492e46ccf1149d63722", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/73eaa6dabd2c143102391ca41ec7b3460e0367cbe31dd97ece042062f10f6a39bf322a56045f4bde3c4e57df5fbe158f895ac996dcf7dfc8664b89b3ecb34ed9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:29.894330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:29.894658) ========= 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.1IFniFPGPX/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.UFKaifENMR/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.005191 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 13:26:29.942987) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:26:29.943319) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 13:26:29.943681) ========= ========= Total master_lag: 0.26 =========