builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1460895262.26 results: success (0) buildid: 20160417030601 builduid: c47ad0463ea84d4994e6432533896f26 revision: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.261094) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.261736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.262015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.432965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.433422) ========= 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.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-17 05:14:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.88M=0.001s 2016-04-17 05:14:22 (7.88 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.452662 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.982820) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.983135) ========= 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.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.028399 ========= master_lag: 0.14 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.156460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.156843) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 --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 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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-17 05:14:23,256 truncating revision to first 12 chars 2016-04-17 05:14:23,256 Setting DEBUG logging. 2016-04-17 05:14:23,256 attempt 1/10 2016-04-17 05:14:23,256 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1da1937a9e03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-17 05:14:23,849 unpacking tar archive at: mozilla-central-1da1937a9e03/testing/mozharness/ program finished with exit code 0 elapsedTime=0.846520 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.098722) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.099044) ========= script_repo_revision: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.099458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.099860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.491010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 41 secs) (at 2016-04-17 05:14:24.491369) ========= /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.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:14:24 INFO - MultiFileLogger online at 20160417 05:14:24 in /builds/slave/test 05:14:24 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 05:14:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:24 INFO - {'append_to_log': False, 05:14:24 INFO - 'base_work_dir': '/builds/slave/test', 05:14:24 INFO - 'blob_upload_branch': 'Firefox', 05:14:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:24 INFO - 'branch': 'Firefox', 05:14:24 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:24 INFO - 'config_files': ('talos/mac_config.py',), 05:14:24 INFO - 'default_actions': ('clobber', 05:14:24 INFO - 'read-buildbot-config', 05:14:24 INFO - 'download-and-extract', 05:14:24 INFO - 'populate-webroot', 05:14:24 INFO - 'create-virtualenv', 05:14:24 INFO - 'install', 05:14:24 INFO - 'run-tests'), 05:14:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:24 INFO - 'download_minidump_stackwalk': True, 05:14:24 INFO - 'download_symbols': 'ondemand', 05:14:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:24 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:24 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:24 INFO - 'installer_path': 'installer.exe', 05:14:24 INFO - 'log_level': 'info', 05:14:24 INFO - 'log_name': 'talos', 05:14:24 INFO - 'log_to_console': True, 05:14:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:24 INFO - 'opt_config_files': (), 05:14:24 INFO - 'pip_index': False, 05:14:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:24 INFO - 'cmd': ('bash', 05:14:24 INFO - '-c', 05:14:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:24 INFO - 'enabled': True, 05:14:24 INFO - 'halt_on_failure': False, 05:14:24 INFO - 'name': 'check_screen_resolution'},), 05:14:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:24 INFO - 'cmd': ('bash', 05:14:24 INFO - '-c', 05:14:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:24 INFO - 'enabled': True, 05:14:24 INFO - 'halt_on_failure': False, 05:14:24 INFO - 'name': 'check_screen_resolution'},), 05:14:24 INFO - 'run_cmd_checks_enabled': True, 05:14:24 INFO - 'sps_profile': False, 05:14:24 INFO - 'sps_profile_interval': 0, 05:14:24 INFO - 'suite': 'dromaeojs', 05:14:24 INFO - 'system_bits': '32', 05:14:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:24 INFO - 'title': 't-yosemite-r7-0027', 05:14:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:24 INFO - 'use_talos_json': True, 05:14:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:24 INFO - 'work_dir': 'build'} 05:14:24 INFO - ##### 05:14:24 INFO - ##### Running clobber step. 05:14:24 INFO - ##### 05:14:24 INFO - Running pre-action listener: _resource_record_pre_action 05:14:24 INFO - Running main action method: clobber 05:14:24 INFO - rmtree: /builds/slave/test/build 05:14:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:26 INFO - Running post-action listener: _resource_record_post_action 05:14:26 INFO - ##### 05:14:26 INFO - ##### Running read-buildbot-config step. 05:14:26 INFO - ##### 05:14:26 INFO - Running pre-action listener: _resource_record_pre_action 05:14:26 INFO - Running main action method: read_buildbot_config 05:14:26 INFO - Using buildbot properties: 05:14:26 INFO - { 05:14:26 INFO - "project": "", 05:14:26 INFO - "product": "firefox", 05:14:26 INFO - "script_repo_revision": "production", 05:14:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:14:26 INFO - "repository": "", 05:14:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:14:26 INFO - "buildid": "20160417030601", 05:14:26 INFO - "stage_platform": "macosx64", 05:14:26 INFO - "basedir": "/builds/slave/test", 05:14:26 INFO - "nightly_build": "True", 05:14:26 INFO - "buildnumber": 219, 05:14:26 INFO - "slavename": "t-yosemite-r7-0027", 05:14:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:14:26 INFO - "platform": "yosemite_r7", 05:14:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:14:26 INFO - "branch": "mozilla-central", 05:14:26 INFO - "repo_path": "mozilla-central", 05:14:26 INFO - "revision": "1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3", 05:14:26 INFO - "pgo_build": "False", 05:14:26 INFO - "builduid": "c47ad0463ea84d4994e6432533896f26", 05:14:26 INFO - "slavebuilddir": "test" 05:14:26 INFO - } 05:14:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:14:26 INFO - Running post-action listener: _resource_record_post_action 05:14:26 INFO - ##### 05:14:26 INFO - ##### Running download-and-extract step. 05:14:26 INFO - ##### 05:14:26 INFO - Running pre-action listener: _resource_record_pre_action 05:14:26 INFO - Running main action method: download_and_extract 05:14:26 INFO - mkdir: /builds/slave/test/build/tests 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:14:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:14:56 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 05:14:56 INFO - retry: attempt #1 caught exception: timed out 05:14:56 INFO - retry: Failed, sleeping 30 seconds before retrying 05:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:15:55 INFO - Downloaded 1199 bytes. 05:15:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:15:55 INFO - Using the following test package requirements: 05:15:55 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:15:55 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:15:55 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:15:55 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'jsshell-mac.zip'], 05:15:55 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:15:55 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:15:55 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:15:55 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:15:55 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:15:55 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:15:55 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:15:55 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:55 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:15:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:15:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:15:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:02 INFO - Downloaded 18032122 bytes. 05:16:02 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'] 05:16:02 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 05:16:05 INFO - Return code: 0 05:16:05 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 05:16:05 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')]} 05:16:05 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:16:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:06 INFO - Downloaded 18032122 bytes. 05:16:06 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'] 05:16:06 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 05:16:10 INFO - Return code: 0 05:16: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')]} 05:16:10 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:16:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:16:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:16:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:11 INFO - Downloaded 10841076 bytes. 05:16:11 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'] 05:16:11 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 05:16:12 INFO - Return code: 0 05:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:12 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/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 05:16:17 INFO - Downloaded 109987209 bytes. 05:16:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:17 INFO - mkdir: /builds/slave/test/properties 05:16:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:16:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:16:17 INFO - Contents: 05:16:17 INFO - build_url:https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:18 INFO - Running post-action listener: _resource_record_post_action 05:16:18 INFO - Running post-action listener: set_extra_try_arguments 05:16:18 INFO - ##### 05:16:18 INFO - ##### Running populate-webroot step. 05:16:18 INFO - ##### 05:16:18 INFO - Running pre-action listener: _resource_record_pre_action 05:16:18 INFO - Running main action method: populate_webroot 05:16:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:16:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:16:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:16:18 INFO - '--tppagecycles', 05:16:18 INFO - '7'], 05:16:18 INFO - 'tests': ['tsvgm']}}, 05:16:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:16:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:16:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:16:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:16:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['damp', 'tps']}, 05:16:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'tests': ['damp', 'tps']}, 05:16:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['dromaeo_dom']}, 05:16:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:16:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['a11yr', 05:16:18 INFO - 'ts_paint', 05:16:18 INFO - 'tpaint', 05:16:18 INFO - 'sessionrestore', 05:16:18 INFO - 'sessionrestore_no_auto_restore', 05:16:18 INFO - 'tabpaint']}, 05:16:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['tsvgx', 05:16:18 INFO - 'tsvgr_opacity', 05:16:18 INFO - 'tart', 05:16:18 INFO - 'tscrollx', 05:16:18 INFO - 'cart']}, 05:16:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:16:18 INFO - 'tsvgr_opacity', 05:16:18 INFO - 'tart', 05:16:18 INFO - 'tscrollx', 05:16:18 INFO - 'cart']}, 05:16:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'talos_options': ['--disable-e10s'], 05:16:18 INFO - 'tests': ['tp5o']}, 05:16:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'tests': ['tp5o']}, 05:16:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'talos_options': ['--disable-e10s', 05:16:18 INFO - '--xperf_path', 05:16:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:18 INFO - 'tests': ['tp5n']}, 05:16:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:18 INFO - 'talos_options': ['--xperf_path', 05:16:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:18 INFO - 'tests': ['tp5n']}}, 05:16:18 INFO - 'talos.zip': {'path': '', 05:16:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:18 INFO - Running post-action listener: _resource_record_post_action 05:16:18 INFO - ##### 05:16:18 INFO - ##### Running create-virtualenv step. 05:16:18 INFO - ##### 05:16:18 INFO - Running pre-action listener: _resource_record_pre_action 05:16:18 INFO - Running main action method: create_virtualenv 05:16:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:16:18 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 05:16:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:16:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:16:18 INFO - Using real prefix '/tools/python27' 05:16:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:16:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:22 INFO - Installing pip.................done. 05:16:22 INFO - Return code: 0 05:16:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:22 INFO - 'HOME': '/Users/cltbld', 05:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:22 INFO - 'LOGNAME': 'cltbld', 05:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:22 INFO - 'MOZ_NO_REMOTE': '1', 05:16:22 INFO - 'NO_EM_RESTART': '1', 05:16:22 INFO - 'PAGER': '/bin/cat', 05:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:22 INFO - 'PWD': '/builds/slave/test', 05:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:22 INFO - 'SHELL': '/bin/bash', 05:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:22 INFO - 'USER': 'cltbld', 05:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:22 INFO - 'XPC_FLAGS': '0x0', 05:16:22 INFO - 'XPC_SERVICE_NAME': '0', 05:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:22 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 05:16:22 INFO - Cleaning up... 05:16:22 INFO - Return code: 0 05:16:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:22 INFO - 'HOME': '/Users/cltbld', 05:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:22 INFO - 'LOGNAME': 'cltbld', 05:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:22 INFO - 'MOZ_NO_REMOTE': '1', 05:16:22 INFO - 'NO_EM_RESTART': '1', 05:16:22 INFO - 'PAGER': '/bin/cat', 05:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:22 INFO - 'PWD': '/builds/slave/test', 05:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:22 INFO - 'SHELL': '/bin/bash', 05:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:22 INFO - 'USER': 'cltbld', 05:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:22 INFO - 'XPC_FLAGS': '0x0', 05:16:22 INFO - 'XPC_SERVICE_NAME': '0', 05:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:22 INFO - Downloading/unpacking psutil>=0.7.1 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:25 INFO - Installing collected packages: psutil 05:16:25 INFO - Running setup.py install for psutil 05:16:26 INFO - building 'psutil._psutil_osx' extension 05:16:26 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 05:16:26 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 05:16:26 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 05:16:26 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 05:16:26 INFO - building 'psutil._psutil_posix' extension 05:16:26 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 05:16:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:16:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:16:26 INFO - ^ 05:16:26 INFO - 1 warning generated. 05:16:26 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 05:16:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:27 INFO - Successfully installed psutil 05:16:27 INFO - Cleaning up... 05:16:27 INFO - Return code: 0 05:16:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:27 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:27 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 05:16:27 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 05:16:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:27 INFO - 'HOME': '/Users/cltbld', 05:16:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:27 INFO - 'LOGNAME': 'cltbld', 05:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:27 INFO - 'MOZ_NO_REMOTE': '1', 05:16:27 INFO - 'NO_EM_RESTART': '1', 05:16:27 INFO - 'PAGER': '/bin/cat', 05:16:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:27 INFO - 'PWD': '/builds/slave/test', 05:16:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:27 INFO - 'SHELL': '/bin/bash', 05:16:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:27 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:27 INFO - 'USER': 'cltbld', 05:16:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:27 INFO - 'XPC_FLAGS': '0x0', 05:16:27 INFO - 'XPC_SERVICE_NAME': '0', 05:16:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:16:27 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 05:16:27 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 05:16:27 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 05:16:27 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 05:16:27 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 05:16:27 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 05:16:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:16:29 INFO - Installing collected packages: mozsystemmonitor 05:16:29 INFO - Running setup.py install for mozsystemmonitor 05:16:30 INFO - Successfully installed mozsystemmonitor 05:16:30 INFO - Cleaning up... 05:16:30 INFO - Return code: 0 05:16:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16:30 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')]} 05:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:30 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')]} 05:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:30 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:30 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 05:16:30 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 05:16:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:30 INFO - 'HOME': '/Users/cltbld', 05:16:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:30 INFO - 'LOGNAME': 'cltbld', 05:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:30 INFO - 'MOZ_NO_REMOTE': '1', 05:16:30 INFO - 'NO_EM_RESTART': '1', 05:16:30 INFO - 'PAGER': '/bin/cat', 05:16:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:30 INFO - 'PWD': '/builds/slave/test', 05:16:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:30 INFO - 'SHELL': '/bin/bash', 05:16:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:30 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:30 INFO - 'USER': 'cltbld', 05:16:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:30 INFO - 'XPC_FLAGS': '0x0', 05:16:30 INFO - 'XPC_SERVICE_NAME': '0', 05:16:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:30 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 05:16:30 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 05:16:30 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 05:16:30 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 05:16:30 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 05:16:30 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 05:16:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:34 INFO - Downloading docopt-0.6.1.tar.gz 05:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:34 INFO - Running setup.py install for blobuploader 05:16:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:34 INFO - Running setup.py install for requests 05:16:34 INFO - Running setup.py install for docopt 05:16:34 INFO - Successfully installed blobuploader requests docopt 05:16:34 INFO - Cleaning up... 05:16:34 INFO - Return code: 0 05:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:34 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')]} 05:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:34 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')]} 05:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:34 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:34 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 05:16:34 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 05:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:34 INFO - 'HOME': '/Users/cltbld', 05:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:34 INFO - 'LOGNAME': 'cltbld', 05:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:34 INFO - 'MOZ_NO_REMOTE': '1', 05:16:34 INFO - 'NO_EM_RESTART': '1', 05:16:34 INFO - 'PAGER': '/bin/cat', 05:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:34 INFO - 'PWD': '/builds/slave/test', 05:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:34 INFO - 'SHELL': '/bin/bash', 05:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:34 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:34 INFO - 'USER': 'cltbld', 05:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:34 INFO - 'XPC_FLAGS': '0x0', 05:16:34 INFO - 'XPC_SERVICE_NAME': '0', 05:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kAyiUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kKjM3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dgiq_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Zikdg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ZqzZXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-xy2dG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Bxr3kV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:35 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7A9JzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-cglF26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NHU8WJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-jsDRGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-C8yQLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dhR6LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kvY5DE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-8DJ4Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-yL3fAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:36 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lWHUWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:36 INFO - Running setup.py install for manifestparser 05:16:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Running setup.py install for mozcrash 05:16:37 INFO - Running setup.py install for mozdebug 05:16:37 INFO - Running setup.py install for mozdevice 05:16:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Running setup.py install for mozfile 05:16:37 INFO - Running setup.py install for mozhttpd 05:16:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Running setup.py install for mozinfo 05:16:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Running setup.py install for mozInstall 05:16:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Running setup.py install for mozleak 05:16:38 INFO - Running setup.py install for mozlog 05:16:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Running setup.py install for moznetwork 05:16:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Running setup.py install for mozprocess 05:16:38 INFO - Running setup.py install for mozprofile 05:16:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:38 INFO - Running setup.py install for mozrunner 05:16:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:39 INFO - Running setup.py install for mozscreenshot 05:16:39 INFO - Running setup.py install for moztest 05:16:39 INFO - Running setup.py install for mozversion 05:16:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:39 INFO - Cleaning up... 05:16:39 INFO - Return code: 0 05:16:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:39 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')]} 05:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:39 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')]} 05:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:39 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:39 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 05:16:39 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 05:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:39 INFO - 'HOME': '/Users/cltbld', 05:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:39 INFO - 'LOGNAME': 'cltbld', 05:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:39 INFO - 'MOZ_NO_REMOTE': '1', 05:16:39 INFO - 'NO_EM_RESTART': '1', 05:16:39 INFO - 'PAGER': '/bin/cat', 05:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:39 INFO - 'PWD': '/builds/slave/test', 05:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:39 INFO - 'SHELL': '/bin/bash', 05:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:39 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:39 INFO - 'USER': 'cltbld', 05:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:39 INFO - 'XPC_FLAGS': '0x0', 05:16:39 INFO - 'XPC_SERVICE_NAME': '0', 05:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:39 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-jjQT9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:39 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-UY8VVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-V4KfFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nZalKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-XjS1JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Ac06t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YJaX4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7B4xi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-bTFQJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_1NRU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:40 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-iuERMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:40 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)) 05:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-tqGw0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:41 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)) 05:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-uwfiBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:41 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)) 05:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-pQS6HG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:41 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)) 05:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ftR1Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:41 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)) 05:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Qd0UYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:41 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)) 05:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:41 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-k4yqdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:41 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)) 05:16:41 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)) 05:16:41 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)) 05:16:41 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)) 05:16:41 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)) 05:16:41 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)) 05:16:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:44 INFO - Downloading blessings-1.6.tar.gz 05:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:44 INFO - Installing collected packages: blessings 05:16:44 INFO - Running setup.py install for blessings 05:16:44 INFO - Successfully installed blessings 05:16:44 INFO - Cleaning up... 05:16:44 INFO - Return code: 0 05:16:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:44 INFO - Reading from file tmpfile_stdout 05:16:44 INFO - Current package versions: 05:16:44 INFO - blessings == 1.6 05:16:44 INFO - blobuploader == 1.2.4 05:16:44 INFO - docopt == 0.6.1 05:16:44 INFO - manifestparser == 1.1 05:16:44 INFO - mozInstall == 1.12 05:16:44 INFO - mozcrash == 0.17 05:16:44 INFO - mozdebug == 0.1 05:16:44 INFO - mozdevice == 0.48 05:16:44 INFO - mozfile == 1.2 05:16:44 INFO - mozhttpd == 0.7 05:16:44 INFO - mozinfo == 0.9 05:16:44 INFO - mozleak == 0.1 05:16:44 INFO - mozlog == 3.1 05:16:44 INFO - moznetwork == 0.27 05:16:44 INFO - mozprocess == 0.22 05:16:44 INFO - mozprofile == 0.28 05:16:44 INFO - mozrunner == 6.11 05:16:44 INFO - mozscreenshot == 0.1 05:16:44 INFO - mozsystemmonitor == 0.0 05:16:44 INFO - moztest == 0.7 05:16:44 INFO - mozversion == 1.4 05:16:44 INFO - psutil == 3.1.1 05:16:44 INFO - requests == 1.2.3 05:16:44 INFO - wsgiref == 0.1.2 05:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:44 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:44 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 05:16:44 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 05:16:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:44 INFO - 'HOME': '/Users/cltbld', 05:16:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:44 INFO - 'LOGNAME': 'cltbld', 05:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:44 INFO - 'MOZ_NO_REMOTE': '1', 05:16:44 INFO - 'NO_EM_RESTART': '1', 05:16:44 INFO - 'PAGER': '/bin/cat', 05:16:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:44 INFO - 'PWD': '/builds/slave/test', 05:16:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:44 INFO - 'SHELL': '/bin/bash', 05:16:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:44 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:44 INFO - 'USER': 'cltbld', 05:16:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:44 INFO - 'XPC_FLAGS': '0x0', 05:16:44 INFO - 'XPC_SERVICE_NAME': '0', 05:16:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 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)) 05:16:44 INFO - Cleaning up... 05:16:44 INFO - Return code: 0 05:16:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:44 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 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:44 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 05:16:44 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 05:16:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:44 INFO - 'HOME': '/Users/cltbld', 05:16:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:44 INFO - 'LOGNAME': 'cltbld', 05:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:44 INFO - 'MOZ_NO_REMOTE': '1', 05:16:44 INFO - 'NO_EM_RESTART': '1', 05:16:44 INFO - 'PAGER': '/bin/cat', 05:16:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:44 INFO - 'PWD': '/builds/slave/test', 05:16:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:44 INFO - 'SHELL': '/bin/bash', 05:16:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:44 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:44 INFO - 'USER': 'cltbld', 05:16:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:44 INFO - 'XPC_FLAGS': '0x0', 05:16:44 INFO - 'XPC_SERVICE_NAME': '0', 05:16:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:45 INFO - Downloading/unpacking jsonschema 05:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:47 INFO - Downloading/unpacking functools32 (from jsonschema) 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:48 INFO - warning: no files found matching '*.txt' 05:16:48 INFO - no previously-included directories found matching 'build' 05:16:48 INFO - no previously-included directories found matching 'dist' 05:16:48 INFO - no previously-included directories found matching '.git*' 05:16:48 INFO - Installing collected packages: jsonschema, functools32 05:16:48 INFO - Running setup.py install for functools32 05:16:48 INFO - warning: no files found matching '*.txt' 05:16:48 INFO - no previously-included directories found matching 'build' 05:16:48 INFO - no previously-included directories found matching 'dist' 05:16:48 INFO - no previously-included directories found matching '.git*' 05:16:48 INFO - Successfully installed jsonschema functools32 05:16:48 INFO - Cleaning up... 05:16:48 INFO - Return code: 0 05:16:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1050aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050bb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d784aaef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, '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.LapkpBx7uG/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.f0WXPLaBbV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 05:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:16:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:16:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:48 INFO - 'HOME': '/Users/cltbld', 05:16:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:48 INFO - 'LOGNAME': 'cltbld', 05:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:48 INFO - 'MOZ_NO_REMOTE': '1', 05:16:48 INFO - 'NO_EM_RESTART': '1', 05:16:48 INFO - 'PAGER': '/bin/cat', 05:16:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:48 INFO - 'PWD': '/builds/slave/test', 05:16:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:48 INFO - 'SHELL': '/bin/bash', 05:16:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:16:48 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:16:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:48 INFO - 'USER': 'cltbld', 05:16:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:48 INFO - 'XPC_FLAGS': '0x0', 05:16:48 INFO - 'XPC_SERVICE_NAME': '0', 05:16:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:48 INFO - Downloading/unpacking flake8 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:16:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:16:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:16:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:52 INFO - no previously-included directories found matching 'docs/_build' 05:16:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:16:52 INFO - Running setup.py install for pep8 05:16:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:52 INFO - no previously-included directories found matching 'docs/_build' 05:16:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:16:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:16:52 INFO - Cleaning up... 05:16:52 INFO - Return code: 0 05:16:52 INFO - Running post-action listener: _resource_record_post_action 05:16:52 INFO - Running post-action listener: _start_resource_monitoring 05:16:52 INFO - Starting resource monitoring. 05:16:52 INFO - ##### 05:16:52 INFO - ##### Running install step. 05:16:52 INFO - ##### 05:16:52 INFO - Running pre-action listener: _resource_record_pre_action 05:16:52 INFO - Running main action method: install 05:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:53 INFO - Reading from file tmpfile_stdout 05:16:53 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:53 INFO - Reading from file tmpfile_stdout 05:16:53 INFO - Output received: 05:16:53 INFO - Usage: mozinstall [options] installer 05:16:53 INFO - Options: 05:16:53 INFO - -h, --help show this help message and exit 05:16:53 INFO - -d DEST, --destination=DEST 05:16:53 INFO - Directory to install application into. [default: 05:16:53 INFO - "/builds/slave/test"] 05:16:53 INFO - --app=APP Application being installed. [default: firefox] 05:16:53 INFO - mkdir: /builds/slave/test/build/application 05:16:53 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'] 05:16:53 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 05:17:26 INFO - Reading from file tmpfile_stdout 05:17:26 INFO - Output received: 05:17:26 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:17:26 INFO - Running post-action listener: _resource_record_post_action 05:17:26 INFO - ##### 05:17:26 INFO - ##### Running run-tests step. 05:17:26 INFO - ##### 05:17:26 INFO - Running pre-action listener: _resource_record_pre_action 05:17:26 INFO - Running main action method: run_tests 05:17:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:26 INFO - 2016-04-17 05:17:26.716 screenresolution[1647:8765] starting screenresolution argv=screenresolution get 05:17:26 INFO - 2016-04-17 05:17:26.719 screenresolution[1647:8765] Display 0: 1600x1200x32@60 05:17:26 INFO - 2016-04-17 05:17:26.725 screenresolution[1648:8767] starting screenresolution argv=screenresolution list 05:17:26 INFO - Available Modes on Display 0 05:17:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:26 INFO - Intel Iris: 05:17:26 INFO - Chipset Model: Intel Iris 05:17:26 INFO - Type: GPU 05:17:26 INFO - Bus: Built-In 05:17:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:26 INFO - Vendor: Intel (0x8086) 05:17:26 INFO - Device ID: 0x0a2e 05:17:26 INFO - Revision ID: 0x0009 05:17:26 INFO - Displays: 05:17:26 INFO - Display: 05:17:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:26 INFO - Display Serial Number: 1600x1200 60 05:17:26 INFO - Main Display: Yes 05:17:26 INFO - Mirror: Off 05:17:26 INFO - Online: Yes 05:17:26 INFO - Rotation: Supported 05:17:26 INFO - Return code: 0 05:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:26 INFO - Python 2.7.3 05:17:26 INFO - Return code: 0 05:17:26 INFO - grabbing minidump binary from tooltool 05:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:26 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 0x104f4ae00>, '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 0x104d73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f45b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:26 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 05:17:26 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 05:17:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:27 INFO - Return code: 0 05:17:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:17:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:17:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:17:28 INFO - Return code: 0 05:17:28 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 05:17:28 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --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 05:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LapkpBx7uG/Render', 05:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:28 INFO - 'HOME': '/Users/cltbld', 05:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:28 INFO - 'LOGNAME': 'cltbld', 05:17:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:28 INFO - 'MOZ_NO_REMOTE': '1', 05:17:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:28 INFO - 'NO_EM_RESTART': '1', 05:17:28 INFO - 'PAGER': '/bin/cat', 05:17:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:28 INFO - 'PWD': '/builds/slave/test', 05:17:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:28 INFO - 'SHELL': '/bin/bash', 05:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f0WXPLaBbV/Listeners', 05:17:28 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 05:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:28 INFO - 'USER': 'cltbld', 05:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:28 INFO - 'XPC_FLAGS': '0x0', 05:17:28 INFO - 'XPC_SERVICE_NAME': '0', 05:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:28 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 05:17:28 INFO - mozversion application_buildid: 20160417030601 05:17:28 INFO - mozversion application_changeset: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 05:17:28 INFO - mozversion application_display_name: Nightly 05:17:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:28 INFO - mozversion application_name: Firefox 05:17:28 INFO - mozversion application_remotingname: firefox 05:17:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:17:28 INFO - mozversion application_vendor: Mozilla 05:17:28 INFO - mozversion application_version: 48.0a1 05:17:28 INFO - mozversion platform_buildid: 20160417030601 05:17:28 INFO - mozversion platform_changeset: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 05:17:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:17:28 INFO - mozversion platform_version: 48.0a1 05:17:28 INFO - using testdate: 1460895448 05:17:28 INFO - actual date: 1460895448 05:17:28 INFO - starting webserver on 'localhost:49276' 05:17:28 INFO - SUITE-START | Running 2 tests 05:17:28 INFO - TEST-START | dromaeo_css 05:17:28 INFO - operating with platform_type : mac_ 05:17:28 INFO - Initialising browser for dromaeo_css test... 05:17:28 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpvUbNxW/profile http://localhost:49276/getInfo.html) 05:17:30 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 05:17:30 INFO - PROCESS | 1666 | colorDepth:24 05:17:30 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 05:17:30 INFO - PROCESS | 1666 | __metrics 05:17:31 INFO - PROCESS | 1666 | 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" 05:17:31 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:17:31 INFO - TEST-INFO | 1666: exit 0 05:17:31 INFO - Browser initialized. 05:17:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:31 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpvUbNxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:43 INFO - PROCESS | 1670 | RSS: Main: 237527040 05:17:43 INFO - PROCESS | 1670 | 05:18:34 INFO - PROCESS | 1670 | 1460895514444 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] 05:20:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 05:20:47 INFO - PROCESS | 1670 | RSS: Main: 341032960 05:20:47 INFO - PROCESS | 1670 | 05:23:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 05:23:50 INFO - PROCESS | 1670 | RSS: Main: 375148544 05:23:50 INFO - PROCESS | 1670 | 05:26:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 05:26:53 INFO - PROCESS | 1670 | RSS: Main: 378175488 05:26:53 INFO - PROCESS | 1670 | 05:29:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 05:29:55 INFO - PROCESS | 1670 | RSS: Main: 377229312 05:29:55 INFO - PROCESS | 1670 | 05:32:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 05:32:59 INFO - PROCESS | 1670 | RSS: Main: 341897216 05:32:59 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 05:36:02 INFO - PROCESS | 1670 | RSS: Main: 421269504 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | __start_tp_report 05:36:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 05:36:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 05:36:02 INFO - PROCESS | 1670 | |i|pagename|runs| 05:36:02 INFO - PROCESS | 1670 | |0;dojo.html;3403.2342722887347;3429.348423799478;3465.740069494788;3472.461768425892;3499.265154317593;5594.300712410949;5639.041362968295;5644.8024319148835;5664.490195882375;5666.660000399979;5619.213310136584;5650.886982260363;5660.915086273699;5671.2060311556415;5706.800261990831;5144.331236939202;5169.457206993268;5194.792208311682;5205.115130427836;5273.709945952971;5244.475552444763;5259.789608415659;5263.0526505229045;5276.944611077781;5287.180487024518;5178.326817513718;5207.921881171786;5239.240310155044;5241.161414173733;5330.227117068022;5270.472952704757;5286.973565132188;5309.681419114827;5325.094733895247;5363.678179309254;5098.745062746848;5125.84622461323;5129.281900533928;5222.16445368743;5226.320578324793;5405.972970135163;5408.215808707715;5433.022056029913;5439.972800135974;5555.749991250404;6143.631382117042;6168;6169.722362493653;6178.382161783786;6183.81448556544;6062.848428789315;6097.037783475835;6173.753049878;6210.441060304594;6211.409916058013;5776;5799.33307669615;5800.245968024215;5815.941840581582;5851.297844258674;6211.9068213976825;6219.222597175353;6230.7819226326865;6232.532560058014;6254.280757712831;5940.8811823764145;6018.849528761816;6032.185654936615;6118.724657390429;6120.908186377208;6172.938270617236;6181;6188.845278867974;6202.503799696014;6215.285242197115;6106.145139680449;6150.661713605705;6159.907601385983;6165.8458538536;6167.229096362955;6047.9395206047375;6102.004547214544;6137.723802428902;6182.876342473215;6199.039148931833;4852.2479015752615;4876.21980483121;4889.071076495455;4889.413270407574;4895.853124406273;4348.391225228471;4438.60052595268;4438.933415998763;4440.933385999213;4475.104979004147;5646.2942132233475;5651.463111004448;5664.433556644385;5686.886262274731;5696.11710184922;6108.908366374508;6183.505319574423;6184.81445556634;6195.2255968004;6198.504119670415;5474.753636086386;5606.243157173811;5629.774809007594;5639.718014099279;5640.32316122068;5296.09966305729;5339.626226164131;5347.1979203119845;5382.596305277119;5400.297961265011;6252.781152659635;6278.372162783777;6307.085472606538;6315.936840631627;6318.905216421849;6087.9391206087375;6162.691865406801;6176.011838105882;6180.81457556274;6191.5665903388135;6206.100115483139;6294.590851594632;6301.8109456716375;6305.274893387319;6315.621062736251;6122.234720659918;6134.04922237054;6139.877202456015;6141.7543298267565;6156.722947467464;6066.908996365147;6142.253225532511;6192.318844927145;6205.007198848162;6208.534359923115;5934.139549765347;6007.789727359522;6040.033594624839;6055.757769689163;6091.9086213707715;5974.61165024272;6032.215811944419;6032.517398608013;6049.727762250621;6061.727222275096;4992.201247800334;5762.855928601818;5890.52875769946;5940.049592065248;5945.197398351167;6025.337212906489;6078.787242446493;6080.270367555921;6098.237720284965;6141.877162456636;4848.515148485123;5898.8230353089475;5907.40925907423;5908.291005079418;5941.168236446814 05:36:02 INFO - PROCESS | 1670 | |1;ext.html;498.37951750071164;499.24613833111994;513.8766695992963;526.0819869328024;538.4238864415075;29764.702352976463;30242.395152096946;30295;36921.815390923075;38418.039549011286;34241.65758342416;41022.58977410225;41624;41686.58313416865;41893;9398.906010939905;9627.566759495805;9629.711108666752;9630.085141911508;9635.084666956664;8434.578271086451;8748.737537873842;9341.53292335384;11361.261518001304;11766.882331176665;11335.659930202108;11456.599019034335;11480.655580332561;11512.769744605102;11517.42412879357;8439.95780021096;11244.775104497905;11413.657590272305;11416.942915285412;11426.37154956477;11130;11565.653030409101;11575.189736718434;11585.710357241052;11588.30470171784;32256.677433225603;38036.23927521434;38575.807120964215;38599.8070009651;38640;3312.7018568328726;3317.20387107095;3420.247545539977;3554.040409089557;3703.981405113588;27323.223990440758;28238;29041.273968150756;29182;29796;2730.071775596287;2773;2788.9860550697117;2813.6342275504257;2849.202223377458;464969;467778.6611066957;468522;469659;470185;61572.07641885375;62789.74420511564;62807;62911.370886291465;63164.68417657882;65795.34204657988;66206;66277.66861165663;66327.66836165788;66636.33363666301;16352.34590616362;16807.579810504834;16828.74756878648;16829.747553786703;16912.154392280336;11730.296182229093;12027.338496382608;13026.54407095747;13101.148425352321;13130.671733206555;12694.047946404056;14471.78292325616;14959.177245251616;15027.699446011018;16610.418635347705;6903;7107.289271072851;7127.857442851114;7145.892386680073;7157.57054576727;836.5691668790578;999.1906555689769;1002.4536627538009;1031.0771859186136;1034.622362837559;28100;28401.57397639037;28706.569401458994;28804.42391152207;28957.56563651547;1151.4530597966066;1154.3131836557236;1167.9299242045336;1176.688177632928;1193.6061099837034;12503.437345319482;14880.70238595222;15060.774088388684;15516.224188790515;15533.533993979972;525573;819741;839389;843782.5621743704;844763;47631;58721.119183212286;58737.706311469025;58783.412165878646;58815.82368352695;1352.7892536180063;1468.2218424235173;1488.5980785188156;1507.7286088503736;1511.516314779282;27932.581011285252;28492.715072849005;28995.7100428993;29155.7084429153;29320.706792931796;50203;50814.49185508245;50852.745736271085;50888.745556271984;50985.23522147245;407953;409673;410852;411094;411641;24927.875360623806;26169.869150654125;26223.21330360092;26232.950681972507;26233.606495902193;32331.515027274138;40051.79974100111;45688;47518.287225690954;47678.76160619313;35606.28787424289;35960;36093.81953090323;36740.26519469542;36968.63031369652;35449.645503544634;35538.24518007945;35586.28827423489;36457.453138202414;36520.45219321766;35344;35386.823065884506;36186.276274473836;36366.63633363738;36380;30635.846820765753;31839.36321273608;31857.522137167496;31898.84050579732;32012.5198122033;21814.563708726055;21819.23632672849;22223.888805559982;23698.881505592362;23942.401439964142 05:36:02 INFO - PROCESS | 1670 | |2;jquery.html;393.8936487148471;398.2751392465712;400.86971734186386;403.11918458168884;408.3364532634469;13327.733865282782;19160.520986975334;19941.3020544281;19977.800221997775;20212.989350532487;16002.439914602963;16221.83778162218;17708.645827083452;19149.61700765984;20015.999200040013;4962.883351245977;5792.7972520961775;5803.506701930331;6027.09593560965;6029.60807547508;5712.257406537145;6010.12853136295;6049.637021778685;6050.183225264599;6075.149479072933;5957.106434034908;5990.550708696844;6000.3699611540815;6006.489448396892;6010.699465026753;5509;5892.234009578749;5915.645061296293;5933.495652869511;6029.974904266263;5469.808556700496;5595.804146854881;5667.716614169275;5677.716114194315;5959.761609535613;12706.237625742486;12877.549285774952;13121.47514099435;13138.408771605205;13155.342232888317;1953.5116220944765;2091.372588223542;2093.696414019974;2166.155199472207;2222.20000799712;8438.957805210996;9379.062093790635;9766.706998790047;9927;9988;1738.8956662600285;1798.9370372036915;1806.3948577226645;1812.1664034543994;1881.595456976743;115258.84741152565;118172.81827181704;120146.39926800398;120988;122826;11345;11368.431578421047;11511.366874821964;11543.422828858524;11669.299842009506;14896.62758431048;14962.251887405586;14967.476138335323;14998;14998.850011499964;7331.401738435344;7377.5204611700065;8414.158584141536;8515.659373624985;8516.06323304436;8259.091499935003;8282;8375.329973602098;8487.575621218914;8519.957400213083;3574.7140228781636;4358.8910277242685;4522.5025247222775;4541.613962813131;4583.220852455091;1916.8562357823218;1962.607478504305;1979.5842872996543;1981.2570286142698;1982.930597429112;905.3391024551983;910.913463220993;959.4339339789558;978.9755256118654;1014.3558840136567;18524.536886577673;19636.607267854564;19702.507437313896;19806.70289945652;19882.105305261008;944.15026476172;968.1915600473683;1004.708634495988;1008;1074.119222237773;10040.849387259197;10195.745106372251;10215.438150901624;10230.335028223291;10392.792144157074;589149;594917;606748;620024;627684;119833.40083299528;126456;127203;127547;128037.07944380841;1010.540204207084;1027;1031.8606988056667;1041.671873359889;1049.485751981539;14225.857741422453;17117.972921624743;17319.480415587554;17505.374193871023;17563.1218439075;36257.81871090628;39126;43481;48841;48872.75563622159;68937.31062689309;69720;70662;71250.28749712436;72740.63629681818;7920.960395197987;7957;8142.877856832034;8377;10690.305130166515;9622.997078501527;11930.164888457724;12664.050196235503;12941.870581294443;13110.934445327712;10888.67333979982;11179.832302515631;11229.270097443967;11462.770744584896;11687.298762074302;8603.741887743377;11127.666170014912;11249.831252531383;11505.712357191138;11681.88318116808;11405.486753095965;11438.82841757391;11443.828342575034;11624.709382265512;11961.700957476132;10891.618793342195;11528.213126965335;11817.881821182022;11867.991220745987;12074.154809163274;11094.833577496183;11387.829182562102;11522.308661480338;11588.188826782382;11653.825192621947 05:36:02 INFO - PROCESS | 1670 | |3;mootools.html;1414.957551273462;1587.0953556472807;1608.7586861970703;1818.9363372281973;1927.8721947660608;7858.803529911748;8528.360372972022;8784.91215087849;8796.604152813123;8808.691695790641;8417.663293468255;8619.784505387368;8648.0487146414;8658.09090045544;8661.523616201106;2437.9512409751796;2468.7654672806057;2477.430191056058;2477.938051548708;2478.0707234786955;2369.206315884181;2406.506666133447;2471.9876400617973;2472.1223965492172;2473.7402572729875;2260.773922607734;2384.7734465225776;2422.1401402502124;2460.815438842094;2465.0632759551345;2410.0841680161425;2433.6471211674298;2434.9391265218424;2435.622478515833;2450.5834008218644;2397.3167647220635;2409.590369637166;2411.565918134735;2420.8426452280546;2423.09134074722;8469.449485783465;8612.267957223581;8639.95680021596;8658.350623703185;8673.913260867374;5221.895562088775;5333.466653334674;5370.597205209586;5396.325459317585;5416.83749487516;3196.8081915085168;3319.286353434023;3320.5351250824906;3542.468629705539;3647.288778688157;1035.223582313265;1048.0043958239703;1050.2648146297622;1058.5025038232018;1064.675274041417;11101.944490277578;11194.888051119466;11221.214514983954;11223.494942727515;11226.101911847114;4408.515063343029;4480.955190448054;4636.675432719745;4643.3963584733765;4695.600873925686;9003.954980225057;9005;9020.368574199743;9023.368364214573;9045.276377889753;5204.4014938281825;5226.163813789775;5235.188545775411;5252.317198764213;5254.579633629301;3726.5900750917376;4509.391232183679;4727.361806156194;4849.199882019428;4890.486498917634;3701.204241088179;3739.139997800521;3817.751846129993;3819.52255968004;3863.671587915059;1206.3847437806783;1209.6673414810998;1211.3035004872233;1221;1221.688469440287;643.4819969924163;782.1201148707704;854.4702284583598;906.981860362789;941.3128416256035;4842.201036828884;4870.9269360959615;4889.9486610378935;5477.75350109246;5726.112452569859;824.4352618456377;917.2661870503704;919.5632074764434;938.4932136646271;996.0487734213808;3183.5065564837487;3647.2340808429994;3682.1715114099115;3752.1557649528636;3787.5265591801026;10149.441780701985;10576.101031412261;10680.679579612624;10682.03861652455;10692.572297108185;10675.786484270271;10717.928207179375;10720.035196832323;10731.89268107309;10786.892131078746;7948.721794737156;7954.363650907913;8147.959260203661;8214;8243.917560824555;5340.118880384693;5411.134218525017;5514.91727624078;5532.253145825262;5670.432956704297;9671.564779584794;9818.410895346302;9922.454265015493;10005.04952029556;10028.448435336126;9675.854862176931;9973.850392243978;10009.899901000897;10031.197504199792;10194.949025254826;6066.211392518944;6873;6937.340952609494;7004.774164521086;7023.894641580483;7984.441089123706;8097.87853182191;8114.945057142534;8120.9848768903885;8157.184281572033;6015.308239552332;6209.285932117684;7159.713611455483;7255.56466612005;7433.776986690408;7058.223595404399;7198.388137008204;7211.783646490614;7256.96371518139;7257.564546127249;6690.69891854858;6767.89848152288;6783.287754785676;6792.252852186157;6797.966010169917;4504.887377815581;4602.884927876696;4637.721736695809;4688.507706690748;4811.687240329367;3818.4845045918446;4739.028499157595;4816.590589799906;4820.325154478306;4842.346283251716 05:36:02 INFO - PROCESS | 1670 | |4;prototype.html;317.85855294393997;320.15319479975466;327.7034283973004;329.9884504042358;336.8804074553532;5823.534117270613;5864.677442740647;5866.970665146669;5874.676892770896;5884.323302820177;4508.4589849218055;5516.365617953937;5817.621854579449;5829.446202610756;5864.912026319598;3369.2082360645227;3836.1176929306275;4025.134596061846;4329.415528903605;4370.125974805052;3863.4011728182177;4201.873943781691;4406.69153159279;4420.77896105195;4448.866534003969;3760.7743535387963;4358.673099517533;4406.4271644686305;4407.647388208936;4434.312681534351;4193.979030104861;4243.618074373321;4443.577860103301;4465.129299786543;4481.865544033668;3828.674562662177;3833.9424908626115;3838.140585263968;3851.0564911596557;3854.402567602026;4888.364512613373;5530.917036244459;5581.5255703265275;5596;5638.520725738276;4787.54518320759;5046.596272298208;5052.0906236877345;5095.031943930641;5117.257997590328;5809.2447981762525;6039.728212230416;6064.665773529817;6093.969530152365;6105.72524236406;3604.1350075981836;3614.313280476701;4253.48958125027;4258.297380932143;4265.253580623409;5180.07571110079;5342.652727572696;6461.967690161519;6475.449586785082;6520.576162549467;4293.957060429356;4314.1371725655;4323.005708687019;4338.154059958278;4338.305871060616;4217.249841315819;4362.585554372329;4388.407564978687;4396.956030439718;4401.185780630593;4494.797734101973;4818.7831547580445;4897.877553061202;5057.080762409162;5082.161443361804;5376.946230537723;6127.479164271087;6131.601445906002;6140.293866205354;6148.1085242639565;1407.8240219972504;1495.1178804505394;1517.7251109068216;1524.2531159731652;1525.3669727063366;970.9368891022061;988.2736188901152;1019.2508506247901;1020.6937918624383;1029.5212726082407;5278.683279003272;5660.490555849911;5682.801101961411;5699.974004679198;5777.364489906107;6322.683865806691;6333.81967901183;6354.777582784581;6359.109724638554;6389.808305750835;5425.348958125051;5674.829755107354;5677;5684.30520161898;5699.202111704365;2682.771964383049;3134.4358015556963;3171.888983885553;3274.459714147187;3281.8523166457567;5487.368952570426;5895.29256489224;6496.4594296108735;6684.699188536528;6704.4301234395625;6208.844778880564;6427.485801135898;6566;6567.310432404624;6570.77002304917;5428.75570599324;5924.652571297203;6742.258351581322;6757.391834734897;6766.15423072116;5920.319163296103;6164.352742961924;6390.073439351361;6426;6434.195725534309;5685.403032681509;6206.882761103045;6291.842703932255;6300.180976473029;6330.873382532415;5853.765849365977;6438.227412710505;6691.698873550799;6704.865902682017;6769.661516924232;3984.2629113614075;4063.6342729154517;4174.436451079066;4192.727472714264;4216.641585465147;5738.938296415176;6068.51451883857;6375.330590288138;6391.137196478416;6395.968020159869;5850.356460789395;6190.566660333733;6199.938000619936;6201.3178550358525;6212.161358216583;4975.577075948585;5210.8957820844125;5255.658382205144;6122.479589234965;6156.321195891244;5146.588272938231;5626.324841019104;5999.340072591924;6066.939330606638;6146.508279337731;5852.488506895998;6168.19813424252;6186.969065154825;6220.595661282002;6226.657533835501;6034.909476357946;6035.336113027652;6038.607490513103;6058.636481810929;6060.272767267956 05:36:02 INFO - PROCESS | 1670 | |5;yui.html;673.579013116802;755.1449848523287;760.1448370583093;762.0017776712507;772.0658003815383;12793.232406055631;15193.468228612;16100;16100.83899161008;16108.436204732836;14650.853491465083;14710.485133020346;14722.969392142555;14743.336549855256;14770.335334909929;933.4865823796919;936.6440752514069;944.551338114397;947.8909925358569;950.95720692569;728.2535401213745;751.7481643649384;889.1419779912384;915.1443400420595;920.2499962530517;981.1856159387727;987.501311837521;991.7619771254919;993.1657407777466;994.2692121290845;958.4967891856761;984.990150098497;985.3201291109111;987.0573602209872;987.2940847294183;560.4983539731958;583.9299284085895;585.9472647461706;586.0769288370833;743.4461326311878;14772.261386930719;15258.237088145548;15264.084154950628;15278.847211527853;15282.694346113127;12382.31897245651;12646.683832904251;12767.42546585406;12855.807162892565;13009.0243231758;9686.78915135608;9717.46553939533;9737.2697047721;9902.80194396108;10758.139348852073;3560.0565850049757;3626.9274614507826;3641.599424063351;3673.0266479382985;3673.687736542397;78042;84173;85363.5731821343;85483.14516854814;86521.13478865194;8713.607887645072;9302.53487325631;9349.766255843659;9569.138777510057;9691;19642;20138.69791953122;20161.798382016284;20792.27227047046;20866.47833804167;7535.773926782205;7549.339145388006;7715.382769378549;7740.574268415178;7929.405294602928;3439.7420193485586;4009.538903026131;4102.405151253111;4133.0700592367075;4160.292750232467;3434.244466217428;3552.8223588820456;3556.270964452281;3560.572731272212;3563.7327200460068;622.5610944284268;626.4330780643434;626.6991843914947;637.4645297949652;641.9984823675081;507.6243579750958;529.2775361631423;566.8469513231404;600.1477901380051;612.1246617337162;7561.584112873845;7595.3164215220895;7647.655855486517;7751.147373788879;8107.635156417975;567.4495739133034;588.3939542271468;589.4370875813588;593.94951429129;598.1207624791549;3578.4095624221713;3675.9081022974106;3703.962960370362;3721.404575267944;3730.421784623388;86427.56786216155;87133.56433217794;90377;91432.6285105724;91692.1661566765;47556.76221618939;54786.72606636941;57648.8470230593;65459.672701636184;70317;56036.159457608985;59298.70350648219;60659;61000.084998725055;62019.689901550206;7755.689772409266;7875.684972601031;8014.679412823655;8065.758027259191;8114.229148230908;34497.48253776246;38257.6609818655;38520;38620.6137938617;39904.60095399125;52184;54284.91430171295;56450.02424915108;56673.71663141658;57310;10656.520456579317;10769.569217231223;11014.659727463864;11399.829002564802;11850.525978960744;10900.433805987632;11948.940255298668;12139.150259481752;12379.319137447166;12512.937435312766;11089.944550277198;11124.555017799521;11373.431328433711;11585;11708.70728231801;11415.942920285346;11420.143489238175;11505.252158609663;11657.591984280509;11711.121665874924;10812.513436895515;10979.890201098206;11073.280236784583;11225.71935701614;11382.146339024772;9429.952850235979;9648.903510964801;9724.513774311397;9764.511774411108;9788.02119788044;7999.400044996532;8707.564621769014;9391.248700104112;9456.905430945602;9471.337006409485 05:36:02 INFO - PROCESS | 1670 | __end_tp_report 05:36:02 INFO - PROCESS | 1670 | __start_cc_report 05:36:02 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1201 05:36:02 INFO - PROCESS | 1670 | __end_cc_report 05:36:02 INFO - PROCESS | 1670 | __startTimestamp1460896562805__endTimestamp 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | ------- Summary: start ------- 05:36:02 INFO - PROCESS | 1670 | Number of tests: 6 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5695.58 Median:5934.14 stddev:613.84 (10.3%) stddev-sans-first:588.79 05:36:02 INFO - PROCESS | 1670 | Values: 3403.2 3429.3 3465.7 3472.5 3499.3 5594.3 5639.0 5644.8 5664.5 5666.7 5619.2 5650.9 5660.9 5671.2 5706.8 5144.3 5169.5 5194.8 5205.1 5273.7 5244.5 5259.8 5263.1 5276.9 5287.2 5178.3 5207.9 5239.2 5241.2 5330.2 5270.5 5287.0 5309.7 5325.1 5363.7 5098.7 5125.8 5129.3 5222.2 5226.3 5406.0 5408.2 5433.0 5440.0 5555.7 6143.6 6168.0 6169.7 6178.4 6183.8 6062.8 6097.0 6173.8 6210.4 6211.4 5776.0 5799.3 5800.2 5815.9 5851.3 6211.9 6219.2 6230.8 6232.5 6254.3 5940.9 6018.8 6032.2 6118.7 6120.9 6172.9 6181.0 6188.8 6202.5 6215.3 6106.1 6150.7 6159.9 6165.8 6167.2 6047.9 6102.0 6137.7 6182.9 6199.0 4852.2 4876.2 4889.1 4889.4 4895.9 4348.4 4438.6 4438.9 4440.9 4475.1 5646.3 5651.5 5664.4 5686.9 5696.1 6108.9 6183.5 6184.8 6195.2 6198.5 5474.8 5606.2 5629.8 5639.7 5640.3 5296.1 5339.6 5347.2 5382.6 5400.3 6252.8 6278.4 6307.1 6315.9 6318.9 6087.9 6162.7 6176.0 6180.8 6191.6 6206.1 6294.6 6301.8 6305.3 6315.6 6122.2 6134.0 6139.9 6141.8 6156.7 6066.9 6142.3 6192.3 6205.0 6208.5 5934.1 6007.8 6040.0 6055.8 6091.9 5974.6 6032.2 6032.5 6049.7 6061.7 4992.2 5762.9 5890.5 5940.0 5945.2 6025.3 6078.8 6080.3 6098.2 6141.9 4848.5 5898.8 5907.4 5908.3 5941.2 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:67956.51 Median:3368.73 stddev:155632.05 (4619.9%) stddev-sans-first:155986.22 05:36:02 INFO - PROCESS | 1670 | Values: 498.4 499.2 513.9 526.1 538.4 29764.7 30242.4 30295.0 36921.8 38418.0 34241.7 41022.6 41624.0 41686.6 41893.0 9398.9 9627.6 9629.7 9630.1 9635.1 8434.6 8748.7 9341.5 11361.3 11766.9 11335.7 11456.6 11480.7 11512.8 11517.4 8440.0 11244.8 11413.7 11416.9 11426.4 11130.0 11565.7 11575.2 11585.7 11588.3 32256.7 38036.2 38575.8 38599.8 38640.0 3312.7 3317.2 3420.2 3554.0 3704.0 27323.2 28238.0 29041.3 29182.0 29796.0 2730.1 2773.0 2789.0 2813.6 2849.2 464969.0 467778.7 468522.0 469659.0 470185.0 61572.1 62789.7 62807.0 62911.4 63164.7 65795.3 66206.0 66277.7 66327.7 66636.3 16352.3 16807.6 16828.7 16829.7 16912.2 11730.3 12027.3 13026.5 13101.1 13130.7 12694.0 14471.8 14959.2 15027.7 16610.4 6903.0 7107.3 7127.9 7145.9 7157.6 836.6 999.2 1002.5 1031.1 1034.6 28100.0 28401.6 28706.6 28804.4 28957.6 1151.5 1154.3 1167.9 1176.7 1193.6 12503.4 14880.7 15060.8 15516.2 15533.5 525573.0 819741.0 839389.0 843782.6 844763.0 47631.0 58721.1 58737.7 58783.4 58815.8 1352.8 1468.2 1488.6 1507.7 1511.5 27932.6 28492.7 28995.7 29155.7 29320.7 50203.0 50814.5 50852.7 50888.7 50985.2 407953.0 409673.0 410852.0 411094.0 411641.0 24927.9 26169.9 26223.2 26233.0 26233.6 32331.5 40051.8 45688.0 47518.3 47678.8 35606.3 35960.0 36093.8 36740.3 36968.6 35449.6 35538.2 35586.3 36457.5 36520.5 35344.0 35386.8 36186.3 36366.6 36380.0 30635.8 31839.4 31857.5 31898.8 32012.5 21814.6 21819.2 22223.9 23698.9 23942.4 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34317.24 Median:10893.14 stddev:101304.27 (930.0%) stddev-sans-first:101556.43 05:36:02 INFO - PROCESS | 1670 | Values: 393.9 398.3 400.9 403.1 408.3 13327.7 19160.5 19941.3 19977.8 20213.0 16002.4 16221.8 17708.6 19149.6 20016.0 4962.9 5792.8 5803.5 6027.1 6029.6 5712.3 6010.1 6049.6 6050.2 6075.1 5957.1 5990.6 6000.4 6006.5 6010.7 5509.0 5892.2 5915.6 5933.5 6030.0 5469.8 5595.8 5667.7 5677.7 5959.8 12706.2 12877.5 13121.5 13138.4 13155.3 1953.5 2091.4 2093.7 2166.2 2222.2 8439.0 9379.1 9766.7 9927.0 9988.0 1738.9 1798.9 1806.4 1812.2 1881.6 115258.8 118172.8 120146.4 120988.0 122826.0 11345.0 11368.4 11511.4 11543.4 11669.3 14896.6 14962.3 14967.5 14998.0 14998.9 7331.4 7377.5 8414.2 8515.7 8516.1 8259.1 8282.0 8375.3 8487.6 8520.0 3574.7 4358.9 4522.5 4541.6 4583.2 1916.9 1962.6 1979.6 1981.3 1982.9 905.3 910.9 959.4 979.0 1014.4 18524.5 19636.6 19702.5 19806.7 19882.1 944.2 968.2 1004.7 1008.0 1074.1 10040.8 10195.7 10215.4 10230.3 10392.8 589149.0 594917.0 606748.0 620024.0 627684.0 119833.4 126456.0 127203.0 127547.0 128037.1 1010.5 1027.0 1031.9 1041.7 1049.5 14225.9 17118.0 17319.5 17505.4 17563.1 36257.8 39126.0 43481.0 48841.0 48872.8 68937.3 69720.0 70662.0 71250.3 72740.6 7921.0 7957.0 8142.9 8377.0 10690.3 9623.0 11930.2 12664.1 12941.9 13110.9 10888.7 11179.8 11229.3 11462.8 11687.3 8603.7 11127.7 11249.8 11505.7 11681.9 11405.5 11438.8 11443.8 11624.7 11961.7 10891.6 11528.2 11817.9 11868.0 12074.2 11094.8 11387.8 11522.3 11588.2 11653.8 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5531.37 Median:4818.46 stddev:3106.58 (64.5%) stddev-sans-first:3099.89 05:36:02 INFO - PROCESS | 1670 | Values: 1415.0 1587.1 1608.8 1818.9 1927.9 7858.8 8528.4 8784.9 8796.6 8808.7 8417.7 8619.8 8648.0 8658.1 8661.5 2438.0 2468.8 2477.4 2477.9 2478.1 2369.2 2406.5 2472.0 2472.1 2473.7 2260.8 2384.8 2422.1 2460.8 2465.1 2410.1 2433.6 2434.9 2435.6 2450.6 2397.3 2409.6 2411.6 2420.8 2423.1 8469.4 8612.3 8640.0 8658.4 8673.9 5221.9 5333.5 5370.6 5396.3 5416.8 3196.8 3319.3 3320.5 3542.5 3647.3 1035.2 1048.0 1050.3 1058.5 1064.7 11101.9 11194.9 11221.2 11223.5 11226.1 4408.5 4481.0 4636.7 4643.4 4695.6 9004.0 9005.0 9020.4 9023.4 9045.3 5204.4 5226.2 5235.2 5252.3 5254.6 3726.6 4509.4 4727.4 4849.2 4890.5 3701.2 3739.1 3817.8 3819.5 3863.7 1206.4 1209.7 1211.3 1221.0 1221.7 643.5 782.1 854.5 907.0 941.3 4842.2 4870.9 4889.9 5477.8 5726.1 824.4 917.3 919.6 938.5 996.0 3183.5 3647.2 3682.2 3752.2 3787.5 10149.4 10576.1 10680.7 10682.0 10692.6 10675.8 10717.9 10720.0 10731.9 10786.9 7948.7 7954.4 8148.0 8214.0 8243.9 5340.1 5411.1 5514.9 5532.3 5670.4 9671.6 9818.4 9922.5 10005.0 10028.4 9675.9 9973.9 10009.9 10031.2 10194.9 6066.2 6873.0 6937.3 7004.8 7023.9 7984.4 8097.9 8114.9 8121.0 8157.2 6015.3 6209.3 7159.7 7255.6 7433.8 7058.2 7198.4 7211.8 7257.0 7257.6 6690.7 6767.9 6783.3 6792.3 6798.0 4504.9 4602.9 4637.7 4688.5 4811.7 3818.5 4739.0 4816.6 4820.3 4842.3 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4988.75 Median:5632.42 stddev:1552.16 (27.6%) stddev-sans-first:1516.41 05:36:02 INFO - PROCESS | 1670 | Values: 317.9 320.2 327.7 330.0 336.9 5823.5 5864.7 5867.0 5874.7 5884.3 4508.5 5516.4 5817.6 5829.4 5864.9 3369.2 3836.1 4025.1 4329.4 4370.1 3863.4 4201.9 4406.7 4420.8 4448.9 3760.8 4358.7 4406.4 4407.6 4434.3 4194.0 4243.6 4443.6 4465.1 4481.9 3828.7 3833.9 3838.1 3851.1 3854.4 4888.4 5530.9 5581.5 5596.0 5638.5 4787.5 5046.6 5052.1 5095.0 5117.3 5809.2 6039.7 6064.7 6094.0 6105.7 3604.1 3614.3 4253.5 4258.3 4265.3 5180.1 5342.7 6462.0 6475.4 6520.6 4294.0 4314.1 4323.0 4338.2 4338.3 4217.2 4362.6 4388.4 4397.0 4401.2 4494.8 4818.8 4897.9 5057.1 5082.2 5376.9 6127.5 6131.6 6140.3 6148.1 1407.8 1495.1 1517.7 1524.3 1525.4 970.9 988.3 1019.3 1020.7 1029.5 5278.7 5660.5 5682.8 5700.0 5777.4 6322.7 6333.8 6354.8 6359.1 6389.8 5425.3 5674.8 5677.0 5684.3 5699.2 2682.8 3134.4 3171.9 3274.5 3281.9 5487.4 5895.3 6496.5 6684.7 6704.4 6208.8 6427.5 6566.0 6567.3 6570.8 5428.8 5924.7 6742.3 6757.4 6766.2 5920.3 6164.4 6390.1 6426.0 6434.2 5685.4 6206.9 6291.8 6300.2 6330.9 5853.8 6438.2 6691.7 6704.9 6769.7 3984.3 4063.6 4174.4 4192.7 4216.6 5738.9 6068.5 6375.3 6391.1 6396.0 5850.4 6190.6 6199.9 6201.3 6212.2 4975.6 5210.9 5255.7 6122.5 6156.3 5146.6 5626.3 5999.3 6066.9 6146.5 5852.5 6168.2 6187.0 6220.6 6226.7 6034.9 6035.3 6038.6 6058.6 6060.3 05:36:02 INFO - PROCESS | 1670 | 05:36:02 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:16778.39 Median:584.94 stddev:23091.97 (3947.8%) stddev-sans-first:23125.08 05:36:02 INFO - PROCESS | 1670 | Values: 673.6 755.1 760.1 762.0 772.1 12793.2 15193.5 16100.0 16100.8 16108.4 14650.9 14710.5 14723.0 14743.3 14770.3 933.5 936.6 944.6 947.9 951.0 728.3 751.7 889.1 915.1 920.2 981.2 987.5 991.8 993.2 994.3 958.5 985.0 985.3 987.1 987.3 560.5 583.9 585.9 586.1 743.4 14772.3 15258.2 15264.1 15278.8 15282.7 12382.3 12646.7 12767.4 12855.8 13009.0 9686.8 9717.5 9737.3 9902.8 10758.1 3560.1 3626.9 3641.6 3673.0 3673.7 78042.0 84173.0 85363.6 85483.1 86521.1 8713.6 9302.5 9349.8 9569.1 9691.0 19642.0 20138.7 20161.8 20792.3 20866.5 7535.8 7549.3 7715.4 7740.6 7929.4 3439.7 4009.5 4102.4 4133.1 4160.3 3434.2 3552.8 3556.3 3560.6 3563.7 622.6 626.4 626.7 637.5 642.0 507.6 529.3 566.8 600.1 612.1 7561.6 7595.3 7647.7 7751.1 8107.6 567.4 588.4 589.4 593.9 598.1 3578.4 3675.9 3704.0 3721.4 3730.4 86427.6 87133.6 90377.0 91432.6 91692.2 47556.8 54786.7 57648.8 65459.7 70317.0 56036.2 59298.7 60659.0 61000.1 62019.7 7755.7 7875.7 8014.7 8065.8 8114.2 34497.5 38257.7 38520.0 38620.6 39904.6 52184.0 54284.9 56450.0 56673.7 57310.0 10656.5 10769.6 11014.7 11399.8 11850.5 10900.4 11948.9 12139.2 12379.3 12512.9 11089.9 11124.6 11373.4 11585.0 11708.7 11415.9 11420.1 11505.3 11657.6 11711.1 10812.5 10979.9 11073.3 11225.7 11382.1 9430.0 9648.9 9724.5 9764.5 9788.0 7999.4 8707.6 9391.2 9456.9 9471.3 05:36:02 INFO - PROCESS | 1670 | -------- Summary: end -------- 05:36:02 INFO - PROCESS | 1670 | 05:36:03 INFO - TEST-INFO | 1670: exit 0 05:36:03 INFO - TEST-OK | dromaeo_css | took 1114857ms 05:36:03 INFO - TEST-START | kraken 05:36:03 INFO - operating with platform_type : mac_ 05:36:03 INFO - Initialising browser for kraken test... 05:36:03 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpop4ZzN/profile http://localhost:49276/getInfo.html) 05:36:05 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 05:36:05 INFO - PROCESS | 1677 | colorDepth:24 05:36:05 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 05:36:05 INFO - PROCESS | 1677 | __metrics 05:36:05 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" 05:36:05 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:36:06 INFO - TEST-INFO | 1677: exit 0 05:36:06 INFO - Browser initialized. 05:36:06 INFO - Running cycle 1/1 for kraken test... 05:36:06 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpop4ZzN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:18 INFO - PROCESS | 1680 | RSS: Main: 237563904 05:36:18 INFO - PROCESS | 1680 | 05:36:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:21 INFO - PROCESS | 1680 | RSS: Main: 285708288 05:36:21 INFO - PROCESS | 1680 | 05:36:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 05:36:25 INFO - PROCESS | 1680 | RSS: Main: 299339776 05:36:25 INFO - PROCESS | 1680 | 05:36:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 05:36:29 INFO - PROCESS | 1680 | RSS: Main: 386023424 05:36:29 INFO - PROCESS | 1680 | 05:36:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 05:36:31 INFO - PROCESS | 1680 | RSS: Main: 285298688 05:36:31 INFO - PROCESS | 1680 | 05:36:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:34 INFO - PROCESS | 1680 | RSS: Main: 290578432 05:36:34 INFO - PROCESS | 1680 | 05:36:37 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:38 INFO - PROCESS | 1680 | RSS: Main: 329527296 05:36:38 INFO - PROCESS | 1680 | 05:36:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:41 INFO - PROCESS | 1680 | RSS: Main: 330084352 05:36:41 INFO - PROCESS | 1680 | 05:36:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 05:36:44 INFO - PROCESS | 1680 | RSS: Main: 328781824 05:36:44 INFO - PROCESS | 1680 | 05:36:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:47 INFO - PROCESS | 1680 | RSS: Main: 319758336 05:36:47 INFO - PROCESS | 1680 | 05:36:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:49 INFO - PROCESS | 1680 | RSS: Main: 327139328 05:36:49 INFO - PROCESS | 1680 | 05:36:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:52 INFO - PROCESS | 1680 | RSS: Main: 329478144 05:36:52 INFO - PROCESS | 1680 | 05:36:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:55 INFO - PROCESS | 1680 | RSS: Main: 334213120 05:36:55 INFO - PROCESS | 1680 | 05:36:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:59 INFO - PROCESS | 1680 | RSS: Main: 332189696 05:36:59 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 05:37:01 INFO - PROCESS | 1680 | RSS: Main: 396169216 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | __start_tp_report 05:37:01 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 05:37:01 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 05:37:01 INFO - PROCESS | 1680 | |i|pagename|runs| 05:37:01 INFO - PROCESS | 1680 | |0;ai-astar;113;111;111;112;112;113;118;113;102;108 05:37:01 INFO - PROCESS | 1680 | |1;audio-beat-detection;156;121;150;173;145;170;142;121;173;119 05:37:01 INFO - PROCESS | 1680 | |2;audio-dft;186;183;188;185;184;181;186;201;184;186 05:37:01 INFO - PROCESS | 1680 | |3;audio-fft;80;74;85;92;57;58;57;79;93;77 05:37:01 INFO - PROCESS | 1680 | |4;audio-oscillator;88;103;135;77;78;79;126;112;109;150 05:37:01 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;87;85;87;85;87;83;88;84;87;83 05:37:01 INFO - PROCESS | 1680 | |6;imaging-darkroom;93;94;94;93;94;93;94;95;94;101 05:37:01 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;83;83;84;87;86;84;84;86;86 05:37:01 INFO - PROCESS | 1680 | |8;json-parse-financial;60;59;58;57;57;57;49;70;88;84 05:37:01 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;66;74;74;69;72;92;91;94;69 05:37:01 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;79;77;82;81;80;81;81;80;82;82 05:37:01 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;140;139;147;147;146;151;132;138;176;140 05:37:01 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;165;168;168;166;169;163;167;171;167;168 05:37:01 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;71;69;69;67;71;66;81;94;92;64 05:37:01 INFO - PROCESS | 1680 | __end_tp_report 05:37:01 INFO - PROCESS | 1680 | __start_cc_report 05:37:01 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1953 05:37:01 INFO - PROCESS | 1680 | __end_cc_report 05:37:01 INFO - PROCESS | 1680 | __startTimestamp1460896621386__endTimestamp 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | ------- Summary: start ------- 05:37:01 INFO - PROCESS | 1680 | Number of tests: 14 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.50 stddev:4.11 (3.7%) stddev-sans-first:4.31 05:37:01 INFO - PROCESS | 1680 | Values: 113.0 111.0 111.0 112.0 112.0 113.0 118.0 113.0 102.0 108.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:153.00 stddev:21.44 (14.0%) stddev-sans-first:22.49 05:37:01 INFO - PROCESS | 1680 | Values: 156.0 121.0 150.0 173.0 145.0 170.0 142.0 121.0 173.0 119.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:186.40 Median:186.00 stddev:5.48 (2.9%) stddev-sans-first:5.81 05:37:01 INFO - PROCESS | 1680 | Values: 186.0 183.0 188.0 185.0 184.0 181.0 186.0 201.0 184.0 186.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:75.20 Median:79.50 stddev:13.73 (17.3%) stddev-sans-first:14.45 05:37:01 INFO - PROCESS | 1680 | Values: 80.0 74.0 85.0 92.0 57.0 58.0 57.0 79.0 93.0 77.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:105.70 Median:113.50 stddev:25.62 (22.6%) stddev-sans-first:26.36 05:37:01 INFO - PROCESS | 1680 | Values: 88.0 103.0 135.0 77.0 78.0 79.0 126.0 112.0 109.0 150.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.84 (2.1%) stddev-sans-first:1.88 05:37:01 INFO - PROCESS | 1680 | Values: 87.0 85.0 87.0 85.0 87.0 83.0 88.0 84.0 87.0 83.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.00 stddev:2.37 (2.5%) stddev-sans-first:2.45 05:37:01 INFO - PROCESS | 1680 | Values: 93.0 94.0 94.0 93.0 94.0 93.0 94.0 95.0 94.0 101.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.48 05:37:01 INFO - PROCESS | 1680 | Values: 85.0 83.0 83.0 84.0 87.0 86.0 84.0 84.0 86.0 86.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:59.50 stddev:12.74 (21.4%) stddev-sans-first:13.44 05:37:01 INFO - PROCESS | 1680 | Values: 60.0 59.0 58.0 57.0 57.0 57.0 49.0 70.0 88.0 84.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:74.00 stddev:11.39 (15.4%) stddev-sans-first:11.15 05:37:01 INFO - PROCESS | 1680 | Values: 64.0 66.0 74.0 74.0 69.0 72.0 92.0 91.0 94.0 69.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:1.58 (2.0%) stddev-sans-first:1.58 05:37:01 INFO - PROCESS | 1680 | Values: 79.0 77.0 82.0 81.0 80.0 81.0 81.0 80.0 82.0 82.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:146.50 stddev:12.05 (8.2%) stddev-sans-first:12.61 05:37:01 INFO - PROCESS | 1680 | Values: 140.0 139.0 147.0 147.0 146.0 151.0 132.0 138.0 176.0 140.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:168.00 stddev:2.20 (1.3%) stddev-sans-first:2.19 05:37:01 INFO - PROCESS | 1680 | Values: 165.0 168.0 168.0 166.0 169.0 163.0 167.0 171.0 167.0 168.0 05:37:01 INFO - PROCESS | 1680 | 05:37:01 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:71.00 stddev:10.81 (15.2%) stddev-sans-first:11.40 05:37:01 INFO - PROCESS | 1680 | Values: 71.0 69.0 69.0 67.0 71.0 66.0 81.0 94.0 92.0 64.0 05:37:01 INFO - PROCESS | 1680 | -------- Summary: end -------- 05:37:01 INFO - PROCESS | 1680 | 05:37:02 INFO - TEST-INFO | 1680: exit 0 05:37:02 INFO - TEST-OK | kraken | took 58966ms 05:37:02 INFO - SUITE-END | took 1173s 05:37:02 INFO - Completed test suite (00:19:34) 05:37:02 INFO - javascript benchmark 05:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.2342722887347, 3429.348423799478, 3465.740069494788, 3472.461768425892, 3499.265154317593, 5594.300712410949, 5639.041362968295, 5644.8024319148835, 5664.490195882375, 5666.660000399979, 5619.213310136584, 5650.886982260363, 5660.915086273699, 5671.2060311556415, 5706.800261990831, 5144.331236939202, 5169.457206993268, 5194.792208311682, 5205.115130427836, 5273.709945952971, 5244.475552444763, 5259.789608415659, 5263.0526505229045, 5276.944611077781, 5287.180487024518, 5178.326817513718, 5207.921881171786, 5239.240310155044, 5241.161414173733, 5330.227117068022, 5270.472952704757, 5286.973565132188, 5309.681419114827, 5325.094733895247, 5363.678179309254, 5098.745062746848, 5125.84622461323, 5129.281900533928, 5222.16445368743, 5226.320578324793, 5405.972970135163, 5408.215808707715, 5433.022056029913, 5439.972800135974, 5555.749991250404, 6143.631382117042, 6168.0, 6169.722362493653, 6178.382161783786, 6183.81448556544, 6062.848428789315, 6097.037783475835, 6173.753049878, 6210.441060304594, 6211.409916058013, 5776.0, 5799.33307669615, 5800.245968024215, 5815.941840581582, 5851.297844258674, 6211.9068213976825, 6219.222597175353, 6230.7819226326865, 6232.532560058014, 6254.280757712831, 5940.8811823764145, 6018.849528761816, 6032.185654936615, 6118.724657390429, 6120.908186377208, 6172.938270617236, 6181.0, 6188.845278867974, 6202.503799696014, 6215.285242197115, 6106.145139680449, 6150.661713605705, 6159.907601385983, 6165.8458538536, 6167.229096362955, 6047.9395206047375, 6102.004547214544, 6137.723802428902, 6182.876342473215, 6199.039148931833, 4852.2479015752615, 4876.21980483121, 4889.071076495455, 4889.413270407574, 4895.853124406273, 4348.391225228471, 4438.60052595268, 4438.933415998763, 4440.933385999213, 4475.104979004147, 5646.2942132233475, 5651.463111004448, 5664.433556644385, 5686.886262274731, 5696.11710184922, 6108.908366374508, 6183.505319574423, 6184.81445556634, 6195.2255968004, 6198.504119670415, 5474.753636086386, 5606.243157173811, 5629.774809007594, 5639.718014099279, 5640.32316122068, 5296.09966305729, 5339.626226164131, 5347.1979203119845, 5382.596305277119, 5400.297961265011, 6252.781152659635, 6278.372162783777, 6307.085472606538, 6315.936840631627, 6318.905216421849, 6087.9391206087375, 6162.691865406801, 6176.011838105882, 6180.81457556274, 6191.5665903388135, 6206.100115483139, 6294.590851594632, 6301.8109456716375, 6305.274893387319, 6315.621062736251, 6122.234720659918, 6134.04922237054, 6139.877202456015, 6141.7543298267565, 6156.722947467464, 6066.908996365147, 6142.253225532511, 6192.318844927145, 6205.007198848162, 6208.534359923115, 5934.139549765347, 6007.789727359522, 6040.033594624839, 6055.757769689163, 6091.9086213707715, 5974.61165024272, 6032.215811944419, 6032.517398608013, 6049.727762250621, 6061.727222275096, 4992.201247800334, 5762.855928601818, 5890.52875769946, 5940.049592065248, 5945.197398351167, 6025.337212906489, 6078.787242446493, 6080.270367555921, 6098.237720284965, 6141.877162456636, 4848.515148485123, 5898.8230353089475, 5907.40925907423, 5908.291005079418, 5941.168236446814], "value": 5658.104978054876, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.37951750071164, 499.24613833111994, 513.8766695992963, 526.0819869328024, 538.4238864415075, 29764.702352976463, 30242.395152096946, 30295.0, 36921.815390923075, 38418.039549011286, 34241.65758342416, 41022.58977410225, 41624.0, 41686.58313416865, 41893.0, 9398.906010939905, 9627.566759495805, 9629.711108666752, 9630.085141911508, 9635.084666956664, 8434.578271086451, 8748.737537873842, 9341.53292335384, 11361.261518001304, 11766.882331176665, 11335.659930202108, 11456.599019034335, 11480.655580332561, 11512.769744605102, 11517.42412879357, 8439.95780021096, 11244.775104497905, 11413.657590272305, 11416.942915285412, 11426.37154956477, 11130.0, 11565.653030409101, 11575.189736718434, 11585.710357241052, 11588.30470171784, 32256.677433225603, 38036.23927521434, 38575.807120964215, 38599.8070009651, 38640.0, 3312.7018568328726, 3317.20387107095, 3420.247545539977, 3554.040409089557, 3703.981405113588, 27323.223990440758, 28238.0, 29041.273968150756, 29182.0, 29796.0, 2730.071775596287, 2773.0, 2788.9860550697117, 2813.6342275504257, 2849.202223377458, 464969.0, 467778.6611066957, 468522.0, 469659.0, 470185.0, 61572.07641885375, 62789.74420511564, 62807.0, 62911.370886291465, 63164.68417657882, 65795.34204657988, 66206.0, 66277.66861165663, 66327.66836165788, 66636.33363666301, 16352.34590616362, 16807.579810504834, 16828.74756878648, 16829.747553786703, 16912.154392280336, 11730.296182229093, 12027.338496382608, 13026.54407095747, 13101.148425352321, 13130.671733206555, 12694.047946404056, 14471.78292325616, 14959.177245251616, 15027.699446011018, 16610.418635347705, 6903.0, 7107.289271072851, 7127.857442851114, 7145.892386680073, 7157.57054576727, 836.5691668790578, 999.1906555689769, 1002.4536627538009, 1031.0771859186136, 1034.622362837559, 28100.0, 28401.57397639037, 28706.569401458994, 28804.42391152207, 28957.56563651547, 1151.4530597966066, 1154.3131836557236, 1167.9299242045336, 1176.688177632928, 1193.6061099837034, 12503.437345319482, 14880.70238595222, 15060.774088388684, 15516.224188790515, 15533.533993979972, 525573.0, 819741.0, 839389.0, 843782.5621743704, 844763.0, 47631.0, 58721.119183212286, 58737.706311469025, 58783.412165878646, 58815.82368352695, 1352.7892536180063, 1468.2218424235173, 1488.5980785188156, 1507.7286088503736, 1511.516314779282, 27932.581011285252, 28492.715072849005, 28995.7100428993, 29155.7084429153, 29320.706792931796, 50203.0, 50814.49185508245, 50852.745736271085, 50888.745556271984, 50985.23522147245, 407953.0, 409673.0, 410852.0, 411094.0, 411641.0, 24927.875360623806, 26169.869150654125, 26223.21330360092, 26232.950681972507, 26233.606495902193, 32331.515027274138, 40051.79974100111, 45688.0, 47518.287225690954, 47678.76160619313, 35606.28787424289, 35960.0, 36093.81953090323, 36740.26519469542, 36968.63031369652, 35449.645503544634, 35538.24518007945, 35586.28827423489, 36457.453138202414, 36520.45219321766, 35344.0, 35386.823065884506, 36186.276274473836, 36366.63633363738, 36380.0, 30635.846820765753, 31839.36321273608, 31857.522137167496, 31898.84050579732, 32012.5198122033, 21814.563708726055, 21819.23632672849, 22223.888805559982, 23698.881505592362, 23942.401439964142], "value": 19515.170542373504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.8936487148471, 398.2751392465712, 400.86971734186386, 403.11918458168884, 408.3364532634469, 13327.733865282782, 19160.520986975334, 19941.3020544281, 19977.800221997775, 20212.989350532487, 16002.439914602963, 16221.83778162218, 17708.645827083452, 19149.61700765984, 20015.999200040013, 4962.883351245977, 5792.7972520961775, 5803.506701930331, 6027.09593560965, 6029.60807547508, 5712.257406537145, 6010.12853136295, 6049.637021778685, 6050.183225264599, 6075.149479072933, 5957.106434034908, 5990.550708696844, 6000.3699611540815, 6006.489448396892, 6010.699465026753, 5509.0, 5892.234009578749, 5915.645061296293, 5933.495652869511, 6029.974904266263, 5469.808556700496, 5595.804146854881, 5667.716614169275, 5677.716114194315, 5959.761609535613, 12706.237625742486, 12877.549285774952, 13121.47514099435, 13138.408771605205, 13155.342232888317, 1953.5116220944765, 2091.372588223542, 2093.696414019974, 2166.155199472207, 2222.20000799712, 8438.957805210996, 9379.062093790635, 9766.706998790047, 9927.0, 9988.0, 1738.8956662600285, 1798.9370372036915, 1806.3948577226645, 1812.1664034543994, 1881.595456976743, 115258.84741152565, 118172.81827181704, 120146.39926800398, 120988.0, 122826.0, 11345.0, 11368.431578421047, 11511.366874821964, 11543.422828858524, 11669.299842009506, 14896.62758431048, 14962.251887405586, 14967.476138335323, 14998.0, 14998.850011499964, 7331.401738435344, 7377.5204611700065, 8414.158584141536, 8515.659373624985, 8516.06323304436, 8259.091499935003, 8282.0, 8375.329973602098, 8487.575621218914, 8519.957400213083, 3574.7140228781636, 4358.8910277242685, 4522.5025247222775, 4541.613962813131, 4583.220852455091, 1916.8562357823218, 1962.607478504305, 1979.5842872996543, 1981.2570286142698, 1982.930597429112, 905.3391024551983, 910.913463220993, 959.4339339789558, 978.9755256118654, 1014.3558840136567, 18524.536886577673, 19636.607267854564, 19702.507437313896, 19806.70289945652, 19882.105305261008, 944.15026476172, 968.1915600473683, 1004.708634495988, 1008.0, 1074.119222237773, 10040.849387259197, 10195.745106372251, 10215.438150901624, 10230.335028223291, 10392.792144157074, 589149.0, 594917.0, 606748.0, 620024.0, 627684.0, 119833.40083299528, 126456.0, 127203.0, 127547.0, 128037.07944380841, 1010.540204207084, 1027.0, 1031.8606988056667, 1041.671873359889, 1049.485751981539, 14225.857741422453, 17117.972921624743, 17319.480415587554, 17505.374193871023, 17563.1218439075, 36257.81871090628, 39126.0, 43481.0, 48841.0, 48872.75563622159, 68937.31062689309, 69720.0, 70662.0, 71250.28749712436, 72740.63629681818, 7920.960395197987, 7957.0, 8142.877856832034, 8377.0, 10690.305130166515, 9622.997078501527, 11930.164888457724, 12664.050196235503, 12941.870581294443, 13110.934445327712, 10888.67333979982, 11179.832302515631, 11229.270097443967, 11462.770744584896, 11687.298762074302, 8603.741887743377, 11127.666170014912, 11249.831252531383, 11505.712357191138, 11681.88318116808, 11405.486753095965, 11438.82841757391, 11443.828342575034, 11624.709382265512, 11961.700957476132, 10891.618793342195, 11528.213126965335, 11817.881821182022, 11867.991220745987, 12074.154809163274, 11094.833577496183, 11387.829182562102, 11522.308661480338, 11588.188826782382, 11653.825192621947], "value": 9306.831699504954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.957551273462, 1587.0953556472807, 1608.7586861970703, 1818.9363372281973, 1927.8721947660608, 7858.803529911748, 8528.360372972022, 8784.91215087849, 8796.604152813123, 8808.691695790641, 8417.663293468255, 8619.784505387368, 8648.0487146414, 8658.09090045544, 8661.523616201106, 2437.9512409751796, 2468.7654672806057, 2477.430191056058, 2477.938051548708, 2478.0707234786955, 2369.206315884181, 2406.506666133447, 2471.9876400617973, 2472.1223965492172, 2473.7402572729875, 2260.773922607734, 2384.7734465225776, 2422.1401402502124, 2460.815438842094, 2465.0632759551345, 2410.0841680161425, 2433.6471211674298, 2434.9391265218424, 2435.622478515833, 2450.5834008218644, 2397.3167647220635, 2409.590369637166, 2411.565918134735, 2420.8426452280546, 2423.09134074722, 8469.449485783465, 8612.267957223581, 8639.95680021596, 8658.350623703185, 8673.913260867374, 5221.895562088775, 5333.466653334674, 5370.597205209586, 5396.325459317585, 5416.83749487516, 3196.8081915085168, 3319.286353434023, 3320.5351250824906, 3542.468629705539, 3647.288778688157, 1035.223582313265, 1048.0043958239703, 1050.2648146297622, 1058.5025038232018, 1064.675274041417, 11101.944490277578, 11194.888051119466, 11221.214514983954, 11223.494942727515, 11226.101911847114, 4408.515063343029, 4480.955190448054, 4636.675432719745, 4643.3963584733765, 4695.600873925686, 9003.954980225057, 9005.0, 9020.368574199743, 9023.368364214573, 9045.276377889753, 5204.4014938281825, 5226.163813789775, 5235.188545775411, 5252.317198764213, 5254.579633629301, 3726.5900750917376, 4509.391232183679, 4727.361806156194, 4849.199882019428, 4890.486498917634, 3701.204241088179, 3739.139997800521, 3817.751846129993, 3819.52255968004, 3863.671587915059, 1206.3847437806783, 1209.6673414810998, 1211.3035004872233, 1221.0, 1221.688469440287, 643.4819969924163, 782.1201148707704, 854.4702284583598, 906.981860362789, 941.3128416256035, 4842.201036828884, 4870.9269360959615, 4889.9486610378935, 5477.75350109246, 5726.112452569859, 824.4352618456377, 917.2661870503704, 919.5632074764434, 938.4932136646271, 996.0487734213808, 3183.5065564837487, 3647.2340808429994, 3682.1715114099115, 3752.1557649528636, 3787.5265591801026, 10149.441780701985, 10576.101031412261, 10680.679579612624, 10682.03861652455, 10692.572297108185, 10675.786484270271, 10717.928207179375, 10720.035196832323, 10731.89268107309, 10786.892131078746, 7948.721794737156, 7954.363650907913, 8147.959260203661, 8214.0, 8243.917560824555, 5340.118880384693, 5411.134218525017, 5514.91727624078, 5532.253145825262, 5670.432956704297, 9671.564779584794, 9818.410895346302, 9922.454265015493, 10005.04952029556, 10028.448435336126, 9675.854862176931, 9973.850392243978, 10009.899901000897, 10031.197504199792, 10194.949025254826, 6066.211392518944, 6873.0, 6937.340952609494, 7004.774164521086, 7023.894641580483, 7984.441089123706, 8097.87853182191, 8114.945057142534, 8120.9848768903885, 8157.184281572033, 6015.308239552332, 6209.285932117684, 7159.713611455483, 7255.56466612005, 7433.776986690408, 7058.223595404399, 7198.388137008204, 7211.783646490614, 7256.96371518139, 7257.564546127249, 6690.69891854858, 6767.89848152288, 6783.287754785676, 6792.252852186157, 6797.966010169917, 4504.887377815581, 4602.884927876696, 4637.721736695809, 4688.507706690748, 4811.687240329367, 3818.4845045918446, 4739.028499157595, 4816.590589799906, 4820.325154478306, 4842.346283251716], "value": 4460.489276892839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.85855294393997, 320.15319479975466, 327.7034283973004, 329.9884504042358, 336.8804074553532, 5823.534117270613, 5864.677442740647, 5866.970665146669, 5874.676892770896, 5884.323302820177, 4508.4589849218055, 5516.365617953937, 5817.621854579449, 5829.446202610756, 5864.912026319598, 3369.2082360645227, 3836.1176929306275, 4025.134596061846, 4329.415528903605, 4370.125974805052, 3863.4011728182177, 4201.873943781691, 4406.69153159279, 4420.77896105195, 4448.866534003969, 3760.7743535387963, 4358.673099517533, 4406.4271644686305, 4407.647388208936, 4434.312681534351, 4193.979030104861, 4243.618074373321, 4443.577860103301, 4465.129299786543, 4481.865544033668, 3828.674562662177, 3833.9424908626115, 3838.140585263968, 3851.0564911596557, 3854.402567602026, 4888.364512613373, 5530.917036244459, 5581.5255703265275, 5596.0, 5638.520725738276, 4787.54518320759, 5046.596272298208, 5052.0906236877345, 5095.031943930641, 5117.257997590328, 5809.2447981762525, 6039.728212230416, 6064.665773529817, 6093.969530152365, 6105.72524236406, 3604.1350075981836, 3614.313280476701, 4253.48958125027, 4258.297380932143, 4265.253580623409, 5180.07571110079, 5342.652727572696, 6461.967690161519, 6475.449586785082, 6520.576162549467, 4293.957060429356, 4314.1371725655, 4323.005708687019, 4338.154059958278, 4338.305871060616, 4217.249841315819, 4362.585554372329, 4388.407564978687, 4396.956030439718, 4401.185780630593, 4494.797734101973, 4818.7831547580445, 4897.877553061202, 5057.080762409162, 5082.161443361804, 5376.946230537723, 6127.479164271087, 6131.601445906002, 6140.293866205354, 6148.1085242639565, 1407.8240219972504, 1495.1178804505394, 1517.7251109068216, 1524.2531159731652, 1525.3669727063366, 970.9368891022061, 988.2736188901152, 1019.2508506247901, 1020.6937918624383, 1029.5212726082407, 5278.683279003272, 5660.490555849911, 5682.801101961411, 5699.974004679198, 5777.364489906107, 6322.683865806691, 6333.81967901183, 6354.777582784581, 6359.109724638554, 6389.808305750835, 5425.348958125051, 5674.829755107354, 5677.0, 5684.30520161898, 5699.202111704365, 2682.771964383049, 3134.4358015556963, 3171.888983885553, 3274.459714147187, 3281.8523166457567, 5487.368952570426, 5895.29256489224, 6496.4594296108735, 6684.699188536528, 6704.4301234395625, 6208.844778880564, 6427.485801135898, 6566.0, 6567.310432404624, 6570.77002304917, 5428.75570599324, 5924.652571297203, 6742.258351581322, 6757.391834734897, 6766.15423072116, 5920.319163296103, 6164.352742961924, 6390.073439351361, 6426.0, 6434.195725534309, 5685.403032681509, 6206.882761103045, 6291.842703932255, 6300.180976473029, 6330.873382532415, 5853.765849365977, 6438.227412710505, 6691.698873550799, 6704.865902682017, 6769.661516924232, 3984.2629113614075, 4063.6342729154517, 4174.436451079066, 4192.727472714264, 4216.641585465147, 5738.938296415176, 6068.51451883857, 6375.330590288138, 6391.137196478416, 6395.968020159869, 5850.356460789395, 6190.566660333733, 6199.938000619936, 6201.3178550358525, 6212.161358216583, 4975.577075948585, 5210.8957820844125, 5255.658382205144, 6122.479589234965, 6156.321195891244, 5146.588272938231, 5626.324841019104, 5999.340072591924, 6066.939330606638, 6146.508279337731, 5852.488506895998, 6168.19813424252, 6186.969065154825, 6220.595661282002, 6226.657533835501, 6034.909476357946, 6035.336113027652, 6038.607490513103, 6058.636481810929, 6060.272767267956], "value": 4494.603432357511, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.579013116802, 755.1449848523287, 760.1448370583093, 762.0017776712507, 772.0658003815383, 12793.232406055631, 15193.468228612, 16100.0, 16100.83899161008, 16108.436204732836, 14650.853491465083, 14710.485133020346, 14722.969392142555, 14743.336549855256, 14770.335334909929, 933.4865823796919, 936.6440752514069, 944.551338114397, 947.8909925358569, 950.95720692569, 728.2535401213745, 751.7481643649384, 889.1419779912384, 915.1443400420595, 920.2499962530517, 981.1856159387727, 987.501311837521, 991.7619771254919, 993.1657407777466, 994.2692121290845, 958.4967891856761, 984.990150098497, 985.3201291109111, 987.0573602209872, 987.2940847294183, 560.4983539731958, 583.9299284085895, 585.9472647461706, 586.0769288370833, 743.4461326311878, 14772.261386930719, 15258.237088145548, 15264.084154950628, 15278.847211527853, 15282.694346113127, 12382.31897245651, 12646.683832904251, 12767.42546585406, 12855.807162892565, 13009.0243231758, 9686.78915135608, 9717.46553939533, 9737.2697047721, 9902.80194396108, 10758.139348852073, 3560.0565850049757, 3626.9274614507826, 3641.599424063351, 3673.0266479382985, 3673.687736542397, 78042.0, 84173.0, 85363.5731821343, 85483.14516854814, 86521.13478865194, 8713.607887645072, 9302.53487325631, 9349.766255843659, 9569.138777510057, 9691.0, 19642.0, 20138.69791953122, 20161.798382016284, 20792.27227047046, 20866.47833804167, 7535.773926782205, 7549.339145388006, 7715.382769378549, 7740.574268415178, 7929.405294602928, 3439.7420193485586, 4009.538903026131, 4102.405151253111, 4133.0700592367075, 4160.292750232467, 3434.244466217428, 3552.8223588820456, 3556.270964452281, 3560.572731272212, 3563.7327200460068, 622.5610944284268, 626.4330780643434, 626.6991843914947, 637.4645297949652, 641.9984823675081, 507.6243579750958, 529.2775361631423, 566.8469513231404, 600.1477901380051, 612.1246617337162, 7561.584112873845, 7595.3164215220895, 7647.655855486517, 7751.147373788879, 8107.635156417975, 567.4495739133034, 588.3939542271468, 589.4370875813588, 593.94951429129, 598.1207624791549, 3578.4095624221713, 3675.9081022974106, 3703.962960370362, 3721.404575267944, 3730.421784623388, 86427.56786216155, 87133.56433217794, 90377.0, 91432.6285105724, 91692.1661566765, 47556.76221618939, 54786.72606636941, 57648.8470230593, 65459.672701636184, 70317.0, 56036.159457608985, 59298.70350648219, 60659.0, 61000.084998725055, 62019.689901550206, 7755.689772409266, 7875.684972601031, 8014.679412823655, 8065.758027259191, 8114.229148230908, 34497.48253776246, 38257.6609818655, 38520.0, 38620.6137938617, 39904.60095399125, 52184.0, 54284.91430171295, 56450.02424915108, 56673.71663141658, 57310.0, 10656.520456579317, 10769.569217231223, 11014.659727463864, 11399.829002564802, 11850.525978960744, 10900.433805987632, 11948.940255298668, 12139.150259481752, 12379.319137447166, 12512.937435312766, 11089.944550277198, 11124.555017799521, 11373.431328433711, 11585.0, 11708.70728231801, 11415.942920285346, 11420.143489238175, 11505.252158609663, 11657.591984280509, 11711.121665874924, 10812.513436895515, 10979.890201098206, 11073.280236784583, 11225.71935701614, 11382.146339024772, 9429.952850235979, 9648.903510964801, 9724.513774311397, 9764.511774411108, 9788.02119788044, 7999.400044996532, 8707.564621769014, 9391.248700104112, 9456.905430945602, 9471.337006409485], "value": 6686.080945373801, "unit": "score"}], "name": "dromaeo_css", "value": 7186.577670118691, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 112.0, 112.0, 113.0, 118.0, 113.0, 102.0, 108.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 150.0, 173.0, 145.0, 170.0, 142.0, 121.0, 173.0, 119.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 188.0, 185.0, 184.0, 181.0, 186.0, 201.0, 184.0, 186.0], "value": 186.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 85.0, 92.0, 57.0, 58.0, 57.0, 79.0, 93.0, 77.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 103.0, 135.0, 77.0, 78.0, 79.0, 126.0, 112.0, 109.0, 150.0], "value": 105.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 83.0, 88.0, 84.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 95.0, 94.0, 101.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 84.0, 87.0, 86.0, 84.0, 84.0, 86.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 57.0, 57.0, 57.0, 49.0, 70.0, 88.0, 84.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 74.0, 74.0, 69.0, 72.0, 92.0, 91.0, 94.0, 69.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 82.0, 81.0, 80.0, 81.0, 81.0, 80.0, 82.0, 82.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 147.0, 147.0, 146.0, 151.0, 132.0, 138.0, 176.0, 140.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 168.0, 166.0, 169.0, 163.0, 167.0, 171.0, 167.0, 168.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 67.0, 71.0, 66.0, 81.0, 94.0, 92.0, 64.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1498.6000000000001, "alertThreshold": 2.0}]} 05:37:02 INFO - Return code: 0 05:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:37:02 ERROR - # TBPL SUCCESS # 05:37:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:02 INFO - 2016-04-17 05:37:02.685 screenresolution[1684:10801] starting screenresolution argv=screenresolution get 05:37:02 INFO - 2016-04-17 05:37:02.687 screenresolution[1684:10801] Display 0: 1600x1200x32@60 05:37:02 INFO - 2016-04-17 05:37:02.694 screenresolution[1685:10804] starting screenresolution argv=screenresolution list 05:37:02 INFO - Available Modes on Display 0 05:37:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:02 INFO - Intel Iris: 05:37:02 INFO - Chipset Model: Intel Iris 05:37:02 INFO - Type: GPU 05:37:02 INFO - Bus: Built-In 05:37:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:02 INFO - Vendor: Intel (0x8086) 05:37:02 INFO - Device ID: 0x0a2e 05:37:02 INFO - Revision ID: 0x0009 05:37:02 INFO - Displays: 05:37:02 INFO - Display: 05:37:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:02 INFO - Display Serial Number: 1600x1200 60 05:37:02 INFO - Main Display: Yes 05:37:02 INFO - Mirror: Off 05:37:02 INFO - Online: Yes 05:37:02 INFO - Rotation: Supported 05:37:02 INFO - Return code: 0 05:37:02 INFO - Running post-action listener: _resource_record_post_action 05:37:02 INFO - Running post-run listener: _resource_record_post_run 05:37:03 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32677376; Write bytes: 695474688; Read time: 333; Write time: 1968 05:37:03 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375357952; Write bytes: 369133568; Read time: 28044; Write time: 715 05:37:03 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 31755776; Write bytes: 299102720; Read time: 319; Write time: 1200 05:37:03 INFO - Running post-run listener: _upload_blobber_files 05:37:03 INFO - Blob upload gear active. 05:37:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:37:03 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'] 05:37:03 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 05:37:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:05 INFO - (blobuploader) - INFO - Done attempting. 05:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:06 INFO - (blobuploader) - INFO - Done attempting. 05:37:06 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:06 INFO - Return code: 0 05:37:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8120f44fe715f61d4e942b7f075cc7b30d3c685f74a04d3c92e41c05cdcc256cd2c540d09c6b89e598c6c05e44580d4bf9f98c5300edbe3de45e6e9c534318d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73479380e6729398fcec6ea199088ff8ac24c3260d24ecfc0a56ff2c146f9c95630a30caa69595ec35b7b302d3c4eee97015398605c0212ed9fce74eafdf59e3"} 05:37:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:06 INFO - Contents: 05:37:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8120f44fe715f61d4e942b7f075cc7b30d3c685f74a04d3c92e41c05cdcc256cd2c540d09c6b89e598c6c05e44580d4bf9f98c5300edbe3de45e6e9c534318d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73479380e6729398fcec6ea199088ff8ac24c3260d24ecfc0a56ff2c146f9c95630a30caa69595ec35b7b302d3c4eee97015398605c0212ed9fce74eafdf59e3"} 05:37:06 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:06 INFO - Copying logs to upload dir... 05:37:06 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1361.729897 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 41 secs) (at 2016-04-17 05:37:06.269317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 05:37:06.273095) ========= 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.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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/8120f44fe715f61d4e942b7f075cc7b30d3c685f74a04d3c92e41c05cdcc256cd2c540d09c6b89e598c6c05e44580d4bf9f98c5300edbe3de45e6e9c534318d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73479380e6729398fcec6ea199088ff8ac24c3260d24ecfc0a56ff2c146f9c95630a30caa69595ec35b7b302d3c4eee97015398605c0212ed9fce74eafdf59e3"} build_url:https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/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/8120f44fe715f61d4e942b7f075cc7b30d3c685f74a04d3c92e41c05cdcc256cd2c540d09c6b89e598c6c05e44580d4bf9f98c5300edbe3de45e6e9c534318d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73479380e6729398fcec6ea199088ff8ac24c3260d24ecfc0a56ff2c146f9c95630a30caa69595ec35b7b302d3c4eee97015398605c0212ed9fce74eafdf59e3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 05:37:06.301004) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:37:06.301338) ========= 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.LapkpBx7uG/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.f0WXPLaBbV/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005275 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:37:06.349918) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 05:37:06.350263) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 05:37:06.353701) ========= ========= Total master_lag: 0.45 =========