builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0058 starttime: 1462018651.84 results: success (0) buildid: 20160430031536 builduid: d5efdbc96c00466680fa5be674687721 revision: 49d3cd645f038fdf64f52d65414f98c59dff148b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:31.839749) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:31.840206) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:31.840793) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:31.950251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:31.950611) ========= 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.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-30 05:17:31-- 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% 13.1M=0.001s 2016-04-30 05:17:32 (13.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.526500 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:32.529761) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:32.530059) ========= 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.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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.026432 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:32.595993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:32.596332) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 49d3cd645f038fdf64f52d65414f98c59dff148b --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 49d3cd645f038fdf64f52d65414f98c59dff148b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-30 05:17:32,676 truncating revision to first 12 chars 2016-04-30 05:17:32,676 Setting DEBUG logging. 2016-04-30 05:17:32,676 attempt 1/10 2016-04-30 05:17:32,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/49d3cd645f03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-30 05:17:33,279 unpacking tar archive at: mozilla-beta-49d3cd645f03/testing/mozharness/ program finished with exit code 0 elapsedTime=0.853577 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:33.473943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:33.474292) ========= script_repo_revision: 49d3cd645f038fdf64f52d65414f98c59dff148b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:33.474692) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:33.474982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-30 05:17:33.492211) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-04-30 05:17:33.492572) ========= /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.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:17:33 INFO - MultiFileLogger online at 20160430 05:17:33 in /builds/slave/test 05:17:33 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 05:17:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:33 INFO - {'append_to_log': False, 05:17:33 INFO - 'base_work_dir': '/builds/slave/test', 05:17:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:17:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:33 INFO - 'branch': 'Mozilla-Beta', 05:17:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:33 INFO - 'config_files': ('talos/mac_config.py',), 05:17:33 INFO - 'default_actions': ('clobber', 05:17:33 INFO - 'read-buildbot-config', 05:17:33 INFO - 'download-and-extract', 05:17:33 INFO - 'populate-webroot', 05:17:33 INFO - 'create-virtualenv', 05:17:33 INFO - 'install', 05:17:33 INFO - 'run-tests'), 05:17:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:33 INFO - 'download_minidump_stackwalk': True, 05:17:33 INFO - 'download_symbols': 'ondemand', 05:17:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:33 INFO - 'installer_path': 'installer.exe', 05:17:33 INFO - 'log_level': 'info', 05:17:33 INFO - 'log_name': 'talos', 05:17:33 INFO - 'log_to_console': True, 05:17:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:33 INFO - 'opt_config_files': (), 05:17:33 INFO - 'pip_index': False, 05:17:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:33 INFO - 'cmd': ('bash', 05:17:33 INFO - '-c', 05:17:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:33 INFO - 'enabled': True, 05:17:33 INFO - 'halt_on_failure': False, 05:17:33 INFO - 'name': 'check_screen_resolution'},), 05:17:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:33 INFO - 'cmd': ('bash', 05:17:33 INFO - '-c', 05:17:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:33 INFO - 'enabled': True, 05:17:33 INFO - 'halt_on_failure': False, 05:17:33 INFO - 'name': 'check_screen_resolution'},), 05:17:33 INFO - 'run_cmd_checks_enabled': True, 05:17:33 INFO - 'sps_profile': False, 05:17:33 INFO - 'sps_profile_interval': 0, 05:17:33 INFO - 'suite': 'dromaeojs', 05:17:33 INFO - 'system_bits': '32', 05:17:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:33 INFO - 'title': 't-yosemite-r7-0058', 05:17:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:33 INFO - 'use_talos_json': True, 05:17:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:33 INFO - 'work_dir': 'build'} 05:17:33 INFO - ##### 05:17:33 INFO - ##### Running clobber step. 05:17:33 INFO - ##### 05:17:33 INFO - Running pre-action listener: _resource_record_pre_action 05:17:33 INFO - Running main action method: clobber 05:17:33 INFO - rmtree: /builds/slave/test/build 05:17:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:35 INFO - Running post-action listener: _resource_record_post_action 05:17:35 INFO - ##### 05:17:35 INFO - ##### Running read-buildbot-config step. 05:17:35 INFO - ##### 05:17:35 INFO - Running pre-action listener: _resource_record_pre_action 05:17:35 INFO - Running main action method: read_buildbot_config 05:17:35 INFO - Using buildbot properties: 05:17:35 INFO - { 05:17:35 INFO - "project": "", 05:17:35 INFO - "product": "firefox", 05:17:35 INFO - "script_repo_revision": "production", 05:17:35 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:17:35 INFO - "repository": "", 05:17:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:17:35 INFO - "buildid": "20160430031536", 05:17:35 INFO - "pgo_build": "False", 05:17:35 INFO - "basedir": "/builds/slave/test", 05:17:35 INFO - "buildnumber": 8, 05:17:35 INFO - "slavename": "t-yosemite-r7-0058", 05:17:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:17:35 INFO - "platform": "yosemite_r7", 05:17:35 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:17:35 INFO - "branch": "mozilla-beta", 05:17:35 INFO - "repo_path": "releases/mozilla-beta", 05:17:35 INFO - "revision": "49d3cd645f038fdf64f52d65414f98c59dff148b", 05:17:35 INFO - "stage_platform": "macosx64", 05:17:35 INFO - "builduid": "d5efdbc96c00466680fa5be674687721", 05:17:35 INFO - "slavebuilddir": "test" 05:17:35 INFO - } 05:17:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 05:17:35 INFO - Running post-action listener: _resource_record_post_action 05:17:35 INFO - ##### 05:17:35 INFO - ##### Running download-and-extract step. 05:17:35 INFO - ##### 05:17:35 INFO - Running pre-action listener: _resource_record_pre_action 05:17:35 INFO - Running main action method: download_and_extract 05:17:35 INFO - mkdir: /builds/slave/test/build/tests 05:17: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')]} 05:17:35 INFO - https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 05:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 05:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 05:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 05:17:36 INFO - Downloaded 1241 bytes. 05:17:36 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 05:17:36 INFO - Using the following test package requirements: 05:17:36 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 05:17:36 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 05:17:36 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 05:17:36 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:17:36 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 05:17:36 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 05:17:36 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 05:17:36 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 05:17:36 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 05:17:36 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 05:17:36 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 05:17:36 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 05:17:36 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 05:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:36 INFO - https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 05:17:43 INFO - Downloaded 17745660 bytes. 05:17:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:46 INFO - Return code: 0 05:17:46 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:46 INFO - https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 05:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 05:17:50 INFO - Downloaded 17745660 bytes. 05:17:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:17:54 INFO - Return code: 0 05:17: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')]} 05:17:54 INFO - https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 05:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 05:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 05:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 05:17:55 INFO - Downloaded 10845194 bytes. 05:17:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:17:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:17:56 INFO - Return code: 0 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 05:17:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 05:17:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 05:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 05:18:03 INFO - Downloaded 88281046 bytes. 05:18:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 05:18:03 INFO - mkdir: /builds/slave/test/properties 05:18:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:03 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:03 INFO - Contents: 05:18:03 INFO - build_url:https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg 05:18:04 INFO - Running post-action listener: _resource_record_post_action 05:18:04 INFO - Running post-action listener: set_extra_try_arguments 05:18:04 INFO - ##### 05:18:04 INFO - ##### Running populate-webroot step. 05:18:04 INFO - ##### 05:18:04 INFO - Running pre-action listener: _resource_record_pre_action 05:18:04 INFO - Running main action method: populate_webroot 05:18:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:04 INFO - '--tppagecycles', 05:18:04 INFO - '7'], 05:18:04 INFO - 'tests': ['tsvgm']}}, 05:18:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:18:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:18:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'tests': ['damp', 'tps']}, 05:18:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['damp', 'tps']}, 05:18:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:18:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:04 INFO - 'other': {'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'other_l64': {'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'other_nol64': {'tests': ['a11yr', 05:18:04 INFO - 'ts_paint', 05:18:04 INFO - 'tpaint', 05:18:04 INFO - 'sessionrestore', 05:18:04 INFO - 'sessionrestore_no_auto_restore', 05:18:04 INFO - 'tabpaint']}, 05:18:04 INFO - 'svgr': {'tests': ['tsvgx', 05:18:04 INFO - 'tsvgr_opacity', 05:18:04 INFO - 'tart', 05:18:04 INFO - 'tscrollx', 05:18:04 INFO - 'cart']}, 05:18:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['tsvgx', 05:18:04 INFO - 'tsvgr_opacity', 05:18:04 INFO - 'tart', 05:18:04 INFO - 'tscrollx', 05:18:04 INFO - 'cart']}, 05:18:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'tests': ['tp5o']}, 05:18:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'talos_options': ['--e10s'], 05:18:04 INFO - 'tests': ['tp5o']}, 05:18:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'talos_options': ['--xperf_path', 05:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:04 INFO - 'tests': ['tp5n']}, 05:18:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:04 INFO - 'talos_options': ['--e10s', 05:18:04 INFO - '--xperf_path', 05:18:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:04 INFO - 'tests': ['tp5n']}}, 05:18:04 INFO - 'talos.zip': {'path': '', 05:18:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:04 INFO - Running post-action listener: _resource_record_post_action 05:18:04 INFO - ##### 05:18:04 INFO - ##### Running create-virtualenv step. 05:18:04 INFO - ##### 05:18:04 INFO - Running pre-action listener: _resource_record_pre_action 05:18:04 INFO - Running main action method: create_virtualenv 05:18:04 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:18:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:04 INFO - Using real prefix '/tools/python27' 05:18:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:05 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:08 INFO - Installing pip.................done. 05:18:08 INFO - Return code: 0 05:18:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:08 INFO - 'HOME': '/Users/cltbld', 05:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:08 INFO - 'LOGNAME': 'cltbld', 05:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:08 INFO - 'MOZ_NO_REMOTE': '1', 05:18:08 INFO - 'NO_EM_RESTART': '1', 05:18:08 INFO - 'PAGER': '/bin/cat', 05:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:08 INFO - 'PWD': '/builds/slave/test', 05:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:08 INFO - 'SHELL': '/bin/bash', 05:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:08 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:08 INFO - 'USER': 'cltbld', 05:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:08 INFO - 'XPC_FLAGS': '0x0', 05:18:08 INFO - 'XPC_SERVICE_NAME': '0', 05:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:18:08 INFO - Cleaning up... 05:18:08 INFO - Return code: 0 05:18:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:08 INFO - 'HOME': '/Users/cltbld', 05:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:08 INFO - 'LOGNAME': 'cltbld', 05:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:08 INFO - 'MOZ_NO_REMOTE': '1', 05:18:08 INFO - 'NO_EM_RESTART': '1', 05:18:08 INFO - 'PAGER': '/bin/cat', 05:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:08 INFO - 'PWD': '/builds/slave/test', 05:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:08 INFO - 'SHELL': '/bin/bash', 05:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:08 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:08 INFO - 'USER': 'cltbld', 05:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:08 INFO - 'XPC_FLAGS': '0x0', 05:18:08 INFO - 'XPC_SERVICE_NAME': '0', 05:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:08 INFO - Downloading/unpacking psutil>=0.7.1 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:11 INFO - Installing collected packages: psutil 05:18:11 INFO - Running setup.py install for psutil 05:18:12 INFO - building 'psutil._psutil_osx' extension 05:18:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:18:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:18:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:18:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:18:12 INFO - building 'psutil._psutil_posix' extension 05:18:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:18:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:12 INFO - ^ 05:18:12 INFO - 1 warning generated. 05:18:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:18:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:13 INFO - Successfully installed psutil 05:18:13 INFO - Cleaning up... 05:18:13 INFO - Return code: 0 05:18:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:13 INFO - 'HOME': '/Users/cltbld', 05:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:13 INFO - 'LOGNAME': 'cltbld', 05:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:13 INFO - 'MOZ_NO_REMOTE': '1', 05:18:13 INFO - 'NO_EM_RESTART': '1', 05:18:13 INFO - 'PAGER': '/bin/cat', 05:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:13 INFO - 'PWD': '/builds/slave/test', 05:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:13 INFO - 'SHELL': '/bin/bash', 05:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:13 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:13 INFO - 'USER': 'cltbld', 05:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:13 INFO - 'XPC_FLAGS': '0x0', 05:18:13 INFO - 'XPC_SERVICE_NAME': '0', 05:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:18:15 INFO - Installing collected packages: mozsystemmonitor 05:18:15 INFO - Running setup.py install for mozsystemmonitor 05:18:15 INFO - Successfully installed mozsystemmonitor 05:18:15 INFO - Cleaning up... 05:18:16 INFO - Return code: 0 05:18:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:16 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:16 INFO - 'HOME': '/Users/cltbld', 05:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:16 INFO - 'LOGNAME': 'cltbld', 05:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:16 INFO - 'MOZ_NO_REMOTE': '1', 05:18:16 INFO - 'NO_EM_RESTART': '1', 05:18:16 INFO - 'PAGER': '/bin/cat', 05:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:16 INFO - 'PWD': '/builds/slave/test', 05:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:16 INFO - 'SHELL': '/bin/bash', 05:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:16 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:16 INFO - 'USER': 'cltbld', 05:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:16 INFO - 'XPC_FLAGS': '0x0', 05:18:16 INFO - 'XPC_SERVICE_NAME': '0', 05:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:18 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:19 INFO - Downloading docopt-0.6.1.tar.gz 05:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:19 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:19 INFO - Running setup.py install for blobuploader 05:18:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:20 INFO - Running setup.py install for requests 05:18:20 INFO - Running setup.py install for docopt 05:18:20 INFO - Successfully installed blobuploader requests docopt 05:18:20 INFO - Cleaning up... 05:18:20 INFO - Return code: 0 05:18:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:20 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:20 INFO - 'HOME': '/Users/cltbld', 05:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:20 INFO - 'LOGNAME': 'cltbld', 05:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:20 INFO - 'MOZ_NO_REMOTE': '1', 05:18:20 INFO - 'NO_EM_RESTART': '1', 05:18:20 INFO - 'PAGER': '/bin/cat', 05:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:20 INFO - 'PWD': '/builds/slave/test', 05:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:20 INFO - 'SHELL': '/bin/bash', 05:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:20 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:20 INFO - 'USER': 'cltbld', 05:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:20 INFO - 'XPC_FLAGS': '0x0', 05:18:20 INFO - 'XPC_SERVICE_NAME': '0', 05:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-LJiWBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-l2PZyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-Oaj657-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-o7ehH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-dp8rjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-ejmfMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-UddrlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-e9Q31O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:21 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-zzg46h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-lEjdl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-1Ap9Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-1ifed2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-t2VgU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-eU3GxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-2UyVes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-vdvsfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:22 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-6JHPEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:22 INFO - Running setup.py install for manifestparser 05:18:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:22 INFO - Running setup.py install for mozcrash 05:18:23 INFO - Running setup.py install for mozdebug 05:18:23 INFO - Running setup.py install for mozdevice 05:18:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Running setup.py install for mozfile 05:18:23 INFO - Running setup.py install for mozhttpd 05:18:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Running setup.py install for mozinfo 05:18:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Running setup.py install for mozInstall 05:18:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:23 INFO - Running setup.py install for mozleak 05:18:24 INFO - Running setup.py install for mozlog 05:18:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Running setup.py install for moznetwork 05:18:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Running setup.py install for mozprocess 05:18:24 INFO - Running setup.py install for mozprofile 05:18:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Running setup.py install for mozrunner 05:18:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:24 INFO - Running setup.py install for mozscreenshot 05:18:25 INFO - Running setup.py install for moztest 05:18:25 INFO - Running setup.py install for mozversion 05:18:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:25 INFO - Cleaning up... 05:18:25 INFO - Return code: 0 05:18:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:25 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:25 INFO - 'HOME': '/Users/cltbld', 05:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:25 INFO - 'LOGNAME': 'cltbld', 05:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:25 INFO - 'MOZ_NO_REMOTE': '1', 05:18:25 INFO - 'NO_EM_RESTART': '1', 05:18:25 INFO - 'PAGER': '/bin/cat', 05:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:25 INFO - 'PWD': '/builds/slave/test', 05:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:25 INFO - 'SHELL': '/bin/bash', 05:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:25 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:25 INFO - 'USER': 'cltbld', 05:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:25 INFO - 'XPC_FLAGS': '0x0', 05:18:25 INFO - 'XPC_SERVICE_NAME': '0', 05:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:25 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-n6shH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:25 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-FEN9kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:25 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-vM62wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:25 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-DInMT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-HKb1o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-Gd2EIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-XQuF9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-vuKghw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-Jnhunz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-vzYIIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-D49OBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-knr8Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:18:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:26 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-3lBKXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:27 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-jQnlnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:27 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-8KbMGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:27 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-lasAmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:27 INFO - Running setup.py (path:/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/pip-6ILvLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:29 INFO - Downloading blessings-1.6.tar.gz 05:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:29 INFO - Installing collected packages: blessings 05:18:29 INFO - Running setup.py install for blessings 05:18:30 INFO - Successfully installed blessings 05:18:30 INFO - Cleaning up... 05:18:30 INFO - Return code: 0 05:18:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:30 INFO - Reading from file tmpfile_stdout 05:18:30 INFO - Current package versions: 05:18:30 INFO - blessings == 1.6 05:18:30 INFO - blobuploader == 1.2.4 05:18:30 INFO - docopt == 0.6.1 05:18:30 INFO - manifestparser == 1.1 05:18:30 INFO - mozInstall == 1.12 05:18:30 INFO - mozcrash == 0.17 05:18:30 INFO - mozdebug == 0.1 05:18:30 INFO - mozdevice == 0.48 05:18:30 INFO - mozfile == 1.2 05:18:30 INFO - mozhttpd == 0.7 05:18:30 INFO - mozinfo == 0.9 05:18:30 INFO - mozleak == 0.1 05:18:30 INFO - mozlog == 3.1 05:18:30 INFO - moznetwork == 0.27 05:18:30 INFO - mozprocess == 0.22 05:18:30 INFO - mozprofile == 0.28 05:18:30 INFO - mozrunner == 6.11 05:18:30 INFO - mozscreenshot == 0.1 05:18:30 INFO - mozsystemmonitor == 0.0 05:18:30 INFO - moztest == 0.7 05:18:30 INFO - mozversion == 1.4 05:18:30 INFO - psutil == 3.1.1 05:18:30 INFO - requests == 1.2.3 05:18:30 INFO - wsgiref == 0.1.2 05:18:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:30 INFO - 'HOME': '/Users/cltbld', 05:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:30 INFO - 'LOGNAME': 'cltbld', 05:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:30 INFO - 'MOZ_NO_REMOTE': '1', 05:18:30 INFO - 'NO_EM_RESTART': '1', 05:18:30 INFO - 'PAGER': '/bin/cat', 05:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:30 INFO - 'PWD': '/builds/slave/test', 05:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:30 INFO - 'SHELL': '/bin/bash', 05:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:30 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:30 INFO - 'USER': 'cltbld', 05:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:30 INFO - 'XPC_FLAGS': '0x0', 05:18:30 INFO - 'XPC_SERVICE_NAME': '0', 05:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:30 INFO - Cleaning up... 05:18:30 INFO - Return code: 0 05:18:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:30 INFO - 'HOME': '/Users/cltbld', 05:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:30 INFO - 'LOGNAME': 'cltbld', 05:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:30 INFO - 'MOZ_NO_REMOTE': '1', 05:18:30 INFO - 'NO_EM_RESTART': '1', 05:18:30 INFO - 'PAGER': '/bin/cat', 05:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:30 INFO - 'PWD': '/builds/slave/test', 05:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:30 INFO - 'SHELL': '/bin/bash', 05:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:30 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:30 INFO - 'USER': 'cltbld', 05:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:30 INFO - 'XPC_FLAGS': '0x0', 05:18:30 INFO - 'XPC_SERVICE_NAME': '0', 05:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:30 INFO - Downloading/unpacking jsonschema 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:33 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:33 INFO - warning: no files found matching '*.txt' 05:18:33 INFO - no previously-included directories found matching 'build' 05:18:33 INFO - no previously-included directories found matching 'dist' 05:18:33 INFO - no previously-included directories found matching '.git*' 05:18:33 INFO - Installing collected packages: jsonschema, functools32 05:18:33 INFO - Running setup.py install for functools32 05:18:33 INFO - warning: no files found matching '*.txt' 05:18:33 INFO - no previously-included directories found matching 'build' 05:18:33 INFO - no previously-included directories found matching 'dist' 05:18:33 INFO - no previously-included directories found matching '.git*' 05:18:34 INFO - Successfully installed jsonschema functools32 05:18:34 INFO - Cleaning up... 05:18:34 INFO - Return code: 0 05:18:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:34 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 0x109ff7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ffe4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca6a494130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, '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.sQ103UV3qw/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.4DvIVvEW1x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:34 INFO - 'HOME': '/Users/cltbld', 05:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:34 INFO - 'LOGNAME': 'cltbld', 05:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:34 INFO - 'MOZ_NO_REMOTE': '1', 05:18:34 INFO - 'NO_EM_RESTART': '1', 05:18:34 INFO - 'PAGER': '/bin/cat', 05:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:34 INFO - 'PWD': '/builds/slave/test', 05:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:34 INFO - 'SHELL': '/bin/bash', 05:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:18:34 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:34 INFO - 'USER': 'cltbld', 05:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:34 INFO - 'XPC_FLAGS': '0x0', 05:18:34 INFO - 'XPC_SERVICE_NAME': '0', 05:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:34 INFO - Downloading/unpacking flake8 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:38 INFO - no previously-included directories found matching 'docs/_build' 05:18:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:38 INFO - Running setup.py install for pep8 05:18:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:38 INFO - no previously-included directories found matching 'docs/_build' 05:18:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:38 INFO - Cleaning up... 05:18:38 INFO - Return code: 0 05:18:38 INFO - Running post-action listener: _resource_record_post_action 05:18:38 INFO - Running post-action listener: _start_resource_monitoring 05:18:38 INFO - Starting resource monitoring. 05:18:38 INFO - ##### 05:18:38 INFO - ##### Running install step. 05:18:38 INFO - ##### 05:18:38 INFO - Running pre-action listener: _resource_record_pre_action 05:18:38 INFO - Running main action method: install 05:18:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:39 INFO - Reading from file tmpfile_stdout 05:18:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:39 INFO - Reading from file tmpfile_stdout 05:18:39 INFO - Output received: 05:18:39 INFO - Usage: mozinstall [options] installer 05:18:39 INFO - Options: 05:18:39 INFO - -h, --help show this help message and exit 05:18:39 INFO - -d DEST, --destination=DEST 05:18:39 INFO - Directory to install application into. [default: 05:18:39 INFO - "/builds/slave/test"] 05:18:39 INFO - --app=APP Application being installed. [default: firefox] 05:18:39 INFO - mkdir: /builds/slave/test/build/application 05:18:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:02 INFO - Reading from file tmpfile_stdout 05:19:02 INFO - Output received: 05:19:02 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:19:02 INFO - Running post-action listener: _resource_record_post_action 05:19:02 INFO - ##### 05:19:02 INFO - ##### Running run-tests step. 05:19:02 INFO - ##### 05:19:02 INFO - Running pre-action listener: _resource_record_pre_action 05:19:02 INFO - Running main action method: run_tests 05:19:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:02 INFO - 2016-04-30 05:19:02.026 screenresolution[1644:7711] starting screenresolution argv=screenresolution get 05:19:02 INFO - 2016-04-30 05:19:02.029 screenresolution[1644:7711] Display 0: 1600x1200x32@60 05:19:02 INFO - 2016-04-30 05:19:02.034 screenresolution[1645:7713] starting screenresolution argv=screenresolution list 05:19:02 INFO - Available Modes on Display 0 05:19:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:02 INFO - Intel Iris: 05:19:02 INFO - Chipset Model: Intel Iris 05:19:02 INFO - Type: GPU 05:19:02 INFO - Bus: Built-In 05:19:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:02 INFO - Vendor: Intel (0x8086) 05:19:02 INFO - Device ID: 0x0a2e 05:19:02 INFO - Revision ID: 0x0009 05:19:02 INFO - Displays: 05:19:02 INFO - Display: 05:19:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:02 INFO - Display Serial Number: 1600x1200 60 05:19:02 INFO - Main Display: Yes 05:19:02 INFO - Mirror: Off 05:19:02 INFO - Online: Yes 05:19:02 INFO - Rotation: Supported 05:19:02 INFO - Return code: 0 05:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:02 INFO - Python 2.7.3 05:19:02 INFO - Return code: 0 05:19:02 INFO - grabbing minidump binary from tooltool 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:02 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 0x109e9bd30>, '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 0x109cffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e92b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:02 INFO - Return code: 0 05:19:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:03 INFO - Return code: 0 05:19:03 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/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:19:03 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/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQ103UV3qw/Render', 05:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:03 INFO - 'HOME': '/Users/cltbld', 05:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:03 INFO - 'LOGNAME': 'cltbld', 05:19:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:03 INFO - 'MOZ_NO_REMOTE': '1', 05:19:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:03 INFO - 'NO_EM_RESTART': '1', 05:19:03 INFO - 'PAGER': '/bin/cat', 05:19:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:03 INFO - 'PWD': '/builds/slave/test', 05:19:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:03 INFO - 'SHELL': '/bin/bash', 05:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4DvIVvEW1x/Listeners', 05:19:03 INFO - 'TMPDIR': '/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/', 05:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:03 INFO - 'USER': 'cltbld', 05:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:03 INFO - 'XPC_FLAGS': '0x0', 05:19:03 INFO - 'XPC_SERVICE_NAME': '0', 05:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:03 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/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:19:03 INFO - mozversion application_buildid: 20160430031536 05:19:03 INFO - mozversion application_changeset: 49d3cd645f038fdf64f52d65414f98c59dff148b 05:19:03 INFO - mozversion application_display_name: Firefox 05:19:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:03 INFO - mozversion application_name: Firefox 05:19:03 INFO - mozversion application_remotingname: firefox 05:19:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:19:03 INFO - mozversion application_vendor: Mozilla 05:19:03 INFO - mozversion application_version: 47.0 05:19:03 INFO - mozversion platform_buildid: 20160430031536 05:19:03 INFO - mozversion platform_changeset: 49d3cd645f038fdf64f52d65414f98c59dff148b 05:19:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:19:03 INFO - mozversion platform_version: 47.0 05:19:03 INFO - using testdate: 1462018743 05:19:03 INFO - actual date: 1462018743 05:19:03 INFO - starting webserver on 'localhost:49270' 05:19:03 INFO - SUITE-START | Running 2 tests 05:19:03 INFO - TEST-START | dromaeo_css 05:19:03 INFO - operating with platform_type : mac_ 05:19:03 INFO - Initialising browser for dromaeo_css test... 05:19:03 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpia5YIW/profile http://localhost:49270/getInfo.html) 05:19:05 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:19:05 INFO - PROCESS | 1663 | colorDepth:24 05:19:05 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:19:05 INFO - PROCESS | 1663 | __metrics 05:19:06 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:06 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:19:06 INFO - TEST-INFO | 1663: exit 0 05:19:06 INFO - Browser initialized. 05:19:06 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:06 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpia5YIW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:18 INFO - PROCESS | 1667 | RSS: Main: 156942336 05:19:18 INFO - PROCESS | 1667 | 05:20:10 INFO - PROCESS | 1667 | 1462018810088 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:22:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:22:20 INFO - PROCESS | 1667 | RSS: Main: 273559552 05:22:20 INFO - PROCESS | 1667 | 05:25:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:25:22 INFO - PROCESS | 1667 | RSS: Main: 294498304 05:25:22 INFO - PROCESS | 1667 | 05:28:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:28:25 INFO - PROCESS | 1667 | RSS: Main: 295223296 05:28:25 INFO - PROCESS | 1667 | 05:31:27 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:31:27 INFO - PROCESS | 1667 | RSS: Main: 251813888 05:31:27 INFO - PROCESS | 1667 | 05:34:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:34:31 INFO - PROCESS | 1667 | RSS: Main: 254160896 05:34:31 INFO - PROCESS | 1667 | 05:37:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:37:34 INFO - PROCESS | 1667 | RSS: Main: 300933120 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | __start_tp_report 05:37:34 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 05:37:34 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 05:37:34 INFO - PROCESS | 1667 | |i|pagename|runs| 05:37:34 INFO - PROCESS | 1667 | |0;dojo.html;2974.717401846825;3611.3860643690555;3632.5822530409005;3643.143861192618;3678.705703543717;5790.797322093727;5792.710364481775;5804.651720896749;5805.129230615405;5814.418558144183;5765.7981970631035;5811.0702287633885;5812.767489300433;5833.270841144857;5843.211166492522;5204.297419848329;5215.295935048767;5224.138017227153;5228.712420816853;5278;5304.151335786275;5309.283246761676;5309.946900531003;5314.4951229633125;5320.388155362125;5243.501867322599;5283.471652834724;5306.522412982831;5309.495597918191;5319.760610772524;5331.147016477365;5360.946390536123;5361.142217245261;5363.704996225226;5381.650192737459;5097.974510127463;5213.765380557884;5258.7370631468275;5264.131418315973;5272.472752724735;5598.608097433182;5654.547636189095;5675.971620141914;5683.374828768832;5716.857078573027;6220.595661282002;6318.178636777189;6333.081703153063;6340.4293613575;6353.047042943549;6207.906881396782;6240.1263823064355;6244.531660125509;6263.843403914893;6317.4314311711705;5934.732937017845;5968.104784282393;5969.611975221596;5978.0733986232635;5980.192673989043;6217.191765070512;6287;6300.905486417708;6302.023186406114;6315.494760419156;5333.386660534011;6245.718942647592;6258.374162583706;6270.874582508324;6288.339724328971;6248.125262463259;6266.216722909637;6269.592476489013;6280.0893870389455;6312.305646378894;6157.384261573897;6210.50315974721;6254.6247225166635;6308.337624549448;6326.019466982625;6293.653849038255;6317.557770955989;6320.55756097078;6338.714757835908;6345.270293916259;4925.1873440881855;4934.185859333242;4935.185694360391;4946.08497427977;4951.975240123848;4403.8238470461465;4441.045175287329;4486.304622783474;4493.303537951622;4520.547945205453;5946.732397042144;5949.524038076944;5957.225560677084;5965.6718880461995;5982.970085149546;6238.906416403757;6257.2491301044165;6264.655443950535;6275.748970041147;6288.937110628835;5776.018076926933;5871.119332100219;5900.43945825146;5917.408259174048;5937.050071988548;5391.595630327741;5395.973020134874;5415.891682166413;5420.4850539198715;5463.426340234298;6234.906476402857;6256.092866534327;6257.499400047986;6276.717547710273;6288.402601752735;6210.782622608187;6240.188775459162;6251.874962500816;6289.40250676185;6315.431611155018;6271.655058971618;6271.905921411274;6295.370462953668;6302.71637776292;6326.8734625306315;6277.905831412441;6278.497720182466;6301.117843502005;6304.590201636879;6316.242050953915;5505.394406615349;6014.789482368096;6223.0354295084335;6240.906386404116;6288.779892703734;6216.720247588779;6217.036359364306;6251.906221406592;6252.874942501034;6259.280182778856;6044.184035155197;6201.844953876189;6241.31345551998;6246.593971391845;6258.812235632939;6065.332813390612;6110.012029148408;6113.877722445615;6122.0627347074505;6126.365000174519;6234.470070044097;6250.656213908279;6265.937340626535;6283.3088360281245;6285.874282514233;6004.819855404345;6106.084087386927;6145.139680444652;6148.047052706837;6150.661713605795 05:37:34 INFO - PROCESS | 1667 | |1;ext.html;474.16073549816804;601.2814686449692;602.4878852974969;628.9653519959667;634.543128947158;32247;32750.8362458188;32756.836215818916;32761.83619081907;32803.67196328036;33551.66448335516;34434;42560;42870.35694464586;42884.356734649;9767.706968790948;9950.502474876228;9961.953994830546;9962.203023758082;9977.351472154287;9702.320837541374;9874.061964113434;9879.06148915852;9881.752956176117;9892.554835032404;9819.509024548744;9887.307888447811;9896;9899.109080182781;9914.405135691846;9817.901820981806;10005.099541041305;10011.399316041061;10020.94780048099;10024.59901603935;9822.65620703272;9937.950310248403;9965.302428829984;9969.601215951354;9981.800363992752;43135.13729725388;43565.34651980223;43586.56413435889;43611.34582981258;43663;3280.5407242986003;3377.307651931348;3613.0425437259146;3821.6560509554274;3981.98009009956;28732.569011464846;29108.854455727796;29123.271918202;29781.702182978326;30696.2325941851;2828;2839.9290017749513;2878.7409133177907;2913.810602310843;2933.574631678395;477141.9999999965;478756;480204.5989770064;480571;480945;48795.5120448791;49624.255636165486;72402.63798680973;72642.63678681572;72858.63570682112;48130.51869481261;48243;48476;48637.7840553989;48643.75678121587;17194;17295.4811355657;17345.826541734674;17386.21762020687;17420.82579174192;13916.512922047883;13987.930060349634;14009.299535023412;14017.649558761113;14067.155970641794;15477.613059673373;15664.608384790243;15684.68630627381;15693.058416495047;15969.201539922957;6327.4938004959495;6384.138141350951;6392.520560957947;6441.259255185713;6463.159789227464;880.8766772651834;921.8156368726281;936.8126374725082;940.501534186882;945.7588314979636;30102.548461772654;30631.2342191447;30697.539536906963;31155.844220778752;31364.686353136178;1119.2668801934747;1131.671815173607;1151.3034614058543;1293.2499150492692;1320.2606540337442;15617.14105724196;15787.68424631501;15794.289256983464;15955.680886382208;15999.68000640004;551890;555667;557701.21149394;558131;558766;63349.68325158437;63991;64003;64046;64092.038619420746;1398.7761958086658;1518.962025949338;1529.17424590722;1532.9923350383176;1557.0891028748308;29930.700692992792;29933.401331972804;29979.550306745852;30587.54118688177;30711.078667640006;49783.253251200535;60175.49561261004;61345.69327153514;61472.77054458796;61504.384956151654;421299;425438;431080;431109;431465;37648.811755941046;38503.61496385001;44941;47282.290765639234;47930;47394.289085663055;48180.55458336256;48193.75903120602;49008;49806.75096624494;38885.80557097196;39726.404103937886;40640.1871962565;40813.38779918244;40813.59186408217;37476.43785343168;37955.430668540546;38166;38169.427458588696;38401.42397863978;38779.6122038776;38933;39007;39494;39584;29213.269668258465;33709.83145084259;34406.82796586001;34439.4834077494;34594.65405345914;25261.87369063143;25717.742822571534;25720.61419078678;25893.611595826453;26210.737892621593 05:37:34 INFO - PROCESS | 1667 | |2;jquery.html;325.89245548968836;373.65810283590514;374.1730774987277;381.9980900095499;382.97893615851115;17303.221355039022;20590.38228853135;21460.57078858422;22193.334199973986;22253.44366390837;22465.55068898621;22638.20766273181;22920.656190157162;22978.851057447104;23075.192368267115;5259.894802103956;5439.646422982513;5805.390434004432;6108.725107370156;6167.136600875881;5964.314103878044;6097.5426842986735;6108.114323423101;6110.3889611038985;6111.052786818051;5737.827865164052;6026.608270462428;6060.212172417601;6064;6072.666003369835;5901.586888917777;5935;5943.227380440559;5977.970110149443;5979.1330257112695;4512.2329204035395;5226.7909283628605;5231.686098834044;5234.241035049897;5236.581073514109;13894.513692020731;13975.37110830015;14161.22113283769;14173.078749881224;14186.929065354607;2147.6349020666366;2324.1633012115626;2463.6674049003514;2600.154949641374;2816;10087.646932357406;10315.02007309312;10417;12601.936990315082;15200.40194805513;1765.1174412793603;1785.1609743420572;1854.3046357615895;1884.4440889937364;1918.4820098573446;112905;114767.42616286865;115308;116830.41584792106;117218;11538.19232653723;11559.248648837798;11568.305901645928;11613.535458581562;11745.530178792924;14508.854911450962;14564.635884102981;14591;14614.707705846036;14617.853821461862;8536;8936.151065648754;8938.46369217836;8976.281897448189;9070.8683459387;9122.680706175252;9144.908550914539;9171.862422063674;9230.215431688383;9248.121428464285;4456.153330867125;4550.2264615014865;4572.634189264851;4584.312353146988;4603.74416535895;1870.653929023135;1910.1595298068805;1911;1914.818092281231;1932.4878907089633;423.3903179421623;431.67192933370865;468.1760102220117;897.4570384917162;1001.7846163074973;11942.522299108112;11967;12112.031037516977;12215.450304736309;12309.50761969529;452.39605127155613;467.38772208406414;467.772370642569;472.5463554987182;483.5068230404967;6372.139761132281;6436.935630643728;6491.156149700509;6496.675166241669;6533.771318003847;670408;678885;683248;688431;692507.5374623095;91202;106901;113460;129266;135461;470.24525636353843;479.58036717871164;480.0447110250576;480.6419217682779;480.76442543153604;9735.561899714672;9789.46157961319;11778;11782.646520604396;11944.701382465508;46949.30506949278;47514.76242618903;48239.27641085457;48450.51549484601;52925.470745292056;60465.395346045974;64309.67845160744;64902.35097649152;65797.67101164463;69799;8049.637766300413;8308.958455207685;8372.166887509446;8385.58072096405;8530.232279094911;8769.772231887668;8789.252913502169;8932.330075244512;9365.250779937725;12984.740305194033;8058.556779376956;8295.087540370434;8496.150384961455;8500.064992850905;9011.684591039282;8340.457870238415;8474;8626.82746345057;8680.652773888974;8713;8384.538850363044;8386.748397548083;8420.663173472993;8501.702440414556;9225.769355766159;10735.516901739284;11929.582464613697;12350.88842004224;12535.561255356019;12625.116241862981;11692.415379230833;12023.75952480963;12135.817962730389;12455.128141030042;12474.937625312179 05:37:34 INFO - PROCESS | 1667 | |3;mootools.html;1498.38566187863;1645.0787558966977;1656.4202529114812;2021.4946263434142;2093.4766308422886;7518.774436766899;8313.584320783959;9131.589078491466;9136.954315228415;9150.222231110347;8841.248493878013;9078.682246121387;9085.772855678611;9090.681826136086;9096.54517274137;2567.884445199961;2581.7289184635624;2605.114261151208;2605.257501612041;2605.869706514676;2510.9372265693323;2573.6268241105026;2580.419405633727;2582.9612555811677;2617.4241666833364;2346.3195673254736;2504.524140413325;2629.0272599138348;2629.290091675256;2630.7500787425165;2592.2871210417097;2606.6741657292837;2616.5944278636844;2619.1749598876295;2624.6850377954574;2579.406736450627;2605.817592768488;2617.1101825379274;2626.619140224676;2627.1987043951585;9108.726738197865;9160.175584197388;9174.220191283748;9180.724578262596;9183.770405739842;5598.524125449342;5662.433756624346;5742.368339482654;5802.883942321172;5808.157817116495;3662.967978256006;3735.6637902588627;3813.3326667833294;3836.3669994450884;3837.6354246346555;1063.8510608514814;1089.2484185911696;1090.6946055104584;1104.7735214281054;1124.5276983666797;12334.5066197352;12337.876621233852;12339.629811105591;12344.074194435363;12347.753044939142;5003.624728145403;5013.39839219296;5111.309973153651;5124.871878203075;5126.512981266774;10490.527926243338;10514.842277365846;10536.789264214673;10539.631112911165;10542.262041657164;4996.625253106032;5810.244668193108;5823.94176058234;5825.679587622722;5831.591788574844;4684.2271025281125;4736.976315118402;5013.799448022111;5205.505476979681;5257.605679574047;4090.579922885716;4120.835166593302;4165.504879506087;4198.5591512890705;4206;1350.6825895914453;1374.17549470317;1381.5717127690448;1382.709630977506;1394.5328315014583;1400.775875859878;1409.83082030155;1435.167602790379;1440.5102265229873;1460.8831293496494;7437.293457121565;8485.957570212233;8573.914260857437;8605.182507661762;8619.870701939475;532.6850190985884;540.716124034877;544.0099019783955;546.5436360638831;547.8657728856456;6391.488680905516;6410.038494225903;6440;6454.806355809334;6462;10702.411367374869;11093.057090147266;11103.44482775858;11238.831417528743;11243.156763242627;10654.946725266323;11009.504572294267;11070.446477676083;11091.72270693239;11114.777704446027;8538.103499132625;8589.828203436022;8590.78523036929;8660.826783464421;8681.348898832613;7922.643481043482;8118.553479558681;8180;8181.590920454072;8183.877241841258;9302.674406395743;9647.228221742384;9718.75703107428;9984.950075249577;10007.749806254902;9875.358101723652;10344.793104137725;10371.377717336984;10397.63608273737;10405.375677459377;7742.65158067877;7766.417518686008;7766.417518686008;7824.139344671968;7843.882341764764;8508.106648802035;8549.529775862387;8596.398252122292;8601.39790214679;8608.440451370641;6875.449964002768;7562.281583249803;7769.92230077692;7784.377249820114;7810.726624568113;7499.212582678959;7557.168711441627;7588.241175882367;7671.884921726067;7720.112187098555;8056.919430805617;8113.878291825746;8137.877931831145;8148.062972757972;8158.184181582032;7066.8233294166;7269.127704675473;7297.1973082961895;7309.817254568678;7324.707011719472;6428.357164283534;7392;7445.069366329209;7450.254974502506;7471 05:37:34 INFO - PROCESS | 1667 | |4;prototype.html;323.4081630615972;330.72054114273436;337.63029482716416;340.2735160432477;342.86970951038603;6570.73717051318;6592;6640.302768209329;6665.600063996164;6667.499937504683;5356.892862142755;6596.307387724291;6600.933990660092;6609.173853268341;6618.205815302171;4741.620670346381;4817.735024573647;4903.117438861003;4937.308776771255;4984.4018717753925;4914.533119353674;4925.655204135712;4940.678855874375;4965.900681986376;4970.478099799523;4889;4910.41075070992;4920.237363208708;4923.876903077434;4928.0143971205725;4023.376376661622;4709.5290470952805;4749.76251187441;4875.293082503053;4886.486918873503;4197.517285512175;4197.538270790211;4208.347706105558;4208.894777630553;4212.431321771553;6375.362463753588;6393.360663933616;6405.743770249184;6410.935890641081;6424.325445828167;5844.532437404998;5854.853628659275;5859.44335288147;5860.179574859523;5863.091220860773;6806.76176333826;7049.506534542584;7057.258987806258;7065.399441047517;7066.823329416806;4039.3738970459626;4779.784909679072;4783.4499032611;4792.0895029944195;4800.207965685658;7428.9257107428775;7461.477696561243;7462.4403169761945;7472.962635186844;7482.738104166328;4861.465238823726;4861.927071093936;4863.708177509361;4864.73243971585;4869.829555965524;4941.135301322254;4944.431390390079;4952.281919121707;4956.826511072095;4958.727270000112;5640.464155905182;5839.824805255849;5841.123831425235;5847.26909136358;5851.414858514199;5723.370429252779;7059.470539709542;7077.646117694095;7086.433085353159;7102.360787529147;1684.6209602839263;1723.1039859273358;1728.585139566495;1729.1786401459458;1731.3853581978217;1045.9644951498115;1080.233034545466;1104.9558017679365;1112.1714322829382;1140.0309736723718;6629.701663425158;6730.32696730323;6752.155980502437;6763.458923286125;6775.525713200127;7326.633668316669;7348.1182258129365;7348.448866335047;7351.264873512606;7362.3373896349585;6721.193456785119;6722.193336799518;6723.159605049369;6723.798286051427;6730.764423245261;3763.6236376362144;3876.321643712339;3910.2179564087296;3915.4909861718365;3921.0197450637343;6410.967945160244;7488.663010164556;7526.5107767994905;7532.435067369969;7545.056867891513;6097.3292937776805;7627;7627.961860190663;7628.656710448043;7630.923690763132;7476.962615186889;7486.064241969754;7491.400687945061;7491.513051651626;7508.43686723487;7099.290070993066;7316.085489313836;7338.669759860926;7340.96329518349;7368.115826100902;7159.964200178966;7187.993680884785;7215.350618444365;7223.747168849065;7229.277072292939;7404;7426.777196684108;7434.182239953709;7443.36731377839;7443.478956473212;4854.150523658275;4855.805767769391;4858.174110401311;4871.853844384674;4883.6093112551625;7059.329363710439;7267.7819665410125;7300.45246606496;7302.452316076209;7304.160021597584;6966.338197871194;7040.225575186623;7043.119610048744;7046.788596342118;7068.3285087916565;6438.806835794933;7024.859502810017;7034.437245020488;7059.399951004222;7067.222605513287;6904.171499420102;6904.964255361535;6909.723611055501;6923.030775691508;6927.3419025192525;6804.523683342119;7058.223595404399;7062.646867656494;7082.822929426806;7117.893231601426;5117.92323115161;5359.276497672764;6679.799606011828;6894.24163342022;6951.818190907659 05:37:34 INFO - PROCESS | 1667 | |5;yui.html;716.122749631701;751.5791156952104;773.4315278270474;778.0896351269016;801.0427539090786;15703.921480392597;15936.442224522145;15960.361585536593;15961.521154365359;15995.200239988013;14644.267786610653;14713.411463541446;14778.852211477883;14786.926065369686;14791.92604036981;776.4215659333804;798.1978111997463;956.9665061722841;982.773961988743;984.5569493727816;962.1388856973005;974.0600320690532;975.2929126383351;975.9658411955583;977.6871401151634;825.9587020648974;1032.256775121911;1035.7462421706693;1038.729930218145;1039.2257767962878;1002.1982414068752;1025.9333143345696;1028.4343611013949;1029.8506716526063;1030.5002073994108;763.175770168217;764.3273918951287;765.2003656179306;765.4985984180371;766.1534004924534;15175.544733657898;15417.15205663688;15464.768028479582;15495.380184792708;15506.689866202612;12960.805587916095;13166.868331316757;13340.533081342106;13644.454221831214;13657.521986730517;10390.740231494274;10480.895191048068;10514.684559463229;10587.682369528926;10817.134629229642;3605.7115430765475;3606.6213047629885;3625.425867822607;3709.8887033388773;3888.786116763605;77996.83004754933;77999;78218.21781782224;79119.60440197762;81536;9850.704478865646;9930;9949.403035817873;10048.246381521416;10740.033396994308;23034.884825575766;23055;23072.423189420064;23072.653910191362;23104.306870793902;7689.923100769032;7867.842643147105;7905.802354941173;7992.840143197103;7999.0801057877925;4222.535521092677;4295.892602684896;4336.739795612273;4398.670099742532;4592.3800286961505;3652.8904132875523;3692.8892133236045;3697.4084146536425;3705.943806015273;3707.7404581679025;669.3975422120034;684.6610927590874;684.934931181547;694.4617921111078;713.0195980526776;608.9360617135226;611.6972098811117;621.586644881158;625.5964902637864;629.3423372575669;7970.2883920336535;8481.109483504266;8508.361872859436;8509.659613615386;8613.956930215309;548.2488990083611;584.6316820403118;606.4026933470528;619.4703528483172;621.996890015547;3814.828332725034;3944.9408258876138;3966.147278335172;3992.6207010333974;4039.8586049488126;86267;88968.99279064915;89037.55481222682;89265.55367223252;89450;82492;82811.5859420699;83219;84404.7797610117;85508;70820.93768593475;71395;73185.6340718293;73943.63028184933;74065;7376.815579610553;8127.634256458356;8432.156784321764;8596.957015214884;8687.348448866234;48735;50817.23774143465;51056.74471627618;51198.48801512086;51601;55850.16224756551;65845.69540066074;69109;72100.27899720936;74213.628931855;11087.833836752628;11104.500297486795;11288;11331.830022549833;11660.883391166319;10204.642837500653;11807.645770626896;12001.039916806809;12045.879541204826;12412.62762117138;11576.47905844256;11833.881661183623;11857.52569897229;11889.881101188877;12064.638060858188;11838.230515016496;11864.466099025392;11952.76094478088;11976.46105925218;12159.45282462309;11543.307401555934;11815.291082535075;11821.468033938665;11920;12265.938670306592;9924.206063514757;10068.993100689873;10158;10342.948285258526;10478.790424191626;9961.452120133463;9976.401415915068;9985.550650220612;10015.649452269136;10021 05:37:34 INFO - PROCESS | 1667 | __end_tp_report 05:37:34 INFO - PROCESS | 1667 | __start_cc_report 05:37:34 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1007 05:37:34 INFO - PROCESS | 1667 | __end_cc_report 05:37:34 INFO - PROCESS | 1667 | __startTimestamp1462019854006__endTimestamp 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | ------- Summary: start ------- 05:37:34 INFO - PROCESS | 1667 | Number of tests: 6 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5817.32 Median:6110.01 stddev:632.09 (10.3%) stddev-sans-first:593.39 05:37:34 INFO - PROCESS | 1667 | Values: 2974.7 3611.4 3632.6 3643.1 3678.7 5790.8 5792.7 5804.7 5805.1 5814.4 5765.8 5811.1 5812.8 5833.3 5843.2 5204.3 5215.3 5224.1 5228.7 5278.0 5304.2 5309.3 5309.9 5314.5 5320.4 5243.5 5283.5 5306.5 5309.5 5319.8 5331.1 5360.9 5361.1 5363.7 5381.7 5098.0 5213.8 5258.7 5264.1 5272.5 5598.6 5654.5 5676.0 5683.4 5716.9 6220.6 6318.2 6333.1 6340.4 6353.0 6207.9 6240.1 6244.5 6263.8 6317.4 5934.7 5968.1 5969.6 5978.1 5980.2 6217.2 6287.0 6300.9 6302.0 6315.5 5333.4 6245.7 6258.4 6270.9 6288.3 6248.1 6266.2 6269.6 6280.1 6312.3 6157.4 6210.5 6254.6 6308.3 6326.0 6293.7 6317.6 6320.6 6338.7 6345.3 4925.2 4934.2 4935.2 4946.1 4952.0 4403.8 4441.0 4486.3 4493.3 4520.5 5946.7 5949.5 5957.2 5965.7 5983.0 6238.9 6257.2 6264.7 6275.7 6288.9 5776.0 5871.1 5900.4 5917.4 5937.1 5391.6 5396.0 5415.9 5420.5 5463.4 6234.9 6256.1 6257.5 6276.7 6288.4 6210.8 6240.2 6251.9 6289.4 6315.4 6271.7 6271.9 6295.4 6302.7 6326.9 6277.9 6278.5 6301.1 6304.6 6316.2 5505.4 6014.8 6223.0 6240.9 6288.8 6216.7 6217.0 6251.9 6252.9 6259.3 6044.2 6201.8 6241.3 6246.6 6258.8 6065.3 6110.0 6113.9 6122.1 6126.4 6234.5 6250.7 6265.9 6283.3 6285.9 6004.8 6106.1 6145.1 6148.0 6150.7 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:63948.39 Median:40726.79 stddev:130435.72 (320.3%) stddev-sans-first:130714.56 05:37:34 INFO - PROCESS | 1667 | Values: 474.2 601.3 602.5 629.0 634.5 32247.0 32750.8 32756.8 32761.8 32803.7 33551.7 34434.0 42560.0 42870.4 42884.4 9767.7 9950.5 9962.0 9962.2 9977.4 9702.3 9874.1 9879.1 9881.8 9892.6 9819.5 9887.3 9896.0 9899.1 9914.4 9817.9 10005.1 10011.4 10020.9 10024.6 9822.7 9938.0 9965.3 9969.6 9981.8 43135.1 43565.3 43586.6 43611.3 43663.0 3280.5 3377.3 3613.0 3821.7 3982.0 28732.6 29108.9 29123.3 29781.7 30696.2 2828.0 2839.9 2878.7 2913.8 2933.6 477142.0 478756.0 480204.6 480571.0 480945.0 48795.5 49624.3 72402.6 72642.6 72858.6 48130.5 48243.0 48476.0 48637.8 48643.8 17194.0 17295.5 17345.8 17386.2 17420.8 13916.5 13987.9 14009.3 14017.6 14067.2 15477.6 15664.6 15684.7 15693.1 15969.2 6327.5 6384.1 6392.5 6441.3 6463.2 880.9 921.8 936.8 940.5 945.8 30102.5 30631.2 30697.5 31155.8 31364.7 1119.3 1131.7 1151.3 1293.2 1320.3 15617.1 15787.7 15794.3 15955.7 15999.7 551890.0 555667.0 557701.2 558131.0 558766.0 63349.7 63991.0 64003.0 64046.0 64092.0 1398.8 1519.0 1529.2 1533.0 1557.1 29930.7 29933.4 29979.6 30587.5 30711.1 49783.3 60175.5 61345.7 61472.8 61504.4 421299.0 425438.0 431080.0 431109.0 431465.0 37648.8 38503.6 44941.0 47282.3 47930.0 47394.3 48180.6 48193.8 49008.0 49806.8 38885.8 39726.4 40640.2 40813.4 40813.6 37476.4 37955.4 38166.0 38169.4 38401.4 38779.6 38933.0 39007.0 39494.0 39584.0 29213.3 33709.8 34406.8 34439.5 34594.7 25261.9 25717.7 25720.6 25893.6 26210.7 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35554.36 Median:23708.74 stddev:112997.22 (476.6%) stddev-sans-first:113283.24 05:37:34 INFO - PROCESS | 1667 | Values: 325.9 373.7 374.2 382.0 383.0 17303.2 20590.4 21460.6 22193.3 22253.4 22465.6 22638.2 22920.7 22978.9 23075.2 5259.9 5439.6 5805.4 6108.7 6167.1 5964.3 6097.5 6108.1 6110.4 6111.1 5737.8 6026.6 6060.2 6064.0 6072.7 5901.6 5935.0 5943.2 5978.0 5979.1 4512.2 5226.8 5231.7 5234.2 5236.6 13894.5 13975.4 14161.2 14173.1 14186.9 2147.6 2324.2 2463.7 2600.2 2816.0 10087.6 10315.0 10417.0 12601.9 15200.4 1765.1 1785.2 1854.3 1884.4 1918.5 112905.0 114767.4 115308.0 116830.4 117218.0 11538.2 11559.2 11568.3 11613.5 11745.5 14508.9 14564.6 14591.0 14614.7 14617.9 8536.0 8936.2 8938.5 8976.3 9070.9 9122.7 9144.9 9171.9 9230.2 9248.1 4456.2 4550.2 4572.6 4584.3 4603.7 1870.7 1910.2 1911.0 1914.8 1932.5 423.4 431.7 468.2 897.5 1001.8 11942.5 11967.0 12112.0 12215.5 12309.5 452.4 467.4 467.8 472.5 483.5 6372.1 6436.9 6491.2 6496.7 6533.8 670408.0 678885.0 683248.0 688431.0 692507.5 91202.0 106901.0 113460.0 129266.0 135461.0 470.2 479.6 480.0 480.6 480.8 9735.6 9789.5 11778.0 11782.6 11944.7 46949.3 47514.8 48239.3 48450.5 52925.5 60465.4 64309.7 64902.4 65797.7 69799.0 8049.6 8309.0 8372.2 8385.6 8530.2 8769.8 8789.3 8932.3 9365.3 12984.7 8058.6 8295.1 8496.2 8500.1 9011.7 8340.5 8474.0 8626.8 8680.7 8713.0 8384.5 8386.7 8420.7 8501.7 9225.8 10735.5 11929.6 12350.9 12535.6 12625.1 11692.4 12023.8 12135.8 12455.1 12474.9 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:6277.04 Median:2895.15 stddev:3302.44 (114.1%) stddev-sans-first:3292.17 05:37:34 INFO - PROCESS | 1667 | Values: 1498.4 1645.1 1656.4 2021.5 2093.5 7518.8 8313.6 9131.6 9137.0 9150.2 8841.2 9078.7 9085.8 9090.7 9096.5 2567.9 2581.7 2605.1 2605.3 2605.9 2510.9 2573.6 2580.4 2583.0 2617.4 2346.3 2504.5 2629.0 2629.3 2630.8 2592.3 2606.7 2616.6 2619.2 2624.7 2579.4 2605.8 2617.1 2626.6 2627.2 9108.7 9160.2 9174.2 9180.7 9183.8 5598.5 5662.4 5742.4 5802.9 5808.2 3663.0 3735.7 3813.3 3836.4 3837.6 1063.9 1089.2 1090.7 1104.8 1124.5 12334.5 12337.9 12339.6 12344.1 12347.8 5003.6 5013.4 5111.3 5124.9 5126.5 10490.5 10514.8 10536.8 10539.6 10542.3 4996.6 5810.2 5823.9 5825.7 5831.6 4684.2 4737.0 5013.8 5205.5 5257.6 4090.6 4120.8 4165.5 4198.6 4206.0 1350.7 1374.2 1381.6 1382.7 1394.5 1400.8 1409.8 1435.2 1440.5 1460.9 7437.3 8486.0 8573.9 8605.2 8619.9 532.7 540.7 544.0 546.5 547.9 6391.5 6410.0 6440.0 6454.8 6462.0 10702.4 11093.1 11103.4 11238.8 11243.2 10654.9 11009.5 11070.4 11091.7 11114.8 8538.1 8589.8 8590.8 8660.8 8681.3 7922.6 8118.6 8180.0 8181.6 8183.9 9302.7 9647.2 9718.8 9985.0 10007.7 9875.4 10344.8 10371.4 10397.6 10405.4 7742.7 7766.4 7766.4 7824.1 7843.9 8508.1 8549.5 8596.4 8601.4 8608.4 6875.4 7562.3 7769.9 7784.4 7810.7 7499.2 7557.2 7588.2 7671.9 7720.1 8056.9 8113.9 8137.9 8148.1 8158.2 7066.8 7269.1 7297.2 7309.8 7324.7 6428.4 7392.0 7445.1 7450.3 7471.0 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5790.24 Median:6605.05 stddev:1809.68 (27.4%) stddev-sans-first:1767.63 05:37:34 INFO - PROCESS | 1667 | Values: 323.4 330.7 337.6 340.3 342.9 6570.7 6592.0 6640.3 6665.6 6667.5 5356.9 6596.3 6600.9 6609.2 6618.2 4741.6 4817.7 4903.1 4937.3 4984.4 4914.5 4925.7 4940.7 4965.9 4970.5 4889.0 4910.4 4920.2 4923.9 4928.0 4023.4 4709.5 4749.8 4875.3 4886.5 4197.5 4197.5 4208.3 4208.9 4212.4 6375.4 6393.4 6405.7 6410.9 6424.3 5844.5 5854.9 5859.4 5860.2 5863.1 6806.8 7049.5 7057.3 7065.4 7066.8 4039.4 4779.8 4783.4 4792.1 4800.2 7428.9 7461.5 7462.4 7473.0 7482.7 4861.5 4861.9 4863.7 4864.7 4869.8 4941.1 4944.4 4952.3 4956.8 4958.7 5640.5 5839.8 5841.1 5847.3 5851.4 5723.4 7059.5 7077.6 7086.4 7102.4 1684.6 1723.1 1728.6 1729.2 1731.4 1046.0 1080.2 1105.0 1112.2 1140.0 6629.7 6730.3 6752.2 6763.5 6775.5 7326.6 7348.1 7348.4 7351.3 7362.3 6721.2 6722.2 6723.2 6723.8 6730.8 3763.6 3876.3 3910.2 3915.5 3921.0 6411.0 7488.7 7526.5 7532.4 7545.1 6097.3 7627.0 7628.0 7628.7 7630.9 7477.0 7486.1 7491.4 7491.5 7508.4 7099.3 7316.1 7338.7 7341.0 7368.1 7160.0 7188.0 7215.4 7223.7 7229.3 7404.0 7426.8 7434.2 7443.4 7443.5 4854.2 4855.8 4858.2 4871.9 4883.6 7059.3 7267.8 7300.5 7302.5 7304.2 6966.3 7040.2 7043.1 7046.8 7068.3 6438.8 7024.9 7034.4 7059.4 7067.2 6904.2 6905.0 6909.7 6923.0 6927.3 6804.5 7058.2 7062.6 7082.8 7117.9 5117.9 5359.3 6679.8 6894.2 6951.8 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18656.22 Median:4495.53 stddev:25784.31 (573.6%) stddev-sans-first:25821.45 05:37:34 INFO - PROCESS | 1667 | Values: 716.1 751.6 773.4 778.1 801.0 15703.9 15936.4 15960.4 15961.5 15995.2 14644.3 14713.4 14778.9 14786.9 14791.9 776.4 798.2 957.0 982.8 984.6 962.1 974.1 975.3 976.0 977.7 826.0 1032.3 1035.7 1038.7 1039.2 1002.2 1025.9 1028.4 1029.9 1030.5 763.2 764.3 765.2 765.5 766.2 15175.5 15417.2 15464.8 15495.4 15506.7 12960.8 13166.9 13340.5 13644.5 13657.5 10390.7 10480.9 10514.7 10587.7 10817.1 3605.7 3606.6 3625.4 3709.9 3888.8 77996.8 77999.0 78218.2 79119.6 81536.0 9850.7 9930.0 9949.4 10048.2 10740.0 23034.9 23055.0 23072.4 23072.7 23104.3 7689.9 7867.8 7905.8 7992.8 7999.1 4222.5 4295.9 4336.7 4398.7 4592.4 3652.9 3692.9 3697.4 3705.9 3707.7 669.4 684.7 684.9 694.5 713.0 608.9 611.7 621.6 625.6 629.3 7970.3 8481.1 8508.4 8509.7 8614.0 548.2 584.6 606.4 619.5 622.0 3814.8 3944.9 3966.1 3992.6 4039.9 86267.0 88969.0 89037.6 89265.6 89450.0 82492.0 82811.6 83219.0 84404.8 85508.0 70820.9 71395.0 73185.6 73943.6 74065.0 7376.8 8127.6 8432.2 8597.0 8687.3 48735.0 50817.2 51056.7 51198.5 51601.0 55850.2 65845.7 69109.0 72100.3 74213.6 11087.8 11104.5 11288.0 11331.8 11660.9 10204.6 11807.6 12001.0 12045.9 12412.6 11576.5 11833.9 11857.5 11889.9 12064.6 11838.2 11864.5 11952.8 11976.5 12159.5 11543.3 11815.3 11821.5 11920.0 12265.9 9924.2 10069.0 10158.0 10342.9 10478.8 9961.5 9976.4 9985.6 10015.6 10021.0 05:37:34 INFO - PROCESS | 1667 | -------- Summary: end -------- 05:37:34 INFO - PROCESS | 1667 | 05:37:34 INFO - TEST-INFO | 1667: exit 0 05:37:34 INFO - TEST-OK | dromaeo_css | took 1110753ms 05:37:34 INFO - TEST-START | kraken 05:37:34 INFO - operating with platform_type : mac_ 05:37:34 INFO - Initialising browser for kraken test... 05:37:34 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpsYukUr/profile http://localhost:49270/getInfo.html) 05:37:36 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:37:36 INFO - PROCESS | 1673 | colorDepth:24 05:37:36 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:37:36 INFO - PROCESS | 1673 | __metrics 05:37:36 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:37:36 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:37 INFO - TEST-INFO | 1673: exit 0 05:37:37 INFO - Browser initialized. 05:37:37 INFO - Running cycle 1/1 for kraken test... 05:37:37 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/T/tmpsYukUr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:49 INFO - PROCESS | 1676 | RSS: Main: 156897280 05:37:49 INFO - PROCESS | 1676 | 05:37:51 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:52 INFO - PROCESS | 1676 | RSS: Main: 212312064 05:37:52 INFO - PROCESS | 1676 | 05:37:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:37:55 INFO - PROCESS | 1676 | RSS: Main: 222846976 05:37:55 INFO - PROCESS | 1676 | 05:37:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:37:59 INFO - PROCESS | 1676 | RSS: Main: 212717568 05:37:59 INFO - PROCESS | 1676 | 05:38:01 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:38:01 INFO - PROCESS | 1676 | RSS: Main: 214929408 05:38:01 INFO - PROCESS | 1676 | 05:38:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:04 INFO - PROCESS | 1676 | RSS: Main: 214949888 05:38:04 INFO - PROCESS | 1676 | 05:38:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:07 INFO - PROCESS | 1676 | RSS: Main: 214736896 05:38:07 INFO - PROCESS | 1676 | 05:38:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:11 INFO - PROCESS | 1676 | RSS: Main: 214786048 05:38:11 INFO - PROCESS | 1676 | 05:38:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:38:14 INFO - PROCESS | 1676 | RSS: Main: 215085056 05:38:14 INFO - PROCESS | 1676 | 05:38:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:16 INFO - PROCESS | 1676 | RSS: Main: 248848384 05:38:16 INFO - PROCESS | 1676 | 05:38:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:18 INFO - PROCESS | 1676 | RSS: Main: 249278464 05:38:18 INFO - PROCESS | 1676 | 05:38:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:21 INFO - PROCESS | 1676 | RSS: Main: 254513152 05:38:21 INFO - PROCESS | 1676 | 05:38:24 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:24 INFO - PROCESS | 1676 | RSS: Main: 256299008 05:38:24 INFO - PROCESS | 1676 | 05:38:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:27 INFO - PROCESS | 1676 | RSS: Main: 256344064 05:38:27 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:38:29 INFO - PROCESS | 1676 | RSS: Main: 318615552 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | __start_tp_report 05:38:29 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:38:29 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:38:29 INFO - PROCESS | 1676 | |i|pagename|runs| 05:38:29 INFO - PROCESS | 1676 | |0;ai-astar;114;111;112;111;113;113;122;112;112;111 05:38:29 INFO - PROCESS | 1676 | |1;audio-beat-detection;152;120;154;147;116;140;117;149;146;117 05:38:29 INFO - PROCESS | 1676 | |2;audio-dft;186;251;238;183;183;184;183;184;184;184 05:38:29 INFO - PROCESS | 1676 | |3;audio-fft;73;70;75;114;55;59;59;57;113;56 05:38:29 INFO - PROCESS | 1676 | |4;audio-oscillator;90;90;129;76;81;79;120;77;78;122 05:38:29 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;91;87;91;88;91;87;91;87;92;86 05:38:29 INFO - PROCESS | 1676 | |6;imaging-darkroom;95;95;95;95;95;94;94;96;95;95 05:38:29 INFO - PROCESS | 1676 | |7;imaging-desaturate;84;87;85;86;84;85;84;86;85;86 05:38:29 INFO - PROCESS | 1676 | |8;json-parse-financial;55;57;57;57;58;59;57;66;50;51 05:38:29 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;62;59;88;74;67;68;65;66;65;65 05:38:29 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;82;81;82;80;80;79;79;79;81;83 05:38:29 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;126;130;122;119;125;127;112;127;133;117 05:38:29 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;136;133;132;138;131;126;134;150;164;143 05:38:29 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;65;64;66;66;85;86;90;87;70 05:38:29 INFO - PROCESS | 1676 | __end_tp_report 05:38:29 INFO - PROCESS | 1676 | __start_cc_report 05:38:29 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1190 05:38:29 INFO - PROCESS | 1676 | __end_cc_report 05:38:29 INFO - PROCESS | 1676 | __startTimestamp1462019909829__endTimestamp 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:38:29 INFO - PROCESS | 1676 | Number of tests: 14 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:113.10 Median:112.50 stddev:3.28 (2.9%) stddev-sans-first:3.46 05:38:29 INFO - PROCESS | 1676 | Values: 114.0 111.0 112.0 111.0 113.0 113.0 122.0 112.0 112.0 111.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:146.50 stddev:16.21 (11.1%) stddev-sans-first:16.09 05:38:29 INFO - PROCESS | 1676 | Values: 152.0 120.0 154.0 147.0 116.0 140.0 117.0 149.0 146.0 117.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:196.00 Median:184.00 stddev:25.76 (14.0%) stddev-sans-first:27.07 05:38:29 INFO - PROCESS | 1676 | Values: 186.0 251.0 238.0 183.0 183.0 184.0 183.0 184.0 184.0 184.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:73.10 Median:59.00 stddev:22.50 (38.1%) stddev-sans-first:23.86 05:38:29 INFO - PROCESS | 1676 | Values: 73.0 70.0 75.0 114.0 55.0 59.0 59.0 57.0 113.0 56.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:78.50 stddev:21.03 (26.8%) stddev-sans-first:22.25 05:38:29 INFO - PROCESS | 1676 | Values: 90.0 90.0 129.0 76.0 81.0 79.0 120.0 77.0 78.0 122.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:91.00 stddev:2.28 (2.5%) stddev-sans-first:2.32 05:38:29 INFO - PROCESS | 1676 | Values: 91.0 87.0 91.0 88.0 91.0 87.0 91.0 87.0 92.0 86.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:38:29 INFO - PROCESS | 1676 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 96.0 95.0 95.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.03 (1.2%) stddev-sans-first:1.00 05:38:29 INFO - PROCESS | 1676 | Values: 84.0 87.0 85.0 86.0 84.0 85.0 84.0 86.0 85.0 86.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:4.40 (7.7%) stddev-sans-first:4.62 05:38:29 INFO - PROCESS | 1676 | Values: 55.0 57.0 57.0 57.0 58.0 59.0 57.0 66.0 50.0 51.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:66.50 stddev:8.06 (12.1%) stddev-sans-first:8.26 05:38:29 INFO - PROCESS | 1676 | Values: 62.0 59.0 88.0 74.0 67.0 68.0 65.0 66.0 65.0 65.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.00 stddev:1.43 (1.8%) stddev-sans-first:1.42 05:38:29 INFO - PROCESS | 1676 | Values: 82.0 81.0 82.0 80.0 80.0 79.0 79.0 79.0 81.0 83.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:126.50 stddev:6.34 (5.0%) stddev-sans-first:6.67 05:38:29 INFO - PROCESS | 1676 | Values: 126.0 130.0 122.0 119.0 125.0 127.0 112.0 127.0 133.0 117.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.70 Median:137.00 stddev:11.13 (8.1%) stddev-sans-first:11.76 05:38:29 INFO - PROCESS | 1676 | Values: 136.0 133.0 132.0 138.0 131.0 126.0 134.0 150.0 164.0 143.0 05:38:29 INFO - PROCESS | 1676 | 05:38:29 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:77.50 stddev:10.85 (14.0%) stddev-sans-first:11.16 05:38:29 INFO - PROCESS | 1676 | Values: 67.0 65.0 64.0 66.0 66.0 85.0 86.0 90.0 87.0 70.0 05:38:29 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:38:29 INFO - PROCESS | 1676 | 05:38:30 INFO - TEST-INFO | 1676: exit 0 05:38:30 INFO - TEST-OK | kraken | took 55998ms 05:38:30 INFO - SUITE-END | took 1166s 05:38:30 INFO - Completed test suite (00:19:27) 05:38:30 INFO - javascript benchmark 05:38:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2974.717401846825, 3611.3860643690555, 3632.5822530409005, 3643.143861192618, 3678.705703543717, 5790.797322093727, 5792.710364481775, 5804.651720896749, 5805.129230615405, 5814.418558144183, 5765.7981970631035, 5811.0702287633885, 5812.767489300433, 5833.270841144857, 5843.211166492522, 5204.297419848329, 5215.295935048767, 5224.138017227153, 5228.712420816853, 5278.0, 5304.151335786275, 5309.283246761676, 5309.946900531003, 5314.4951229633125, 5320.388155362125, 5243.501867322599, 5283.471652834724, 5306.522412982831, 5309.495597918191, 5319.760610772524, 5331.147016477365, 5360.946390536123, 5361.142217245261, 5363.704996225226, 5381.650192737459, 5097.974510127463, 5213.765380557884, 5258.7370631468275, 5264.131418315973, 5272.472752724735, 5598.608097433182, 5654.547636189095, 5675.971620141914, 5683.374828768832, 5716.857078573027, 6220.595661282002, 6318.178636777189, 6333.081703153063, 6340.4293613575, 6353.047042943549, 6207.906881396782, 6240.1263823064355, 6244.531660125509, 6263.843403914893, 6317.4314311711705, 5934.732937017845, 5968.104784282393, 5969.611975221596, 5978.0733986232635, 5980.192673989043, 6217.191765070512, 6287.0, 6300.905486417708, 6302.023186406114, 6315.494760419156, 5333.386660534011, 6245.718942647592, 6258.374162583706, 6270.874582508324, 6288.339724328971, 6248.125262463259, 6266.216722909637, 6269.592476489013, 6280.0893870389455, 6312.305646378894, 6157.384261573897, 6210.50315974721, 6254.6247225166635, 6308.337624549448, 6326.019466982625, 6293.653849038255, 6317.557770955989, 6320.55756097078, 6338.714757835908, 6345.270293916259, 4925.1873440881855, 4934.185859333242, 4935.185694360391, 4946.08497427977, 4951.975240123848, 4403.8238470461465, 4441.045175287329, 4486.304622783474, 4493.303537951622, 4520.547945205453, 5946.732397042144, 5949.524038076944, 5957.225560677084, 5965.6718880461995, 5982.970085149546, 6238.906416403757, 6257.2491301044165, 6264.655443950535, 6275.748970041147, 6288.937110628835, 5776.018076926933, 5871.119332100219, 5900.43945825146, 5917.408259174048, 5937.050071988548, 5391.595630327741, 5395.973020134874, 5415.891682166413, 5420.4850539198715, 5463.426340234298, 6234.906476402857, 6256.092866534327, 6257.499400047986, 6276.717547710273, 6288.402601752735, 6210.782622608187, 6240.188775459162, 6251.874962500816, 6289.40250676185, 6315.431611155018, 6271.655058971618, 6271.905921411274, 6295.370462953668, 6302.71637776292, 6326.8734625306315, 6277.905831412441, 6278.497720182466, 6301.117843502005, 6304.590201636879, 6316.242050953915, 5505.394406615349, 6014.789482368096, 6223.0354295084335, 6240.906386404116, 6288.779892703734, 6216.720247588779, 6217.036359364306, 6251.906221406592, 6252.874942501034, 6259.280182778856, 6044.184035155197, 6201.844953876189, 6241.31345551998, 6246.593971391845, 6258.812235632939, 6065.332813390612, 6110.012029148408, 6113.877722445615, 6122.0627347074505, 6126.365000174519, 6234.470070044097, 6250.656213908279, 6265.937340626535, 6283.3088360281245, 6285.874282514233, 6004.819855404345, 6106.084087386927, 6145.139680444652, 6148.047052706837, 6150.661713605795], "unit": "score", "name": "dojo.html", "value": 5777.910780162027}, {"lowerIsBetter": false, "replicates": [474.16073549816804, 601.2814686449692, 602.4878852974969, 628.9653519959667, 634.543128947158, 32247.0, 32750.8362458188, 32756.836215818916, 32761.83619081907, 32803.67196328036, 33551.66448335516, 34434.0, 42560.0, 42870.35694464586, 42884.356734649, 9767.706968790948, 9950.502474876228, 9961.953994830546, 9962.203023758082, 9977.351472154287, 9702.320837541374, 9874.061964113434, 9879.06148915852, 9881.752956176117, 9892.554835032404, 9819.509024548744, 9887.307888447811, 9896.0, 9899.109080182781, 9914.405135691846, 9817.901820981806, 10005.099541041305, 10011.399316041061, 10020.94780048099, 10024.59901603935, 9822.65620703272, 9937.950310248403, 9965.302428829984, 9969.601215951354, 9981.800363992752, 43135.13729725388, 43565.34651980223, 43586.56413435889, 43611.34582981258, 43663.0, 3280.5407242986003, 3377.307651931348, 3613.0425437259146, 3821.6560509554274, 3981.98009009956, 28732.569011464846, 29108.854455727796, 29123.271918202, 29781.702182978326, 30696.2325941851, 2828.0, 2839.9290017749513, 2878.7409133177907, 2913.810602310843, 2933.574631678395, 477141.9999999965, 478756.0, 480204.5989770064, 480571.0, 480945.0, 48795.5120448791, 49624.255636165486, 72402.63798680973, 72642.63678681572, 72858.63570682112, 48130.51869481261, 48243.0, 48476.0, 48637.7840553989, 48643.75678121587, 17194.0, 17295.4811355657, 17345.826541734674, 17386.21762020687, 17420.82579174192, 13916.512922047883, 13987.930060349634, 14009.299535023412, 14017.649558761113, 14067.155970641794, 15477.613059673373, 15664.608384790243, 15684.68630627381, 15693.058416495047, 15969.201539922957, 6327.4938004959495, 6384.138141350951, 6392.520560957947, 6441.259255185713, 6463.159789227464, 880.8766772651834, 921.8156368726281, 936.8126374725082, 940.501534186882, 945.7588314979636, 30102.548461772654, 30631.2342191447, 30697.539536906963, 31155.844220778752, 31364.686353136178, 1119.2668801934747, 1131.671815173607, 1151.3034614058543, 1293.2499150492692, 1320.2606540337442, 15617.14105724196, 15787.68424631501, 15794.289256983464, 15955.680886382208, 15999.68000640004, 551890.0, 555667.0, 557701.21149394, 558131.0, 558766.0, 63349.68325158437, 63991.0, 64003.0, 64046.0, 64092.038619420746, 1398.7761958086658, 1518.962025949338, 1529.17424590722, 1532.9923350383176, 1557.0891028748308, 29930.700692992792, 29933.401331972804, 29979.550306745852, 30587.54118688177, 30711.078667640006, 49783.253251200535, 60175.49561261004, 61345.69327153514, 61472.77054458796, 61504.384956151654, 421299.0, 425438.0, 431080.0, 431109.0, 431465.0, 37648.811755941046, 38503.61496385001, 44941.0, 47282.290765639234, 47930.0, 47394.289085663055, 48180.55458336256, 48193.75903120602, 49008.0, 49806.75096624494, 38885.80557097196, 39726.404103937886, 40640.1871962565, 40813.38779918244, 40813.59186408217, 37476.43785343168, 37955.430668540546, 38166.0, 38169.427458588696, 38401.42397863978, 38779.6122038776, 38933.0, 39007.0, 39494.0, 39584.0, 29213.269668258465, 33709.83145084259, 34406.82796586001, 34439.4834077494, 34594.65405345914, 25261.87369063143, 25717.742822571534, 25720.61419078678, 25893.611595826453, 26210.737892621593], "unit": "score", "name": "ext.html", "value": 20103.129878905136}, {"lowerIsBetter": false, "replicates": [325.89245548968836, 373.65810283590514, 374.1730774987277, 381.9980900095499, 382.97893615851115, 17303.221355039022, 20590.38228853135, 21460.57078858422, 22193.334199973986, 22253.44366390837, 22465.55068898621, 22638.20766273181, 22920.656190157162, 22978.851057447104, 23075.192368267115, 5259.894802103956, 5439.646422982513, 5805.390434004432, 6108.725107370156, 6167.136600875881, 5964.314103878044, 6097.5426842986735, 6108.114323423101, 6110.3889611038985, 6111.052786818051, 5737.827865164052, 6026.608270462428, 6060.212172417601, 6064.0, 6072.666003369835, 5901.586888917777, 5935.0, 5943.227380440559, 5977.970110149443, 5979.1330257112695, 4512.2329204035395, 5226.7909283628605, 5231.686098834044, 5234.241035049897, 5236.581073514109, 13894.513692020731, 13975.37110830015, 14161.22113283769, 14173.078749881224, 14186.929065354607, 2147.6349020666366, 2324.1633012115626, 2463.6674049003514, 2600.154949641374, 2816.0, 10087.646932357406, 10315.02007309312, 10417.0, 12601.936990315082, 15200.40194805513, 1765.1174412793603, 1785.1609743420572, 1854.3046357615895, 1884.4440889937364, 1918.4820098573446, 112905.0, 114767.42616286865, 115308.0, 116830.41584792106, 117218.0, 11538.19232653723, 11559.248648837798, 11568.305901645928, 11613.535458581562, 11745.530178792924, 14508.854911450962, 14564.635884102981, 14591.0, 14614.707705846036, 14617.853821461862, 8536.0, 8936.151065648754, 8938.46369217836, 8976.281897448189, 9070.8683459387, 9122.680706175252, 9144.908550914539, 9171.862422063674, 9230.215431688383, 9248.121428464285, 4456.153330867125, 4550.2264615014865, 4572.634189264851, 4584.312353146988, 4603.74416535895, 1870.653929023135, 1910.1595298068805, 1911.0, 1914.818092281231, 1932.4878907089633, 423.3903179421623, 431.67192933370865, 468.1760102220117, 897.4570384917162, 1001.7846163074973, 11942.522299108112, 11967.0, 12112.031037516977, 12215.450304736309, 12309.50761969529, 452.39605127155613, 467.38772208406414, 467.772370642569, 472.5463554987182, 483.5068230404967, 6372.139761132281, 6436.935630643728, 6491.156149700509, 6496.675166241669, 6533.771318003847, 670408.0, 678885.0, 683248.0, 688431.0, 692507.5374623095, 91202.0, 106901.0, 113460.0, 129266.0, 135461.0, 470.24525636353843, 479.58036717871164, 480.0447110250576, 480.6419217682779, 480.76442543153604, 9735.561899714672, 9789.46157961319, 11778.0, 11782.646520604396, 11944.701382465508, 46949.30506949278, 47514.76242618903, 48239.27641085457, 48450.51549484601, 52925.470745292056, 60465.395346045974, 64309.67845160744, 64902.35097649152, 65797.67101164463, 69799.0, 8049.637766300413, 8308.958455207685, 8372.166887509446, 8385.58072096405, 8530.232279094911, 8769.772231887668, 8789.252913502169, 8932.330075244512, 9365.250779937725, 12984.740305194033, 8058.556779376956, 8295.087540370434, 8496.150384961455, 8500.064992850905, 9011.684591039282, 8340.457870238415, 8474.0, 8626.82746345057, 8680.652773888974, 8713.0, 8384.538850363044, 8386.748397548083, 8420.663173472993, 8501.702440414556, 9225.769355766159, 10735.516901739284, 11929.582464613697, 12350.88842004224, 12535.561255356019, 12625.116241862981, 11692.415379230833, 12023.75952480963, 12135.817962730389, 12455.128141030042, 12474.937625312179], "unit": "score", "name": "jquery.html", "value": 8482.737585586841}, {"lowerIsBetter": false, "replicates": [1498.38566187863, 1645.0787558966977, 1656.4202529114812, 2021.4946263434142, 2093.4766308422886, 7518.774436766899, 8313.584320783959, 9131.589078491466, 9136.954315228415, 9150.222231110347, 8841.248493878013, 9078.682246121387, 9085.772855678611, 9090.681826136086, 9096.54517274137, 2567.884445199961, 2581.7289184635624, 2605.114261151208, 2605.257501612041, 2605.869706514676, 2510.9372265693323, 2573.6268241105026, 2580.419405633727, 2582.9612555811677, 2617.4241666833364, 2346.3195673254736, 2504.524140413325, 2629.0272599138348, 2629.290091675256, 2630.7500787425165, 2592.2871210417097, 2606.6741657292837, 2616.5944278636844, 2619.1749598876295, 2624.6850377954574, 2579.406736450627, 2605.817592768488, 2617.1101825379274, 2626.619140224676, 2627.1987043951585, 9108.726738197865, 9160.175584197388, 9174.220191283748, 9180.724578262596, 9183.770405739842, 5598.524125449342, 5662.433756624346, 5742.368339482654, 5802.883942321172, 5808.157817116495, 3662.967978256006, 3735.6637902588627, 3813.3326667833294, 3836.3669994450884, 3837.6354246346555, 1063.8510608514814, 1089.2484185911696, 1090.6946055104584, 1104.7735214281054, 1124.5276983666797, 12334.5066197352, 12337.876621233852, 12339.629811105591, 12344.074194435363, 12347.753044939142, 5003.624728145403, 5013.39839219296, 5111.309973153651, 5124.871878203075, 5126.512981266774, 10490.527926243338, 10514.842277365846, 10536.789264214673, 10539.631112911165, 10542.262041657164, 4996.625253106032, 5810.244668193108, 5823.94176058234, 5825.679587622722, 5831.591788574844, 4684.2271025281125, 4736.976315118402, 5013.799448022111, 5205.505476979681, 5257.605679574047, 4090.579922885716, 4120.835166593302, 4165.504879506087, 4198.5591512890705, 4206.0, 1350.6825895914453, 1374.17549470317, 1381.5717127690448, 1382.709630977506, 1394.5328315014583, 1400.775875859878, 1409.83082030155, 1435.167602790379, 1440.5102265229873, 1460.8831293496494, 7437.293457121565, 8485.957570212233, 8573.914260857437, 8605.182507661762, 8619.870701939475, 532.6850190985884, 540.716124034877, 544.0099019783955, 546.5436360638831, 547.8657728856456, 6391.488680905516, 6410.038494225903, 6440.0, 6454.806355809334, 6462.0, 10702.411367374869, 11093.057090147266, 11103.44482775858, 11238.831417528743, 11243.156763242627, 10654.946725266323, 11009.504572294267, 11070.446477676083, 11091.72270693239, 11114.777704446027, 8538.103499132625, 8589.828203436022, 8590.78523036929, 8660.826783464421, 8681.348898832613, 7922.643481043482, 8118.553479558681, 8180.0, 8181.590920454072, 8183.877241841258, 9302.674406395743, 9647.228221742384, 9718.75703107428, 9984.950075249577, 10007.749806254902, 9875.358101723652, 10344.793104137725, 10371.377717336984, 10397.63608273737, 10405.375677459377, 7742.65158067877, 7766.417518686008, 7766.417518686008, 7824.139344671968, 7843.882341764764, 8508.106648802035, 8549.529775862387, 8596.398252122292, 8601.39790214679, 8608.440451370641, 6875.449964002768, 7562.281583249803, 7769.92230077692, 7784.377249820114, 7810.726624568113, 7499.212582678959, 7557.168711441627, 7588.241175882367, 7671.884921726067, 7720.112187098555, 8056.919430805617, 8113.878291825746, 8137.877931831145, 8148.062972757972, 8158.184181582032, 7066.8233294166, 7269.127704675473, 7297.1973082961895, 7309.817254568678, 7324.707011719472, 6428.357164283534, 7392.0, 7445.069366329209, 7450.254974502506, 7471.0], "unit": "score", "name": "mootools.html", "value": 5055.5880072045}, {"lowerIsBetter": false, "replicates": [323.4081630615972, 330.72054114273436, 337.63029482716416, 340.2735160432477, 342.86970951038603, 6570.73717051318, 6592.0, 6640.302768209329, 6665.600063996164, 6667.499937504683, 5356.892862142755, 6596.307387724291, 6600.933990660092, 6609.173853268341, 6618.205815302171, 4741.620670346381, 4817.735024573647, 4903.117438861003, 4937.308776771255, 4984.4018717753925, 4914.533119353674, 4925.655204135712, 4940.678855874375, 4965.900681986376, 4970.478099799523, 4889.0, 4910.41075070992, 4920.237363208708, 4923.876903077434, 4928.0143971205725, 4023.376376661622, 4709.5290470952805, 4749.76251187441, 4875.293082503053, 4886.486918873503, 4197.517285512175, 4197.538270790211, 4208.347706105558, 4208.894777630553, 4212.431321771553, 6375.362463753588, 6393.360663933616, 6405.743770249184, 6410.935890641081, 6424.325445828167, 5844.532437404998, 5854.853628659275, 5859.44335288147, 5860.179574859523, 5863.091220860773, 6806.76176333826, 7049.506534542584, 7057.258987806258, 7065.399441047517, 7066.823329416806, 4039.3738970459626, 4779.784909679072, 4783.4499032611, 4792.0895029944195, 4800.207965685658, 7428.9257107428775, 7461.477696561243, 7462.4403169761945, 7472.962635186844, 7482.738104166328, 4861.465238823726, 4861.927071093936, 4863.708177509361, 4864.73243971585, 4869.829555965524, 4941.135301322254, 4944.431390390079, 4952.281919121707, 4956.826511072095, 4958.727270000112, 5640.464155905182, 5839.824805255849, 5841.123831425235, 5847.26909136358, 5851.414858514199, 5723.370429252779, 7059.470539709542, 7077.646117694095, 7086.433085353159, 7102.360787529147, 1684.6209602839263, 1723.1039859273358, 1728.585139566495, 1729.1786401459458, 1731.3853581978217, 1045.9644951498115, 1080.233034545466, 1104.9558017679365, 1112.1714322829382, 1140.0309736723718, 6629.701663425158, 6730.32696730323, 6752.155980502437, 6763.458923286125, 6775.525713200127, 7326.633668316669, 7348.1182258129365, 7348.448866335047, 7351.264873512606, 7362.3373896349585, 6721.193456785119, 6722.193336799518, 6723.159605049369, 6723.798286051427, 6730.764423245261, 3763.6236376362144, 3876.321643712339, 3910.2179564087296, 3915.4909861718365, 3921.0197450637343, 6410.967945160244, 7488.663010164556, 7526.5107767994905, 7532.435067369969, 7545.056867891513, 6097.3292937776805, 7627.0, 7627.961860190663, 7628.656710448043, 7630.923690763132, 7476.962615186889, 7486.064241969754, 7491.400687945061, 7491.513051651626, 7508.43686723487, 7099.290070993066, 7316.085489313836, 7338.669759860926, 7340.96329518349, 7368.115826100902, 7159.964200178966, 7187.993680884785, 7215.350618444365, 7223.747168849065, 7229.277072292939, 7404.0, 7426.777196684108, 7434.182239953709, 7443.36731377839, 7443.478956473212, 4854.150523658275, 4855.805767769391, 4858.174110401311, 4871.853844384674, 4883.6093112551625, 7059.329363710439, 7267.7819665410125, 7300.45246606496, 7302.452316076209, 7304.160021597584, 6966.338197871194, 7040.225575186623, 7043.119610048744, 7046.788596342118, 7068.3285087916565, 6438.806835794933, 7024.859502810017, 7034.437245020488, 7059.399951004222, 7067.222605513287, 6904.171499420102, 6904.964255361535, 6909.723611055501, 6923.030775691508, 6927.3419025192525, 6804.523683342119, 7058.223595404399, 7062.646867656494, 7082.822929426806, 7117.893231601426, 5117.92323115161, 5359.276497672764, 6679.799606011828, 6894.24163342022, 6951.818190907659], "unit": "score", "name": "prototype.html", "value": 5190.116478080713}, {"lowerIsBetter": false, "replicates": [716.122749631701, 751.5791156952104, 773.4315278270474, 778.0896351269016, 801.0427539090786, 15703.921480392597, 15936.442224522145, 15960.361585536593, 15961.521154365359, 15995.200239988013, 14644.267786610653, 14713.411463541446, 14778.852211477883, 14786.926065369686, 14791.92604036981, 776.4215659333804, 798.1978111997463, 956.9665061722841, 982.773961988743, 984.5569493727816, 962.1388856973005, 974.0600320690532, 975.2929126383351, 975.9658411955583, 977.6871401151634, 825.9587020648974, 1032.256775121911, 1035.7462421706693, 1038.729930218145, 1039.2257767962878, 1002.1982414068752, 1025.9333143345696, 1028.4343611013949, 1029.8506716526063, 1030.5002073994108, 763.175770168217, 764.3273918951287, 765.2003656179306, 765.4985984180371, 766.1534004924534, 15175.544733657898, 15417.15205663688, 15464.768028479582, 15495.380184792708, 15506.689866202612, 12960.805587916095, 13166.868331316757, 13340.533081342106, 13644.454221831214, 13657.521986730517, 10390.740231494274, 10480.895191048068, 10514.684559463229, 10587.682369528926, 10817.134629229642, 3605.7115430765475, 3606.6213047629885, 3625.425867822607, 3709.8887033388773, 3888.786116763605, 77996.83004754933, 77999.0, 78218.21781782224, 79119.60440197762, 81536.0, 9850.704478865646, 9930.0, 9949.403035817873, 10048.246381521416, 10740.033396994308, 23034.884825575766, 23055.0, 23072.423189420064, 23072.653910191362, 23104.306870793902, 7689.923100769032, 7867.842643147105, 7905.802354941173, 7992.840143197103, 7999.0801057877925, 4222.535521092677, 4295.892602684896, 4336.739795612273, 4398.670099742532, 4592.3800286961505, 3652.8904132875523, 3692.8892133236045, 3697.4084146536425, 3705.943806015273, 3707.7404581679025, 669.3975422120034, 684.6610927590874, 684.934931181547, 694.4617921111078, 713.0195980526776, 608.9360617135226, 611.6972098811117, 621.586644881158, 625.5964902637864, 629.3423372575669, 7970.2883920336535, 8481.109483504266, 8508.361872859436, 8509.659613615386, 8613.956930215309, 548.2488990083611, 584.6316820403118, 606.4026933470528, 619.4703528483172, 621.996890015547, 3814.828332725034, 3944.9408258876138, 3966.147278335172, 3992.6207010333974, 4039.8586049488126, 86267.0, 88968.99279064915, 89037.55481222682, 89265.55367223252, 89450.0, 82492.0, 82811.5859420699, 83219.0, 84404.7797610117, 85508.0, 70820.93768593475, 71395.0, 73185.6340718293, 73943.63028184933, 74065.0, 7376.815579610553, 8127.634256458356, 8432.156784321764, 8596.957015214884, 8687.348448866234, 48735.0, 50817.23774143465, 51056.74471627618, 51198.48801512086, 51601.0, 55850.16224756551, 65845.69540066074, 69109.0, 72100.27899720936, 74213.628931855, 11087.833836752628, 11104.500297486795, 11288.0, 11331.830022549833, 11660.883391166319, 10204.642837500653, 11807.645770626896, 12001.039916806809, 12045.879541204826, 12412.62762117138, 11576.47905844256, 11833.881661183623, 11857.52569897229, 11889.881101188877, 12064.638060858188, 11838.230515016496, 11864.466099025392, 11952.76094478088, 11976.46105925218, 12159.45282462309, 11543.307401555934, 11815.291082535075, 11821.468033938665, 11920.0, 12265.938670306592, 9924.206063514757, 10068.993100689873, 10158.0, 10342.948285258526, 10478.790424191626, 9961.452120133463, 9976.401415915068, 9985.550650220612, 10015.649452269136, 10021.0], "unit": "score", "name": "yui.html", "value": 7183.405518319369}], "name": "dromaeo_css", "value": 7553.480651138077}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 111.0, 112.0, 111.0, 113.0, 113.0, 122.0, 112.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.1}, {"lowerIsBetter": true, "replicates": [152.0, 120.0, 154.0, 147.0, 116.0, 140.0, 117.0, 149.0, 146.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 135.8}, {"lowerIsBetter": true, "replicates": [186.0, 251.0, 238.0, 183.0, 183.0, 184.0, 183.0, 184.0, 184.0, 184.0], "unit": "score", "name": "audio-dft", "value": 196.0}, {"lowerIsBetter": true, "replicates": [73.0, 70.0, 75.0, 114.0, 55.0, 59.0, 59.0, 57.0, 113.0, 56.0], "unit": "score", "name": "audio-fft", "value": 73.1}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 129.0, 76.0, 81.0, 79.0, 120.0, 77.0, 78.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 94.2}, {"lowerIsBetter": true, "replicates": [91.0, 87.0, 91.0, 88.0, 91.0, 87.0, 91.0, 87.0, 92.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 94.9}, {"lowerIsBetter": true, "replicates": [84.0, 87.0, 85.0, 86.0, 84.0, 85.0, 84.0, 86.0, 85.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 85.2}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 57.0, 57.0, 58.0, 59.0, 57.0, 66.0, 50.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 56.7}, {"lowerIsBetter": true, "replicates": [62.0, 59.0, 88.0, 74.0, 67.0, 68.0, 65.0, 66.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 82.0, 80.0, 80.0, 79.0, 79.0, 79.0, 81.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.6}, {"lowerIsBetter": true, "replicates": [126.0, 130.0, 122.0, 119.0, 125.0, 127.0, 112.0, 127.0, 133.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.8}, {"lowerIsBetter": true, "replicates": [136.0, 133.0, 132.0, 138.0, 131.0, 126.0, 134.0, 150.0, 164.0, 143.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.7}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 64.0, 66.0, 66.0, 85.0, 86.0, 90.0, 87.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 74.6}], "name": "kraken", "value": 1423.7}]} 05:38:31 INFO - Return code: 0 05:38:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:31 INFO - # TBPL SUCCESS # 05:38:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:31 INFO - 2016-04-30 05:38:31.130 screenresolution[1680:9746] starting screenresolution argv=screenresolution get 05:38:31 INFO - 2016-04-30 05:38:31.133 screenresolution[1680:9746] Display 0: 1600x1200x32@60 05:38:31 INFO - 2016-04-30 05:38:31.139 screenresolution[1681:9749] starting screenresolution argv=screenresolution list 05:38:31 INFO - Available Modes on Display 0 05:38:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:31 INFO - Intel Iris: 05:38:31 INFO - Chipset Model: Intel Iris 05:38:31 INFO - Type: GPU 05:38:31 INFO - Bus: Built-In 05:38:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:31 INFO - Vendor: Intel (0x8086) 05:38:31 INFO - Device ID: 0x0a2e 05:38:31 INFO - Revision ID: 0x0009 05:38:31 INFO - Displays: 05:38:31 INFO - Display: 05:38:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:31 INFO - Display Serial Number: 1600x1200 60 05:38:31 INFO - Main Display: Yes 05:38:31 INFO - Mirror: Off 05:38:31 INFO - Online: Yes 05:38:31 INFO - Rotation: Supported 05:38:31 INFO - Return code: 0 05:38:31 INFO - Running post-action listener: _resource_record_post_action 05:38:31 INFO - Running post-run listener: _resource_record_post_run 05:38:32 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 32927232; Write bytes: 509778944; Read time: 332; Write time: 1595 05:38:32 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 205154816; Write bytes: 198587904; Read time: 19126; Write time: 381 05:38:32 INFO - run-tests - Wall time: 1169s; CPU: 52.0%; Read bytes: 30854656; Write bytes: 301446656; Read time: 305; Write time: 1182 05:38:32 INFO - Running post-run listener: _upload_blobber_files 05:38:32 INFO - Blob upload gear active. 05:38:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:38:32 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'] 05:38:32 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 05:38:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:33 INFO - (blobuploader) - INFO - Done attempting. 05:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:34 INFO - (blobuploader) - INFO - Done attempting. 05:38:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:34 INFO - Return code: 0 05:38:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/beda8a136b5d64f7b018eae7d304ae2bd763ec472c19ad229cd9dd99fbb94554627f720dd2ed50a60de9954bee85553f7546d6d1c7101fb26a978fa0b688866a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/efec71ee1575719016ead74a03f3f3e82278318dc61e48cdc28c21fa08ef53f911e3bd832073daedf767afe5d5a0997f1791cd3220f6a4169fbfbf10e358df63"} 05:38:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:34 INFO - Contents: 05:38:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/beda8a136b5d64f7b018eae7d304ae2bd763ec472c19ad229cd9dd99fbb94554627f720dd2ed50a60de9954bee85553f7546d6d1c7101fb26a978fa0b688866a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/efec71ee1575719016ead74a03f3f3e82278318dc61e48cdc28c21fa08ef53f911e3bd832073daedf767afe5d5a0997f1791cd3220f6a4169fbfbf10e358df63"} 05:38:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:34 INFO - Copying logs to upload dir... 05:38:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.152958 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-04-30 05:38:34.691982) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 05:38:34.696216) ========= 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.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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/beda8a136b5d64f7b018eae7d304ae2bd763ec472c19ad229cd9dd99fbb94554627f720dd2ed50a60de9954bee85553f7546d6d1c7101fb26a978fa0b688866a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/efec71ee1575719016ead74a03f3f3e82278318dc61e48cdc28c21fa08ef53f911e3bd832073daedf767afe5d5a0997f1791cd3220f6a4169fbfbf10e358df63"} build_url:https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009550 build_url: 'https://queue.taskcluster.net/v1/task/ZXTvVx7kS4We3RctHUmcXA/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/beda8a136b5d64f7b018eae7d304ae2bd763ec472c19ad229cd9dd99fbb94554627f720dd2ed50a60de9954bee85553f7546d6d1c7101fb26a978fa0b688866a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/efec71ee1575719016ead74a03f3f3e82278318dc61e48cdc28c21fa08ef53f911e3bd832073daedf767afe5d5a0997f1791cd3220f6a4169fbfbf10e358df63"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 05:38:34.719611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:38:34.722939) ========= 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.sQ103UV3qw/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.4DvIVvEW1x/Listeners TMPDIR=/var/folders/r7/fvdp6_8944ngrts9c1mfmcsc00000w/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.004705 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 05:38:34.770567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 05:38:34.773903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 05:38:34.774334) ========= ========= Total master_lag: 0.22 =========