builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1468190161.25 results: success (0) buildid: 20160710132925 builduid: 3736ed9763b34a63a290d9069e82464e revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.246500) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.246929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.247298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.283184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.283502) ========= 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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-07-10 15:36:01-- 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% 18.1M=0.001s 2016-07-10 15:36:01 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.500889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.806869) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.807237) ========= 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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.025648 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.856465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 15:36:01.856885) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-07-10 15:36:01,938 truncating revision to first 12 chars 2016-07-10 15:36:01,938 Setting DEBUG logging. 2016-07-10 15:36:01,939 attempt 1/10 2016-07-10 15:36:01,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ebd3919e8a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 15:36:03,015 unpacking tar archive at: mozilla-beta-ebd3919e8a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345473 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 15:36:03.225022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:03.225408) ========= script_repo_revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:03.225867) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:03.226198) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:03.245287) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2016-07-10 15:36:03.245884) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:36:03 INFO - MultiFileLogger online at 20160710 15:36:03 in /builds/slave/test 15:36:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:36:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:36:03 INFO - {'append_to_log': False, 15:36:03 INFO - 'base_work_dir': '/builds/slave/test', 15:36:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:36:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:36:03 INFO - 'branch': 'Mozilla-Beta', 15:36:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:36:03 INFO - 'config_files': ('talos/mac_config.py',), 15:36:03 INFO - 'default_actions': ('clobber', 15:36:03 INFO - 'read-buildbot-config', 15:36:03 INFO - 'download-and-extract', 15:36:03 INFO - 'populate-webroot', 15:36:03 INFO - 'create-virtualenv', 15:36:03 INFO - 'install', 15:36:03 INFO - 'run-tests'), 15:36:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:36:03 INFO - 'download_minidump_stackwalk': True, 15:36:03 INFO - 'download_symbols': 'ondemand', 15:36:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:36:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:36:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:36:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:36:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:36:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:36:03 INFO - 'installer_path': 'installer.exe', 15:36:03 INFO - 'log_level': 'info', 15:36:03 INFO - 'log_name': 'talos', 15:36:03 INFO - 'log_to_console': True, 15:36:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:36:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:36:03 INFO - 'opt_config_files': (), 15:36:03 INFO - 'pip_index': False, 15:36:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:36:03 INFO - 'cmd': ('bash', 15:36:03 INFO - '-c', 15:36:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:36:03 INFO - 'enabled': True, 15:36:03 INFO - 'halt_on_failure': False, 15:36:03 INFO - 'name': 'check_screen_resolution'},), 15:36:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:36:03 INFO - 'cmd': ('bash', 15:36:03 INFO - '-c', 15:36:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:36:03 INFO - 'enabled': True, 15:36:03 INFO - 'halt_on_failure': False, 15:36:03 INFO - 'name': 'check_screen_resolution'},), 15:36:03 INFO - 'run_cmd_checks_enabled': True, 15:36:03 INFO - 'sps_profile': False, 15:36:03 INFO - 'sps_profile_interval': 0, 15:36:03 INFO - 'suite': 'dromaeojs', 15:36:03 INFO - 'system_bits': '32', 15:36:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:36:03 INFO - 'title': 't-yosemite-r7-0364', 15:36:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:36:03 INFO - 'use_talos_json': True, 15:36:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:36:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:36:03 INFO - 'work_dir': 'build'} 15:36:03 INFO - ##### 15:36:03 INFO - ##### Running clobber step. 15:36:03 INFO - ##### 15:36:03 INFO - Running pre-action listener: _resource_record_pre_action 15:36:03 INFO - Running main action method: clobber 15:36:03 INFO - rmtree: /builds/slave/test/build 15:36:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:36:05 INFO - Running post-action listener: _resource_record_post_action 15:36:05 INFO - ##### 15:36:05 INFO - ##### Running read-buildbot-config step. 15:36:05 INFO - ##### 15:36:05 INFO - Running pre-action listener: _resource_record_pre_action 15:36:05 INFO - Running main action method: read_buildbot_config 15:36:05 INFO - Using buildbot properties: 15:36:05 INFO - { 15:36:05 INFO - "project": "", 15:36:05 INFO - "product": "firefox", 15:36:05 INFO - "script_repo_revision": "production", 15:36:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 15:36:05 INFO - "repository": "", 15:36:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 15:36:05 INFO - "buildid": "20160710132925", 15:36:05 INFO - "pgo_build": "False", 15:36:05 INFO - "basedir": "/builds/slave/test", 15:36:05 INFO - "buildnumber": 7, 15:36:05 INFO - "slavename": "t-yosemite-r7-0364", 15:36:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:36:05 INFO - "platform": "yosemite_r7", 15:36:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:36:05 INFO - "branch": "mozilla-beta", 15:36:05 INFO - "repo_path": "releases/mozilla-beta", 15:36:05 INFO - "revision": "ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e", 15:36:05 INFO - "stage_platform": "macosx64", 15:36:05 INFO - "builduid": "3736ed9763b34a63a290d9069e82464e", 15:36:05 INFO - "slavebuilddir": "test" 15:36:05 INFO - } 15:36:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 15:36:05 INFO - Running post-action listener: _resource_record_post_action 15:36:05 INFO - ##### 15:36:05 INFO - ##### Running download-and-extract step. 15:36:05 INFO - ##### 15:36:05 INFO - Running pre-action listener: _resource_record_pre_action 15:36:05 INFO - Running main action method: download_and_extract 15:36:05 INFO - mkdir: /builds/slave/test/build/tests 15:36: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')]} 15:36:05 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:36:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 15:36:35 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json: timed out 15:36:35 INFO - retry: attempt #1 caught exception: timed out 15:36:35 INFO - retry: Failed, sleeping 30 seconds before retrying 15:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #2 15:37:10 INFO - Downloaded 1165 bytes. 15:37:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:37:10 INFO - Using the following test package requirements: 15:37:10 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:37:10 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 15:37:10 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 15:37:10 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:37:10 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 15:37:10 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:37:10 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 15:37:10 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 15:37:10 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 15:37:10 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:37:10 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 15:37:10 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 15:37: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')]} 15:37:10 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:37:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:37:11 INFO - Downloaded 18351523 bytes. 15:37:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:37:14 INFO - Return code: 0 15:37:14 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:14 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:37:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:37:15 INFO - Downloaded 18351523 bytes. 15:37:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:37:19 INFO - Return code: 0 15:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:19 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:37:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 15:37:20 INFO - Downloaded 10840145 bytes. 15:37:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:37:20 INFO - Return code: 0 15:37:20 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')]} 15:37:20 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:37:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 15:37:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 15:37:23 INFO - Downloaded 86188281 bytes. 15:37:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:23 INFO - mkdir: /builds/slave/test/properties 15:37:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:23 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:23 INFO - Contents: 15:37:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:24 INFO - Running post-action listener: _resource_record_post_action 15:37:24 INFO - Running post-action listener: set_extra_try_arguments 15:37:24 INFO - ##### 15:37:24 INFO - ##### Running populate-webroot step. 15:37:24 INFO - ##### 15:37:24 INFO - Running pre-action listener: _resource_record_pre_action 15:37:24 INFO - Running main action method: populate_webroot 15:37:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:37:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:37:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:37:24 INFO - '--tppagecycles', 15:37:24 INFO - '7'], 15:37:24 INFO - 'tests': ['tsvgm']}}, 15:37:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:37:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:37:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['damp', 'tps']}, 15:37:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'tests': ['damp', 'tps']}, 15:37:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['dromaeo_dom']}, 15:37:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['a11yr', 15:37:24 INFO - 'ts_paint', 15:37:24 INFO - 'tpaint', 15:37:24 INFO - 'sessionrestore', 15:37:24 INFO - 'sessionrestore_no_auto_restore', 15:37:24 INFO - 'tabpaint']}, 15:37:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['tsvgx', 15:37:24 INFO - 'tsvgr_opacity', 15:37:24 INFO - 'tart', 15:37:24 INFO - 'tscrollx', 15:37:24 INFO - 'cart']}, 15:37:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:24 INFO - 'tsvgr_opacity', 15:37:24 INFO - 'tart', 15:37:24 INFO - 'tscrollx', 15:37:24 INFO - 'cart']}, 15:37:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'talos_options': ['--disable-e10s'], 15:37:24 INFO - 'tests': ['tp5o']}, 15:37:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'tests': ['tp5o']}, 15:37:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'talos_options': ['--disable-e10s', 15:37:24 INFO - '--xperf_path', 15:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:24 INFO - 'tests': ['tp5n']}, 15:37:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:24 INFO - 'talos_options': ['--xperf_path', 15:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:24 INFO - 'tests': ['tp5n']}}, 15:37:24 INFO - 'talos.zip': {'path': '', 15:37:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:37:24 INFO - Running post-action listener: _resource_record_post_action 15:37:24 INFO - ##### 15:37:24 INFO - ##### Running create-virtualenv step. 15:37:24 INFO - ##### 15:37:24 INFO - Running pre-action listener: _resource_record_pre_action 15:37:24 INFO - Running main action method: create_virtualenv 15:37:24 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37:24 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 15:37:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:37:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:24 INFO - Using real prefix '/tools/python27' 15:37:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:25 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:28 INFO - Installing pip.................done. 15:37:28 INFO - Return code: 0 15:37:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:28 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:28 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 15:37:28 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 15:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:28 INFO - 'HOME': '/Users/cltbld', 15:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:28 INFO - 'LOGNAME': 'cltbld', 15:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:28 INFO - 'MOZ_NO_REMOTE': '1', 15:37:28 INFO - 'NO_EM_RESTART': '1', 15:37:28 INFO - 'PAGER': '/bin/cat', 15:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:28 INFO - 'PWD': '/builds/slave/test', 15:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:28 INFO - 'SHELL': '/bin/bash', 15:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:28 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:28 INFO - 'USER': 'cltbld', 15:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:28 INFO - 'XPC_FLAGS': '0x0', 15:37:28 INFO - 'XPC_SERVICE_NAME': '0', 15:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:28 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 15:37:28 INFO - Cleaning up... 15:37:28 INFO - Return code: 0 15:37:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:28 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:28 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 15:37:28 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 15:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:28 INFO - 'HOME': '/Users/cltbld', 15:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:28 INFO - 'LOGNAME': 'cltbld', 15:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:28 INFO - 'MOZ_NO_REMOTE': '1', 15:37:28 INFO - 'NO_EM_RESTART': '1', 15:37:28 INFO - 'PAGER': '/bin/cat', 15:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:28 INFO - 'PWD': '/builds/slave/test', 15:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:28 INFO - 'SHELL': '/bin/bash', 15:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:28 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:28 INFO - 'USER': 'cltbld', 15:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:28 INFO - 'XPC_FLAGS': '0x0', 15:37:28 INFO - 'XPC_SERVICE_NAME': '0', 15:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:28 INFO - Downloading/unpacking psutil>=0.7.1 15:37:28 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 15:37:28 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 15:37:28 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 15:37:28 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 15:37:28 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 15:37:28 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 15:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:31 INFO - Installing collected packages: psutil 15:37:31 INFO - Running setup.py install for psutil 15:37:31 INFO - building 'psutil._psutil_osx' extension 15:37:31 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 15:37:32 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 15:37:32 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 15:37:32 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 15:37:32 INFO - building 'psutil._psutil_posix' extension 15:37:32 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 15:37:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:37:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:37:32 INFO - ^ 15:37:32 INFO - 1 warning generated. 15:37:32 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 15:37:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:32 INFO - Successfully installed psutil 15:37:32 INFO - Cleaning up... 15:37:32 INFO - Return code: 0 15:37:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:32 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:32 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 15:37:32 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 15:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:32 INFO - 'HOME': '/Users/cltbld', 15:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:32 INFO - 'LOGNAME': 'cltbld', 15:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:32 INFO - 'MOZ_NO_REMOTE': '1', 15:37:32 INFO - 'NO_EM_RESTART': '1', 15:37:32 INFO - 'PAGER': '/bin/cat', 15:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:32 INFO - 'PWD': '/builds/slave/test', 15:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:32 INFO - 'SHELL': '/bin/bash', 15:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:32 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:32 INFO - 'USER': 'cltbld', 15:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:32 INFO - 'XPC_FLAGS': '0x0', 15:37:32 INFO - 'XPC_SERVICE_NAME': '0', 15:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:37:35 INFO - Installing collected packages: mozsystemmonitor 15:37:35 INFO - Running setup.py install for mozsystemmonitor 15:37:35 INFO - Successfully installed mozsystemmonitor 15:37:35 INFO - Cleaning up... 15:37:35 INFO - Return code: 0 15:37:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37:35 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')]} 15:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:35 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')]} 15:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:35 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:35 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 15:37:35 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 15:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:35 INFO - 'HOME': '/Users/cltbld', 15:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:35 INFO - 'LOGNAME': 'cltbld', 15:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:35 INFO - 'MOZ_NO_REMOTE': '1', 15:37:35 INFO - 'NO_EM_RESTART': '1', 15:37:35 INFO - 'PAGER': '/bin/cat', 15:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:35 INFO - 'PWD': '/builds/slave/test', 15:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:35 INFO - 'SHELL': '/bin/bash', 15:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:35 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:35 INFO - 'USER': 'cltbld', 15:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:35 INFO - 'XPC_FLAGS': '0x0', 15:37:35 INFO - 'XPC_SERVICE_NAME': '0', 15:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:35 INFO - Downloading/unpacking blobuploader==1.2.4 15:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:38 INFO - Downloading blobuploader-1.2.4.tar.gz 15:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:37:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:37:38 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 15:37:38 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 15:37:38 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 15:37:38 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 15:37:38 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 15:37:38 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 15:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:37:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:39 INFO - Downloading docopt-0.6.1.tar.gz 15:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:37:39 INFO - Installing collected packages: blobuploader, requests, docopt 15:37:39 INFO - Running setup.py install for blobuploader 15:37:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:37:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:37:39 INFO - Running setup.py install for requests 15:37:40 INFO - Running setup.py install for docopt 15:37:40 INFO - Successfully installed blobuploader requests docopt 15:37:40 INFO - Cleaning up... 15:37:40 INFO - Return code: 0 15:37:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:40 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:40 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 15:37:40 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 15:37:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:40 INFO - 'HOME': '/Users/cltbld', 15:37:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:40 INFO - 'LOGNAME': 'cltbld', 15:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:40 INFO - 'MOZ_NO_REMOTE': '1', 15:37:40 INFO - 'NO_EM_RESTART': '1', 15:37:40 INFO - 'PAGER': '/bin/cat', 15:37:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:40 INFO - 'PWD': '/builds/slave/test', 15:37:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:40 INFO - 'SHELL': '/bin/bash', 15:37:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:40 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:40 INFO - 'USER': 'cltbld', 15:37:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:40 INFO - 'XPC_FLAGS': '0x0', 15:37:40 INFO - 'XPC_SERVICE_NAME': '0', 15:37:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-bzo_UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:40 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-kJYa5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-yd6GCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-izNa8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-NNEBjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Z5kIZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-P7e7g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-eecota-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-oxrfoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-TRJdMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-o15Jw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:41 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-fSnBdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:42 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-TmKSPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:42 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-nr96rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:42 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-WDQXiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:42 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-j57jRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:42 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_U1JN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:37:42 INFO - Running setup.py install for manifestparser 15:37:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:37:42 INFO - Running setup.py install for mozcrash 15:37:42 INFO - Running setup.py install for mozdebug 15:37:43 INFO - Running setup.py install for mozdevice 15:37:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozfile 15:37:43 INFO - Running setup.py install for mozhttpd 15:37:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozinfo 15:37:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozInstall 15:37:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:37:43 INFO - Running setup.py install for mozleak 15:37:43 INFO - Running setup.py install for mozlog 15:37:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Running setup.py install for moznetwork 15:37:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Running setup.py install for mozprocess 15:37:44 INFO - Running setup.py install for mozprofile 15:37:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Running setup.py install for mozrunner 15:37:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:37:44 INFO - Running setup.py install for mozscreenshot 15:37:44 INFO - Running setup.py install for moztest 15:37:45 INFO - Running setup.py install for mozversion 15:37:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:37:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:37:45 INFO - Cleaning up... 15:37:45 INFO - Return code: 0 15:37:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:45 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:45 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 15:37:45 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 15:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:45 INFO - 'HOME': '/Users/cltbld', 15:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:45 INFO - 'LOGNAME': 'cltbld', 15:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:45 INFO - 'MOZ_NO_REMOTE': '1', 15:37:45 INFO - 'NO_EM_RESTART': '1', 15:37:45 INFO - 'PAGER': '/bin/cat', 15:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:45 INFO - 'PWD': '/builds/slave/test', 15:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:45 INFO - 'SHELL': '/bin/bash', 15:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:45 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:45 INFO - 'USER': 'cltbld', 15:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:45 INFO - 'XPC_FLAGS': '0x0', 15:37:45 INFO - 'XPC_SERVICE_NAME': '0', 15:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-PcNngA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_PnUMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-hl4m9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-EvnAxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-vAbkiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:45 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)) 15:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:45 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-uFuhgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-2FeILP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-twMx9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-5bEoxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-a_y6a2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-vtX_qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-i_gzsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-5qR0Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-qKdWhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:46 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)) 15:37:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:46 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-CssHqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:47 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)) 15:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:47 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-eitXDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:47 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)) 15:37:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:47 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-cCauLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 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)) 15:37:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:49 INFO - Downloading blessings-1.6.tar.gz 15:37:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:37:49 INFO - Installing collected packages: blessings 15:37:49 INFO - Running setup.py install for blessings 15:37:49 INFO - Successfully installed blessings 15:37:49 INFO - Cleaning up... 15:37:50 INFO - Return code: 0 15:37:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:37:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:50 INFO - Reading from file tmpfile_stdout 15:37:50 INFO - Current package versions: 15:37:50 INFO - blessings == 1.6 15:37:50 INFO - blobuploader == 1.2.4 15:37:50 INFO - docopt == 0.6.1 15:37:50 INFO - manifestparser == 1.1 15:37:50 INFO - mozInstall == 1.12 15:37:50 INFO - mozcrash == 0.17 15:37:50 INFO - mozdebug == 0.1 15:37:50 INFO - mozdevice == 0.48 15:37:50 INFO - mozfile == 1.2 15:37:50 INFO - mozhttpd == 0.7 15:37:50 INFO - mozinfo == 0.9 15:37:50 INFO - mozleak == 0.1 15:37:50 INFO - mozlog == 3.1 15:37:50 INFO - moznetwork == 0.27 15:37:50 INFO - mozprocess == 0.22 15:37:50 INFO - mozprofile == 0.28 15:37:50 INFO - mozrunner == 6.11 15:37:50 INFO - mozscreenshot == 0.1 15:37:50 INFO - mozsystemmonitor == 0.0 15:37:50 INFO - moztest == 0.7 15:37:50 INFO - mozversion == 1.4 15:37:50 INFO - psutil == 3.1.1 15:37:50 INFO - requests == 1.2.3 15:37:50 INFO - wsgiref == 0.1.2 15:37:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:50 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 15:37:50 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 15:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:50 INFO - 'HOME': '/Users/cltbld', 15:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:50 INFO - 'LOGNAME': 'cltbld', 15:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:50 INFO - 'MOZ_NO_REMOTE': '1', 15:37:50 INFO - 'NO_EM_RESTART': '1', 15:37:50 INFO - 'PAGER': '/bin/cat', 15:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:50 INFO - 'PWD': '/builds/slave/test', 15:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:50 INFO - 'SHELL': '/bin/bash', 15:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:50 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:50 INFO - 'USER': 'cltbld', 15:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:50 INFO - 'XPC_FLAGS': '0x0', 15:37:50 INFO - 'XPC_SERVICE_NAME': '0', 15:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 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)) 15:37:50 INFO - Cleaning up... 15:37:50 INFO - Return code: 0 15:37:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 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 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:50 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 15:37:50 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 15:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:50 INFO - 'HOME': '/Users/cltbld', 15:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:50 INFO - 'LOGNAME': 'cltbld', 15:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:50 INFO - 'MOZ_NO_REMOTE': '1', 15:37:50 INFO - 'NO_EM_RESTART': '1', 15:37:50 INFO - 'PAGER': '/bin/cat', 15:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:50 INFO - 'PWD': '/builds/slave/test', 15:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:50 INFO - 'SHELL': '/bin/bash', 15:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:50 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:50 INFO - 'USER': 'cltbld', 15:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:50 INFO - 'XPC_FLAGS': '0x0', 15:37:50 INFO - 'XPC_SERVICE_NAME': '0', 15:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:50 INFO - Downloading/unpacking jsonschema 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:37:53 INFO - Downloading/unpacking functools32 (from jsonschema) 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 INFO - Downloading functools32-3.2.3-2.tar.gz 15:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:37:53 INFO - warning: no files found matching '*.txt' 15:37:53 INFO - no previously-included directories found matching 'build' 15:37:53 INFO - no previously-included directories found matching 'dist' 15:37:53 INFO - no previously-included directories found matching '.git*' 15:37:53 INFO - Installing collected packages: jsonschema, functools32 15:37:53 INFO - Running setup.py install for functools32 15:37:54 INFO - warning: no files found matching '*.txt' 15:37:54 INFO - no previously-included directories found matching 'build' 15:37:54 INFO - no previously-included directories found matching 'dist' 15:37:54 INFO - no previously-included directories found matching '.git*' 15:37:54 INFO - Successfully installed jsonschema functools32 15:37:54 INFO - Cleaning up... 15:37:54 INFO - Return code: 0 15:37:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10749ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa1048c1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, '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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 15:37:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:37:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:54 INFO - 'HOME': '/Users/cltbld', 15:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:54 INFO - 'LOGNAME': 'cltbld', 15:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:54 INFO - 'MOZ_NO_REMOTE': '1', 15:37:54 INFO - 'NO_EM_RESTART': '1', 15:37:54 INFO - 'PAGER': '/bin/cat', 15:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:54 INFO - 'PWD': '/builds/slave/test', 15:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:54 INFO - 'SHELL': '/bin/bash', 15:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:37:54 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:54 INFO - 'USER': 'cltbld', 15:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:54 INFO - 'XPC_FLAGS': '0x0', 15:37:54 INFO - 'XPC_SERVICE_NAME': '0', 15:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:54 INFO - Downloading/unpacking flake8 15:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:37:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:37:56 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 15:37:56 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 15:37:56 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 15:37:56 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 15:37:56 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 15:37:56 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 15:37:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:37:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:37:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:37:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:37:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:37:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:37:58 INFO - no previously-included directories found matching 'docs/_build' 15:37:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:37:58 INFO - Running setup.py install for pep8 15:37:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:37:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:37:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:37:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:37:58 INFO - no previously-included directories found matching 'docs/_build' 15:37:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:37:58 INFO - Cleaning up... 15:37:58 INFO - Return code: 0 15:37:58 INFO - Running post-action listener: _resource_record_post_action 15:37:58 INFO - Running post-action listener: _start_resource_monitoring 15:37:58 INFO - Starting resource monitoring. 15:37:58 INFO - ##### 15:37:58 INFO - ##### Running install step. 15:37:58 INFO - ##### 15:37:58 INFO - Running pre-action listener: _resource_record_pre_action 15:37:58 INFO - Running main action method: install 15:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:37:58 INFO - Reading from file tmpfile_stdout 15:37:58 INFO - Detecting whether we're running mozinstall >=1.0... 15:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:37:58 INFO - Reading from file tmpfile_stdout 15:37:58 INFO - Output received: 15:37:58 INFO - Usage: mozinstall [options] installer 15:37:58 INFO - Options: 15:37:58 INFO - -h, --help show this help message and exit 15:37:58 INFO - -d DEST, --destination=DEST 15:37:58 INFO - Directory to install application into. [default: 15:37:58 INFO - "/builds/slave/test"] 15:37:58 INFO - --app=APP Application being installed. [default: firefox] 15:37:58 INFO - mkdir: /builds/slave/test/build/application 15:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:38:21 INFO - Reading from file tmpfile_stdout 15:38:21 INFO - Output received: 15:38:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:38:21 INFO - Running post-action listener: _resource_record_post_action 15:38:21 INFO - ##### 15:38:21 INFO - ##### Running run-tests step. 15:38:21 INFO - ##### 15:38:21 INFO - Running pre-action listener: _resource_record_pre_action 15:38:21 INFO - Running main action method: run_tests 15:38:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:38:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:38:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:38:21 INFO - 2016-07-10 15:38:21.108 screenresolution[1671:17131] starting screenresolution argv=screenresolution get 15:38:21 INFO - 2016-07-10 15:38:21.111 screenresolution[1671:17131] Display 0: 1600x1200x32@60 15:38:21 INFO - 2016-07-10 15:38:21.117 screenresolution[1672:17134] starting screenresolution argv=screenresolution list 15:38:21 INFO - Available Modes on Display 0 15:38:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:38:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:38:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:38:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:38:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:38:21 INFO - Intel Iris: 15:38:21 INFO - Chipset Model: Intel Iris 15:38:21 INFO - Type: GPU 15:38:21 INFO - Bus: Built-In 15:38:21 INFO - VRAM (Dynamic, Max): 1536 MB 15:38:21 INFO - Vendor: Intel (0x8086) 15:38:21 INFO - Device ID: 0x0a2e 15:38:21 INFO - Revision ID: 0x0009 15:38:21 INFO - Displays: 15:38:21 INFO - Display: 15:38:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:38:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:38:21 INFO - Display Serial Number: 1600x1200 60 15:38:21 INFO - Main Display: Yes 15:38:21 INFO - Mirror: Off 15:38:21 INFO - Online: Yes 15:38:21 INFO - Rotation: Supported 15:38:21 INFO - Return code: 0 15:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:21 INFO - Python 2.7.3 15:38:21 INFO - Return code: 0 15:38:21 INFO - grabbing minidump binary from tooltool 15:38:21 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')]} 15:38:21 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 0x10733ae00>, '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 0x1071a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107335b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:21 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 15:38:21 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 15:38:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:21 INFO - Return code: 0 15:38:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:38:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:38:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:38:22 INFO - Return code: 0 15:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 15:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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 15:38:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CFGgWHTGZx/Render', 15:38:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:22 INFO - 'HOME': '/Users/cltbld', 15:38:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:22 INFO - 'LOGNAME': 'cltbld', 15:38:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:22 INFO - 'MOZ_NO_REMOTE': '1', 15:38:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:22 INFO - 'NO_EM_RESTART': '1', 15:38:22 INFO - 'PAGER': '/bin/cat', 15:38:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:22 INFO - 'PWD': '/builds/slave/test', 15:38:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:22 INFO - 'SHELL': '/bin/bash', 15:38:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PH2qTWlbCH/Listeners', 15:38:22 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 15:38:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:22 INFO - 'USER': 'cltbld', 15:38:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:22 INFO - 'XPC_FLAGS': '0x0', 15:38:22 INFO - 'XPC_SERVICE_NAME': '0', 15:38:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 15:38:22 INFO - mozversion application_buildid: 20160710132925 15:38:22 INFO - mozversion application_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 15:38:22 INFO - mozversion application_display_name: Firefox 15:38:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:22 INFO - mozversion application_name: Firefox 15:38:22 INFO - mozversion application_remotingname: firefox 15:38:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:22 INFO - mozversion application_vendor: Mozilla 15:38:22 INFO - mozversion application_version: 48.0 15:38:22 INFO - mozversion platform_buildid: 20160710132925 15:38:22 INFO - mozversion platform_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 15:38:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:22 INFO - mozversion platform_version: 48.0 15:38:22 INFO - using testdate: 1468190302 15:38:22 INFO - actual date: 1468190302 15:38:22 INFO - starting webserver on 'localhost:49288' 15:38:22 INFO - SUITE-START | Running 2 tests 15:38:22 INFO - TEST-START | dromaeo_css 15:38:22 INFO - operating with platform_type : mac_ 15:38:22 INFO - Initialising browser for dromaeo_css test... 15:38:22 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpY4z9FA/profile http://localhost:49288/getInfo.html) 15:38:25 INFO - PROCESS | 1690 | __metrics Screen width/height:1600/1200 15:38:25 INFO - PROCESS | 1690 | colorDepth:24 15:38:25 INFO - PROCESS | 1690 | Browser inner width/height: 1024/689 15:38:25 INFO - PROCESS | 1690 | __metrics 15:38:25 INFO - PROCESS | 1690 | 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" 15:38:25 ERROR - PROCESS | 1690 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:38:25 INFO - TEST-INFO | 1690: exit 0 15:38:25 INFO - Browser initialized. 15:38:25 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:25 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpY4z9FA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:37 INFO - PROCESS | 1694 | RSS: Main: 161628160 15:38:37 INFO - PROCESS | 1694 | 15:39:29 INFO - PROCESS | 1694 | 1468190369351 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] 15:41:39 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49288/tests/dromaeo/cssquery-ext.html) 15:41:40 INFO - PROCESS | 1694 | RSS: Main: 292601856 15:41:40 INFO - PROCESS | 1694 | 15:44:43 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-ext.html (next: http://localhost:49288/tests/dromaeo/cssquery-jquery.html) 15:44:44 INFO - PROCESS | 1694 | RSS: Main: 296497152 15:44:44 INFO - PROCESS | 1694 | 15:47:46 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49288/tests/dromaeo/cssquery-mootools.html) 15:47:46 INFO - PROCESS | 1694 | RSS: Main: 299278336 15:47:46 INFO - PROCESS | 1694 | 15:50:48 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49288/tests/dromaeo/cssquery-prototype.html) 15:50:49 INFO - PROCESS | 1694 | RSS: Main: 298344448 15:50:49 INFO - PROCESS | 1694 | 15:53:52 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49288/tests/dromaeo/cssquery-yui.html) 15:53:53 INFO - PROCESS | 1694 | RSS: Main: 265965568 15:53:53 INFO - PROCESS | 1694 | 15:56:55 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49288/tests/dromaeo/cssquery-yui.html (next: http://localhost:49288/tests/dromaeo/cssquery-dojo.html) 15:56:56 INFO - PROCESS | 1694 | RSS: Main: 309960704 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | __start_tp_report 15:56:56 INFO - PROCESS | 1694 | _x_x_mozilla_page_load 15:56:56 INFO - PROCESS | 1694 | _x_x_mozilla_page_load_details 15:56:56 INFO - PROCESS | 1694 | |i|pagename|runs| 15:56:56 INFO - PROCESS | 1694 | |0;dojo.html;3568.0901370150614;3634.7637403568765;3652.561692596889;3664.2488289900575;3715.294094122117;6085.543584231184;6103.847403814907;6107.816765497037;6114.480269177126;6121.051237058263;6033.095035744641;6092.299385570661;6098.054801505763;6101.6033957792715;6131.478824299928;5349.411564727877;5442.9727851360785;5448.101063324567;5506.5870059745475;5552.08390615548;5452.263944367519;5494.587905907053;5495.53287970523;5495.752691128889;5501.504864562188;5434.8913021739545;5490.945090549103;5501.504864562188;5519.199716041172;5522.779088836442;5480.479354461319;5497.038018346773;5511.669299842022;5517.2551705520045;5529.364123125852;5293.285406470154;5358.062339090644;5364.570834333283;5403.945960540384;5456.127019676833;4871.293662418965;5918.112283157518;6037.849053773647;6062.393760623946;6065.666388348639;6190.350013248634;6270.2789179244055;6271.247450305992;6273.3726627337355;6274;6209.565330426872;6348.396902294275;6352.904706429407;6365.2679941806355;6378.362163783585;6161.952468080437;6177.042558403499;6193.318734939153;6195.132681424604;6205.844853878599;6504.902426463606;6537.738490460423;6540.705668244846;6544.803655890331;6552.213734351908;6145.262568491809;6220.875582488325;6223.315435302113;6239.656818875005;6252.656103914328;6291.559590828598;6329.461995730415;6343.365663433712;6344.333844946214;6357.268914074848;6203.348648391944;6254.937450625435;6275.497960163176;6281.309056003836;6306.1171435998995;6148.047052706837;6253.343398943137;6295.496360291166;6303.590266632654;6305.621662700161;5063.949360506348;5093;5093.745312734349;5098.898022039539;5133.435322114565;4564.908701825945;4578.084383123389;4599.6090332321455;4611.700239484423;4624.098300831306;6000.399960003964;6068.4841788448475;6097.603655762361;6105.633661980295;6123.142760013601;5994.910076348859;6304.085907543472;6338.968305158444;6348.047792831112;6362.872742545123;6117.755289788359;6134.9079763803575;6135.785247516315;6141;6152.969235153795;5526.253955716007;5651.717414129277;5658.264425624642;5666.858328541819;5675.091985282335;6496.675166241764;6502;6529.6082235067;6533.934660653333;6631.270560238273;6322.968385158045;6338.904916426165;6362.872742545216;6368.617882927039;6374.394432528902;6559.212894452697;6620.073189753534;6632.635205063575;6642.6014439133805;6664.166979127609;6181.412765787243;6241.188645476241;6289.276733175743;6299.685015749103;6307.148534947907;6331.366863313632;6358;6374.58565193261;6375.458086062737;6380.489560835215;6182.28903676083;6229.657368844757;6231.719572619152;6233;6245.063240513778;6144.095330447575;6146.678464130055;6217.067439883873;6233.688315584112;6233.812985610439;5105;6212.565120441706;6250.124982502362;6254.093156492192;6259.78090766821;6111.834843226386;6215.254169499689;6236.18929539157;6240.375962403723;6247.281562620356;6122.581127188433;6158.168647232746;6183.536234782319;6192.938070619236;6196.194494715658 15:56:56 INFO - PROCESS | 1694 | |1;ext.html;552.4171998541539;559.6054781379131;577.9277590301211;596.71059536125;625.2778041362226;32219.355612887728;32798.67201327989;39607;39892;40009.399859002064;35285.47071793925;42723.57276427235;43204.351934721;43249.78375108128;43626.78186609063;8956.955215223947;9599.856002159955;9817.214622830192;9840;11546.07631389491;11659.417029148508;11701.3564253966;11712.238704484182;11724.589639362624;11725.006748852715;11650.825237621442;11757.941210293979;11760.588379406723;11775.23460975034;11805.055595552378;9140.680076197334;11745.941270293679;11769.352685602289;12009.819852702216;12012.579559715456;11575.13186511015;11634.127440442;11687.883121168765;11697.3566453845;11714.180007399485;39550.80224598858;39893.80053099745;39917.00207494807;39967.8001609993;39968.200635987116;3256.2185075581688;3369.9831500842583;3493.6506349365113;3578.4453409721536;3726.6459686329754;27744;28528.144155675363;29016.564751528746;29237.122886313446;29853;2759.958600620972;2813.282612933709;2844.8293102413713;2855.129185598392;2920.065579014716;408342;414896.9255153705;415135;415342.7698584524;415939.9203003996;55920.72039639776;63658.68170659117;65918.67040664767;66191.6761664764;66305.6684716583;69233.30766692369;69423.61152777018;69426;69442;69443.65278173678;18350.26598936054;18991.90504047471;19050.237990480502;19052.237910483425;19057.90471047636;12996.350182490838;13204.141730787467;13206.933965330112;13241.602751917459;14644.633884152981;17017.489475315513;18667.924600224964;20355.796442035688;20409.387718368474;20474;8358.623861926228;8463.899693039866;8474.491530508067;8487.448315859448;8533.871991920127;974.181687382602;994.7314225159166;1042.1246153231323;1045.3518818332682;1112.3270421394939;28592.428151436856;28642.71357286442;29092.709072909423;29239.976600819296;29261;1086.3590481615727;1093.814051611228;1221.6090850927617;1244.004796163066;1249.2254802022621;14548.98157129012;14891.851081489263;14934.925325373304;15136.243187840564;15156.31796569157;506797.9320206751;514925;638460;641049;641169;63875;64426.6778666113;64430.355696442435;64528.35471645317;64716.352836471975;1485.8291296500825;1539.5227479481173;1560.5396408059423;1577.9053256804627;1596.2657177698156;29467.557986630647;30641.693583064774;30972.691132108965;30987;33299;54167.458325417814;54905;54940.17589736077;55080;55117.72441137929;483356;550999;564664;566550;566786;35355.33941941661;45131;45352;45683;46746.53253467422;46081.61755147351;46406.53593464022;46497.76751116223;47019.529804702885;48155;38198.23603527858;38298.23403531969;40033.599664004156;40376.3943540853;41631;39260;39451.40822887716;39465.60534394619;40450.78647640575;40675.59324406718;38991.610083899934;39176.608233918436;39265.60734392734;39802.004949876486;40315.596844031184;27812.30469238285;34339.484907725906;34529;34548.48177277393;34641;25834.612480812426;26055.827487763476;26146.08488702886;26483.470330592896;26582.06962756294 15:56:56 INFO - PROCESS | 1694 | |2;jquery.html;373.8672771166236;388.56868875548156;419.85724853549794;420.36105120217263;421.6668831623016;16009.359625614961;18936.526586835334;19333;20798.89600551999;20804.99999999998;20489.487762805937;20807.791922080774;21863.672044919298;21957.341279761593;21958.780412195872;5851.180834683148;5894.115882617599;6181.165542651752;6184.536159788011;6190.81427557174;4514;6070.969645151768;6172.351903050182;6195.50435965124;6532.869342613145;6787.796366109;6796.490263230253;6801.353871382211;6805.727770889183;6807.727690892359;5459.754311056012;6607.702653380586;6688.130543029375;6693.497987650946;6741.528093033489;5916.852078698024;5916.852078698024;5942;5955.106733989936;5969.403059693996;26710.732892671214;27233.591496127574;27594.862025689745;27775.305617359525;27793.305167370774;2428.259380888828;2444.4133407982135;2453.9386515337083;2576.3559110222445;2692.273086266697;15375.308111135026;15837.841621583868;15855.207239637972;15969.600759980976;16163.595910102224;1801.7927938287135;1833.2117189608464;1849.2787812753038;1868.4979806078604;1877.915493802782;112072.4396378021;114808;117243;119428.80571194264;120201;10118.038786315288;11730.12024098196;12155.392230388445;12182.147249692436;12194.39028048594;14277.571672849832;14569.708605827824;15130.848691513163;16115;16205.675886482204;9135.269178465707;9151.496667683208;9255.352125351295;9366;9496.240300775922;8096.10942796292;9482.241420686467;9613.759656008655;9619.56711947964;9706.272029597667;4917.188663870421;4942.117993689196;4950.752462376867;4959;4970.478099799541;1526.6488707597314;2035.745531808524;2065.9276925307845;2078.605065037638;2079.5632917087582;939.1922946266205;969.767255858603;1007.8790545134631;1015.5074788727461;1025.553884060436;20914.16343346249;21390.78609213888;21762.89118554397;21855.125794968342;21902.23342183016;978.1245785022365;1008.7528555503802;1011.7521207304258;1021.9029192226726;1056.234230183111;11015.559377624806;11533.538658453568;11608;11609.30344179352;11610.6516804496;628439;643640;644108;645244;650814.7459262768;120160.3991980052;131487;132767.3361633205;134793.3260333692;140717;989.1098011789447;999.14073896449;1001.5993602558889;1002.9398236105857;1023.7952409518126;17246.310147594457;17844.107794609958;18494.81505184931;19080.809191908458;19635;40772;50954.980900381044;54258.45741542534;54953.45046549483;55127.173092404446;74207;74587.50824983361;76069.6196519036;76173;77618.83571746315;8255.752327430188;8445.577721114043;8537.615807288563;8632.179942905414;8804.03155652865;9483.193928516153;11001.394923279297;12113.273203607812;12817.743645127232;13172.209667419986;11178.776424471302;11326.207165498665;11350.54597816078;11531.71170720705;11680.06559475223;8908.821823563621;9735.26985476078;11260.436978151223;11389.487473063566;11618.76762464763;10883.796236145638;11669.88330116688;11697.824532632187;11934.761304774029;11996;8367.707130250412;8454;8674.956625217086;8721.781955451164;8915.242204412698;10749.838752418877;11202.495887685236;11333.886661133613;11429.942850285695;11677.708057298636 15:56:56 INFO - PROCESS | 1694 | |3;mootools.html;1521.8934674572783;1591.4668586023681;1647.530453820661;1975.6937674660433;2081.3339731285987;8441.155884411564;9105.863412048817;9329.533523323824;9332.393394429357;9379.812403751921;8978.820423591524;9231.584578693959;9259;9270.397424167424;9300.395474294166;2524.8863801128946;2548.1081621432445;2550.4899020195944;2551.323899166713;2554.7445255474395;2492.962605560918;2564.974350256492;2566.0505612923243;2574.060467929211;2578.2136448383235;2524.962125568118;2549.7195308516048;2577.3943123365993;2578.793696504275;2582.7933765298826;2530.367408147963;2558.8848501817465;2567.5891857302836;2574.4207553300544;2574.5751950928075;2531.923822465579;2546.274311821122;2556.987215063931;2557.539642864284;2568.114000669766;8936.374453788238;9241.676541321089;9317.534123293875;9336.3464557481;9353.719388418358;5039.445660977326;5833.212516310286;6008.068749343859;6012.368701286346;6019.097135429721;3477.078574177845;3507;3662.4689420033947;3707.8146092695256;3777.9055523611855;1060.4696507712317;1062.3094988257617;1071.780572243653;1082.3884505254507;1086.788076325117;12589.811152832715;12652.620421387373;12669.239845609201;12717.30054846983;12717.55488557896;4741.762911854394;4809.639277054235;4824.155772739757;4874.780634871439;4908.3619129513645;9850.655227067018;9868.358556693793;9880.555375008142;9898.901010989943;9922.355046921926;5656.91514627281;5685.459881311269;5686.175504551899;5696.82909512707;5697.088465845528;4688.179568575486;4811.109944660249;5094.261332106824;5165.225216217597;5264.8420547382875;3967.956502401266;4011.2378648056774;4071.328230841878;4105.240530501807;4108.281050816155;1257.88050135237;1261.9179753316005;1271.650296168561;1281.91026628137;1282.6216266201307;907.7912080221586;912.0332447605559;955.441066975813;978.280963491833;1013.986013986014;6074.210352654127;6192.969035154885;6250.781222657185;6266.342034086447;6277.403646653652;443.3372108697518;455.2283878825359;469.85904228731243;474.50177313820353;481.7759741720117;3932.9213415731524;4004.7196696230985;4045.2718510667773;4128.112455822013;4129.29801933672;11168.553257869757;11619.360935148648;11705.765884682429;11712.121590880719;11746.412679365998;11410.885891140982;11432.142589305835;11442.027427668741;11482.425878706199;11488;7739.496932699357;8547.316214702932;8557.101504341954;8701.956490217635;8762.123787621314;5968.134620480093;6102.420270074336;6158.107074474086;6158.107074474266;6175.351588083367;10045.09594136502;10088.69733907984;10205.74485637865;10239.436830974368;10268.332558383681;10061.69814905554;10421.218408619232;10529;10728.731781705208;10738.516766745672;7714.15144334134;7884.881726773988;8331.416800824127;8612.956935215283;8788.121187881161;8634.697785577475;8649.178328059075;8667.739967800977;8713.477191368538;8716.128387161232;7554.962225188839;7601.12587052496;7750.689972401041;7795.571243581658;7804.648790804541;7476.5514069156025;7509.887351689838;7534.623268836646;7672.462927595015;7681.692732290646;7280.126384833854;7314.232005639331;7319.451041171827;7324.414046876131;7333.633318333956;4976.477469865757;4989.975050124726;5107.080725469451;5213.661112027706;5226.398964119175;5172.948270517247;5198.402183748766;5290.735463226746;5393.137098064448;5449.754761035683 15:56:56 INFO - PROCESS | 1694 | |4;prototype.html;327.18857234059533;334.3480213583512;340.0766917818123;343.9449688049913;344.0778713049028;6344.270408902978;6750.426213771829;6759.12131422915;6774.983752437137;6853.965730171342;5460.945390546093;6439.259485159208;6541.934580654192;6656.6671666416605;6840.179178498587;4993.251012348141;5024.422191447976;5026.421961474441;5032.84901452957;5046.697198168122;3845.5962123977;4907.386576677915;5013.749312534359;5018.498150184971;5023.4976502349655;4984.476629953857;4998.2752500887345;5001.974990125044;5004.699718016931;5008.223725322581;4037.7375470594316;4952.801887924478;4973.801047958077;4975.776090075937;4976.129177393942;4251.149770046003;4253.447051883235;4254.617084462413;4261.978690106561;4271.679624028179;6501.869962600769;6504.146318299309;6521.315261897527;6532.444742196919;6537.346265373472;5557.499825015728;5879.67661778602;5896.086106653475;5897.3218079920925;5911.29064512257;6184.5980011299125;7173.820654483628;7185.353318201334;7196.028536147658;7221.711131554732;4662.277347011219;4666.300054991744;4666.556677115668;4667.719936803803;4672.672912896099;7453.14288856783;7553.206913274137;7561.924380756177;7567.167611562723;7576.621168941531;4692.929606055912;4725;4730.172219861579;4731.313959475857;4732.242841145469;4558.8632341029825;4625.976870115695;4783.345243389883;4878.463369029404;4880.463149053601;4629.675922685447;5029.270755740397;5631.352394474606;5685.573581981368;5693.629914055573;7057.294270573005;7074.610896400644;7077.327653872874;7083.964580177066;7085.964570177116;1564.4993602047236;1605.6226786705115;1617.2641448141067;1631.8939268947481;1663.6755832612525;1030.567161792049;1033.943133127685;1049.2602715085998;1050.369778133114;1056.1181413519644;6597.505187110986;6662.133922590129;6685.130932978779;6687.364700353459;6695.163104611924;7260.273972602803;7285.125784905739;7292.270772922771;7306.926930730731;7315.085614298213;6643.634600097041;6649.235337936199;6651.201855777241;6656.567323123982;6669.533132680763;3847.9807600962376;3909.4722212501515;3909.5113110861143;3910.843566257318;3916.7845768483007;6473.77341793035;7522.699092036367;7802.609869506502;7885.369170466349;7910.288074073246;7765.844683106419;7808.375329973702;7815.25755053269;7831;7835.294823465915;7884.21157884207;7889.8816517752275;7893.881591776243;7897.763067107996;7912.564808935564;7241.855162896607;7541.2458754124145;7546.849063018599;7553.811154721176;7557.130929942907;7359.411247100112;7388.593627350332;7412;7414.4439167063765;7431.925680743123;6002.969985150046;7191.9571662107655;7615.048118985127;7876.6061696913775;7876.8972343870755;4275.55106713803;4698.882527936829;4757.7621118943225;4763.618910487222;4772.5227477252;6255.218097737783;6871.931280687129;6975.965120174366;7476.476646634684;7485.5508669480005;7107.253738357398;7119.82200444993;7177.354038136593;7186.928130718835;7190.352868241884;7098.822529436806;7140.571565706075;7144;7150.213476517475;7176.461765367514;5690.630109043064;6976;6979.302069793183;6990.6504674767075;7000.019997200294;6289.591176573508;6881.277465866012;7127.75052873147;7149.892751608626;7155.713771449084;5133.820316289061;5873.9412605873395;6285.905711414424;6939.236683964659;7013.018177455262 15:56:56 INFO - PROCESS | 1694 | |5;yui.html;608.4098424528207;749.5877267502874;758.1698040645491;766.6090293950084;806.9314108300794;15324.540263792076;15506.612334691637;15525;15553.45562905297;15571.844281557194;14250.358733856976;14317.713645727079;16101.436449724262;16546.420875269367;16550.917245413755;795.2643804480861;968.4576637083221;979.9804003919917;985.0445068283753;986.7335819328777;797.4417907464768;968.3076600230869;995.7560397702573;998.4508520313834;998.9800203995916;1036.5594622285537;1037.5642230263309;1040.5005597313293;1041.463646222196;1041.9322744021651;708.4828075504859;1015.9085682288555;1035.4563853976647;1035.7462421706693;1036.5439206749006;771.3944553525456;772.4631381190076;772.7411317208741;773.0993392697501;773.9613019349066;15791.289391977389;15842.920785396114;15844.366225350972;15858.682826343524;15875.444359447363;12724.809127863089;12759.808602870964;12792.552260670926;12917.741645167138;12979.67550811228;10621.522031508523;10641.095506881924;10745.247832651718;10896.673099807018;10921.781564368748;3571.1072231942017;3574.3744844652083;3615.6022837487853;3647.215848592565;3842.2507611015862;87288.69066963937;88040.55979720125;88796.5560172195;90232.54883725605;90494.5475272626;9712.271579631555;9783.461909594898;9811.558479868423;9814.705558833246;9944.254180936314;22324.888375558345;22332.44168895758;22353.888230558743;22387.664185037236;22405.77594224037;7571.091469023752;7580.772576822704;7594.848103037908;7652.120006199247;7745.341645960157;4227.830886764556;4254.574542545721;4343.131373725268;4385.846495372647;4561.771911404483;3685.6682898538725;3734.3838266686234;3735.0662334416397;3744.4570537271943;3760.4547340635454;600.3846057790712;655.6787174284566;687.6149356360454;687.7042871565175;695.4818660098259;565.4882331490008;608.8143116349556;624.9906251406233;626.5050610018126;631.9494440444753;6731.596104233761;8282.23761873223;8465.576721163918;8519.659213631385;8534.957325213334;592.1680039544447;597.9910301345483;613.7054213977316;616.4267231474771;620.9192804935329;3384.526166336715;3856.305864944337;3897.6029741708644;3946.940795888064;3975.662068724133;85399.86500337541;88446;89408;89601.55199223962;97223.51388243014;71875.6406217976;72555;75100.62449687826;75384.62307688427;75961.24038759652;57634;63506.507142106704;66502.33497665059;67137.66431167813;67548;8563.529005904735;8631.87052194205;8737;8804.427712198687;8870.51212183336;46468.53531464642;47953;49535.50464495453;49792.50207497879;50041.24938126004;64354;68829.65585172242;69461.61076778335;69750;70153.29846701468;10013.449260290754;10840.39023423965;10911.508982095665;11130.777384452427;11577.536898523964;11749.530018799152;11814.822777658514;11992.940035299767;12171.81742273849;12471.93764031174;8529.609690444302;12005.219660722025;12143;12165.026797856328;12176.817347739614;11550.075993920635;11661.76676466449;11765.823512647488;11771.470283837076;12240.081993850676;11508.94245528767;11654.184207105422;11762;11763.764724705286;12210.38948052576;9635.181009614262;9759.951200243953;9977.950110249401;10007.499625018865;10241.539130739284;9599.376040557341;9800.460974646463;9919.900800991898;10020.899791001997;10073.943557407905 15:56:56 INFO - PROCESS | 1694 | __end_tp_report 15:56:56 INFO - PROCESS | 1694 | __start_cc_report 15:56:56 INFO - PROCESS | 1694 | _x_x_mozilla_cycle_collect,1156 15:56:56 INFO - PROCESS | 1694 | __end_cc_report 15:56:56 INFO - PROCESS | 1694 | __startTimestamp1468191416118__endTimestamp 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | ------- Summary: start ------- 15:56:56 INFO - PROCESS | 1694 | Number of tests: 6 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#0] dojo.html Cycles:165 Average:5942.97 Median:6182.29 stddev:614.86 (9.9%) stddev-sans-first:587.85 15:56:56 INFO - PROCESS | 1694 | Values: 3568.1 3634.8 3652.6 3664.2 3715.3 6085.5 6103.8 6107.8 6114.5 6121.1 6033.1 6092.3 6098.1 6101.6 6131.5 5349.4 5443.0 5448.1 5506.6 5552.1 5452.3 5494.6 5495.5 5495.8 5501.5 5434.9 5490.9 5501.5 5519.2 5522.8 5480.5 5497.0 5511.7 5517.3 5529.4 5293.3 5358.1 5364.6 5403.9 5456.1 4871.3 5918.1 6037.8 6062.4 6065.7 6190.4 6270.3 6271.2 6273.4 6274.0 6209.6 6348.4 6352.9 6365.3 6378.4 6162.0 6177.0 6193.3 6195.1 6205.8 6504.9 6537.7 6540.7 6544.8 6552.2 6145.3 6220.9 6223.3 6239.7 6252.7 6291.6 6329.5 6343.4 6344.3 6357.3 6203.3 6254.9 6275.5 6281.3 6306.1 6148.0 6253.3 6295.5 6303.6 6305.6 5063.9 5093.0 5093.7 5098.9 5133.4 4564.9 4578.1 4599.6 4611.7 4624.1 6000.4 6068.5 6097.6 6105.6 6123.1 5994.9 6304.1 6339.0 6348.0 6362.9 6117.8 6134.9 6135.8 6141.0 6153.0 5526.3 5651.7 5658.3 5666.9 5675.1 6496.7 6502.0 6529.6 6533.9 6631.3 6323.0 6338.9 6362.9 6368.6 6374.4 6559.2 6620.1 6632.6 6642.6 6664.2 6181.4 6241.2 6289.3 6299.7 6307.1 6331.4 6358.0 6374.6 6375.5 6380.5 6182.3 6229.7 6231.7 6233.0 6245.1 6144.1 6146.7 6217.1 6233.7 6233.8 5105.0 6212.6 6250.1 6254.1 6259.8 6111.8 6215.3 6236.2 6240.4 6247.3 6122.6 6158.2 6183.5 6192.9 6196.2 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#1] ext.html Cycles:180 Average:67210.71 Median:3652.55 stddev:139293.31 (3813.6%) stddev-sans-first:139594.15 15:56:56 INFO - PROCESS | 1694 | Values: 552.4 559.6 577.9 596.7 625.3 32219.4 32798.7 39607.0 39892.0 40009.4 35285.5 42723.6 43204.4 43249.8 43626.8 8957.0 9599.9 9817.2 9840.0 11546.1 11659.4 11701.4 11712.2 11724.6 11725.0 11650.8 11757.9 11760.6 11775.2 11805.1 9140.7 11745.9 11769.4 12009.8 12012.6 11575.1 11634.1 11687.9 11697.4 11714.2 39550.8 39893.8 39917.0 39967.8 39968.2 3256.2 3370.0 3493.7 3578.4 3726.6 27744.0 28528.1 29016.6 29237.1 29853.0 2760.0 2813.3 2844.8 2855.1 2920.1 408342.0 414896.9 415135.0 415342.8 415939.9 55920.7 63658.7 65918.7 66191.7 66305.7 69233.3 69423.6 69426.0 69442.0 69443.7 18350.3 18991.9 19050.2 19052.2 19057.9 12996.4 13204.1 13206.9 13241.6 14644.6 17017.5 18667.9 20355.8 20409.4 20474.0 8358.6 8463.9 8474.5 8487.4 8533.9 974.2 994.7 1042.1 1045.4 1112.3 28592.4 28642.7 29092.7 29240.0 29261.0 1086.4 1093.8 1221.6 1244.0 1249.2 14549.0 14891.9 14934.9 15136.2 15156.3 506797.9 514925.0 638460.0 641049.0 641169.0 63875.0 64426.7 64430.4 64528.4 64716.4 1485.8 1539.5 1560.5 1577.9 1596.3 29467.6 30641.7 30972.7 30987.0 33299.0 54167.5 54905.0 54940.2 55080.0 55117.7 483356.0 550999.0 564664.0 566550.0 566786.0 35355.3 45131.0 45352.0 45683.0 46746.5 46081.6 46406.5 46497.8 47019.5 48155.0 38198.2 38298.2 40033.6 40376.4 41631.0 39260.0 39451.4 39465.6 40450.8 40675.6 38991.6 39176.6 39265.6 39802.0 40315.6 27812.3 34339.5 34529.0 34548.5 34641.0 25834.6 26055.8 26146.1 26483.5 26582.1 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#2] jquery.html Cycles:180 Average:36628.21 Median:21958.06 stddev:106799.03 (486.4%) stddev-sans-first:107063.93 15:56:56 INFO - PROCESS | 1694 | Values: 373.9 388.6 419.9 420.4 421.7 16009.4 18936.5 19333.0 20798.9 20805.0 20489.5 20807.8 21863.7 21957.3 21958.8 5851.2 5894.1 6181.2 6184.5 6190.8 4514.0 6071.0 6172.4 6195.5 6532.9 6787.8 6796.5 6801.4 6805.7 6807.7 5459.8 6607.7 6688.1 6693.5 6741.5 5916.9 5916.9 5942.0 5955.1 5969.4 26710.7 27233.6 27594.9 27775.3 27793.3 2428.3 2444.4 2453.9 2576.4 2692.3 15375.3 15837.8 15855.2 15969.6 16163.6 1801.8 1833.2 1849.3 1868.5 1877.9 112072.4 114808.0 117243.0 119428.8 120201.0 10118.0 11730.1 12155.4 12182.1 12194.4 14277.6 14569.7 15130.8 16115.0 16205.7 9135.3 9151.5 9255.4 9366.0 9496.2 8096.1 9482.2 9613.8 9619.6 9706.3 4917.2 4942.1 4950.8 4959.0 4970.5 1526.6 2035.7 2065.9 2078.6 2079.6 939.2 969.8 1007.9 1015.5 1025.6 20914.2 21390.8 21762.9 21855.1 21902.2 978.1 1008.8 1011.8 1021.9 1056.2 11015.6 11533.5 11608.0 11609.3 11610.7 628439.0 643640.0 644108.0 645244.0 650814.7 120160.4 131487.0 132767.3 134793.3 140717.0 989.1 999.1 1001.6 1002.9 1023.8 17246.3 17844.1 18494.8 19080.8 19635.0 40772.0 50955.0 54258.5 54953.5 55127.2 74207.0 74587.5 76069.6 76173.0 77618.8 8255.8 8445.6 8537.6 8632.2 8804.0 9483.2 11001.4 12113.3 12817.7 13172.2 11178.8 11326.2 11350.5 11531.7 11680.1 8908.8 9735.3 11260.4 11389.5 11618.8 10883.8 11669.9 11697.8 11934.8 11996.0 8367.7 8454.0 8675.0 8721.8 8915.2 10749.8 11202.5 11333.9 11429.9 11677.7 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#3] mootools.html Cycles:180 Average:5996.68 Median:4849.47 stddev:3392.80 (70.0%) stddev-sans-first:3385.65 15:56:56 INFO - PROCESS | 1694 | Values: 1521.9 1591.5 1647.5 1975.7 2081.3 8441.2 9105.9 9329.5 9332.4 9379.8 8978.8 9231.6 9259.0 9270.4 9300.4 2524.9 2548.1 2550.5 2551.3 2554.7 2493.0 2565.0 2566.1 2574.1 2578.2 2525.0 2549.7 2577.4 2578.8 2582.8 2530.4 2558.9 2567.6 2574.4 2574.6 2531.9 2546.3 2557.0 2557.5 2568.1 8936.4 9241.7 9317.5 9336.3 9353.7 5039.4 5833.2 6008.1 6012.4 6019.1 3477.1 3507.0 3662.5 3707.8 3777.9 1060.5 1062.3 1071.8 1082.4 1086.8 12589.8 12652.6 12669.2 12717.3 12717.6 4741.8 4809.6 4824.2 4874.8 4908.4 9850.7 9868.4 9880.6 9898.9 9922.4 5656.9 5685.5 5686.2 5696.8 5697.1 4688.2 4811.1 5094.3 5165.2 5264.8 3968.0 4011.2 4071.3 4105.2 4108.3 1257.9 1261.9 1271.7 1281.9 1282.6 907.8 912.0 955.4 978.3 1014.0 6074.2 6193.0 6250.8 6266.3 6277.4 443.3 455.2 469.9 474.5 481.8 3932.9 4004.7 4045.3 4128.1 4129.3 11168.6 11619.4 11705.8 11712.1 11746.4 11410.9 11432.1 11442.0 11482.4 11488.0 7739.5 8547.3 8557.1 8702.0 8762.1 5968.1 6102.4 6158.1 6158.1 6175.4 10045.1 10088.7 10205.7 10239.4 10268.3 10061.7 10421.2 10529.0 10728.7 10738.5 7714.2 7884.9 8331.4 8613.0 8788.1 8634.7 8649.2 8667.7 8713.5 8716.1 7555.0 7601.1 7750.7 7795.6 7804.6 7476.6 7509.9 7534.6 7672.5 7681.7 7280.1 7314.2 7319.5 7324.4 7333.6 4976.5 4990.0 5107.1 5213.7 5226.4 5172.9 5198.4 5290.7 5393.1 5449.8 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#4] prototype.html Cycles:180 Average:5809.40 Median:6534.90 stddev:1870.71 (28.6%) stddev-sans-first:1830.15 15:56:56 INFO - PROCESS | 1694 | Values: 327.2 334.3 340.1 343.9 344.1 6344.3 6750.4 6759.1 6775.0 6854.0 5460.9 6439.3 6541.9 6656.7 6840.2 4993.3 5024.4 5026.4 5032.8 5046.7 3845.6 4907.4 5013.7 5018.5 5023.5 4984.5 4998.3 5002.0 5004.7 5008.2 4037.7 4952.8 4973.8 4975.8 4976.1 4251.1 4253.4 4254.6 4262.0 4271.7 6501.9 6504.1 6521.3 6532.4 6537.3 5557.5 5879.7 5896.1 5897.3 5911.3 6184.6 7173.8 7185.4 7196.0 7221.7 4662.3 4666.3 4666.6 4667.7 4672.7 7453.1 7553.2 7561.9 7567.2 7576.6 4692.9 4725.0 4730.2 4731.3 4732.2 4558.9 4626.0 4783.3 4878.5 4880.5 4629.7 5029.3 5631.4 5685.6 5693.6 7057.3 7074.6 7077.3 7084.0 7086.0 1564.5 1605.6 1617.3 1631.9 1663.7 1030.6 1033.9 1049.3 1050.4 1056.1 6597.5 6662.1 6685.1 6687.4 6695.2 7260.3 7285.1 7292.3 7306.9 7315.1 6643.6 6649.2 6651.2 6656.6 6669.5 3848.0 3909.5 3909.5 3910.8 3916.8 6473.8 7522.7 7802.6 7885.4 7910.3 7765.8 7808.4 7815.3 7831.0 7835.3 7884.2 7889.9 7893.9 7897.8 7912.6 7241.9 7541.2 7546.8 7553.8 7557.1 7359.4 7388.6 7412.0 7414.4 7431.9 6003.0 7192.0 7615.0 7876.6 7876.9 4275.6 4698.9 4757.8 4763.6 4772.5 6255.2 6871.9 6976.0 7476.5 7485.6 7107.3 7119.8 7177.4 7186.9 7190.4 7098.8 7140.6 7144.0 7150.2 7176.5 5690.6 6976.0 6979.3 6990.7 7000.0 6289.6 6881.3 7127.8 7149.9 7155.7 5133.8 5873.9 6285.9 6939.2 7013.0 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - PROCESS | 1694 | [#5] yui.html Cycles:180 Average:18405.24 Median:49916.88 stddev:25533.82 (51.2%) stddev-sans-first:25570.48 15:56:56 INFO - PROCESS | 1694 | Values: 608.4 749.6 758.2 766.6 806.9 15324.5 15506.6 15525.0 15553.5 15571.8 14250.4 14317.7 16101.4 16546.4 16550.9 795.3 968.5 980.0 985.0 986.7 797.4 968.3 995.8 998.5 999.0 1036.6 1037.6 1040.5 1041.5 1041.9 708.5 1015.9 1035.5 1035.7 1036.5 771.4 772.5 772.7 773.1 774.0 15791.3 15842.9 15844.4 15858.7 15875.4 12724.8 12759.8 12792.6 12917.7 12979.7 10621.5 10641.1 10745.2 10896.7 10921.8 3571.1 3574.4 3615.6 3647.2 3842.3 87288.7 88040.6 88796.6 90232.5 90494.5 9712.3 9783.5 9811.6 9814.7 9944.3 22324.9 22332.4 22353.9 22387.7 22405.8 7571.1 7580.8 7594.8 7652.1 7745.3 4227.8 4254.6 4343.1 4385.8 4561.8 3685.7 3734.4 3735.1 3744.5 3760.5 600.4 655.7 687.6 687.7 695.5 565.5 608.8 625.0 626.5 631.9 6731.6 8282.2 8465.6 8519.7 8535.0 592.2 598.0 613.7 616.4 620.9 3384.5 3856.3 3897.6 3946.9 3975.7 85399.9 88446.0 89408.0 89601.6 97223.5 71875.6 72555.0 75100.6 75384.6 75961.2 57634.0 63506.5 66502.3 67137.7 67548.0 8563.5 8631.9 8737.0 8804.4 8870.5 46468.5 47953.0 49535.5 49792.5 50041.2 64354.0 68829.7 69461.6 69750.0 70153.3 10013.4 10840.4 10911.5 11130.8 11577.5 11749.5 11814.8 11992.9 12171.8 12471.9 8529.6 12005.2 12143.0 12165.0 12176.8 11550.1 11661.8 11765.8 11771.5 12240.1 11508.9 11654.2 11762.0 11763.8 12210.4 9635.2 9760.0 9978.0 10007.5 10241.5 9599.4 9800.5 9919.9 10020.9 10073.9 15:56:56 INFO - PROCESS | 1694 | -------- Summary: end -------- 15:56:56 INFO - PROCESS | 1694 | 15:56:56 INFO - TEST-INFO | 1694: exit 0 15:56:56 INFO - TEST-OK | dromaeo_css | took 1113954ms 15:56:56 INFO - TEST-START | kraken 15:56:56 INFO - operating with platform_type : mac_ 15:56:56 INFO - Initialising browser for kraken test... 15:56:56 INFO - TEST-INFO | started process 1698 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp0ZdTbc/profile http://localhost:49288/getInfo.html) 15:56:58 INFO - PROCESS | 1698 | __metrics Screen width/height:1600/1200 15:56:58 INFO - PROCESS | 1698 | colorDepth:24 15:56:58 INFO - PROCESS | 1698 | Browser inner width/height: 1024/689 15:56:58 INFO - PROCESS | 1698 | __metrics 15:56:59 INFO - PROCESS | 1698 | 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" 15:56:59 ERROR - PROCESS | 1698 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:56:59 INFO - TEST-INFO | 1698: exit 0 15:56:59 INFO - Browser initialized. 15:56:59 INFO - Running cycle 1/1 for kraken test... 15:56:59 INFO - TEST-INFO | started process 1701 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp0ZdTbc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:11 INFO - PROCESS | 1701 | RSS: Main: 162181120 15:57:11 INFO - PROCESS | 1701 | 15:57:14 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49288/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:14 INFO - PROCESS | 1701 | RSS: Main: 223875072 15:57:14 INFO - PROCESS | 1701 | 15:57:17 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49288/tests/kraken/driver.html?testName=audio-dft) 15:57:18 INFO - PROCESS | 1701 | RSS: Main: 239894528 15:57:18 INFO - PROCESS | 1701 | 15:57:21 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49288/tests/kraken/driver.html?testName=audio-fft) 15:57:22 INFO - PROCESS | 1701 | RSS: Main: 325115904 15:57:22 INFO - PROCESS | 1701 | 15:57:23 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49288/tests/kraken/driver.html?testName=audio-oscillator) 15:57:24 INFO - PROCESS | 1701 | RSS: Main: 231653376 15:57:24 INFO - PROCESS | 1701 | 15:57:26 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49288/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:27 INFO - PROCESS | 1701 | RSS: Main: 232333312 15:57:27 INFO - PROCESS | 1701 | 15:57:30 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49288/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:30 INFO - PROCESS | 1701 | RSS: Main: 270745600 15:57:30 INFO - PROCESS | 1701 | 15:57:33 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49288/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:34 INFO - PROCESS | 1701 | RSS: Main: 271020032 15:57:34 INFO - PROCESS | 1701 | 15:57:36 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49288/tests/kraken/driver.html?testName=json-parse-financial) 15:57:37 INFO - PROCESS | 1701 | RSS: Main: 268619776 15:57:37 INFO - PROCESS | 1701 | 15:57:38 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49288/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:39 INFO - PROCESS | 1701 | RSS: Main: 260866048 15:57:39 INFO - PROCESS | 1701 | 15:57:41 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:41 INFO - PROCESS | 1701 | RSS: Main: 268550144 15:57:41 INFO - PROCESS | 1701 | 15:57:43 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:44 INFO - PROCESS | 1701 | RSS: Main: 271269888 15:57:44 INFO - PROCESS | 1701 | 15:57:47 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:57:48 INFO - PROCESS | 1701 | RSS: Main: 278241280 15:57:48 INFO - PROCESS | 1701 | 15:57:50 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:57:51 INFO - PROCESS | 1701 | RSS: Main: 275394560 15:57:51 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | Cycle 1(1): loaded http://localhost:49288/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49288/tests/kraken/driver.html?testName=ai-astar) 15:57:53 INFO - PROCESS | 1701 | RSS: Main: 336871424 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | __start_tp_report 15:57:53 INFO - PROCESS | 1701 | _x_x_mozilla_page_load 15:57:53 INFO - PROCESS | 1701 | _x_x_mozilla_page_load_details 15:57:53 INFO - PROCESS | 1701 | |i|pagename|runs| 15:57:53 INFO - PROCESS | 1701 | |0;ai-astar;114;114;120;112;111;112;114;113;114;134 15:57:53 INFO - PROCESS | 1701 | |1;audio-beat-detection;154;119;140;191;134;136;134;127;166;115 15:57:53 INFO - PROCESS | 1701 | |2;audio-dft;187;233;259;201;182;183;185;182;183;185 15:57:53 INFO - PROCESS | 1701 | |3;audio-fft;72;78;73;153;54;58;55;67;128;46 15:57:53 INFO - PROCESS | 1701 | |4;audio-oscillator;89;91;143;79;78;91;127;80;79;127 15:57:53 INFO - PROCESS | 1701 | |5;imaging-gaussian-blur;87;85;88;85;88;84;88;86;87;85 15:57:53 INFO - PROCESS | 1701 | |6;imaging-darkroom;97;93;93;93;92;93;93;92;92;93 15:57:53 INFO - PROCESS | 1701 | |7;imaging-desaturate;87;85;84;86;83;83;83;86;84;83 15:57:53 INFO - PROCESS | 1701 | |8;json-parse-financial;60;63;61;61;62;61;61;76;57;55 15:57:53 INFO - PROCESS | 1701 | |9;json-stringify-tinderbox;61;62;62;75;77;73;68;70;65;65 15:57:53 INFO - PROCESS | 1701 | |10;stanford-crypto-aes;80;79;75;80;80;78;78;79;79;84 15:57:53 INFO - PROCESS | 1701 | |11;stanford-crypto-ccm;125;125;128;125;110;141;132;116;130;164 15:57:53 INFO - PROCESS | 1701 | |12;stanford-crypto-pbkdf2;164;162;162;160;163;162;178;162;162;159 15:57:53 INFO - PROCESS | 1701 | |13;stanford-crypto-sha256-iterative;65;66;69;68;66;66;64;68;67;66 15:57:53 INFO - PROCESS | 1701 | __end_tp_report 15:57:53 INFO - PROCESS | 1701 | __start_cc_report 15:57:53 INFO - PROCESS | 1701 | _x_x_mozilla_cycle_collect,1942 15:57:53 INFO - PROCESS | 1701 | __end_cc_report 15:57:53 INFO - PROCESS | 1701 | __startTimestamp1468191473328__endTimestamp 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | ------- Summary: start ------- 15:57:53 INFO - PROCESS | 1701 | Number of tests: 14 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:6.84 (6.0%) stddev-sans-first:7.23 15:57:53 INFO - PROCESS | 1701 | Values: 114.0 114.0 120.0 112.0 111.0 112.0 114.0 113.0 114.0 134.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:138.00 stddev:23.02 (16.7%) stddev-sans-first:23.98 15:57:53 INFO - PROCESS | 1701 | Values: 154.0 119.0 140.0 191.0 134.0 136.0 134.0 127.0 166.0 115.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#2] audio-dft Cycles:10 Average:198.00 Median:186.00 stddev:26.62 (14.3%) stddev-sans-first:27.93 15:57:53 INFO - PROCESS | 1701 | Values: 187.0 233.0 259.0 201.0 182.0 183.0 185.0 182.0 183.0 185.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#3] audio-fft Cycles:10 Average:78.40 Median:62.50 stddev:34.70 (55.5%) stddev-sans-first:36.72 15:57:53 INFO - PROCESS | 1701 | Values: 72.0 78.0 73.0 153.0 54.0 58.0 55.0 67.0 128.0 46.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:79.50 stddev:24.34 (30.6%) stddev-sans-first:25.57 15:57:53 INFO - PROCESS | 1701 | Values: 89.0 91.0 143.0 79.0 78.0 91.0 127.0 80.0 79.0 127.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 15:57:53 INFO - PROCESS | 1701 | Values: 87.0 85.0 88.0 85.0 88.0 84.0 88.0 86.0 87.0 85.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.45 (1.6%) stddev-sans-first:0.50 15:57:53 INFO - PROCESS | 1701 | Values: 97.0 93.0 93.0 93.0 92.0 93.0 93.0 92.0 92.0 93.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.51 (1.8%) stddev-sans-first:1.27 15:57:53 INFO - PROCESS | 1701 | Values: 87.0 85.0 84.0 86.0 83.0 83.0 83.0 86.0 84.0 83.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:61.00 stddev:5.56 (9.1%) stddev-sans-first:5.86 15:57:53 INFO - PROCESS | 1701 | Values: 60.0 63.0 61.0 61.0 62.0 61.0 61.0 76.0 57.0 55.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:69.00 stddev:5.75 (8.3%) stddev-sans-first:5.55 15:57:53 INFO - PROCESS | 1701 | Values: 61.0 62.0 62.0 75.0 77.0 73.0 68.0 70.0 65.0 65.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.50 stddev:2.25 (2.8%) stddev-sans-first:2.37 15:57:53 INFO - PROCESS | 1701 | Values: 80.0 79.0 75.0 80.0 80.0 78.0 78.0 79.0 79.0 84.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:129.00 stddev:14.74 (11.4%) stddev-sans-first:15.54 15:57:53 INFO - PROCESS | 1701 | Values: 125.0 125.0 128.0 125.0 110.0 141.0 132.0 116.0 130.0 164.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:162.00 stddev:5.32 (3.3%) stddev-sans-first:5.63 15:57:53 INFO - PROCESS | 1701 | Values: 164.0 162.0 162.0 160.0 163.0 162.0 178.0 162.0 162.0 159.0 15:57:53 INFO - PROCESS | 1701 | 15:57:53 INFO - PROCESS | 1701 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:1.51 (2.3%) stddev-sans-first:1.50 15:57:53 INFO - PROCESS | 1701 | Values: 65.0 66.0 69.0 68.0 66.0 66.0 64.0 68.0 67.0 66.0 15:57:53 INFO - PROCESS | 1701 | -------- Summary: end -------- 15:57:53 INFO - PROCESS | 1701 | 15:57:54 INFO - TEST-INFO | 1701: exit 0 15:57:54 INFO - TEST-OK | kraken | took 57334ms 15:57:54 INFO - SUITE-END | took 1171s 15:57:54 INFO - Completed test suite (00:19:31) 15:57:54 INFO - javascript benchmark 15:57:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3568.0901370150614, 3634.7637403568765, 3652.561692596889, 3664.2488289900575, 3715.294094122117, 6085.543584231184, 6103.847403814907, 6107.816765497037, 6114.480269177126, 6121.051237058263, 6033.095035744641, 6092.299385570661, 6098.054801505763, 6101.6033957792715, 6131.478824299928, 5349.411564727877, 5442.9727851360785, 5448.101063324567, 5506.5870059745475, 5552.08390615548, 5452.263944367519, 5494.587905907053, 5495.53287970523, 5495.752691128889, 5501.504864562188, 5434.8913021739545, 5490.945090549103, 5501.504864562188, 5519.199716041172, 5522.779088836442, 5480.479354461319, 5497.038018346773, 5511.669299842022, 5517.2551705520045, 5529.364123125852, 5293.285406470154, 5358.062339090644, 5364.570834333283, 5403.945960540384, 5456.127019676833, 4871.293662418965, 5918.112283157518, 6037.849053773647, 6062.393760623946, 6065.666388348639, 6190.350013248634, 6270.2789179244055, 6271.247450305992, 6273.3726627337355, 6274.0, 6209.565330426872, 6348.396902294275, 6352.904706429407, 6365.2679941806355, 6378.362163783585, 6161.952468080437, 6177.042558403499, 6193.318734939153, 6195.132681424604, 6205.844853878599, 6504.902426463606, 6537.738490460423, 6540.705668244846, 6544.803655890331, 6552.213734351908, 6145.262568491809, 6220.875582488325, 6223.315435302113, 6239.656818875005, 6252.656103914328, 6291.559590828598, 6329.461995730415, 6343.365663433712, 6344.333844946214, 6357.268914074848, 6203.348648391944, 6254.937450625435, 6275.497960163176, 6281.309056003836, 6306.1171435998995, 6148.047052706837, 6253.343398943137, 6295.496360291166, 6303.590266632654, 6305.621662700161, 5063.949360506348, 5093.0, 5093.745312734349, 5098.898022039539, 5133.435322114565, 4564.908701825945, 4578.084383123389, 4599.6090332321455, 4611.700239484423, 4624.098300831306, 6000.399960003964, 6068.4841788448475, 6097.603655762361, 6105.633661980295, 6123.142760013601, 5994.910076348859, 6304.085907543472, 6338.968305158444, 6348.047792831112, 6362.872742545123, 6117.755289788359, 6134.9079763803575, 6135.785247516315, 6141.0, 6152.969235153795, 5526.253955716007, 5651.717414129277, 5658.264425624642, 5666.858328541819, 5675.091985282335, 6496.675166241764, 6502.0, 6529.6082235067, 6533.934660653333, 6631.270560238273, 6322.968385158045, 6338.904916426165, 6362.872742545216, 6368.617882927039, 6374.394432528902, 6559.212894452697, 6620.073189753534, 6632.635205063575, 6642.6014439133805, 6664.166979127609, 6181.412765787243, 6241.188645476241, 6289.276733175743, 6299.685015749103, 6307.148534947907, 6331.366863313632, 6358.0, 6374.58565193261, 6375.458086062737, 6380.489560835215, 6182.28903676083, 6229.657368844757, 6231.719572619152, 6233.0, 6245.063240513778, 6144.095330447575, 6146.678464130055, 6217.067439883873, 6233.688315584112, 6233.812985610439, 5105.0, 6212.565120441706, 6250.124982502362, 6254.093156492192, 6259.78090766821, 6111.834843226386, 6215.254169499689, 6236.18929539157, 6240.375962403723, 6247.281562620356, 6122.581127188433, 6158.168647232746, 6183.536234782319, 6192.938070619236, 6196.194494715658], "value": 5906.904919059014, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.4171998541539, 559.6054781379131, 577.9277590301211, 596.71059536125, 625.2778041362226, 32219.355612887728, 32798.67201327989, 39607.0, 39892.0, 40009.399859002064, 35285.47071793925, 42723.57276427235, 43204.351934721, 43249.78375108128, 43626.78186609063, 8956.955215223947, 9599.856002159955, 9817.214622830192, 9840.0, 11546.07631389491, 11659.417029148508, 11701.3564253966, 11712.238704484182, 11724.589639362624, 11725.006748852715, 11650.825237621442, 11757.941210293979, 11760.588379406723, 11775.23460975034, 11805.055595552378, 9140.680076197334, 11745.941270293679, 11769.352685602289, 12009.819852702216, 12012.579559715456, 11575.13186511015, 11634.127440442, 11687.883121168765, 11697.3566453845, 11714.180007399485, 39550.80224598858, 39893.80053099745, 39917.00207494807, 39967.8001609993, 39968.200635987116, 3256.2185075581688, 3369.9831500842583, 3493.6506349365113, 3578.4453409721536, 3726.6459686329754, 27744.0, 28528.144155675363, 29016.564751528746, 29237.122886313446, 29853.0, 2759.958600620972, 2813.282612933709, 2844.8293102413713, 2855.129185598392, 2920.065579014716, 408342.0, 414896.9255153705, 415135.0, 415342.7698584524, 415939.9203003996, 55920.72039639776, 63658.68170659117, 65918.67040664767, 66191.6761664764, 66305.6684716583, 69233.30766692369, 69423.61152777018, 69426.0, 69442.0, 69443.65278173678, 18350.26598936054, 18991.90504047471, 19050.237990480502, 19052.237910483425, 19057.90471047636, 12996.350182490838, 13204.141730787467, 13206.933965330112, 13241.602751917459, 14644.633884152981, 17017.489475315513, 18667.924600224964, 20355.796442035688, 20409.387718368474, 20474.0, 8358.623861926228, 8463.899693039866, 8474.491530508067, 8487.448315859448, 8533.871991920127, 974.181687382602, 994.7314225159166, 1042.1246153231323, 1045.3518818332682, 1112.3270421394939, 28592.428151436856, 28642.71357286442, 29092.709072909423, 29239.976600819296, 29261.0, 1086.3590481615727, 1093.814051611228, 1221.6090850927617, 1244.004796163066, 1249.2254802022621, 14548.98157129012, 14891.851081489263, 14934.925325373304, 15136.243187840564, 15156.31796569157, 506797.9320206751, 514925.0, 638460.0, 641049.0, 641169.0, 63875.0, 64426.6778666113, 64430.355696442435, 64528.35471645317, 64716.352836471975, 1485.8291296500825, 1539.5227479481173, 1560.5396408059423, 1577.9053256804627, 1596.2657177698156, 29467.557986630647, 30641.693583064774, 30972.691132108965, 30987.0, 33299.0, 54167.458325417814, 54905.0, 54940.17589736077, 55080.0, 55117.72441137929, 483356.0, 550999.0, 564664.0, 566550.0, 566786.0, 35355.33941941661, 45131.0, 45352.0, 45683.0, 46746.53253467422, 46081.61755147351, 46406.53593464022, 46497.76751116223, 47019.529804702885, 48155.0, 38198.23603527858, 38298.23403531969, 40033.599664004156, 40376.3943540853, 41631.0, 39260.0, 39451.40822887716, 39465.60534394619, 40450.78647640575, 40675.59324406718, 38991.610083899934, 39176.608233918436, 39265.60734392734, 39802.004949876486, 40315.596844031184, 27812.30469238285, 34339.484907725906, 34529.0, 34548.48177277393, 34641.0, 25834.612480812426, 26055.827487763476, 26146.08488702886, 26483.470330592896, 26582.06962756294], "value": 21075.79418074698, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.8672771166236, 388.56868875548156, 419.85724853549794, 420.36105120217263, 421.6668831623016, 16009.359625614961, 18936.526586835334, 19333.0, 20798.89600551999, 20804.99999999998, 20489.487762805937, 20807.791922080774, 21863.672044919298, 21957.341279761593, 21958.780412195872, 5851.180834683148, 5894.115882617599, 6181.165542651752, 6184.536159788011, 6190.81427557174, 4514.0, 6070.969645151768, 6172.351903050182, 6195.50435965124, 6532.869342613145, 6787.796366109, 6796.490263230253, 6801.353871382211, 6805.727770889183, 6807.727690892359, 5459.754311056012, 6607.702653380586, 6688.130543029375, 6693.497987650946, 6741.528093033489, 5916.852078698024, 5916.852078698024, 5942.0, 5955.106733989936, 5969.403059693996, 26710.732892671214, 27233.591496127574, 27594.862025689745, 27775.305617359525, 27793.305167370774, 2428.259380888828, 2444.4133407982135, 2453.9386515337083, 2576.3559110222445, 2692.273086266697, 15375.308111135026, 15837.841621583868, 15855.207239637972, 15969.600759980976, 16163.595910102224, 1801.7927938287135, 1833.2117189608464, 1849.2787812753038, 1868.4979806078604, 1877.915493802782, 112072.4396378021, 114808.0, 117243.0, 119428.80571194264, 120201.0, 10118.038786315288, 11730.12024098196, 12155.392230388445, 12182.147249692436, 12194.39028048594, 14277.571672849832, 14569.708605827824, 15130.848691513163, 16115.0, 16205.675886482204, 9135.269178465707, 9151.496667683208, 9255.352125351295, 9366.0, 9496.240300775922, 8096.10942796292, 9482.241420686467, 9613.759656008655, 9619.56711947964, 9706.272029597667, 4917.188663870421, 4942.117993689196, 4950.752462376867, 4959.0, 4970.478099799541, 1526.6488707597314, 2035.745531808524, 2065.9276925307845, 2078.605065037638, 2079.5632917087582, 939.1922946266205, 969.767255858603, 1007.8790545134631, 1015.5074788727461, 1025.553884060436, 20914.16343346249, 21390.78609213888, 21762.89118554397, 21855.125794968342, 21902.23342183016, 978.1245785022365, 1008.7528555503802, 1011.7521207304258, 1021.9029192226726, 1056.234230183111, 11015.559377624806, 11533.538658453568, 11608.0, 11609.30344179352, 11610.6516804496, 628439.0, 643640.0, 644108.0, 645244.0, 650814.7459262768, 120160.3991980052, 131487.0, 132767.3361633205, 134793.3260333692, 140717.0, 989.1098011789447, 999.14073896449, 1001.5993602558889, 1002.9398236105857, 1023.7952409518126, 17246.310147594457, 17844.107794609958, 18494.81505184931, 19080.809191908458, 19635.0, 40772.0, 50954.980900381044, 54258.45741542534, 54953.45046549483, 55127.173092404446, 74207.0, 74587.50824983361, 76069.6196519036, 76173.0, 77618.83571746315, 8255.752327430188, 8445.577721114043, 8537.615807288563, 8632.179942905414, 8804.03155652865, 9483.193928516153, 11001.394923279297, 12113.273203607812, 12817.743645127232, 13172.209667419986, 11178.776424471302, 11326.207165498665, 11350.54597816078, 11531.71170720705, 11680.06559475223, 8908.821823563621, 9735.26985476078, 11260.436978151223, 11389.487473063566, 11618.76762464763, 10883.796236145638, 11669.88330116688, 11697.824532632187, 11934.761304774029, 11996.0, 8367.707130250412, 8454.0, 8674.956625217086, 8721.781955451164, 8915.242204412698, 10749.838752418877, 11202.495887685236, 11333.886661133613, 11429.942850285695, 11677.708057298636], "value": 10034.87265705054, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.8934674572783, 1591.4668586023681, 1647.530453820661, 1975.6937674660433, 2081.3339731285987, 8441.155884411564, 9105.863412048817, 9329.533523323824, 9332.393394429357, 9379.812403751921, 8978.820423591524, 9231.584578693959, 9259.0, 9270.397424167424, 9300.395474294166, 2524.8863801128946, 2548.1081621432445, 2550.4899020195944, 2551.323899166713, 2554.7445255474395, 2492.962605560918, 2564.974350256492, 2566.0505612923243, 2574.060467929211, 2578.2136448383235, 2524.962125568118, 2549.7195308516048, 2577.3943123365993, 2578.793696504275, 2582.7933765298826, 2530.367408147963, 2558.8848501817465, 2567.5891857302836, 2574.4207553300544, 2574.5751950928075, 2531.923822465579, 2546.274311821122, 2556.987215063931, 2557.539642864284, 2568.114000669766, 8936.374453788238, 9241.676541321089, 9317.534123293875, 9336.3464557481, 9353.719388418358, 5039.445660977326, 5833.212516310286, 6008.068749343859, 6012.368701286346, 6019.097135429721, 3477.078574177845, 3507.0, 3662.4689420033947, 3707.8146092695256, 3777.9055523611855, 1060.4696507712317, 1062.3094988257617, 1071.780572243653, 1082.3884505254507, 1086.788076325117, 12589.811152832715, 12652.620421387373, 12669.239845609201, 12717.30054846983, 12717.55488557896, 4741.762911854394, 4809.639277054235, 4824.155772739757, 4874.780634871439, 4908.3619129513645, 9850.655227067018, 9868.358556693793, 9880.555375008142, 9898.901010989943, 9922.355046921926, 5656.91514627281, 5685.459881311269, 5686.175504551899, 5696.82909512707, 5697.088465845528, 4688.179568575486, 4811.109944660249, 5094.261332106824, 5165.225216217597, 5264.8420547382875, 3967.956502401266, 4011.2378648056774, 4071.328230841878, 4105.240530501807, 4108.281050816155, 1257.88050135237, 1261.9179753316005, 1271.650296168561, 1281.91026628137, 1282.6216266201307, 907.7912080221586, 912.0332447605559, 955.441066975813, 978.280963491833, 1013.986013986014, 6074.210352654127, 6192.969035154885, 6250.781222657185, 6266.342034086447, 6277.403646653652, 443.3372108697518, 455.2283878825359, 469.85904228731243, 474.50177313820353, 481.7759741720117, 3932.9213415731524, 4004.7196696230985, 4045.2718510667773, 4128.112455822013, 4129.29801933672, 11168.553257869757, 11619.360935148648, 11705.765884682429, 11712.121590880719, 11746.412679365998, 11410.885891140982, 11432.142589305835, 11442.027427668741, 11482.425878706199, 11488.0, 7739.496932699357, 8547.316214702932, 8557.101504341954, 8701.956490217635, 8762.123787621314, 5968.134620480093, 6102.420270074336, 6158.107074474086, 6158.107074474266, 6175.351588083367, 10045.09594136502, 10088.69733907984, 10205.74485637865, 10239.436830974368, 10268.332558383681, 10061.69814905554, 10421.218408619232, 10529.0, 10728.731781705208, 10738.516766745672, 7714.15144334134, 7884.881726773988, 8331.416800824127, 8612.956935215283, 8788.121187881161, 8634.697785577475, 8649.178328059075, 8667.739967800977, 8713.477191368538, 8716.128387161232, 7554.962225188839, 7601.12587052496, 7750.689972401041, 7795.571243581658, 7804.648790804541, 7476.5514069156025, 7509.887351689838, 7534.623268836646, 7672.462927595015, 7681.692732290646, 7280.126384833854, 7314.232005639331, 7319.451041171827, 7324.414046876131, 7333.633318333956, 4976.477469865757, 4989.975050124726, 5107.080725469451, 5213.661112027706, 5226.398964119175, 5172.948270517247, 5198.402183748766, 5290.735463226746, 5393.137098064448, 5449.754761035683], "value": 4733.005634412923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.18857234059533, 334.3480213583512, 340.0766917818123, 343.9449688049913, 344.0778713049028, 6344.270408902978, 6750.426213771829, 6759.12131422915, 6774.983752437137, 6853.965730171342, 5460.945390546093, 6439.259485159208, 6541.934580654192, 6656.6671666416605, 6840.179178498587, 4993.251012348141, 5024.422191447976, 5026.421961474441, 5032.84901452957, 5046.697198168122, 3845.5962123977, 4907.386576677915, 5013.749312534359, 5018.498150184971, 5023.4976502349655, 4984.476629953857, 4998.2752500887345, 5001.974990125044, 5004.699718016931, 5008.223725322581, 4037.7375470594316, 4952.801887924478, 4973.801047958077, 4975.776090075937, 4976.129177393942, 4251.149770046003, 4253.447051883235, 4254.617084462413, 4261.978690106561, 4271.679624028179, 6501.869962600769, 6504.146318299309, 6521.315261897527, 6532.444742196919, 6537.346265373472, 5557.499825015728, 5879.67661778602, 5896.086106653475, 5897.3218079920925, 5911.29064512257, 6184.5980011299125, 7173.820654483628, 7185.353318201334, 7196.028536147658, 7221.711131554732, 4662.277347011219, 4666.300054991744, 4666.556677115668, 4667.719936803803, 4672.672912896099, 7453.14288856783, 7553.206913274137, 7561.924380756177, 7567.167611562723, 7576.621168941531, 4692.929606055912, 4725.0, 4730.172219861579, 4731.313959475857, 4732.242841145469, 4558.8632341029825, 4625.976870115695, 4783.345243389883, 4878.463369029404, 4880.463149053601, 4629.675922685447, 5029.270755740397, 5631.352394474606, 5685.573581981368, 5693.629914055573, 7057.294270573005, 7074.610896400644, 7077.327653872874, 7083.964580177066, 7085.964570177116, 1564.4993602047236, 1605.6226786705115, 1617.2641448141067, 1631.8939268947481, 1663.6755832612525, 1030.567161792049, 1033.943133127685, 1049.2602715085998, 1050.369778133114, 1056.1181413519644, 6597.505187110986, 6662.133922590129, 6685.130932978779, 6687.364700353459, 6695.163104611924, 7260.273972602803, 7285.125784905739, 7292.270772922771, 7306.926930730731, 7315.085614298213, 6643.634600097041, 6649.235337936199, 6651.201855777241, 6656.567323123982, 6669.533132680763, 3847.9807600962376, 3909.4722212501515, 3909.5113110861143, 3910.843566257318, 3916.7845768483007, 6473.77341793035, 7522.699092036367, 7802.609869506502, 7885.369170466349, 7910.288074073246, 7765.844683106419, 7808.375329973702, 7815.25755053269, 7831.0, 7835.294823465915, 7884.21157884207, 7889.8816517752275, 7893.881591776243, 7897.763067107996, 7912.564808935564, 7241.855162896607, 7541.2458754124145, 7546.849063018599, 7553.811154721176, 7557.130929942907, 7359.411247100112, 7388.593627350332, 7412.0, 7414.4439167063765, 7431.925680743123, 6002.969985150046, 7191.9571662107655, 7615.048118985127, 7876.6061696913775, 7876.8972343870755, 4275.55106713803, 4698.882527936829, 4757.7621118943225, 4763.618910487222, 4772.5227477252, 6255.218097737783, 6871.931280687129, 6975.965120174366, 7476.476646634684, 7485.5508669480005, 7107.253738357398, 7119.82200444993, 7177.354038136593, 7186.928130718835, 7190.352868241884, 7098.822529436806, 7140.571565706075, 7144.0, 7150.213476517475, 7176.461765367514, 5690.630109043064, 6976.0, 6979.302069793183, 6990.6504674767075, 7000.019997200294, 6289.591176573508, 6881.277465866012, 7127.75052873147, 7149.892751608626, 7155.713771449084, 5133.820316289061, 5873.9412605873395, 6285.905711414424, 6939.236683964659, 7013.018177455262], "value": 5186.154039004184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.4098424528207, 749.5877267502874, 758.1698040645491, 766.6090293950084, 806.9314108300794, 15324.540263792076, 15506.612334691637, 15525.0, 15553.45562905297, 15571.844281557194, 14250.358733856976, 14317.713645727079, 16101.436449724262, 16546.420875269367, 16550.917245413755, 795.2643804480861, 968.4576637083221, 979.9804003919917, 985.0445068283753, 986.7335819328777, 797.4417907464768, 968.3076600230869, 995.7560397702573, 998.4508520313834, 998.9800203995916, 1036.5594622285537, 1037.5642230263309, 1040.5005597313293, 1041.463646222196, 1041.9322744021651, 708.4828075504859, 1015.9085682288555, 1035.4563853976647, 1035.7462421706693, 1036.5439206749006, 771.3944553525456, 772.4631381190076, 772.7411317208741, 773.0993392697501, 773.9613019349066, 15791.289391977389, 15842.920785396114, 15844.366225350972, 15858.682826343524, 15875.444359447363, 12724.809127863089, 12759.808602870964, 12792.552260670926, 12917.741645167138, 12979.67550811228, 10621.522031508523, 10641.095506881924, 10745.247832651718, 10896.673099807018, 10921.781564368748, 3571.1072231942017, 3574.3744844652083, 3615.6022837487853, 3647.215848592565, 3842.2507611015862, 87288.69066963937, 88040.55979720125, 88796.5560172195, 90232.54883725605, 90494.5475272626, 9712.271579631555, 9783.461909594898, 9811.558479868423, 9814.705558833246, 9944.254180936314, 22324.888375558345, 22332.44168895758, 22353.888230558743, 22387.664185037236, 22405.77594224037, 7571.091469023752, 7580.772576822704, 7594.848103037908, 7652.120006199247, 7745.341645960157, 4227.830886764556, 4254.574542545721, 4343.131373725268, 4385.846495372647, 4561.771911404483, 3685.6682898538725, 3734.3838266686234, 3735.0662334416397, 3744.4570537271943, 3760.4547340635454, 600.3846057790712, 655.6787174284566, 687.6149356360454, 687.7042871565175, 695.4818660098259, 565.4882331490008, 608.8143116349556, 624.9906251406233, 626.5050610018126, 631.9494440444753, 6731.596104233761, 8282.23761873223, 8465.576721163918, 8519.659213631385, 8534.957325213334, 592.1680039544447, 597.9910301345483, 613.7054213977316, 616.4267231474771, 620.9192804935329, 3384.526166336715, 3856.305864944337, 3897.6029741708644, 3946.940795888064, 3975.662068724133, 85399.86500337541, 88446.0, 89408.0, 89601.55199223962, 97223.51388243014, 71875.6406217976, 72555.0, 75100.62449687826, 75384.62307688427, 75961.24038759652, 57634.0, 63506.507142106704, 66502.33497665059, 67137.66431167813, 67548.0, 8563.529005904735, 8631.87052194205, 8737.0, 8804.427712198687, 8870.51212183336, 46468.53531464642, 47953.0, 49535.50464495453, 49792.50207497879, 50041.24938126004, 64354.0, 68829.65585172242, 69461.61076778335, 69750.0, 70153.29846701468, 10013.449260290754, 10840.39023423965, 10911.508982095665, 11130.777384452427, 11577.536898523964, 11749.530018799152, 11814.822777658514, 11992.940035299767, 12171.81742273849, 12471.93764031174, 8529.609690444302, 12005.219660722025, 12143.0, 12165.026797856328, 12176.817347739614, 11550.075993920635, 11661.76676466449, 11765.823512647488, 11771.470283837076, 12240.081993850676, 11508.94245528767, 11654.184207105422, 11762.0, 11763.764724705286, 12210.38948052576, 9635.181009614262, 9759.951200243953, 9977.950110249401, 10007.499625018865, 10241.539130739284, 9599.376040557341, 9800.460974646463, 9919.900800991898, 10020.899791001997, 10073.943557407905], "value": 7113.488058310308, "unit": "score"}], "name": "dromaeo_css", "value": 7758.774974684743, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 120.0, 112.0, 111.0, 112.0, 114.0, 113.0, 114.0, 134.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 140.0, 191.0, 134.0, 136.0, 134.0, 127.0, 166.0, 115.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 233.0, 259.0, 201.0, 182.0, 183.0, 185.0, 182.0, 183.0, 185.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 73.0, 153.0, 54.0, 58.0, 55.0, 67.0, 128.0, 46.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 143.0, 79.0, 78.0, 91.0, 127.0, 80.0, 79.0, 127.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 86.0, 83.0, 83.0, 83.0, 86.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 61.0, 61.0, 62.0, 61.0, 61.0, 76.0, 57.0, 55.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 75.0, 77.0, 73.0, 68.0, 70.0, 65.0, 65.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 75.0, 80.0, 80.0, 78.0, 78.0, 79.0, 79.0, 84.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 128.0, 125.0, 110.0, 141.0, 132.0, 116.0, 130.0, 164.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 160.0, 163.0, 162.0, 178.0, 162.0, 162.0, 159.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 69.0, 68.0, 66.0, 66.0, 64.0, 68.0, 67.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1464.2, "alertThreshold": 2.0}]} 15:57:54 INFO - Return code: 0 15:57:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:57:54 ERROR - # TBPL SUCCESS # 15:57:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:57:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:57:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:57:54 INFO - 2016-07-10 15:57:54.643 screenresolution[1705:19383] starting screenresolution argv=screenresolution get 15:57:54 INFO - 2016-07-10 15:57:54.645 screenresolution[1705:19383] Display 0: 1600x1200x32@60 15:57:54 INFO - 2016-07-10 15:57:54.651 screenresolution[1706:19386] starting screenresolution argv=screenresolution list 15:57:54 INFO - Available Modes on Display 0 15:57:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:57:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:57:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:57:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:57:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:57:54 INFO - Intel Iris: 15:57:54 INFO - Chipset Model: Intel Iris 15:57:54 INFO - Type: GPU 15:57:54 INFO - Bus: Built-In 15:57:54 INFO - VRAM (Dynamic, Max): 1536 MB 15:57:54 INFO - Vendor: Intel (0x8086) 15:57:54 INFO - Device ID: 0x0a2e 15:57:54 INFO - Revision ID: 0x0009 15:57:54 INFO - Displays: 15:57:54 INFO - Display: 15:57:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:57:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:57:54 INFO - Display Serial Number: 1600x1200 60 15:57:54 INFO - Main Display: Yes 15:57:54 INFO - Mirror: Off 15:57:54 INFO - Online: Yes 15:57:54 INFO - Rotation: Supported 15:57:54 INFO - Return code: 0 15:57:54 INFO - Running post-action listener: _resource_record_post_action 15:57:54 INFO - Running post-run listener: _resource_record_post_run 15:57:55 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 31583744; Write bytes: 493702144; Read time: 192; Write time: 1502 15:57:55 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 187142656; Write bytes: 186890240; Read time: 17521; Write time: 403 15:57:55 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29818368; Write bytes: 296023040; Read time: 178; Write time: 1064 15:57:55 INFO - Running post-run listener: _upload_blobber_files 15:57:55 INFO - Blob upload gear active. 15:57:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:57:55 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 15:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:57:56 INFO - (blobuploader) - INFO - Open directory for files ... 15:57:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:57:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:57:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:57 INFO - (blobuploader) - INFO - Done attempting. 15:57:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:57:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:58 INFO - (blobuploader) - INFO - Done attempting. 15:57:58 INFO - (blobuploader) - INFO - Iteration through files over. 15:57:58 INFO - Return code: 0 15:57:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:57:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:57:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed44d37f1cda798154a8670ccbb8f2f60921201e783da80a42c3edea2994c450987b4d857017a15a62593ff6c2ac1474b1b68fae666ae508e4f1555a85d2fc94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c32ad17ff32f252106445d404a5d5b8b22124bb2bf1581efd844aceb4985893c8a33d2d462e46be0adfc7e2f01f71a6e89dec8041a83097795de104a2d0c71b"} 15:57:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:57:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:57:58 INFO - Contents: 15:57:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed44d37f1cda798154a8670ccbb8f2f60921201e783da80a42c3edea2994c450987b4d857017a15a62593ff6c2ac1474b1b68fae666ae508e4f1555a85d2fc94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c32ad17ff32f252106445d404a5d5b8b22124bb2bf1581efd844aceb4985893c8a33d2d462e46be0adfc7e2f01f71a6e89dec8041a83097795de104a2d0c71b"} 15:57:58 INFO - Running post-run listener: copy_logs_to_upload_dir 15:57:58 INFO - Copying logs to upload dir... 15:57:58 INFO - mkdir: /builds/slave/test/build/upload/logs 15:57:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1315.133845 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2016-07-10 15:57:58.420069) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 15:57:58.423593) ========= 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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed44d37f1cda798154a8670ccbb8f2f60921201e783da80a42c3edea2994c450987b4d857017a15a62593ff6c2ac1474b1b68fae666ae508e4f1555a85d2fc94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c32ad17ff32f252106445d404a5d5b8b22124bb2bf1581efd844aceb4985893c8a33d2d462e46be0adfc7e2f01f71a6e89dec8041a83097795de104a2d0c71b"} build_url:https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010974 build_url: 'https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed44d37f1cda798154a8670ccbb8f2f60921201e783da80a42c3edea2994c450987b4d857017a15a62593ff6c2ac1474b1b68fae666ae508e4f1555a85d2fc94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1c32ad17ff32f252106445d404a5d5b8b22124bb2bf1581efd844aceb4985893c8a33d2d462e46be0adfc7e2f01f71a6e89dec8041a83097795de104a2d0c71b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 15:57:58.457838) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:57:58.458170) ========= 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.CFGgWHTGZx/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.PH2qTWlbCH/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.004400 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:57:58.506227) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 15:57:58.506554) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 15:57:58.506924) ========= ========= Total master_lag: 0.18 =========