builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0148 starttime: 1461045339.47 results: success (0) buildid: 20160418205131 builduid: d5db07eff05d47449dc0edb10d63b704 revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.474942) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.475347) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:39.475618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.156721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.156973) ========= 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.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-18 22:55:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-04-18 22:55:40 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.625887 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.829009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:40.829327) ========= 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.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.026633 ========= master_lag: 0.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:41.071494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 22:55:41.071818) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb65ec85049efa44b18e2e8d06af26768c3bfb94 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cb65ec85049efa44b18e2e8d06af26768c3bfb94 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-18 22:55:41,156 truncating revision to first 12 chars 2016-04-18 22:55:41,156 Setting DEBUG logging. 2016-04-18 22:55:41,156 attempt 1/10 2016-04-18 22:55:41,156 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb65ec85049e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 22:55:42,108 unpacking tar archive at: mozilla-central-cb65ec85049e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.196017 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 22:55:42.299908) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:42.300184) ========= script_repo_revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:42.300539) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:42.300818) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:55:42.317475) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-04-18 22:55:42.317817) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:55:42 INFO - MultiFileLogger online at 20160418 22:55:42 in /builds/slave/test 22:55:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:55:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:55:42 INFO - {'append_to_log': False, 22:55:42 INFO - 'base_work_dir': '/builds/slave/test', 22:55:42 INFO - 'blob_upload_branch': 'Firefox', 22:55:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:55:42 INFO - 'branch': 'Firefox', 22:55:42 INFO - 'buildbot_json_path': 'buildprops.json', 22:55:42 INFO - 'config_files': ('talos/mac_config.py',), 22:55:42 INFO - 'default_actions': ('clobber', 22:55:42 INFO - 'read-buildbot-config', 22:55:42 INFO - 'download-and-extract', 22:55:42 INFO - 'populate-webroot', 22:55:42 INFO - 'create-virtualenv', 22:55:42 INFO - 'install', 22:55:42 INFO - 'run-tests'), 22:55:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:55:42 INFO - 'download_minidump_stackwalk': True, 22:55:42 INFO - 'download_symbols': 'ondemand', 22:55:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:55:42 INFO - 'tooltool.py': '/tools/tooltool.py', 22:55:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:55:42 INFO - '/tools/misc-python/virtualenv.py')}, 22:55:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:55:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:55:42 INFO - 'installer_path': 'installer.exe', 22:55:42 INFO - 'log_level': 'info', 22:55:42 INFO - 'log_name': 'talos', 22:55:42 INFO - 'log_to_console': True, 22:55:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:55:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:55:42 INFO - 'opt_config_files': (), 22:55:42 INFO - 'pip_index': False, 22:55:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:42 INFO - 'cmd': ('bash', 22:55:42 INFO - '-c', 22:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:42 INFO - 'enabled': True, 22:55:42 INFO - 'halt_on_failure': False, 22:55:42 INFO - 'name': 'check_screen_resolution'},), 22:55:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:55:42 INFO - 'cmd': ('bash', 22:55:42 INFO - '-c', 22:55:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:55:42 INFO - 'enabled': True, 22:55:42 INFO - 'halt_on_failure': False, 22:55:42 INFO - 'name': 'check_screen_resolution'},), 22:55:42 INFO - 'run_cmd_checks_enabled': True, 22:55:42 INFO - 'sps_profile': False, 22:55:42 INFO - 'sps_profile_interval': 0, 22:55:42 INFO - 'suite': 'dromaeojs', 22:55:42 INFO - 'system_bits': '32', 22:55:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:55:42 INFO - 'title': 't-yosemite-r7-0148', 22:55:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:55:42 INFO - 'use_talos_json': True, 22:55:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:55:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:55:42 INFO - 'work_dir': 'build'} 22:55:42 INFO - ##### 22:55:42 INFO - ##### Running clobber step. 22:55:42 INFO - ##### 22:55:42 INFO - Running pre-action listener: _resource_record_pre_action 22:55:42 INFO - Running main action method: clobber 22:55:42 INFO - rmtree: /builds/slave/test/build 22:55:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:55:44 INFO - Running post-action listener: _resource_record_post_action 22:55:44 INFO - ##### 22:55:44 INFO - ##### Running read-buildbot-config step. 22:55:44 INFO - ##### 22:55:44 INFO - Running pre-action listener: _resource_record_pre_action 22:55:44 INFO - Running main action method: read_buildbot_config 22:55:44 INFO - Using buildbot properties: 22:55:44 INFO - { 22:55:44 INFO - "project": "", 22:55:44 INFO - "product": "firefox", 22:55:44 INFO - "script_repo_revision": "production", 22:55:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:55:44 INFO - "repository": "", 22:55:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:55:44 INFO - "buildid": "20160418205131", 22:55:44 INFO - "pgo_build": "False", 22:55:44 INFO - "basedir": "/builds/slave/test", 22:55:44 INFO - "buildnumber": 226, 22:55:44 INFO - "slavename": "t-yosemite-r7-0148", 22:55:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:55:44 INFO - "platform": "yosemite_r7", 22:55:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:55:44 INFO - "branch": "mozilla-central", 22:55:44 INFO - "repo_path": "mozilla-central", 22:55:44 INFO - "revision": "cb65ec85049efa44b18e2e8d06af26768c3bfb94", 22:55:44 INFO - "stage_platform": "macosx64", 22:55:44 INFO - "builduid": "d5db07eff05d47449dc0edb10d63b704", 22:55:44 INFO - "slavebuilddir": "test" 22:55:44 INFO - } 22:55:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:55:44 INFO - Running post-action listener: _resource_record_post_action 22:55:44 INFO - ##### 22:55:44 INFO - ##### Running download-and-extract step. 22:55:44 INFO - ##### 22:55:44 INFO - Running pre-action listener: _resource_record_pre_action 22:55:44 INFO - Running main action method: download_and_extract 22:55:44 INFO - mkdir: /builds/slave/test/build/tests 22:55:44 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')]} 22:55:44 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:55:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 22:56:14 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 22:56:14 INFO - retry: attempt #1 caught exception: timed out 22:56:14 INFO - retry: Failed, sleeping 30 seconds before retrying 22:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #2 22:56:44 INFO - Downloaded 1199 bytes. 22:56:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:56:44 INFO - Using the following test package requirements: 22:56:44 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:56:44 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:56:44 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:56:44 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'jsshell-mac.zip'], 22:56:44 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:56:44 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:56:44 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:56:44 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:56:44 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:56:44 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:56:44 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:56:44 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:56:44 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')]} 22:56:44 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:56:46 INFO - Downloaded 18037923 bytes. 22:56:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:56:50 INFO - Return code: 0 22:56:50 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:50 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:56:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:56:52 INFO - Downloaded 18037923 bytes. 22:56:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:56:56 INFO - Return code: 0 22:56:56 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')]} 22:56:56 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:56:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:56:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:56:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:56:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:56:57 INFO - Downloaded 10845952 bytes. 22:56:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:56:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:56:58 INFO - Return code: 0 22:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:58 INFO - https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 22:57:05 INFO - Downloaded 109970044 bytes. 22:57:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:57:05 INFO - mkdir: /builds/slave/test/properties 22:57:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:57:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:57:05 INFO - Contents: 22:57:05 INFO - build_url:https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:57:06 INFO - Running post-action listener: _resource_record_post_action 22:57:06 INFO - Running post-action listener: set_extra_try_arguments 22:57:06 INFO - ##### 22:57:06 INFO - ##### Running populate-webroot step. 22:57:06 INFO - ##### 22:57:06 INFO - Running pre-action listener: _resource_record_pre_action 22:57:06 INFO - Running main action method: populate_webroot 22:57:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:57:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:57:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:57:06 INFO - '--tppagecycles', 22:57:06 INFO - '7'], 22:57:06 INFO - 'tests': ['tsvgm']}}, 22:57:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:57:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:57:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:57:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:57:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:57:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['damp', 'tps']}, 22:57:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'tests': ['damp', 'tps']}, 22:57:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['dromaeo_dom']}, 22:57:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:57:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'other-e10s': {'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['a11yr', 22:57:06 INFO - 'ts_paint', 22:57:06 INFO - 'tpaint', 22:57:06 INFO - 'sessionrestore', 22:57:06 INFO - 'sessionrestore_no_auto_restore', 22:57:06 INFO - 'tabpaint']}, 22:57:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['tsvgx', 22:57:06 INFO - 'tsvgr_opacity', 22:57:06 INFO - 'tart', 22:57:06 INFO - 'tscrollx', 22:57:06 INFO - 'cart']}, 22:57:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:57:06 INFO - 'tsvgr_opacity', 22:57:06 INFO - 'tart', 22:57:06 INFO - 'tscrollx', 22:57:06 INFO - 'cart']}, 22:57:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'talos_options': ['--disable-e10s'], 22:57:06 INFO - 'tests': ['tp5o']}, 22:57:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'tests': ['tp5o']}, 22:57:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'talos_options': ['--disable-e10s', 22:57:06 INFO - '--xperf_path', 22:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:06 INFO - 'tests': ['tp5n']}, 22:57:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:57:06 INFO - 'talos_options': ['--xperf_path', 22:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:57:06 INFO - 'tests': ['tp5n']}}, 22:57:06 INFO - 'talos.zip': {'path': '', 22:57:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:57:06 INFO - Running post-action listener: _resource_record_post_action 22:57:06 INFO - ##### 22:57:06 INFO - ##### Running create-virtualenv step. 22:57:06 INFO - ##### 22:57:06 INFO - Running pre-action listener: _resource_record_pre_action 22:57:06 INFO - Running main action method: create_virtualenv 22:57:06 INFO - Creating virtualenv /builds/slave/test/build/venv 22:57:06 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 22:57:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:57:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:57:06 INFO - Using real prefix '/tools/python27' 22:57:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:57:07 INFO - Installing distribute.............................................................................................................................................................................................done. 22:57:10 INFO - Installing pip.................done. 22:57:10 INFO - Return code: 0 22:57:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:10 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:10 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 22:57:10 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 22:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:10 INFO - 'HOME': '/Users/cltbld', 22:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:10 INFO - 'LOGNAME': 'cltbld', 22:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:10 INFO - 'MOZ_NO_REMOTE': '1', 22:57:10 INFO - 'NO_EM_RESTART': '1', 22:57:10 INFO - 'PAGER': '/bin/cat', 22:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:10 INFO - 'PWD': '/builds/slave/test', 22:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:10 INFO - 'SHELL': '/bin/bash', 22:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:10 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:10 INFO - 'USER': 'cltbld', 22:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:10 INFO - 'XPC_FLAGS': '0x0', 22:57:10 INFO - 'XPC_SERVICE_NAME': '0', 22:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:10 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 22:57:10 INFO - Cleaning up... 22:57:10 INFO - Return code: 0 22:57:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:10 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:10 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 22:57:10 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 22:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:10 INFO - 'HOME': '/Users/cltbld', 22:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:10 INFO - 'LOGNAME': 'cltbld', 22:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:10 INFO - 'MOZ_NO_REMOTE': '1', 22:57:10 INFO - 'NO_EM_RESTART': '1', 22:57:10 INFO - 'PAGER': '/bin/cat', 22:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:10 INFO - 'PWD': '/builds/slave/test', 22:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:10 INFO - 'SHELL': '/bin/bash', 22:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:10 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:10 INFO - 'USER': 'cltbld', 22:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:10 INFO - 'XPC_FLAGS': '0x0', 22:57:10 INFO - 'XPC_SERVICE_NAME': '0', 22:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:10 INFO - Downloading/unpacking psutil>=0.7.1 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:14 INFO - Installing collected packages: psutil 22:57:14 INFO - Running setup.py install for psutil 22:57:14 INFO - building 'psutil._psutil_osx' extension 22:57:14 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 22:57:14 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 22:57:15 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 22:57:15 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 22:57:15 INFO - building 'psutil._psutil_posix' extension 22:57:15 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 22:57:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:57:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:57:15 INFO - ^ 22:57:15 INFO - 1 warning generated. 22:57:15 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 22:57:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:57:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:57:15 INFO - Successfully installed psutil 22:57:15 INFO - Cleaning up... 22:57:15 INFO - Return code: 0 22:57:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:15 INFO - 'HOME': '/Users/cltbld', 22:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:15 INFO - 'LOGNAME': 'cltbld', 22:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:15 INFO - 'MOZ_NO_REMOTE': '1', 22:57:15 INFO - 'NO_EM_RESTART': '1', 22:57:15 INFO - 'PAGER': '/bin/cat', 22:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:15 INFO - 'PWD': '/builds/slave/test', 22:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:15 INFO - 'SHELL': '/bin/bash', 22:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:15 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:15 INFO - 'USER': 'cltbld', 22:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:15 INFO - 'XPC_FLAGS': '0x0', 22:57:15 INFO - 'XPC_SERVICE_NAME': '0', 22:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:57:15 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 22:57:15 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 22:57:15 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 22:57:15 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 22:57:15 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 22:57:15 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 22:57:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:57:18 INFO - Installing collected packages: mozsystemmonitor 22:57:18 INFO - Running setup.py install for mozsystemmonitor 22:57:18 INFO - Successfully installed mozsystemmonitor 22:57:18 INFO - Cleaning up... 22:57:18 INFO - Return code: 0 22:57:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:18 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:18 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 22:57:18 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 22:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:18 INFO - 'HOME': '/Users/cltbld', 22:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:18 INFO - 'LOGNAME': 'cltbld', 22:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:18 INFO - 'MOZ_NO_REMOTE': '1', 22:57:18 INFO - 'NO_EM_RESTART': '1', 22:57:18 INFO - 'PAGER': '/bin/cat', 22:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:18 INFO - 'PWD': '/builds/slave/test', 22:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:18 INFO - 'SHELL': '/bin/bash', 22:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:18 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:18 INFO - 'USER': 'cltbld', 22:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:18 INFO - 'XPC_FLAGS': '0x0', 22:57:18 INFO - 'XPC_SERVICE_NAME': '0', 22:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:18 INFO - Downloading/unpacking blobuploader==1.2.4 22:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:21 INFO - Downloading blobuploader-1.2.4.tar.gz 22:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:57:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:57:21 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 22:57:21 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 22:57:21 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 22:57:21 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 22:57:21 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 22:57:21 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 22:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:57:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:22 INFO - Downloading docopt-0.6.1.tar.gz 22:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:57:22 INFO - Installing collected packages: blobuploader, requests, docopt 22:57:22 INFO - Running setup.py install for blobuploader 22:57:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:57:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:57:22 INFO - Running setup.py install for requests 22:57:23 INFO - Running setup.py install for docopt 22:57:23 INFO - Successfully installed blobuploader requests docopt 22:57:23 INFO - Cleaning up... 22:57:23 INFO - Return code: 0 22:57:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:23 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:23 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 22:57:23 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 22:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:23 INFO - 'HOME': '/Users/cltbld', 22:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:23 INFO - 'LOGNAME': 'cltbld', 22:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:23 INFO - 'MOZ_NO_REMOTE': '1', 22:57:23 INFO - 'NO_EM_RESTART': '1', 22:57:23 INFO - 'PAGER': '/bin/cat', 22:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:23 INFO - 'PWD': '/builds/slave/test', 22:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:23 INFO - 'SHELL': '/bin/bash', 22:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:23 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:23 INFO - 'USER': 'cltbld', 22:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:23 INFO - 'XPC_FLAGS': '0x0', 22:57:23 INFO - 'XPC_SERVICE_NAME': '0', 22:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-2Zi9dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-_Y9tPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-epscmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:23 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-5UO9Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-o9thAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-9LpQAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6bt8UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-DTfNLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-CsyYoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-OZe_eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-b8Tk2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-DJb5WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-oH0wAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:24 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-vGvrUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-nOhnci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-VX2aWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:25 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ErNjap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:57:25 INFO - Running setup.py install for manifestparser 22:57:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:57:25 INFO - Running setup.py install for mozcrash 22:57:25 INFO - Running setup.py install for mozdebug 22:57:25 INFO - Running setup.py install for mozdevice 22:57:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:57:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:57:25 INFO - Running setup.py install for mozfile 22:57:26 INFO - Running setup.py install for mozhttpd 22:57:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Running setup.py install for mozinfo 22:57:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Running setup.py install for mozInstall 22:57:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Running setup.py install for mozleak 22:57:26 INFO - Running setup.py install for mozlog 22:57:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Running setup.py install for moznetwork 22:57:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:57:26 INFO - Running setup.py install for mozprocess 22:57:27 INFO - Running setup.py install for mozprofile 22:57:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:57:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:57:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:57:27 INFO - Running setup.py install for mozrunner 22:57:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:57:27 INFO - Running setup.py install for mozscreenshot 22:57:27 INFO - Running setup.py install for moztest 22:57:27 INFO - Running setup.py install for mozversion 22:57:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:57:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:57:27 INFO - Cleaning up... 22:57:27 INFO - Return code: 0 22:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:27 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')]} 22:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:27 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')]} 22:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:27 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:27 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 22:57:27 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 22:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:27 INFO - 'HOME': '/Users/cltbld', 22:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:27 INFO - 'LOGNAME': 'cltbld', 22:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:27 INFO - 'MOZ_NO_REMOTE': '1', 22:57:27 INFO - 'NO_EM_RESTART': '1', 22:57:27 INFO - 'PAGER': '/bin/cat', 22:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:27 INFO - 'PWD': '/builds/slave/test', 22:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:27 INFO - 'SHELL': '/bin/bash', 22:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:27 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:27 INFO - 'USER': 'cltbld', 22:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:27 INFO - 'XPC_FLAGS': '0x0', 22:57:27 INFO - 'XPC_SERVICE_NAME': '0', 22:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-u5DZ1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-2YsHkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-6T30Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-5p5wVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-g9WuZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-sHIpxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-TccTiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-9jB0xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:28 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)) 22:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-N7EdVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-MueoFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-BGKcO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ddzv66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-bZDR7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-qfbC5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-DcEKYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-7Xy9UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:29 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)) 22:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ufGBen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:29 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)) 22:57:29 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)) 22:57:29 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)) 22:57:29 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)) 22:57:29 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)) 22:57:29 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)) 22:57:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:32 INFO - Downloading blessings-1.6.tar.gz 22:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:57:32 INFO - Installing collected packages: blessings 22:57:32 INFO - Running setup.py install for blessings 22:57:32 INFO - Successfully installed blessings 22:57:32 INFO - Cleaning up... 22:57:32 INFO - Return code: 0 22:57:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:33 INFO - Reading from file tmpfile_stdout 22:57:33 INFO - Current package versions: 22:57:33 INFO - blessings == 1.6 22:57:33 INFO - blobuploader == 1.2.4 22:57:33 INFO - docopt == 0.6.1 22:57:33 INFO - manifestparser == 1.1 22:57:33 INFO - mozInstall == 1.12 22:57:33 INFO - mozcrash == 0.17 22:57:33 INFO - mozdebug == 0.1 22:57:33 INFO - mozdevice == 0.48 22:57:33 INFO - mozfile == 1.2 22:57:33 INFO - mozhttpd == 0.7 22:57:33 INFO - mozinfo == 0.9 22:57:33 INFO - mozleak == 0.1 22:57:33 INFO - mozlog == 3.1 22:57:33 INFO - moznetwork == 0.27 22:57:33 INFO - mozprocess == 0.22 22:57:33 INFO - mozprofile == 0.28 22:57:33 INFO - mozrunner == 6.11 22:57:33 INFO - mozscreenshot == 0.1 22:57:33 INFO - mozsystemmonitor == 0.0 22:57:33 INFO - moztest == 0.7 22:57:33 INFO - mozversion == 1.4 22:57:33 INFO - psutil == 3.1.1 22:57:33 INFO - requests == 1.2.3 22:57:33 INFO - wsgiref == 0.1.2 22:57:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:33 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:33 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 22:57:33 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 22:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:33 INFO - 'HOME': '/Users/cltbld', 22:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:33 INFO - 'LOGNAME': 'cltbld', 22:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:33 INFO - 'MOZ_NO_REMOTE': '1', 22:57:33 INFO - 'NO_EM_RESTART': '1', 22:57:33 INFO - 'PAGER': '/bin/cat', 22:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:33 INFO - 'PWD': '/builds/slave/test', 22:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:33 INFO - 'SHELL': '/bin/bash', 22:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:33 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:33 INFO - 'USER': 'cltbld', 22:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:33 INFO - 'XPC_FLAGS': '0x0', 22:57:33 INFO - 'XPC_SERVICE_NAME': '0', 22:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 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)) 22:57:33 INFO - Cleaning up... 22:57:33 INFO - Return code: 0 22:57:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:33 INFO - 'HOME': '/Users/cltbld', 22:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:33 INFO - 'LOGNAME': 'cltbld', 22:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:33 INFO - 'MOZ_NO_REMOTE': '1', 22:57:33 INFO - 'NO_EM_RESTART': '1', 22:57:33 INFO - 'PAGER': '/bin/cat', 22:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:33 INFO - 'PWD': '/builds/slave/test', 22:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:33 INFO - 'SHELL': '/bin/bash', 22:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:33 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:33 INFO - 'USER': 'cltbld', 22:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:33 INFO - 'XPC_FLAGS': '0x0', 22:57:33 INFO - 'XPC_SERVICE_NAME': '0', 22:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:33 INFO - Downloading/unpacking jsonschema 22:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:57:35 INFO - Downloading/unpacking functools32 (from jsonschema) 22:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:36 INFO - Downloading functools32-3.2.3-2.tar.gz 22:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:57:36 INFO - warning: no files found matching '*.txt' 22:57:36 INFO - no previously-included directories found matching 'build' 22:57:36 INFO - no previously-included directories found matching 'dist' 22:57:36 INFO - no previously-included directories found matching '.git*' 22:57:36 INFO - Installing collected packages: jsonschema, functools32 22:57:36 INFO - Running setup.py install for functools32 22:57:36 INFO - warning: no files found matching '*.txt' 22:57:36 INFO - no previously-included directories found matching 'build' 22:57:36 INFO - no previously-included directories found matching 'dist' 22:57:36 INFO - no previously-included directories found matching '.git*' 22:57:36 INFO - Successfully installed jsonschema functools32 22:57:36 INFO - Cleaning up... 22:57:36 INFO - Return code: 0 22:57:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:36 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 0x10c682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9298c64290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, '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.dCJe8aqrbP/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.7NrkRctgrh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 22:57:36 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 22:57:36 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 22:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:57:36 INFO - 'HOME': '/Users/cltbld', 22:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:57:36 INFO - 'LOGNAME': 'cltbld', 22:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:36 INFO - 'MOZ_NO_REMOTE': '1', 22:57:36 INFO - 'NO_EM_RESTART': '1', 22:57:36 INFO - 'PAGER': '/bin/cat', 22:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:36 INFO - 'PWD': '/builds/slave/test', 22:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:57:36 INFO - 'SHELL': '/bin/bash', 22:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:57:36 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:57:36 INFO - 'USER': 'cltbld', 22:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:36 INFO - 'XPC_FLAGS': '0x0', 22:57:36 INFO - 'XPC_SERVICE_NAME': '0', 22:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:37 INFO - Downloading/unpacking flake8 22:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:57:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:57:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:39 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 22:57:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:57:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:40 INFO - no previously-included directories found matching 'docs/_build' 22:57:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:57:40 INFO - Running setup.py install for pep8 22:57:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:40 INFO - no previously-included directories found matching 'docs/_build' 22:57:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:57:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:57:40 INFO - Cleaning up... 22:57:41 INFO - Return code: 0 22:57:41 INFO - Running post-action listener: _resource_record_post_action 22:57:41 INFO - Running post-action listener: _start_resource_monitoring 22:57:41 INFO - Starting resource monitoring. 22:57:41 INFO - ##### 22:57:41 INFO - ##### Running install step. 22:57:41 INFO - ##### 22:57:41 INFO - Running pre-action listener: _resource_record_pre_action 22:57:41 INFO - Running main action method: install 22:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:41 INFO - Reading from file tmpfile_stdout 22:57:41 INFO - Detecting whether we're running mozinstall >=1.0... 22:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:57:41 INFO - Reading from file tmpfile_stdout 22:57:41 INFO - Output received: 22:57:41 INFO - Usage: mozinstall [options] installer 22:57:41 INFO - Options: 22:57:41 INFO - -h, --help show this help message and exit 22:57:41 INFO - -d DEST, --destination=DEST 22:57:41 INFO - Directory to install application into. [default: 22:57:41 INFO - "/builds/slave/test"] 22:57:41 INFO - --app=APP Application being installed. [default: firefox] 22:57:41 INFO - mkdir: /builds/slave/test/build/application 22:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:58:15 INFO - Reading from file tmpfile_stdout 22:58:15 INFO - Output received: 22:58:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:58:15 INFO - Running post-action listener: _resource_record_post_action 22:58:15 INFO - ##### 22:58:15 INFO - ##### Running run-tests step. 22:58:15 INFO - ##### 22:58:15 INFO - Running pre-action listener: _resource_record_pre_action 22:58:15 INFO - Running main action method: run_tests 22:58:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:58:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:58:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:58:15 INFO - 2016-04-18 22:58:15.545 screenresolution[1649:8758] starting screenresolution argv=screenresolution get 22:58:15 INFO - 2016-04-18 22:58:15.547 screenresolution[1649:8758] Display 0: 1600x1200x32@60 22:58:15 INFO - 2016-04-18 22:58:15.553 screenresolution[1650:8760] starting screenresolution argv=screenresolution list 22:58:15 INFO - Available Modes on Display 0 22:58:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:58:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:58:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:58:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:58:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:58:15 INFO - Intel Iris: 22:58:15 INFO - Chipset Model: Intel Iris 22:58:15 INFO - Type: GPU 22:58:15 INFO - Bus: Built-In 22:58:15 INFO - VRAM (Dynamic, Max): 1536 MB 22:58:15 INFO - Vendor: Intel (0x8086) 22:58:15 INFO - Device ID: 0x0a2e 22:58:15 INFO - Revision ID: 0x0009 22:58:15 INFO - Displays: 22:58:15 INFO - Display: 22:58:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:58:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:58:15 INFO - Display Serial Number: 1600x1200 60 22:58:15 INFO - Main Display: Yes 22:58:15 INFO - Mirror: Off 22:58:15 INFO - Online: Yes 22:58:15 INFO - Rotation: Supported 22:58:15 INFO - Return code: 0 22:58:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:58:15 INFO - Python 2.7.3 22:58:15 INFO - Return code: 0 22:58:15 INFO - grabbing minidump binary from tooltool 22:58:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c521e00>, '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 0x10c34adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c51cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:58:15 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 22:58:15 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 22:58:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:58:15 INFO - Return code: 0 22:58:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:58:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:58:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:58:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:58:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:58:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:58:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:58:17 INFO - Return code: 0 22:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 22:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0148 --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 22:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dCJe8aqrbP/Render', 22:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:17 INFO - 'HOME': '/Users/cltbld', 22:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:17 INFO - 'LOGNAME': 'cltbld', 22:58:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:58:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:17 INFO - 'MOZ_NO_REMOTE': '1', 22:58:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:58:17 INFO - 'NO_EM_RESTART': '1', 22:58:17 INFO - 'PAGER': '/bin/cat', 22:58:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:17 INFO - 'PWD': '/builds/slave/test', 22:58:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:17 INFO - 'SHELL': '/bin/bash', 22:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7NrkRctgrh/Listeners', 22:58:17 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 22:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:17 INFO - 'USER': 'cltbld', 22:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:17 INFO - 'XPC_FLAGS': '0x0', 22:58:17 INFO - 'XPC_SERVICE_NAME': '0', 22:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 22:58:17 INFO - mozversion application_buildid: 20160418205131 22:58:17 INFO - mozversion application_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:58:17 INFO - mozversion application_display_name: Nightly 22:58:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:58:17 INFO - mozversion application_name: Firefox 22:58:17 INFO - mozversion application_remotingname: firefox 22:58:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:58:17 INFO - mozversion application_vendor: Mozilla 22:58:17 INFO - mozversion application_version: 48.0a1 22:58:17 INFO - mozversion platform_buildid: 20160418205131 22:58:17 INFO - mozversion platform_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:58:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:58:17 INFO - mozversion platform_version: 48.0a1 22:58:17 INFO - using testdate: 1461045497 22:58:17 INFO - actual date: 1461045497 22:58:17 INFO - starting webserver on 'localhost:49281' 22:58:17 INFO - SUITE-START | Running 2 tests 22:58:17 INFO - TEST-START | dromaeo_css 22:58:17 INFO - operating with platform_type : mac_ 22:58:17 INFO - Initialising browser for dromaeo_css test... 22:58:17 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpivfAOs/profile http://localhost:49281/getInfo.html) 22:58:19 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:58:19 INFO - PROCESS | 1668 | colorDepth:24 22:58:19 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:58:19 INFO - PROCESS | 1668 | __metrics 22:58:19 INFO - PROCESS | 1668 | 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" 22:58:19 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:58:20 INFO - TEST-INFO | 1668: exit 0 22:58:20 INFO - Browser initialized. 22:58:20 INFO - Running cycle 1/1 for dromaeo_css test... 22:58:20 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpivfAOs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:58:32 INFO - PROCESS | 1671 | RSS: Main: 237371392 22:58:32 INFO - PROCESS | 1671 | 22:59:23 INFO - PROCESS | 1671 | 1461045563220 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] 23:01:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 23:01:35 INFO - PROCESS | 1671 | RSS: Main: 432218112 23:01:35 INFO - PROCESS | 1671 | 23:04:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 23:04:39 INFO - PROCESS | 1671 | RSS: Main: 372899840 23:04:39 INFO - PROCESS | 1671 | 23:07:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 23:07:42 INFO - PROCESS | 1671 | RSS: Main: 373837824 23:07:42 INFO - PROCESS | 1671 | 23:10:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 23:10:44 INFO - PROCESS | 1671 | RSS: Main: 375656448 23:10:44 INFO - PROCESS | 1671 | 23:13:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 23:13:48 INFO - PROCESS | 1671 | RSS: Main: 351350784 23:13:48 INFO - PROCESS | 1671 | 23:16:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 23:16:52 INFO - PROCESS | 1671 | RSS: Main: 380977152 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | __start_tp_report 23:16:52 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 23:16:52 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 23:16:52 INFO - PROCESS | 1671 | |i|pagename|runs| 23:16:52 INFO - PROCESS | 1671 | |0;dojo.html;3388.136025313545;3443.6039855416634;3472.374972504949;3479.269353435778;3504.6495350464957;5602.663840169593;5647.152927060943;5647.491725744682;5688.658680479164;5702.059160238556;5688.829335119943;5726.656400615966;5777.913331300034;5832.300123985117;5863.179154918314;5094.184930411135;5107.897842043176;5142.357205349332;5173.224016397533;5261.158214685651;5222.92165617516;5273.551748101416;5275.57795376369;5285.762140703678;5329.280547126146;5092.210707340369;5141.665791723526;5154.664946778447;5167.48325167484;5189.455107213726;5095.133827249377;5124.590032797404;5129.871753206163;5134.5378915897745;5142.51146141116;5009.824656137054;5073.213651883927;5093.465186155475;5113.795448182068;5118.897622047538;5540.584456165763;5578;5665.3201615806365;5702.030654788655;5704.857378565569;6150.200473938359;6165.876682466325;6166.537509686791;6180.567360284737;6182.505399568024;6168.629882207082;6193.721282542297;6223.346548612421;6266.811995640092;6267.153934218868;5619.80330688424;5698.999999999958;5743.282089738783;5830.679312637761;5839.124131380242;6193.318734939153;6247.500199983991;6251.937480625226;6254.124422580842;6260.937390626035;6122.632642041492;6131.448169664752;6132.2947860996555;6143.6928153593035;6147.170132032208;6125.111858780452;6147.047207682816;6184.134221209034;6234.532410069263;6235.875282494325;6157.784477543264;6165.691715414211;6180.381961803873;6189.81430557084;6199.721012554446;6195.876082478415;6230.252369715572;6250.656213908279;6252.781152659635;6258;4818.831340903122;4886.584640305614;4887.633427492952;4894.2658601210105;4901.264810278415;4339.913201735948;4379.124175164916;4395.934060989088;4413.16149931512;4431.889202769892;5687.630304030225;5689.345725241567;5693.373728889819;5708.657480551097;5712.971435142797;6103.610425594294;6122.215193971453;6254.280757712831;6263.467605253513;6263.71813268404;5645.576581756384;5695.316562012585;5699.601027928005;5752.02215623345;5769.48074673273;5316.175992721134;5330.760115794798;5344.225087362389;5345.358556973188;5362.1420572708175;6151.815445536641;6217.191765070512;6277.2781130170615;6282.842928926722;6287.59130656515;6221.160143380585;6258.311585725475;6271.122042913905;6277.74889004453;6281.309056003744;6252.843678908063;6258.43674069336;6267.96866015667;6271.874562508633;6275.058741188858;6043.486303664238;6090.908636370369;6100.520618787021;6124.969375153096;6157.353477884937;6214.937850621436;6223.3465486125115;6227.190465239671;6249.312575616587;6249.8750024998335;5062.924056139087;6083.482903953037;6085.939140608537;6114.205153330038;6120.693965301806;5929.288485381781;5988.000158938161;6061.909071364022;6085.939140608537;6117.296510901126;5722.570807189394;5874.971879921082;5887.735051922759;5902.439268269508;5913.46778789911;5982.670953097621;6025.487833534199;6044.546659000681;6050.21347224858;6054.152418661303;5871.412858714094;5893.499052580579;5902.2622172228475;5927.9703601481715;5929.436703513159 23:16:52 INFO - PROCESS | 1671 | |1;ext.html;512.3902555958407;533.0538294527214;582.0512564519834;593.0333556303225;605.8152263559613;29323.266918327052;37437.625623743756;38412.42381364278;38596.80701596495;38740.61259387405;42332.36501452473;43050.56949430505;43090.78454607722;43153.35269970953;43284.783576082154;8410.495370277773;9510.762230944212;9760.07279308468;9761.951190244074;9792.314537982344;8565.657373705044;11523.539058437653;11868.228565143281;11879;11900.404979751022;11443.027342675883;11550.4802283897;11572.016378607828;11578.652640420802;11589.6523104307;8584.914150858536;9934.403935763841;11446.141539384538;11512.942435287854;11863.881361186364;11319.717007074807;11493.827592586118;11506.88493115075;11517.308961462339;11532.481038353295;37345.69290074834;37994.62005379939;38021;38080.80959595184;38193.80903095467;3095.189518155637;3130.0922732407575;3180;3419.264858055505;3928.46965659638;27630.171094867186;27772.861135694395;28182.01362952287;28458.715412845813;28644.713552864414;2680.731926807304;2713.864306784653;2716.660417447819;2750.2574304937757;2831.66020077592;470852;471217;471932.6403367961;472436;473145;59052;59285.11072333918;59719.402805972255;59752;59829.70085149634;62791.372086278556;65515.67242163759;67184.6640766793;67218.6556268872;67282.32717672859;16244.350225991064;16491.505254842374;16498.25757840876;16508.587285317964;16574.419895303607;11618.88381116195;11855.46650400734;12582;12672.429740661692;12903.483860645469;18971.620567588572;19037.714434283498;19088;19088.522786930436;19102.61794764097;7670.7698769037015;7823.608819558999;7883.329916957008;7932.563708996061;7972.68450705627;932.4871320773683;976.9316147869723;986.2258127370123;1073.8496610474383;1092.2845536173818;29088.41823163567;29776.2555936099;29806.70193298039;30025.94909178212;30374.69625303763;1104.861892263467;1123.1239633086207;1124.7806677697934;1205.7528206717602;1206.9396530173456;13042.673933151747;14980.1011939284;15411.845881541265;15596.220188990732;15657.68684626324;639138;644957;645843;646107;648096;57262;59708.10437843436;59792.40207597868;59823.40176598266;60448.697756510934;1346.6767975685764;1362.7410791949696;1445.4290555230834;1487.8809695224356;1494.8505149485181;28422.573661394683;28566.00018999325;28602.713972860838;29060;29226;44632.553674464136;44844.878878027004;45387;45417.54582454133;45562.772186138856;323203;324445;324499;325448;325961;22969.655455167853;26061.73938260669;26791.79436925382;26927.326816829736;27054.72945270522;44001.77999110112;44224;44425.77787111044;44627;44753.88115297144;35107.64892351146;36425;37394.43908341323;37469.250614987;37879.62120378761;28493.857530712216;35701.46447803233;36273.27453450864;36963.44554831626;37020.62979370279;36250.456243155844;36658.08354791045;37120.44319335158;37301.81349093237;37317.4402383959;30173.396532069673;31854.203644909063;32188;32215.51676724898;32282.515762264055;23592.292231233092;23887.761122388554;24031.639525406783;24208.87895560511;24295.27114186577 23:16:52 INFO - PROCESS | 1671 | |2;jquery.html;391.620128475379;393.99606003939954;396.32624538284887;396.978166200859;402.61147992187534;13374.59876203713;19764.604707905833;20436.182552697894;20502.589948201028;20548.383548493533;15978;16915;19337.033148342598;20012.799872001277;20228.797712022875;5855.795047173339;6016.669083200422;6053.243344581927;6057.575969682124;6073.908891366633;4952.529509696573;5701.34434540027;5876.236089308384;5918.437748413915;5941.970290148543;5846.50304724099;5861.560382971273;5881.8823623527505;5883.411658834125;5890.234269544953;5676.517496012823;5847.386024467433;5888.4700376966275;5888.882222355551;5901.557383196235;5073.822416215452;5096.05722941257;5142.1772516397195;5156.767945442464;5167.689938603658;24445.38886527833;25059.874700626562;25499.235022949342;25537.61693574598;25863.870680646476;2065.1842522203647;2072.7409073865765;2218.5673793610254;2766.21162968553;3040.7415369693604;14368.425262989467;14658.633534161625;14731.852681473156;14891.10653360791;15031.248437578186;1329.508082009653;1541.8381069987602;1549.7907782449338;1735.3752649046332;1742.1028170492207;103566;103733;104306;106905;109692;7723.806904827425;9118.48634925716;11578.710532236593;11592.594259201056;11623.825642615368;12386.690332741573;12526.498940042296;14942.25288735559;14966.625834354229;14984.775228371584;6673.499487538454;6937.819356965517;7538.736144234926;8635.136486351315;8718.782030449163;7143.749968751173;7635.618219089023;8046.109164346374;8571.785705357415;8661.783455413664;4458.509563947963;4562.297474460125;4571.497135315112;4586.334981427674;4618.1918164321105;1654.983450165483;1914.334528959786;1961.3723608445162;1965.9606807863763;1968.4094771568473;926.6432423516994;954.4130359828794;978.295627148452;985.2019863910112;1001.9649312274036;19309.613807723767;20150.39548813538;20183.39449816508;20296.492587685425;20596.794032059486;977.3451787302524;995.721198064543;1005.3967619428284;1012.2661070723814;1017.8218811707922;10315.381077135396;10344.482775861177;10826.512806923707;11017.669469915754;11078.778424431466;543504.5649543533;596369;614268;624757;645488;125780.74219257873;127644;128126.35936820257;128609;131857;1018.3686114608989;1037;1043.879953805307;1049.4018409506507;1071.341125208007;14897.702045958804;18360.540986475444;18360.72458913144;18554.44336669902;18783.15475803565;49676.751616243135;50452;50913.49086509235;51113.48886511087;51208.71978200575;71269.57460850857;71310.64807874923;71958.28041719725;72113;72301.27698722946;6883.931160688529;7168.641567921479;7979.202079791974;8000.319972802377;8256.009278886571;11181.217314787888;12473.93763031179;12585.056120790794;12746.426410811351;12753.872461275269;10509;10689.251752377259;10994.450277485934;11042.889571104186;11113.833292500458;10922.180836437457;11023.06303964172;11054.502547385548;11128.276662016942;11414.543418263176;11015.559377624806;11047.834282485608;11193.216474847008;11352.886471135513;11619;8017.719379821679;8087.989001374828;8127.390445716713;8296.751097467086;8447.873281900653;10149.746256343651;10893.564257429614;10913;11217.943910280397;11281.943590281997 23:16:52 INFO - PROCESS | 1671 | |3;mootools.html;1350.500314883493;1415.8230221222348;1452.4698485052957;1765.7263124215742;1973.8618296719226;7903.92096079039;8598.183172598601;8815.206631403173;8835.823283534326;8849.601767920434;8443.873341899876;8666.869996950036;8684.218420342168;8690.56547172642;8695.434796738207;2432.562138815017;2463.0147940823636;2465.4206261528525;2468.271859801354;2473.4063824682034;2409.5421869844763;2472.159465781634;2476.2199907029317;2480.193936970483;2487.465194983078;2418.9153379631716;2444.4499987502845;2464.088287333689;2465.963010554834;2471.542764588548;2410.313060777679;2432.6107822748363;2444.340028192387;2445.058652418814;2450.191436825843;2380.4343526247;2422.3822925153972;2423.7697418745192;2425.4906469641396;2428.1016024071205;8466.111058338909;8593.957030214871;8732.083131271253;8746.56267186638;8754.168354006359;5230.189320655304;5435.891282174334;5443.074677304868;5447.101228297326;5471.097268950618;3240.5139229115584;3405.676460736226;3424.5890493140982;3538.6461353864665;3610.9277814443562;1015.9796804063877;1039.3607931122306;1045.1064339989275;1048.1038711901367;1050.3855244681795;11498.252613580175;11543.249688770287;11553.24903881245;11559.942200288944;11586.362750048827;3991.7804520751056;4503.189425903303;4534.77326133692;4671.252599584051;4708.223143181405;9098.590563424661;9112.362134650512;9117.680881169128;9125.680601178927;9158.313126515539;5191.662541934762;5234.293370395024;5245.763940622681;5256.737163141904;5262.342207224097;4070.247004304213;4554.407926969473;4696.624270058387;4766.118268120409;4924.3352147460355;3620.5293311869814;3641.362761516724;3699.9630003699617;3723;3733.757305775116;1126.645106791358;1137.351709525579;1141.2410746853416;1152.2164927848976;1152.4065106470175;657.6284399314372;804.9356051515865;885.9291256699448;926.6525053105086;943.6366998705548;5382.973085134549;5661.688607126565;5741.827745167651;5852.561057920673;5880.382559831241;937.1752857485369;963.5808423335872;969.1035791892472;976.0581039297113;1017.5065093429681;3678.9264214715554;3892.649661530419;3897.512810898638;3968.503937007874;3970.5830887756947;10098.949505252427;10257.74355641115;10532.315399499008;10625.734356641145;10752.569897204181;10480.371177729361;10546.841797373045;10581.894181058246;10588;10605.893941060645;7735.110462296795;8048.758537243892;8167.264946154874;8167.71413000554;8173.141820108803;5636.126400407943;5787.334456537552;5841.707914604338;5915.40845915405;5961.821145365646;9799;9903.55434005457;9913.256505762241;9940.155086817413;9971.40171589707;8839.425437346552;9080.636774528944;9817.214622830013;9945.254105942227;10026.398416095057;7235.63821808918;7533.472656914183;7535;7536.962315188608;7571.977782999445;8017.318527924958;8118.878216826871;8181;8201.71294004707;8201.835963280582;7218.675159617935;7353.8161545961775;7386.778396648109;7548.698052077855;7689.192634773268;7202.063731714844;7279.781606551812;7438.181800002104;7454.813629659302;7498.437617178843;6014.36849130835;6341.55609107358;6572.8356791080605;6766.424853887278;6812.284710105368;4664.137134630104;4715.952840471688;4847.684900481458;4848.00615873738;5002.399712034579;4758.833440829554;4916.582090522203;4930.6795058321095;4948.38145231846;5082.136036873814 23:16:52 INFO - PROCESS | 1671 | |4;prototype.html;310.77313561100397;315.58342987256816;319.6116718187402;325.79637726421004;328.19591999600954;5824.563157763168;5884.941150588493;5895.469407753301;5906.232189815329;5925.407459254073;5042.369703787027;5648.265725455695;5851.15158302046;5858.73635686394;5861;4190.769507677077;4219.177260434209;4254.659627229829;4297.871063868073;4389.7146685465505;3757.2485502899394;4027.8388864445387;4452.599266066053;4499.460064792213;4529.644584815894;3842.9423558646504;4246.68149888758;4446.088551846864;4451.820267629081;4466.932996005063;3171.160726778463;3957.960420395788;3991.481107456041;4113.074558224375;4435.911281774363;3181.681831816823;3618.4029635110173;3690.5017822593873;3873.8644147454984;3893.4549163117185;5567.721613919288;5596.524295434893;5600.991821472134;5632.718364081779;5649.887002259973;4941;5126.359205099363;5156.819511317086;5159.845204643867;5164.406093299243;4875.366202393701;5498.80754173602;5707.885842283173;6174;6218.782342618032;4113.280175969194;4273.358996150602;4279.251131052085;4292.678049146327;4297.226499230137;5797.507211886995;6136.171616831715;6371.74513019474;6527.314631963623;6552.541322107454;4344.565543445693;4347.34789781529;4372.0818628088455;4379.5182529921685;4394.604485596312;4401.867943961686;4422.535633758409;4424.33634954753;4450.599446049871;4470.5976462117915;4907.705537667752;4931.926021109686;5107.182850743863;5112.335396398445;5137.614678899097;6190.564236848592;6208.937910620926;6213.2233470816145;6218.751249950042;6220.626762394271;1505.4053648808658;1525.6948610277989;1531.4180611367608;1532.5938626264049;1532.7547592385165;860.3117505995303;944.607987685103;976.0673865745721;976.1504282811402;1017.5421060522795;5113.437521872591;5141.331626888481;5684.772609095672;5694.5159661428415;5738.225339579103;5617.354004289559;6386.712597933104;6388.425041746265;6414.679266036679;6417.518686098468;4856.975715121402;5479.917801232985;5703.800366987136;5718.941181174774;5731.942680573225;2990.491616425213;3110.891118810876;3281;3335.483000134983;3374.611919629274;5561.805336813192;6103.389661033861;6319.747210111636;6634.483723626486;6657.23441804189;5553.8333849984565;6515.185601799775;6556.377144171296;6587.670616469253;6591.176102987127;5963.4334738199805;6109.2974307955155;6441;6638.767643132467;6814.318568143146;5306.920396193983;5401.351837779492;5498.920604461514;6399.232092148972;6443.456099145545;5366.543843773166;6251.570777380805;6257.374262573706;6359.170169274628;6376.52176086786;6139.57023008403;6661.733530658719;6742.764003259862;6743.2582415935185;6789.1174147360525;4198.832046718097;4368.650507959419;4373.278409062596;4380.846670366522;4388.648908087409;6238.874290073776;6402.679866006774;6408.359164083741;6422.811779820748;6427.453666438405;5198.726312053571;6170.809362676186;6188.535859810442;6210.149707181577;6212.229514588321;5030.141958903198;6066.484348830399;6227.906581401192;6236.469900058546;6238.251409830849;6054.606450580698;6071.058985857199;6082.7262773174425;6095;6113.23584551931;5493.093639549589;6196.225471816023;6208.565400421927;6210.4100110489435;6218.626882387071;5693.857653558529;6106.816795496142;6111.572189946839;6131.264248290234;6137.631742095488 23:16:52 INFO - PROCESS | 1671 | |5;yui.html;556.7494627417663;606.5875204860695;658.7694306992553;701.582558377765;741.1883987034197;14358.066725662824;14617.926910365446;14639.853601463983;14834.258287085642;15093.924530377333;11523.711907202323;13651.31743412828;13709.65725856854;13852.722945541083;13870.514531991383;725.434161354142;740.8222026713604;891.2602539891907;901.0989010989027;918.701422037837;920.7559996600907;923.6628630549868;924.1128516624016;924.685606893656;925.4863550729376;972.125087421323;976.9413835169877;977.5991843344231;977.8435450327913;979.8824141103078;750.504666919833;938.943663380196;966.5891995901713;969.3795970578835;974.6880998080617;719.0364911019259;719.931606497382;720.3085038363125;720.387670480093;720.8990741296222;14897.404103835834;14976.026558273677;15000;15010.849891501055;15011;11980;12385.62843114708;12450.81323780144;12569.497220111276;12706.936465317614;9806.411615303105;9994.350367226107;10131.696049118538;10139.695809125738;10730.409827459487;3400.591928968515;3478.4086705260165;3485.8257087145794;3531.9293614127832;3605.387084195667;73091.26908730897;73381;73747.89378159332;74241.88637170447;75781.6210918942;9222.262219022463;9223.58493867764;9373.203277721334;9394.342396032349;9780.35106156219;20477.488062798548;20515.17939282412;20543.589128217653;20547.8972605136;20614.278500252418;7185.173705023884;7250.492465527468;7262.891056634155;7270.454715896329;7411.444141689395;3929.744566603219;3999.6400323970984;4023.3160362738404;4093.7543747375253;4247.086876321606;3600.1719604491113;3703.5926048134684;3707.721920855947;3714.182879766447;3726.4782930389765;588.5938702295402;602.1960682488926;604;608.4554323880191;609.8170548835332;480.8773762690561;534.4335004894824;566.6372374439479;585.3473377184494;624.1511544299749;7528.134264559646;7744.419168562267;7807.6762204473525;7827.882581761278;7904.249096335839;565.573688874731;567.7274908044162;597.9192808970733;628.4909223528865;630.4893036640244;3640.581333146669;3645.9817700911326;3751.2872554214614;3791.0332865119217;3807.809609519513;83458.74811877827;85843.57078214569;86746.13253867507;88631;88925.11074889297;70568.23579410555;78694;80090;80226;81018.59490702627;70931.645341774;70975.64512177405;70997;71515.28484715191;72055.6397218021;8136.308413784895;8141.8371632568205;8273.710420135267;8327.250547450758;8378.832423351621;43911.267542259266;44368;44509;44607;45935.77032114818;57678.7116064417;59445.10832337605;59512.70243648927;60402;62890.37109628845;11297.26567773092;11548.653540393801;11616.709582260512;11730.706732331419;11773.705657358634;11879.643610691694;11884.465199066235;12347.814782778445;13007.934960325138;13011.544595939096;8584.313254939714;10644.308119972176;10774.191935604704;10921.180911431515;11184.776304474028;11085.27945683528;11160.609378671708;11331.830022549504;11384.829227561428;11491.540338386705;8832.48325631074;11042;11318.660440186808;11460.369679667365;11553.82669259945;9341.859872101788;9548.570314335733;9778.022197780165;9864.408135511892;9884.011598840058;9460.526973651153;9711.951440242754;9719.951400242953;9919.45443000613;10082.237745477518 23:16:52 INFO - PROCESS | 1671 | __end_tp_report 23:16:52 INFO - PROCESS | 1671 | __start_cc_report 23:16:52 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2018 23:16:52 INFO - PROCESS | 1671 | __end_cc_report 23:16:52 INFO - PROCESS | 1671 | __startTimestamp1461046612126__endTimestamp 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | ------- Summary: start ------- 23:16:52 INFO - PROCESS | 1671 | Number of tests: 6 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5711.93 Median:5902.44 stddev:628.63 (10.7%) stddev-sans-first:603.55 23:16:52 INFO - PROCESS | 1671 | Values: 3388.1 3443.6 3472.4 3479.3 3504.6 5602.7 5647.2 5647.5 5688.7 5702.1 5688.8 5726.7 5777.9 5832.3 5863.2 5094.2 5107.9 5142.4 5173.2 5261.2 5222.9 5273.6 5275.6 5285.8 5329.3 5092.2 5141.7 5154.7 5167.5 5189.5 5095.1 5124.6 5129.9 5134.5 5142.5 5009.8 5073.2 5093.5 5113.8 5118.9 5540.6 5578.0 5665.3 5702.0 5704.9 6150.2 6165.9 6166.5 6180.6 6182.5 6168.6 6193.7 6223.3 6266.8 6267.2 5619.8 5699.0 5743.3 5830.7 5839.1 6193.3 6247.5 6251.9 6254.1 6260.9 6122.6 6131.4 6132.3 6143.7 6147.2 6125.1 6147.0 6184.1 6234.5 6235.9 6157.8 6165.7 6180.4 6189.8 6199.7 6195.9 6230.3 6250.7 6252.8 6258.0 4818.8 4886.6 4887.6 4894.3 4901.3 4339.9 4379.1 4395.9 4413.2 4431.9 5687.6 5689.3 5693.4 5708.7 5713.0 6103.6 6122.2 6254.3 6263.5 6263.7 5645.6 5695.3 5699.6 5752.0 5769.5 5316.2 5330.8 5344.2 5345.4 5362.1 6151.8 6217.2 6277.3 6282.8 6287.6 6221.2 6258.3 6271.1 6277.7 6281.3 6252.8 6258.4 6268.0 6271.9 6275.1 6043.5 6090.9 6100.5 6125.0 6157.4 6214.9 6223.3 6227.2 6249.3 6249.9 5062.9 6083.5 6085.9 6114.2 6120.7 5929.3 5988.0 6061.9 6085.9 6117.3 5722.6 5875.0 5887.7 5902.4 5913.5 5982.7 6025.5 6044.5 6050.2 6054.2 5871.4 5893.5 5902.3 5928.0 5929.4 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:62379.42 Median:324472.00 stddev:133115.15 (41.0%) stddev-sans-first:133407.53 23:16:52 INFO - PROCESS | 1671 | Values: 512.4 533.1 582.1 593.0 605.8 29323.3 37437.6 38412.4 38596.8 38740.6 42332.4 43050.6 43090.8 43153.4 43284.8 8410.5 9510.8 9760.1 9762.0 9792.3 8565.7 11523.5 11868.2 11879.0 11900.4 11443.0 11550.5 11572.0 11578.7 11589.7 8584.9 9934.4 11446.1 11512.9 11863.9 11319.7 11493.8 11506.9 11517.3 11532.5 37345.7 37994.6 38021.0 38080.8 38193.8 3095.2 3130.1 3180.0 3419.3 3928.5 27630.2 27772.9 28182.0 28458.7 28644.7 2680.7 2713.9 2716.7 2750.3 2831.7 470852.0 471217.0 471932.6 472436.0 473145.0 59052.0 59285.1 59719.4 59752.0 59829.7 62791.4 65515.7 67184.7 67218.7 67282.3 16244.4 16491.5 16498.3 16508.6 16574.4 11618.9 11855.5 12582.0 12672.4 12903.5 18971.6 19037.7 19088.0 19088.5 19102.6 7670.8 7823.6 7883.3 7932.6 7972.7 932.5 976.9 986.2 1073.8 1092.3 29088.4 29776.3 29806.7 30025.9 30374.7 1104.9 1123.1 1124.8 1205.8 1206.9 13042.7 14980.1 15411.8 15596.2 15657.7 639138.0 644957.0 645843.0 646107.0 648096.0 57262.0 59708.1 59792.4 59823.4 60448.7 1346.7 1362.7 1445.4 1487.9 1494.9 28422.6 28566.0 28602.7 29060.0 29226.0 44632.6 44844.9 45387.0 45417.5 45562.8 323203.0 324445.0 324499.0 325448.0 325961.0 22969.7 26061.7 26791.8 26927.3 27054.7 44001.8 44224.0 44425.8 44627.0 44753.9 35107.6 36425.0 37394.4 37469.3 37879.6 28493.9 35701.5 36273.3 36963.4 37020.6 36250.5 36658.1 37120.4 37301.8 37317.4 30173.4 31854.2 32188.0 32215.5 32282.5 23592.3 23887.8 24031.6 24208.9 24295.3 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34504.48 Median:20572.59 stddev:100750.12 (489.7%) stddev-sans-first:101000.19 23:16:52 INFO - PROCESS | 1671 | Values: 391.6 394.0 396.3 397.0 402.6 13374.6 19764.6 20436.2 20502.6 20548.4 15978.0 16915.0 19337.0 20012.8 20228.8 5855.8 6016.7 6053.2 6057.6 6073.9 4952.5 5701.3 5876.2 5918.4 5942.0 5846.5 5861.6 5881.9 5883.4 5890.2 5676.5 5847.4 5888.5 5888.9 5901.6 5073.8 5096.1 5142.2 5156.8 5167.7 24445.4 25059.9 25499.2 25537.6 25863.9 2065.2 2072.7 2218.6 2766.2 3040.7 14368.4 14658.6 14731.9 14891.1 15031.2 1329.5 1541.8 1549.8 1735.4 1742.1 103566.0 103733.0 104306.0 106905.0 109692.0 7723.8 9118.5 11578.7 11592.6 11623.8 12386.7 12526.5 14942.3 14966.6 14984.8 6673.5 6937.8 7538.7 8635.1 8718.8 7143.7 7635.6 8046.1 8571.8 8661.8 4458.5 4562.3 4571.5 4586.3 4618.2 1655.0 1914.3 1961.4 1966.0 1968.4 926.6 954.4 978.3 985.2 1002.0 19309.6 20150.4 20183.4 20296.5 20596.8 977.3 995.7 1005.4 1012.3 1017.8 10315.4 10344.5 10826.5 11017.7 11078.8 543504.6 596369.0 614268.0 624757.0 645488.0 125780.7 127644.0 128126.4 128609.0 131857.0 1018.4 1037.0 1043.9 1049.4 1071.3 14897.7 18360.5 18360.7 18554.4 18783.2 49676.8 50452.0 50913.5 51113.5 51208.7 71269.6 71310.6 71958.3 72113.0 72301.3 6883.9 7168.6 7979.2 8000.3 8256.0 11181.2 12473.9 12585.1 12746.4 12753.9 10509.0 10689.3 10994.5 11042.9 11113.8 10922.2 11023.1 11054.5 11128.3 11414.5 11015.6 11047.8 11193.2 11352.9 11619.0 8017.7 8088.0 8127.4 8296.8 8447.9 10149.7 10893.6 10913.0 11217.9 11281.9 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5607.27 Median:5042.27 stddev:3114.60 (61.8%) stddev-sans-first:3106.90 23:16:52 INFO - PROCESS | 1671 | Values: 1350.5 1415.8 1452.5 1765.7 1973.9 7903.9 8598.2 8815.2 8835.8 8849.6 8443.9 8666.9 8684.2 8690.6 8695.4 2432.6 2463.0 2465.4 2468.3 2473.4 2409.5 2472.2 2476.2 2480.2 2487.5 2418.9 2444.4 2464.1 2466.0 2471.5 2410.3 2432.6 2444.3 2445.1 2450.2 2380.4 2422.4 2423.8 2425.5 2428.1 8466.1 8594.0 8732.1 8746.6 8754.2 5230.2 5435.9 5443.1 5447.1 5471.1 3240.5 3405.7 3424.6 3538.6 3610.9 1016.0 1039.4 1045.1 1048.1 1050.4 11498.3 11543.2 11553.2 11559.9 11586.4 3991.8 4503.2 4534.8 4671.3 4708.2 9098.6 9112.4 9117.7 9125.7 9158.3 5191.7 5234.3 5245.8 5256.7 5262.3 4070.2 4554.4 4696.6 4766.1 4924.3 3620.5 3641.4 3700.0 3723.0 3733.8 1126.6 1137.4 1141.2 1152.2 1152.4 657.6 804.9 885.9 926.7 943.6 5383.0 5661.7 5741.8 5852.6 5880.4 937.2 963.6 969.1 976.1 1017.5 3678.9 3892.6 3897.5 3968.5 3970.6 10098.9 10257.7 10532.3 10625.7 10752.6 10480.4 10546.8 10581.9 10588.0 10605.9 7735.1 8048.8 8167.3 8167.7 8173.1 5636.1 5787.3 5841.7 5915.4 5961.8 9799.0 9903.6 9913.3 9940.2 9971.4 8839.4 9080.6 9817.2 9945.3 10026.4 7235.6 7533.5 7535.0 7537.0 7572.0 8017.3 8118.9 8181.0 8201.7 8201.8 7218.7 7353.8 7386.8 7548.7 7689.2 7202.1 7279.8 7438.2 7454.8 7498.4 6014.4 6341.6 6572.8 6766.4 6812.3 4664.1 4716.0 4847.7 4848.0 5002.4 4758.8 4916.6 4930.7 4948.4 5082.1 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5016.40 Median:5625.04 stddev:1555.31 (27.6%) stddev-sans-first:1519.04 23:16:52 INFO - PROCESS | 1671 | Values: 310.8 315.6 319.6 325.8 328.2 5824.6 5884.9 5895.5 5906.2 5925.4 5042.4 5648.3 5851.2 5858.7 5861.0 4190.8 4219.2 4254.7 4297.9 4389.7 3757.2 4027.8 4452.6 4499.5 4529.6 3842.9 4246.7 4446.1 4451.8 4466.9 3171.2 3958.0 3991.5 4113.1 4435.9 3181.7 3618.4 3690.5 3873.9 3893.5 5567.7 5596.5 5601.0 5632.7 5649.9 4941.0 5126.4 5156.8 5159.8 5164.4 4875.4 5498.8 5707.9 6174.0 6218.8 4113.3 4273.4 4279.3 4292.7 4297.2 5797.5 6136.2 6371.7 6527.3 6552.5 4344.6 4347.3 4372.1 4379.5 4394.6 4401.9 4422.5 4424.3 4450.6 4470.6 4907.7 4931.9 5107.2 5112.3 5137.6 6190.6 6208.9 6213.2 6218.8 6220.6 1505.4 1525.7 1531.4 1532.6 1532.8 860.3 944.6 976.1 976.2 1017.5 5113.4 5141.3 5684.8 5694.5 5738.2 5617.4 6386.7 6388.4 6414.7 6417.5 4857.0 5479.9 5703.8 5718.9 5731.9 2990.5 3110.9 3281.0 3335.5 3374.6 5561.8 6103.4 6319.7 6634.5 6657.2 5553.8 6515.2 6556.4 6587.7 6591.2 5963.4 6109.3 6441.0 6638.8 6814.3 5306.9 5401.4 5498.9 6399.2 6443.5 5366.5 6251.6 6257.4 6359.2 6376.5 6139.6 6661.7 6742.8 6743.3 6789.1 4198.8 4368.7 4373.3 4380.8 4388.6 6238.9 6402.7 6408.4 6422.8 6427.5 5198.7 6170.8 6188.5 6210.1 6212.2 5030.1 6066.5 6227.9 6236.5 6238.3 6054.6 6071.1 6082.7 6095.0 6113.2 5493.1 6196.2 6208.6 6210.4 6218.6 5693.9 6106.8 6111.6 6131.3 6137.6 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17540.39 Median:30503.00 stddev:24331.29 (79.8%) stddev-sans-first:24366.12 23:16:52 INFO - PROCESS | 1671 | Values: 556.7 606.6 658.8 701.6 741.2 14358.1 14617.9 14639.9 14834.3 15093.9 11523.7 13651.3 13709.7 13852.7 13870.5 725.4 740.8 891.3 901.1 918.7 920.8 923.7 924.1 924.7 925.5 972.1 976.9 977.6 977.8 979.9 750.5 938.9 966.6 969.4 974.7 719.0 719.9 720.3 720.4 720.9 14897.4 14976.0 15000.0 15010.8 15011.0 11980.0 12385.6 12450.8 12569.5 12706.9 9806.4 9994.4 10131.7 10139.7 10730.4 3400.6 3478.4 3485.8 3531.9 3605.4 73091.3 73381.0 73747.9 74241.9 75781.6 9222.3 9223.6 9373.2 9394.3 9780.4 20477.5 20515.2 20543.6 20547.9 20614.3 7185.2 7250.5 7262.9 7270.5 7411.4 3929.7 3999.6 4023.3 4093.8 4247.1 3600.2 3703.6 3707.7 3714.2 3726.5 588.6 602.2 604.0 608.5 609.8 480.9 534.4 566.6 585.3 624.2 7528.1 7744.4 7807.7 7827.9 7904.2 565.6 567.7 597.9 628.5 630.5 3640.6 3646.0 3751.3 3791.0 3807.8 83458.7 85843.6 86746.1 88631.0 88925.1 70568.2 78694.0 80090.0 80226.0 81018.6 70931.6 70975.6 70997.0 71515.3 72055.6 8136.3 8141.8 8273.7 8327.3 8378.8 43911.3 44368.0 44509.0 44607.0 45935.8 57678.7 59445.1 59512.7 60402.0 62890.4 11297.3 11548.7 11616.7 11730.7 11773.7 11879.6 11884.5 12347.8 13007.9 13011.5 8584.3 10644.3 10774.2 10921.2 11184.8 11085.3 11160.6 11331.8 11384.8 11491.5 8832.5 11042.0 11318.7 11460.4 11553.8 9341.9 9548.6 9778.0 9864.4 9884.0 9460.5 9712.0 9720.0 9919.5 10082.2 23:16:52 INFO - PROCESS | 1671 | -------- Summary: end -------- 23:16:52 INFO - PROCESS | 1671 | 23:16:52 INFO - TEST-INFO | 1671: exit 0 23:16:52 INFO - TEST-OK | dromaeo_css | took 1115411ms 23:16:52 INFO - TEST-START | kraken 23:16:52 INFO - operating with platform_type : mac_ 23:16:52 INFO - Initialising browser for kraken test... 23:16:52 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmp3xTCL4/profile http://localhost:49281/getInfo.html) 23:16:54 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 23:16:54 INFO - PROCESS | 1677 | colorDepth:24 23:16:54 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 23:16:54 INFO - PROCESS | 1677 | __metrics 23:16:55 INFO - PROCESS | 1677 | 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" 23:16:55 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:16:55 INFO - TEST-INFO | 1677: exit 0 23:16:55 INFO - Browser initialized. 23:16:55 INFO - Running cycle 1/1 for kraken test... 23:16:55 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmp3xTCL4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:07 INFO - PROCESS | 1679 | RSS: Main: 237555712 23:17:07 INFO - PROCESS | 1679 | 23:17:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 23:17:11 INFO - PROCESS | 1679 | RSS: Main: 285782016 23:17:11 INFO - PROCESS | 1679 | 23:17:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 23:17:14 INFO - PROCESS | 1679 | RSS: Main: 299376640 23:17:14 INFO - PROCESS | 1679 | 23:17:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 23:17:18 INFO - PROCESS | 1679 | RSS: Main: 387149824 23:17:18 INFO - PROCESS | 1679 | 23:17:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 23:17:21 INFO - PROCESS | 1679 | RSS: Main: 285405184 23:17:21 INFO - PROCESS | 1679 | 23:17:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:17:23 INFO - PROCESS | 1679 | RSS: Main: 290988032 23:17:23 INFO - PROCESS | 1679 | 23:17:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 23:17:27 INFO - PROCESS | 1679 | RSS: Main: 327782400 23:17:27 INFO - PROCESS | 1679 | 23:17:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 23:17:31 INFO - PROCESS | 1679 | RSS: Main: 330100736 23:17:31 INFO - PROCESS | 1679 | 23:17:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 23:17:34 INFO - PROCESS | 1679 | RSS: Main: 326320128 23:17:34 INFO - PROCESS | 1679 | 23:17:35 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:17:36 INFO - PROCESS | 1679 | RSS: Main: 320045056 23:17:36 INFO - PROCESS | 1679 | 23:17:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:17:39 INFO - PROCESS | 1679 | RSS: Main: 327241728 23:17:39 INFO - PROCESS | 1679 | 23:17:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:17:41 INFO - PROCESS | 1679 | RSS: Main: 330530816 23:17:41 INFO - PROCESS | 1679 | 23:17:44 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:17:45 INFO - PROCESS | 1679 | RSS: Main: 334839808 23:17:45 INFO - PROCESS | 1679 | 23:17:48 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:17:48 INFO - PROCESS | 1679 | RSS: Main: 332193792 23:17:48 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 23:17:50 INFO - PROCESS | 1679 | RSS: Main: 396247040 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | __start_tp_report 23:17:50 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 23:17:50 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 23:17:50 INFO - PROCESS | 1679 | |i|pagename|runs| 23:17:50 INFO - PROCESS | 1679 | |0;ai-astar;113;108;113;111;113;113;118;113;112;114 23:17:50 INFO - PROCESS | 1679 | |1;audio-beat-detection;156;123;141;168;119;141;139;119;168;121 23:17:50 INFO - PROCESS | 1679 | |2;audio-dft;185;186;186;184;185;182;182;186;187;188 23:17:50 INFO - PROCESS | 1679 | |3;audio-fft;79;73;87;91;55;59;61;77;86;55 23:17:50 INFO - PROCESS | 1679 | |4;audio-oscillator;88;100;136;79;78;78;125;106;111;161 23:17:50 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;86;90;87;88;85;90;88;86;126 23:17:50 INFO - PROCESS | 1679 | |6;imaging-darkroom;128;139;96;100;94;95;96;120;144;145 23:17:50 INFO - PROCESS | 1679 | |7;imaging-desaturate;84;85;87;87;84;84;87;84;86;84 23:17:50 INFO - PROCESS | 1679 | |8;json-parse-financial;60;61;60;60;58;59;59;79;60;58 23:17:50 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;65;66;79;74;71;67;68;67;66;68 23:17:50 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;81;80;82;87;81;82;81;81;82;83 23:17:50 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;139;140;140;138;138;138;136;139;175;141 23:17:50 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;169;194;170;168;171;168;171;170;177;169 23:17:50 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;72;70;70;69;69;71;67;70;73;72 23:17:50 INFO - PROCESS | 1679 | __end_tp_report 23:17:50 INFO - PROCESS | 1679 | __start_cc_report 23:17:50 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1947 23:17:50 INFO - PROCESS | 1679 | __end_cc_report 23:17:50 INFO - PROCESS | 1679 | __startTimestamp1461046670660__endTimestamp 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | ------- Summary: start ------- 23:17:50 INFO - PROCESS | 1679 | Number of tests: 14 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:2.49 (2.2%) stddev-sans-first:2.64 23:17:50 INFO - PROCESS | 1679 | Values: 113.0 108.0 113.0 111.0 113.0 113.0 118.0 113.0 112.0 114.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:141.00 stddev:19.31 (13.7%) stddev-sans-first:19.54 23:17:50 INFO - PROCESS | 1679 | Values: 156.0 123.0 141.0 168.0 119.0 141.0 139.0 119.0 168.0 121.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.97 (1.1%) stddev-sans-first:2.09 23:17:50 INFO - PROCESS | 1679 | Values: 185.0 186.0 186.0 184.0 185.0 182.0 182.0 186.0 187.0 188.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:72.30 Median:78.00 stddev:13.84 (17.7%) stddev-sans-first:14.47 23:17:50 INFO - PROCESS | 1679 | Values: 79.0 73.0 87.0 91.0 55.0 59.0 61.0 77.0 86.0 55.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:119.50 stddev:27.82 (23.3%) stddev-sans-first:28.72 23:17:50 INFO - PROCESS | 1679 | Values: 88.0 100.0 136.0 79.0 78.0 78.0 125.0 106.0 111.0 161.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:88.00 stddev:12.24 (13.9%) stddev-sans-first:12.95 23:17:50 INFO - PROCESS | 1679 | Values: 89.0 86.0 90.0 87.0 88.0 85.0 90.0 88.0 86.0 126.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:115.70 Median:119.50 stddev:21.84 (18.3%) stddev-sans-first:22.71 23:17:50 INFO - PROCESS | 1679 | Values: 128.0 139.0 96.0 100.0 94.0 95.0 96.0 120.0 144.0 145.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.41 23:17:50 INFO - PROCESS | 1679 | Values: 84.0 85.0 87.0 87.0 84.0 84.0 87.0 84.0 86.0 84.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:60.00 stddev:6.26 (10.4%) stddev-sans-first:6.62 23:17:50 INFO - PROCESS | 1679 | Values: 60.0 61.0 60.0 60.0 58.0 59.0 59.0 79.0 60.0 58.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:68.00 stddev:4.38 (6.4%) stddev-sans-first:4.39 23:17:50 INFO - PROCESS | 1679 | Values: 65.0 66.0 79.0 74.0 71.0 67.0 68.0 67.0 66.0 68.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:1.94 (2.4%) stddev-sans-first:2.03 23:17:50 INFO - PROCESS | 1679 | Values: 81.0 80.0 82.0 87.0 81.0 82.0 81.0 81.0 82.0 83.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:139.50 stddev:11.54 (8.3%) stddev-sans-first:12.17 23:17:50 INFO - PROCESS | 1679 | Values: 139.0 140.0 140.0 138.0 138.0 138.0 136.0 139.0 175.0 141.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:170.50 stddev:7.92 (4.6%) stddev-sans-first:8.28 23:17:50 INFO - PROCESS | 1679 | Values: 169.0 194.0 170.0 168.0 171.0 168.0 171.0 170.0 177.0 169.0 23:17:50 INFO - PROCESS | 1679 | 23:17:50 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:1.77 (2.5%) stddev-sans-first:1.76 23:17:50 INFO - PROCESS | 1679 | Values: 72.0 70.0 70.0 69.0 69.0 71.0 67.0 70.0 73.0 72.0 23:17:50 INFO - PROCESS | 1679 | -------- Summary: end -------- 23:17:50 INFO - PROCESS | 1679 | 23:17:51 INFO - TEST-INFO | 1679: exit 0 23:17:51 INFO - TEST-OK | kraken | took 58712ms 23:17:51 INFO - SUITE-END | took 1174s 23:17:51 INFO - Completed test suite (00:19:34) 23:17:51 INFO - javascript benchmark 23:17:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.136025313545, 3443.6039855416634, 3472.374972504949, 3479.269353435778, 3504.6495350464957, 5602.663840169593, 5647.152927060943, 5647.491725744682, 5688.658680479164, 5702.059160238556, 5688.829335119943, 5726.656400615966, 5777.913331300034, 5832.300123985117, 5863.179154918314, 5094.184930411135, 5107.897842043176, 5142.357205349332, 5173.224016397533, 5261.158214685651, 5222.92165617516, 5273.551748101416, 5275.57795376369, 5285.762140703678, 5329.280547126146, 5092.210707340369, 5141.665791723526, 5154.664946778447, 5167.48325167484, 5189.455107213726, 5095.133827249377, 5124.590032797404, 5129.871753206163, 5134.5378915897745, 5142.51146141116, 5009.824656137054, 5073.213651883927, 5093.465186155475, 5113.795448182068, 5118.897622047538, 5540.584456165763, 5578.0, 5665.3201615806365, 5702.030654788655, 5704.857378565569, 6150.200473938359, 6165.876682466325, 6166.537509686791, 6180.567360284737, 6182.505399568024, 6168.629882207082, 6193.721282542297, 6223.346548612421, 6266.811995640092, 6267.153934218868, 5619.80330688424, 5698.999999999958, 5743.282089738783, 5830.679312637761, 5839.124131380242, 6193.318734939153, 6247.500199983991, 6251.937480625226, 6254.124422580842, 6260.937390626035, 6122.632642041492, 6131.448169664752, 6132.2947860996555, 6143.6928153593035, 6147.170132032208, 6125.111858780452, 6147.047207682816, 6184.134221209034, 6234.532410069263, 6235.875282494325, 6157.784477543264, 6165.691715414211, 6180.381961803873, 6189.81430557084, 6199.721012554446, 6195.876082478415, 6230.252369715572, 6250.656213908279, 6252.781152659635, 6258.0, 4818.831340903122, 4886.584640305614, 4887.633427492952, 4894.2658601210105, 4901.264810278415, 4339.913201735948, 4379.124175164916, 4395.934060989088, 4413.16149931512, 4431.889202769892, 5687.630304030225, 5689.345725241567, 5693.373728889819, 5708.657480551097, 5712.971435142797, 6103.610425594294, 6122.215193971453, 6254.280757712831, 6263.467605253513, 6263.71813268404, 5645.576581756384, 5695.316562012585, 5699.601027928005, 5752.02215623345, 5769.48074673273, 5316.175992721134, 5330.760115794798, 5344.225087362389, 5345.358556973188, 5362.1420572708175, 6151.815445536641, 6217.191765070512, 6277.2781130170615, 6282.842928926722, 6287.59130656515, 6221.160143380585, 6258.311585725475, 6271.122042913905, 6277.74889004453, 6281.309056003744, 6252.843678908063, 6258.43674069336, 6267.96866015667, 6271.874562508633, 6275.058741188858, 6043.486303664238, 6090.908636370369, 6100.520618787021, 6124.969375153096, 6157.353477884937, 6214.937850621436, 6223.3465486125115, 6227.190465239671, 6249.312575616587, 6249.8750024998335, 5062.924056139087, 6083.482903953037, 6085.939140608537, 6114.205153330038, 6120.693965301806, 5929.288485381781, 5988.000158938161, 6061.909071364022, 6085.939140608537, 6117.296510901126, 5722.570807189394, 5874.971879921082, 5887.735051922759, 5902.439268269508, 5913.46778789911, 5982.670953097621, 6025.487833534199, 6044.546659000681, 6050.21347224858, 6054.152418661303, 5871.412858714094, 5893.499052580579, 5902.2622172228475, 5927.9703601481715, 5929.436703513159], "value": 5672.320740383902, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.3902555958407, 533.0538294527214, 582.0512564519834, 593.0333556303225, 605.8152263559613, 29323.266918327052, 37437.625623743756, 38412.42381364278, 38596.80701596495, 38740.61259387405, 42332.36501452473, 43050.56949430505, 43090.78454607722, 43153.35269970953, 43284.783576082154, 8410.495370277773, 9510.762230944212, 9760.07279308468, 9761.951190244074, 9792.314537982344, 8565.657373705044, 11523.539058437653, 11868.228565143281, 11879.0, 11900.404979751022, 11443.027342675883, 11550.4802283897, 11572.016378607828, 11578.652640420802, 11589.6523104307, 8584.914150858536, 9934.403935763841, 11446.141539384538, 11512.942435287854, 11863.881361186364, 11319.717007074807, 11493.827592586118, 11506.88493115075, 11517.308961462339, 11532.481038353295, 37345.69290074834, 37994.62005379939, 38021.0, 38080.80959595184, 38193.80903095467, 3095.189518155637, 3130.0922732407575, 3180.0, 3419.264858055505, 3928.46965659638, 27630.171094867186, 27772.861135694395, 28182.01362952287, 28458.715412845813, 28644.713552864414, 2680.731926807304, 2713.864306784653, 2716.660417447819, 2750.2574304937757, 2831.66020077592, 470852.0, 471217.0, 471932.6403367961, 472436.0, 473145.0, 59052.0, 59285.11072333918, 59719.402805972255, 59752.0, 59829.70085149634, 62791.372086278556, 65515.67242163759, 67184.6640766793, 67218.6556268872, 67282.32717672859, 16244.350225991064, 16491.505254842374, 16498.25757840876, 16508.587285317964, 16574.419895303607, 11618.88381116195, 11855.46650400734, 12582.0, 12672.429740661692, 12903.483860645469, 18971.620567588572, 19037.714434283498, 19088.0, 19088.522786930436, 19102.61794764097, 7670.7698769037015, 7823.608819558999, 7883.329916957008, 7932.563708996061, 7972.68450705627, 932.4871320773683, 976.9316147869723, 986.2258127370123, 1073.8496610474383, 1092.2845536173818, 29088.41823163567, 29776.2555936099, 29806.70193298039, 30025.94909178212, 30374.69625303763, 1104.861892263467, 1123.1239633086207, 1124.7806677697934, 1205.7528206717602, 1206.9396530173456, 13042.673933151747, 14980.1011939284, 15411.845881541265, 15596.220188990732, 15657.68684626324, 639138.0, 644957.0, 645843.0, 646107.0, 648096.0, 57262.0, 59708.10437843436, 59792.40207597868, 59823.40176598266, 60448.697756510934, 1346.6767975685764, 1362.7410791949696, 1445.4290555230834, 1487.8809695224356, 1494.8505149485181, 28422.573661394683, 28566.00018999325, 28602.713972860838, 29060.0, 29226.0, 44632.553674464136, 44844.878878027004, 45387.0, 45417.54582454133, 45562.772186138856, 323203.0, 324445.0, 324499.0, 325448.0, 325961.0, 22969.655455167853, 26061.73938260669, 26791.79436925382, 26927.326816829736, 27054.72945270522, 44001.77999110112, 44224.0, 44425.77787111044, 44627.0, 44753.88115297144, 35107.64892351146, 36425.0, 37394.43908341323, 37469.250614987, 37879.62120378761, 28493.857530712216, 35701.46447803233, 36273.27453450864, 36963.44554831626, 37020.62979370279, 36250.456243155844, 36658.08354791045, 37120.44319335158, 37301.81349093237, 37317.4402383959, 30173.396532069673, 31854.203644909063, 32188.0, 32215.51676724898, 32282.515762264055, 23592.292231233092, 23887.761122388554, 24031.639525406783, 24208.87895560511, 24295.27114186577], "value": 19653.238751211455, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.620128475379, 393.99606003939954, 396.32624538284887, 396.978166200859, 402.61147992187534, 13374.59876203713, 19764.604707905833, 20436.182552697894, 20502.589948201028, 20548.383548493533, 15978.0, 16915.0, 19337.033148342598, 20012.799872001277, 20228.797712022875, 5855.795047173339, 6016.669083200422, 6053.243344581927, 6057.575969682124, 6073.908891366633, 4952.529509696573, 5701.34434540027, 5876.236089308384, 5918.437748413915, 5941.970290148543, 5846.50304724099, 5861.560382971273, 5881.8823623527505, 5883.411658834125, 5890.234269544953, 5676.517496012823, 5847.386024467433, 5888.4700376966275, 5888.882222355551, 5901.557383196235, 5073.822416215452, 5096.05722941257, 5142.1772516397195, 5156.767945442464, 5167.689938603658, 24445.38886527833, 25059.874700626562, 25499.235022949342, 25537.61693574598, 25863.870680646476, 2065.1842522203647, 2072.7409073865765, 2218.5673793610254, 2766.21162968553, 3040.7415369693604, 14368.425262989467, 14658.633534161625, 14731.852681473156, 14891.10653360791, 15031.248437578186, 1329.508082009653, 1541.8381069987602, 1549.7907782449338, 1735.3752649046332, 1742.1028170492207, 103566.0, 103733.0, 104306.0, 106905.0, 109692.0, 7723.806904827425, 9118.48634925716, 11578.710532236593, 11592.594259201056, 11623.825642615368, 12386.690332741573, 12526.498940042296, 14942.25288735559, 14966.625834354229, 14984.775228371584, 6673.499487538454, 6937.819356965517, 7538.736144234926, 8635.136486351315, 8718.782030449163, 7143.749968751173, 7635.618219089023, 8046.109164346374, 8571.785705357415, 8661.783455413664, 4458.509563947963, 4562.297474460125, 4571.497135315112, 4586.334981427674, 4618.1918164321105, 1654.983450165483, 1914.334528959786, 1961.3723608445162, 1965.9606807863763, 1968.4094771568473, 926.6432423516994, 954.4130359828794, 978.295627148452, 985.2019863910112, 1001.9649312274036, 19309.613807723767, 20150.39548813538, 20183.39449816508, 20296.492587685425, 20596.794032059486, 977.3451787302524, 995.721198064543, 1005.3967619428284, 1012.2661070723814, 1017.8218811707922, 10315.381077135396, 10344.482775861177, 10826.512806923707, 11017.669469915754, 11078.778424431466, 543504.5649543533, 596369.0, 614268.0, 624757.0, 645488.0, 125780.74219257873, 127644.0, 128126.35936820257, 128609.0, 131857.0, 1018.3686114608989, 1037.0, 1043.879953805307, 1049.4018409506507, 1071.341125208007, 14897.702045958804, 18360.540986475444, 18360.72458913144, 18554.44336669902, 18783.15475803565, 49676.751616243135, 50452.0, 50913.49086509235, 51113.48886511087, 51208.71978200575, 71269.57460850857, 71310.64807874923, 71958.28041719725, 72113.0, 72301.27698722946, 6883.931160688529, 7168.641567921479, 7979.202079791974, 8000.319972802377, 8256.009278886571, 11181.217314787888, 12473.93763031179, 12585.056120790794, 12746.426410811351, 12753.872461275269, 10509.0, 10689.251752377259, 10994.450277485934, 11042.889571104186, 11113.833292500458, 10922.180836437457, 11023.06303964172, 11054.502547385548, 11128.276662016942, 11414.543418263176, 11015.559377624806, 11047.834282485608, 11193.216474847008, 11352.886471135513, 11619.0, 8017.719379821679, 8087.989001374828, 8127.390445716713, 8296.751097467086, 8447.873281900653, 10149.746256343651, 10893.564257429614, 10913.0, 11217.943910280397, 11281.943590281997], "value": 9433.251537629792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.500314883493, 1415.8230221222348, 1452.4698485052957, 1765.7263124215742, 1973.8618296719226, 7903.92096079039, 8598.183172598601, 8815.206631403173, 8835.823283534326, 8849.601767920434, 8443.873341899876, 8666.869996950036, 8684.218420342168, 8690.56547172642, 8695.434796738207, 2432.562138815017, 2463.0147940823636, 2465.4206261528525, 2468.271859801354, 2473.4063824682034, 2409.5421869844763, 2472.159465781634, 2476.2199907029317, 2480.193936970483, 2487.465194983078, 2418.9153379631716, 2444.4499987502845, 2464.088287333689, 2465.963010554834, 2471.542764588548, 2410.313060777679, 2432.6107822748363, 2444.340028192387, 2445.058652418814, 2450.191436825843, 2380.4343526247, 2422.3822925153972, 2423.7697418745192, 2425.4906469641396, 2428.1016024071205, 8466.111058338909, 8593.957030214871, 8732.083131271253, 8746.56267186638, 8754.168354006359, 5230.189320655304, 5435.891282174334, 5443.074677304868, 5447.101228297326, 5471.097268950618, 3240.5139229115584, 3405.676460736226, 3424.5890493140982, 3538.6461353864665, 3610.9277814443562, 1015.9796804063877, 1039.3607931122306, 1045.1064339989275, 1048.1038711901367, 1050.3855244681795, 11498.252613580175, 11543.249688770287, 11553.24903881245, 11559.942200288944, 11586.362750048827, 3991.7804520751056, 4503.189425903303, 4534.77326133692, 4671.252599584051, 4708.223143181405, 9098.590563424661, 9112.362134650512, 9117.680881169128, 9125.680601178927, 9158.313126515539, 5191.662541934762, 5234.293370395024, 5245.763940622681, 5256.737163141904, 5262.342207224097, 4070.247004304213, 4554.407926969473, 4696.624270058387, 4766.118268120409, 4924.3352147460355, 3620.5293311869814, 3641.362761516724, 3699.9630003699617, 3723.0, 3733.757305775116, 1126.645106791358, 1137.351709525579, 1141.2410746853416, 1152.2164927848976, 1152.4065106470175, 657.6284399314372, 804.9356051515865, 885.9291256699448, 926.6525053105086, 943.6366998705548, 5382.973085134549, 5661.688607126565, 5741.827745167651, 5852.561057920673, 5880.382559831241, 937.1752857485369, 963.5808423335872, 969.1035791892472, 976.0581039297113, 1017.5065093429681, 3678.9264214715554, 3892.649661530419, 3897.512810898638, 3968.503937007874, 3970.5830887756947, 10098.949505252427, 10257.74355641115, 10532.315399499008, 10625.734356641145, 10752.569897204181, 10480.371177729361, 10546.841797373045, 10581.894181058246, 10588.0, 10605.893941060645, 7735.110462296795, 8048.758537243892, 8167.264946154874, 8167.71413000554, 8173.141820108803, 5636.126400407943, 5787.334456537552, 5841.707914604338, 5915.40845915405, 5961.821145365646, 9799.0, 9903.55434005457, 9913.256505762241, 9940.155086817413, 9971.40171589707, 8839.425437346552, 9080.636774528944, 9817.214622830013, 9945.254105942227, 10026.398416095057, 7235.63821808918, 7533.472656914183, 7535.0, 7536.962315188608, 7571.977782999445, 8017.318527924958, 8118.878216826871, 8181.0, 8201.71294004707, 8201.835963280582, 7218.675159617935, 7353.8161545961775, 7386.778396648109, 7548.698052077855, 7689.192634773268, 7202.063731714844, 7279.781606551812, 7438.181800002104, 7454.813629659302, 7498.437617178843, 6014.36849130835, 6341.55609107358, 6572.8356791080605, 6766.424853887278, 6812.284710105368, 4664.137134630104, 4715.952840471688, 4847.684900481458, 4848.00615873738, 5002.399712034579, 4758.833440829554, 4916.582090522203, 4930.6795058321095, 4948.38145231846, 5082.136036873814], "value": 4519.417035234221, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.77313561100397, 315.58342987256816, 319.6116718187402, 325.79637726421004, 328.19591999600954, 5824.563157763168, 5884.941150588493, 5895.469407753301, 5906.232189815329, 5925.407459254073, 5042.369703787027, 5648.265725455695, 5851.15158302046, 5858.73635686394, 5861.0, 4190.769507677077, 4219.177260434209, 4254.659627229829, 4297.871063868073, 4389.7146685465505, 3757.2485502899394, 4027.8388864445387, 4452.599266066053, 4499.460064792213, 4529.644584815894, 3842.9423558646504, 4246.68149888758, 4446.088551846864, 4451.820267629081, 4466.932996005063, 3171.160726778463, 3957.960420395788, 3991.481107456041, 4113.074558224375, 4435.911281774363, 3181.681831816823, 3618.4029635110173, 3690.5017822593873, 3873.8644147454984, 3893.4549163117185, 5567.721613919288, 5596.524295434893, 5600.991821472134, 5632.718364081779, 5649.887002259973, 4941.0, 5126.359205099363, 5156.819511317086, 5159.845204643867, 5164.406093299243, 4875.366202393701, 5498.80754173602, 5707.885842283173, 6174.0, 6218.782342618032, 4113.280175969194, 4273.358996150602, 4279.251131052085, 4292.678049146327, 4297.226499230137, 5797.507211886995, 6136.171616831715, 6371.74513019474, 6527.314631963623, 6552.541322107454, 4344.565543445693, 4347.34789781529, 4372.0818628088455, 4379.5182529921685, 4394.604485596312, 4401.867943961686, 4422.535633758409, 4424.33634954753, 4450.599446049871, 4470.5976462117915, 4907.705537667752, 4931.926021109686, 5107.182850743863, 5112.335396398445, 5137.614678899097, 6190.564236848592, 6208.937910620926, 6213.2233470816145, 6218.751249950042, 6220.626762394271, 1505.4053648808658, 1525.6948610277989, 1531.4180611367608, 1532.5938626264049, 1532.7547592385165, 860.3117505995303, 944.607987685103, 976.0673865745721, 976.1504282811402, 1017.5421060522795, 5113.437521872591, 5141.331626888481, 5684.772609095672, 5694.5159661428415, 5738.225339579103, 5617.354004289559, 6386.712597933104, 6388.425041746265, 6414.679266036679, 6417.518686098468, 4856.975715121402, 5479.917801232985, 5703.800366987136, 5718.941181174774, 5731.942680573225, 2990.491616425213, 3110.891118810876, 3281.0, 3335.483000134983, 3374.611919629274, 5561.805336813192, 6103.389661033861, 6319.747210111636, 6634.483723626486, 6657.23441804189, 5553.8333849984565, 6515.185601799775, 6556.377144171296, 6587.670616469253, 6591.176102987127, 5963.4334738199805, 6109.2974307955155, 6441.0, 6638.767643132467, 6814.318568143146, 5306.920396193983, 5401.351837779492, 5498.920604461514, 6399.232092148972, 6443.456099145545, 5366.543843773166, 6251.570777380805, 6257.374262573706, 6359.170169274628, 6376.52176086786, 6139.57023008403, 6661.733530658719, 6742.764003259862, 6743.2582415935185, 6789.1174147360525, 4198.832046718097, 4368.650507959419, 4373.278409062596, 4380.846670366522, 4388.648908087409, 6238.874290073776, 6402.679866006774, 6408.359164083741, 6422.811779820748, 6427.453666438405, 5198.726312053571, 6170.809362676186, 6188.535859810442, 6210.149707181577, 6212.229514588321, 5030.141958903198, 6066.484348830399, 6227.906581401192, 6236.469900058546, 6238.251409830849, 6054.606450580698, 6071.058985857199, 6082.7262773174425, 6095.0, 6113.23584551931, 5493.093639549589, 6196.225471816023, 6208.565400421927, 6210.4100110489435, 6218.626882387071, 5693.857653558529, 6106.816795496142, 6111.572189946839, 6131.264248290234, 6137.631742095488], "value": 4514.231008476198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.7494627417663, 606.5875204860695, 658.7694306992553, 701.582558377765, 741.1883987034197, 14358.066725662824, 14617.926910365446, 14639.853601463983, 14834.258287085642, 15093.924530377333, 11523.711907202323, 13651.31743412828, 13709.65725856854, 13852.722945541083, 13870.514531991383, 725.434161354142, 740.8222026713604, 891.2602539891907, 901.0989010989027, 918.701422037837, 920.7559996600907, 923.6628630549868, 924.1128516624016, 924.685606893656, 925.4863550729376, 972.125087421323, 976.9413835169877, 977.5991843344231, 977.8435450327913, 979.8824141103078, 750.504666919833, 938.943663380196, 966.5891995901713, 969.3795970578835, 974.6880998080617, 719.0364911019259, 719.931606497382, 720.3085038363125, 720.387670480093, 720.8990741296222, 14897.404103835834, 14976.026558273677, 15000.0, 15010.849891501055, 15011.0, 11980.0, 12385.62843114708, 12450.81323780144, 12569.497220111276, 12706.936465317614, 9806.411615303105, 9994.350367226107, 10131.696049118538, 10139.695809125738, 10730.409827459487, 3400.591928968515, 3478.4086705260165, 3485.8257087145794, 3531.9293614127832, 3605.387084195667, 73091.26908730897, 73381.0, 73747.89378159332, 74241.88637170447, 75781.6210918942, 9222.262219022463, 9223.58493867764, 9373.203277721334, 9394.342396032349, 9780.35106156219, 20477.488062798548, 20515.17939282412, 20543.589128217653, 20547.8972605136, 20614.278500252418, 7185.173705023884, 7250.492465527468, 7262.891056634155, 7270.454715896329, 7411.444141689395, 3929.744566603219, 3999.6400323970984, 4023.3160362738404, 4093.7543747375253, 4247.086876321606, 3600.1719604491113, 3703.5926048134684, 3707.721920855947, 3714.182879766447, 3726.4782930389765, 588.5938702295402, 602.1960682488926, 604.0, 608.4554323880191, 609.8170548835332, 480.8773762690561, 534.4335004894824, 566.6372374439479, 585.3473377184494, 624.1511544299749, 7528.134264559646, 7744.419168562267, 7807.6762204473525, 7827.882581761278, 7904.249096335839, 565.573688874731, 567.7274908044162, 597.9192808970733, 628.4909223528865, 630.4893036640244, 3640.581333146669, 3645.9817700911326, 3751.2872554214614, 3791.0332865119217, 3807.809609519513, 83458.74811877827, 85843.57078214569, 86746.13253867507, 88631.0, 88925.11074889297, 70568.23579410555, 78694.0, 80090.0, 80226.0, 81018.59490702627, 70931.645341774, 70975.64512177405, 70997.0, 71515.28484715191, 72055.6397218021, 8136.308413784895, 8141.8371632568205, 8273.710420135267, 8327.250547450758, 8378.832423351621, 43911.267542259266, 44368.0, 44509.0, 44607.0, 45935.77032114818, 57678.7116064417, 59445.10832337605, 59512.70243648927, 60402.0, 62890.37109628845, 11297.26567773092, 11548.653540393801, 11616.709582260512, 11730.706732331419, 11773.705657358634, 11879.643610691694, 11884.465199066235, 12347.814782778445, 13007.934960325138, 13011.544595939096, 8584.313254939714, 10644.308119972176, 10774.191935604704, 10921.180911431515, 11184.776304474028, 11085.27945683528, 11160.609378671708, 11331.830022549504, 11384.829227561428, 11491.540338386705, 8832.48325631074, 11042.0, 11318.660440186808, 11460.369679667365, 11553.82669259945, 9341.859872101788, 9548.570314335733, 9778.022197780165, 9864.408135511892, 9884.011598840058, 9460.526973651153, 9711.951440242754, 9719.951400242953, 9919.45443000613, 10082.237745477518], "value": 6750.937964505043, "unit": "score"}], "name": "dromaeo_css", "value": 7246.941111575952, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 111.0, 113.0, 113.0, 118.0, 113.0, 112.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 141.0, 168.0, 119.0, 141.0, 139.0, 119.0, 168.0, 121.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 184.0, 185.0, 182.0, 182.0, 186.0, 187.0, 188.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 87.0, 91.0, 55.0, 59.0, 61.0, 77.0, 86.0, 55.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 136.0, 79.0, 78.0, 78.0, 125.0, 106.0, 111.0, 161.0], "value": 106.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 88.0, 85.0, 90.0, 88.0, 86.0, 126.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 139.0, 96.0, 100.0, 94.0, 95.0, 96.0, 120.0, 144.0, 145.0], "value": 115.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 87.0, 84.0, 84.0, 87.0, 84.0, 86.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 58.0, 59.0, 59.0, 79.0, 60.0, 58.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 79.0, 74.0, 71.0, 67.0, 68.0, 67.0, 66.0, 68.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 82.0, 87.0, 81.0, 82.0, 81.0, 81.0, 82.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 140.0, 138.0, 138.0, 138.0, 136.0, 139.0, 175.0, 141.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 194.0, 170.0, 168.0, 171.0, 168.0, 171.0, 170.0, 177.0, 169.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 70.0, 69.0, 69.0, 71.0, 67.0, 70.0, 73.0, 72.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1506.2, "alertThreshold": 2.0}]} 23:17:51 INFO - Return code: 0 23:17:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:17:51 ERROR - # TBPL SUCCESS # 23:17:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:17:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:17:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:17:51 INFO - 2016-04-18 23:17:51.989 screenresolution[1683:10921] starting screenresolution argv=screenresolution get 23:17:51 INFO - 2016-04-18 23:17:51.992 screenresolution[1683:10921] Display 0: 1600x1200x32@60 23:17:51 INFO - 2016-04-18 23:17:51.998 screenresolution[1684:10924] starting screenresolution argv=screenresolution list 23:17:52 INFO - Available Modes on Display 0 23:17:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:17:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:17:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:17:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:17:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:17:52 INFO - Intel Iris: 23:17:52 INFO - Chipset Model: Intel Iris 23:17:52 INFO - Type: GPU 23:17:52 INFO - Bus: Built-In 23:17:52 INFO - VRAM (Dynamic, Max): 1536 MB 23:17:52 INFO - Vendor: Intel (0x8086) 23:17:52 INFO - Device ID: 0x0a2e 23:17:52 INFO - Revision ID: 0x0009 23:17:52 INFO - Displays: 23:17:52 INFO - Display: 23:17:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:17:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:17:52 INFO - Display Serial Number: 1600x1200 60 23:17:52 INFO - Main Display: Yes 23:17:52 INFO - Mirror: Off 23:17:52 INFO - Online: Yes 23:17:52 INFO - Rotation: Supported 23:17:52 INFO - Return code: 0 23:17:52 INFO - Running post-action listener: _resource_record_post_action 23:17:52 INFO - Running post-run listener: _resource_record_post_run 23:17:53 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 32411136; Write bytes: 695169536; Read time: 327; Write time: 1967 23:17:53 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 385909248; Write bytes: 379344384; Read time: 29197; Write time: 670 23:17:53 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30625280; Write bytes: 298171392; Read time: 308; Write time: 1260 23:17:53 INFO - Running post-run listener: _upload_blobber_files 23:17:53 INFO - Blob upload gear active. 23:17:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:17:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:17:53 INFO - (blobuploader) - INFO - Open directory for files ... 23:17:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:17:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:17:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:54 INFO - (blobuploader) - INFO - Done attempting. 23:17:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:17:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:17:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:55 INFO - (blobuploader) - INFO - Done attempting. 23:17:55 INFO - (blobuploader) - INFO - Iteration through files over. 23:17:55 INFO - Return code: 0 23:17:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:17:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:17:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fbc216ee0ffd18d6aab50a6ea4596f759d0c259f6008c24d961b82b8addac37e9f5503af7d01a12595dad5582b3dce6dced87acb90bf922f44ef8501e7c359d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/005638bcd666749dcc056569fa6738348a8b73217957ce8bf192db4990bcda076d17c31c7f53f819b6f4625b883083e197b823b097742e01aa54aa180df752e3"} 23:17:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:17:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:17:55 INFO - Contents: 23:17:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fbc216ee0ffd18d6aab50a6ea4596f759d0c259f6008c24d961b82b8addac37e9f5503af7d01a12595dad5582b3dce6dced87acb90bf922f44ef8501e7c359d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/005638bcd666749dcc056569fa6738348a8b73217957ce8bf192db4990bcda076d17c31c7f53f819b6f4625b883083e197b823b097742e01aa54aa180df752e3"} 23:17:55 INFO - Running post-run listener: copy_logs_to_upload_dir 23:17:55 INFO - Copying logs to upload dir... 23:17:55 INFO - mkdir: /builds/slave/test/build/upload/logs 23:17:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1333.421057 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-04-18 23:17:55.772440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:55.776136) ========= 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.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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/Firefox/sha512/1fbc216ee0ffd18d6aab50a6ea4596f759d0c259f6008c24d961b82b8addac37e9f5503af7d01a12595dad5582b3dce6dced87acb90bf922f44ef8501e7c359d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/005638bcd666749dcc056569fa6738348a8b73217957ce8bf192db4990bcda076d17c31c7f53f819b6f4625b883083e197b823b097742e01aa54aa180df752e3"} build_url:https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/dktmVEqHTs2ZM9awVuXJ1A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fbc216ee0ffd18d6aab50a6ea4596f759d0c259f6008c24d961b82b8addac37e9f5503af7d01a12595dad5582b3dce6dced87acb90bf922f44ef8501e7c359d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/005638bcd666749dcc056569fa6738348a8b73217957ce8bf192db4990bcda076d17c31c7f53f819b6f4625b883083e197b823b097742e01aa54aa180df752e3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:55.804315) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:55.804683) ========= 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.dCJe8aqrbP/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.7NrkRctgrh/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.005233 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 23:17:55.853649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 23:17:55.853995) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 23:17:55.854420) ========= ========= Total master_lag: 0.39 =========