builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0073 starttime: 1462530107.82 results: success (0) buildid: 20160505123841 builduid: 1457772db7ef4c1982b4796b67a4914d revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.817472) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.817952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.818236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.835687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.835971) ========= 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.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 03:21:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.92M=0.001s 2016-05-06 03:21:47 (7.92 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130177 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.982545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:47.982852) ========= 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.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.020871 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:48.030899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 03:21:48.031207) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --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-aurora --rev 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 03:21:48,105 truncating revision to first 12 chars 2016-05-06 03:21:48,105 Setting DEBUG logging. 2016-05-06 03:21:48,105 attempt 1/10 2016-05-06 03:21:48,105 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0123e9f41ced?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 03:21:49,862 unpacking tar archive at: mozilla-aurora-0123e9f41ced/testing/mozharness/ program finished with exit code 0 elapsedTime=2.010417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-06 03:21:50.058683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:50.059039) ========= script_repo_revision: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:50.059429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:50.059711) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 03:21:50.073055) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-06 03:21:50.073331) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:21:50 INFO - MultiFileLogger online at 20160506 03:21:50 in /builds/slave/test 03:21:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:21:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:50 INFO - {'append_to_log': False, 03:21:50 INFO - 'base_work_dir': '/builds/slave/test', 03:21:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:21:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:50 INFO - 'branch': 'Mozilla-Aurora', 03:21:50 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:50 INFO - 'config_files': ('talos/mac_config.py',), 03:21:50 INFO - 'default_actions': ('clobber', 03:21:50 INFO - 'read-buildbot-config', 03:21:50 INFO - 'download-and-extract', 03:21:50 INFO - 'populate-webroot', 03:21:50 INFO - 'create-virtualenv', 03:21:50 INFO - 'install', 03:21:50 INFO - 'run-tests'), 03:21:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:50 INFO - 'download_minidump_stackwalk': True, 03:21:50 INFO - 'download_symbols': 'ondemand', 03:21:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:50 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:50 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:50 INFO - 'installer_path': 'installer.exe', 03:21:50 INFO - 'log_level': 'info', 03:21:50 INFO - 'log_name': 'talos', 03:21:50 INFO - 'log_to_console': True, 03:21:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:50 INFO - 'opt_config_files': (), 03:21:50 INFO - 'pip_index': False, 03:21:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:50 INFO - 'cmd': ('bash', 03:21:50 INFO - '-c', 03:21:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:50 INFO - 'enabled': True, 03:21:50 INFO - 'halt_on_failure': False, 03:21:50 INFO - 'name': 'check_screen_resolution'},), 03:21:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:50 INFO - 'cmd': ('bash', 03:21:50 INFO - '-c', 03:21:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:50 INFO - 'enabled': True, 03:21:50 INFO - 'halt_on_failure': False, 03:21:50 INFO - 'name': 'check_screen_resolution'},), 03:21:50 INFO - 'run_cmd_checks_enabled': True, 03:21:50 INFO - 'sps_profile': False, 03:21:50 INFO - 'sps_profile_interval': 0, 03:21:50 INFO - 'suite': 'dromaeojs-e10s', 03:21:50 INFO - 'system_bits': '32', 03:21:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:50 INFO - 'title': 't-yosemite-r7-0073', 03:21:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:50 INFO - 'use_talos_json': True, 03:21:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:50 INFO - 'work_dir': 'build'} 03:21:50 INFO - ##### 03:21:50 INFO - ##### Running clobber step. 03:21:50 INFO - ##### 03:21:50 INFO - Running pre-action listener: _resource_record_pre_action 03:21:50 INFO - Running main action method: clobber 03:21:50 INFO - rmtree: /builds/slave/test/build 03:21:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:52 INFO - Running post-action listener: _resource_record_post_action 03:21:52 INFO - ##### 03:21:52 INFO - ##### Running read-buildbot-config step. 03:21:52 INFO - ##### 03:21:52 INFO - Running pre-action listener: _resource_record_pre_action 03:21:52 INFO - Running main action method: read_buildbot_config 03:21:52 INFO - Using buildbot properties: 03:21:52 INFO - { 03:21:52 INFO - "project": "", 03:21:52 INFO - "product": "firefox", 03:21:52 INFO - "script_repo_revision": "production", 03:21:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:21:52 INFO - "repository": "", 03:21:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:21:52 INFO - "buildid": "20160505123841", 03:21:52 INFO - "pgo_build": "False", 03:21:52 INFO - "basedir": "/builds/slave/test", 03:21:52 INFO - "buildnumber": 319, 03:21:52 INFO - "slavename": "t-yosemite-r7-0073", 03:21:52 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:21:52 INFO - "platform": "yosemite_r7", 03:21:52 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:21:52 INFO - "branch": "mozilla-aurora", 03:21:52 INFO - "repo_path": "releases/mozilla-aurora", 03:21:52 INFO - "revision": "0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b", 03:21:52 INFO - "stage_platform": "macosx64", 03:21:52 INFO - "builduid": "1457772db7ef4c1982b4796b67a4914d", 03:21:52 INFO - "slavebuilddir": "test" 03:21:52 INFO - } 03:21:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:21:52 INFO - Running post-action listener: _resource_record_post_action 03:21:52 INFO - ##### 03:21:52 INFO - ##### Running download-and-extract step. 03:21:52 INFO - ##### 03:21:52 INFO - Running pre-action listener: _resource_record_pre_action 03:21:52 INFO - Running main action method: download_and_extract 03:21:52 INFO - mkdir: /builds/slave/test/build/tests 03:21:52 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')]} 03:21:52 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:21:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:21:53 INFO - Downloaded 1199 bytes. 03:21:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:21:53 INFO - Using the following test package requirements: 03:21:53 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:53 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:21:53 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:21:53 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'jsshell-mac.zip'], 03:21:53 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:21:53 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:21:53 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:21:53 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:21:53 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:21:53 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:21:53 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:21:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:53 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:54 INFO - Downloaded 18114223 bytes. 03:21:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:21:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:21:58 INFO - Return code: 0 03:21:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:58 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:59 INFO - Downloaded 18114223 bytes. 03:21:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:21:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:22:02 INFO - Return code: 0 03:22: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')]} 03:22:02 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:22:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:22:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:22:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:22:03 INFO - Downloaded 10840145 bytes. 03:22:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:22:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:22:04 INFO - Return code: 0 03:22:04 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')]} 03:22:04 INFO - https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:22:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:22:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:22:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:22:07 INFO - Downloaded 114081343 bytes. 03:22:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:22:07 INFO - mkdir: /builds/slave/test/properties 03:22:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:22:07 INFO - Writing to file /builds/slave/test/properties/build_url 03:22:07 INFO - Contents: 03:22:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:22:08 INFO - Running post-action listener: _resource_record_post_action 03:22:08 INFO - Running post-action listener: set_extra_try_arguments 03:22:08 INFO - ##### 03:22:08 INFO - ##### Running populate-webroot step. 03:22:08 INFO - ##### 03:22:08 INFO - Running pre-action listener: _resource_record_pre_action 03:22:08 INFO - Running main action method: populate_webroot 03:22:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:22:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:22:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:22:08 INFO - '--tppagecycles', 03:22:08 INFO - '7'], 03:22:08 INFO - 'tests': ['tsvgm']}}, 03:22:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:22:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:22:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:22:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:22:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['damp', 'tps']}, 03:22:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'tests': ['damp', 'tps']}, 03:22:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['dromaeo_dom']}, 03:22:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:22:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'other-e10s': {'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['a11yr', 03:22:08 INFO - 'ts_paint', 03:22:08 INFO - 'tpaint', 03:22:08 INFO - 'sessionrestore', 03:22:08 INFO - 'sessionrestore_no_auto_restore', 03:22:08 INFO - 'tabpaint']}, 03:22:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['tsvgx', 03:22:08 INFO - 'tsvgr_opacity', 03:22:08 INFO - 'tart', 03:22:08 INFO - 'tscrollx', 03:22:08 INFO - 'cart']}, 03:22:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:22:08 INFO - 'tsvgr_opacity', 03:22:08 INFO - 'tart', 03:22:08 INFO - 'tscrollx', 03:22:08 INFO - 'cart']}, 03:22:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'talos_options': ['--disable-e10s'], 03:22:08 INFO - 'tests': ['tp5o']}, 03:22:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'tests': ['tp5o']}, 03:22:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'talos_options': ['--disable-e10s', 03:22:08 INFO - '--xperf_path', 03:22:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:08 INFO - 'tests': ['tp5n']}, 03:22:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:08 INFO - 'talos_options': ['--xperf_path', 03:22:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:08 INFO - 'tests': ['tp5n']}}, 03:22:08 INFO - 'talos.zip': {'path': '', 03:22:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:22:08 INFO - Running post-action listener: _resource_record_post_action 03:22:08 INFO - ##### 03:22:08 INFO - ##### Running create-virtualenv step. 03:22:08 INFO - ##### 03:22:08 INFO - Running pre-action listener: _resource_record_pre_action 03:22:08 INFO - Running main action method: create_virtualenv 03:22:08 INFO - Creating virtualenv /builds/slave/test/build/venv 03:22:08 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 03:22:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:22:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:22:08 INFO - Using real prefix '/tools/python27' 03:22:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:22:09 INFO - Installing distribute.............................................................................................................................................................................................done. 03:22:11 INFO - Installing pip.................done. 03:22:11 INFO - Return code: 0 03:22:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:22:11 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')]} 03:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:11 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')]} 03:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:11 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:11 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 03:22:11 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 03:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:11 INFO - 'HOME': '/Users/cltbld', 03:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:11 INFO - 'LOGNAME': 'cltbld', 03:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:11 INFO - 'MOZ_NO_REMOTE': '1', 03:22:11 INFO - 'NO_EM_RESTART': '1', 03:22:11 INFO - 'PAGER': '/bin/cat', 03:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:11 INFO - 'PWD': '/builds/slave/test', 03:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:11 INFO - 'SHELL': '/bin/bash', 03:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:11 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:11 INFO - 'USER': 'cltbld', 03:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:11 INFO - 'XPC_FLAGS': '0x0', 03:22:11 INFO - 'XPC_SERVICE_NAME': '0', 03:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 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 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:12 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 03:22:12 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 03:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:12 INFO - 'HOME': '/Users/cltbld', 03:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PAGER': '/bin/cat', 03:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:12 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - 'XPC_FLAGS': '0x0', 03:22:12 INFO - 'XPC_SERVICE_NAME': '0', 03:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 INFO - Downloading/unpacking psutil>=0.7.1 03:22:12 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 03:22:12 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 03:22:12 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 03:22:12 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 03:22:12 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 03:22:12 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 03:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:22:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:15 INFO - Installing collected packages: psutil 03:22:15 INFO - Running setup.py install for psutil 03:22:15 INFO - building 'psutil._psutil_osx' extension 03:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:22:15 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:22:15 INFO - building 'psutil._psutil_posix' extension 03:22:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:22:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:22:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:22:16 INFO - ^ 03:22:16 INFO - 1 warning generated. 03:22:16 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 03:22:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:16 INFO - Successfully installed psutil 03:22:16 INFO - Cleaning up... 03:22:16 INFO - Return code: 0 03:22:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', '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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:22: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 mozsystemmonitor==0.0.0 03:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:16 INFO - 'HOME': '/Users/cltbld', 03:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:16 INFO - 'LOGNAME': 'cltbld', 03:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:16 INFO - 'MOZ_NO_REMOTE': '1', 03:22:16 INFO - 'NO_EM_RESTART': '1', 03:22:16 INFO - 'PAGER': '/bin/cat', 03:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:16 INFO - 'PWD': '/builds/slave/test', 03:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:16 INFO - 'SHELL': '/bin/bash', 03:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:16 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:16 INFO - 'USER': 'cltbld', 03:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:16 INFO - 'XPC_FLAGS': '0x0', 03:22:16 INFO - 'XPC_SERVICE_NAME': '0', 03:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:22:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:22:18 INFO - Installing collected packages: mozsystemmonitor 03:22:18 INFO - Running setup.py install for mozsystemmonitor 03:22:19 INFO - Successfully installed mozsystemmonitor 03:22:19 INFO - Cleaning up... 03:22:19 INFO - Return code: 0 03:22:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:19 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:19 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 03:22:19 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 03:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:19 INFO - 'HOME': '/Users/cltbld', 03:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:19 INFO - 'LOGNAME': 'cltbld', 03:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:19 INFO - 'MOZ_NO_REMOTE': '1', 03:22:19 INFO - 'NO_EM_RESTART': '1', 03:22:19 INFO - 'PAGER': '/bin/cat', 03:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:19 INFO - 'PWD': '/builds/slave/test', 03:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:19 INFO - 'SHELL': '/bin/bash', 03:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:19 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:19 INFO - 'USER': 'cltbld', 03:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:19 INFO - 'XPC_FLAGS': '0x0', 03:22:19 INFO - 'XPC_SERVICE_NAME': '0', 03:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:21 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:23 INFO - Downloading docopt-0.6.1.tar.gz 03:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:23 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:23 INFO - Running setup.py install for blobuploader 03:22:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:23 INFO - Running setup.py install for requests 03:22:23 INFO - Running setup.py install for docopt 03:22:23 INFO - Successfully installed blobuploader requests docopt 03:22:23 INFO - Cleaning up... 03:22:24 INFO - Return code: 0 03:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:24 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')]} 03:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:24 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')]} 03:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:24 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:24 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 03:22:24 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 03:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:24 INFO - 'HOME': '/Users/cltbld', 03:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:24 INFO - 'LOGNAME': 'cltbld', 03:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:24 INFO - 'MOZ_NO_REMOTE': '1', 03:22:24 INFO - 'NO_EM_RESTART': '1', 03:22:24 INFO - 'PAGER': '/bin/cat', 03:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:24 INFO - 'PWD': '/builds/slave/test', 03:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:24 INFO - 'SHELL': '/bin/bash', 03:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:24 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:24 INFO - 'USER': 'cltbld', 03:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:24 INFO - 'XPC_FLAGS': '0x0', 03:22:24 INFO - 'XPC_SERVICE_NAME': '0', 03:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-JLRIlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-ZVIiuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-z0oSDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-pZVrb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-opJcvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-mEH9Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-DByxYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:24 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-R5wHXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-EjZQoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-tdQbGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-uMzHDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-5mL3Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1V96dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-661RrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-ix89Jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-2fHUwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:25 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-EtSsid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:26 INFO - Running setup.py install for manifestparser 03:22:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:26 INFO - Running setup.py install for mozcrash 03:22:26 INFO - Running setup.py install for mozdebug 03:22:26 INFO - Running setup.py install for mozdevice 03:22:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:26 INFO - Running setup.py install for mozfile 03:22:26 INFO - Running setup.py install for mozhttpd 03:22:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:26 INFO - Running setup.py install for mozinfo 03:22:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Running setup.py install for mozInstall 03:22:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Running setup.py install for mozleak 03:22:27 INFO - Running setup.py install for mozlog 03:22:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Running setup.py install for moznetwork 03:22:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Running setup.py install for mozprocess 03:22:27 INFO - Running setup.py install for mozprofile 03:22:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:27 INFO - Running setup.py install for mozrunner 03:22:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:28 INFO - Running setup.py install for mozscreenshot 03:22:28 INFO - Running setup.py install for moztest 03:22:28 INFO - Running setup.py install for mozversion 03:22:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:28 INFO - Cleaning up... 03:22:28 INFO - Return code: 0 03:22:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:28 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:28 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 03:22:28 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 03:22:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:28 INFO - 'HOME': '/Users/cltbld', 03:22:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:28 INFO - 'LOGNAME': 'cltbld', 03:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:28 INFO - 'MOZ_NO_REMOTE': '1', 03:22:28 INFO - 'NO_EM_RESTART': '1', 03:22:28 INFO - 'PAGER': '/bin/cat', 03:22:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:28 INFO - 'PWD': '/builds/slave/test', 03:22:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:28 INFO - 'SHELL': '/bin/bash', 03:22:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:28 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:28 INFO - 'USER': 'cltbld', 03:22:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:28 INFO - 'XPC_FLAGS': '0x0', 03:22:28 INFO - 'XPC_SERVICE_NAME': '0', 03:22:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:28 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-SW6wtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:28 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-z50qAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8ATrNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-ch3OO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-GFTfj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-jfUc7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-I3k9gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-6NCJKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:29 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)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-sKGeCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-LEsK7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:29 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-VOYTks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-lkjLZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-rxKL3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-_PAoSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1jfclm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-n6QB96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:30 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)) 03:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:30 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-IfEDc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:30 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)) 03:22:30 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)) 03:22:30 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)) 03:22:30 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)) 03:22:30 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)) 03:22:30 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)) 03:22:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:33 INFO - Downloading blessings-1.6.tar.gz 03:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:33 INFO - Installing collected packages: blessings 03:22:33 INFO - Running setup.py install for blessings 03:22:33 INFO - Successfully installed blessings 03:22:33 INFO - Cleaning up... 03:22:33 INFO - Return code: 0 03:22:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:33 INFO - Reading from file tmpfile_stdout 03:22:33 INFO - Current package versions: 03:22:33 INFO - blessings == 1.6 03:22:33 INFO - blobuploader == 1.2.4 03:22:33 INFO - docopt == 0.6.1 03:22:33 INFO - manifestparser == 1.1 03:22:33 INFO - mozInstall == 1.12 03:22:33 INFO - mozcrash == 0.17 03:22:33 INFO - mozdebug == 0.1 03:22:33 INFO - mozdevice == 0.48 03:22:33 INFO - mozfile == 1.2 03:22:33 INFO - mozhttpd == 0.7 03:22:33 INFO - mozinfo == 0.9 03:22:33 INFO - mozleak == 0.1 03:22:33 INFO - mozlog == 3.1 03:22:33 INFO - moznetwork == 0.27 03:22:33 INFO - mozprocess == 0.22 03:22:33 INFO - mozprofile == 0.28 03:22:33 INFO - mozrunner == 6.11 03:22:33 INFO - mozscreenshot == 0.1 03:22:33 INFO - mozsystemmonitor == 0.0 03:22:33 INFO - moztest == 0.7 03:22:33 INFO - mozversion == 1.4 03:22:33 INFO - psutil == 3.1.1 03:22:33 INFO - requests == 1.2.3 03:22:33 INFO - wsgiref == 0.1.2 03:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:33 INFO - 'HOME': '/Users/cltbld', 03:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:33 INFO - 'LOGNAME': 'cltbld', 03:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:33 INFO - 'MOZ_NO_REMOTE': '1', 03:22:33 INFO - 'NO_EM_RESTART': '1', 03:22:33 INFO - 'PAGER': '/bin/cat', 03:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:33 INFO - 'PWD': '/builds/slave/test', 03:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:33 INFO - 'SHELL': '/bin/bash', 03:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:33 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:33 INFO - 'USER': 'cltbld', 03:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:33 INFO - 'XPC_FLAGS': '0x0', 03:22:33 INFO - 'XPC_SERVICE_NAME': '0', 03:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 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)) 03:22:34 INFO - Cleaning up... 03:22:34 INFO - Return code: 0 03:22:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22: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', 'jsonschema'] in /builds/slave/test/build 03:22: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 jsonschema 03:22:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:34 INFO - 'HOME': '/Users/cltbld', 03:22:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:34 INFO - 'LOGNAME': 'cltbld', 03:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:34 INFO - 'MOZ_NO_REMOTE': '1', 03:22:34 INFO - 'NO_EM_RESTART': '1', 03:22:34 INFO - 'PAGER': '/bin/cat', 03:22:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:34 INFO - 'PWD': '/builds/slave/test', 03:22:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:34 INFO - 'SHELL': '/bin/bash', 03:22:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:34 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:34 INFO - 'USER': 'cltbld', 03:22:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:34 INFO - 'XPC_FLAGS': '0x0', 03:22:34 INFO - 'XPC_SERVICE_NAME': '0', 03:22:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:34 INFO - Downloading/unpacking jsonschema 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:36 INFO - Downloading/unpacking functools32 (from jsonschema) 03:22:36 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 03:22:36 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 03:22:36 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 03:22:36 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 03:22:36 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 03:22:36 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 03:22:37 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:37 INFO - warning: no files found matching '*.txt' 03:22:37 INFO - no previously-included directories found matching 'build' 03:22:37 INFO - no previously-included directories found matching 'dist' 03:22:37 INFO - no previously-included directories found matching '.git*' 03:22:37 INFO - Installing collected packages: jsonschema, functools32 03:22:37 INFO - Running setup.py install for functools32 03:22:37 INFO - warning: no files found matching '*.txt' 03:22:37 INFO - no previously-included directories found matching 'build' 03:22:37 INFO - no previously-included directories found matching 'dist' 03:22:37 INFO - no previously-included directories found matching '.git*' 03:22:37 INFO - Successfully installed jsonschema functools32 03:22:37 INFO - Cleaning up... 03:22:37 INFO - Return code: 0 03:22:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:22:37 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')]} 03:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:37 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')]} 03:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:37 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 0x108f76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3285d24f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, '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.d4qHAvb2JY/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.B4NU1jST6s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 03:22:37 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 03:22:37 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 03:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:37 INFO - 'HOME': '/Users/cltbld', 03:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:37 INFO - 'LOGNAME': 'cltbld', 03:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:37 INFO - 'MOZ_NO_REMOTE': '1', 03:22:37 INFO - 'NO_EM_RESTART': '1', 03:22:37 INFO - 'PAGER': '/bin/cat', 03:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:37 INFO - 'PWD': '/builds/slave/test', 03:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:37 INFO - 'SHELL': '/bin/bash', 03:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:22:37 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:37 INFO - 'USER': 'cltbld', 03:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:37 INFO - 'XPC_FLAGS': '0x0', 03:22:37 INFO - 'XPC_SERVICE_NAME': '0', 03:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:37 INFO - Downloading/unpacking flake8 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:22:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:22:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:22:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:41 INFO - no previously-included directories found matching 'docs/_build' 03:22:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:22:41 INFO - Running setup.py install for pep8 03:22:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:22:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:22:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:22:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:22:41 INFO - no previously-included directories found matching 'docs/_build' 03:22:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:22:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:22:41 INFO - Cleaning up... 03:22:41 INFO - Return code: 0 03:22:41 INFO - Running post-action listener: _resource_record_post_action 03:22:41 INFO - Running post-action listener: _start_resource_monitoring 03:22:41 INFO - Starting resource monitoring. 03:22:41 INFO - ##### 03:22:41 INFO - ##### Running install step. 03:22:41 INFO - ##### 03:22:41 INFO - Running pre-action listener: _resource_record_pre_action 03:22:41 INFO - Running main action method: install 03:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:42 INFO - Reading from file tmpfile_stdout 03:22:42 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:42 INFO - Reading from file tmpfile_stdout 03:22:42 INFO - Output received: 03:22:42 INFO - Usage: mozinstall [options] installer 03:22:42 INFO - Options: 03:22:42 INFO - -h, --help show this help message and exit 03:22:42 INFO - -d DEST, --destination=DEST 03:22:42 INFO - Directory to install application into. [default: 03:22:42 INFO - "/builds/slave/test"] 03:22:42 INFO - --app=APP Application being installed. [default: firefox] 03:22:42 INFO - mkdir: /builds/slave/test/build/application 03:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:23:13 INFO - Reading from file tmpfile_stdout 03:23:13 INFO - Output received: 03:23:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:23:13 INFO - Running post-action listener: _resource_record_post_action 03:23:13 INFO - ##### 03:23:13 INFO - ##### Running run-tests step. 03:23:13 INFO - ##### 03:23:13 INFO - Running pre-action listener: _resource_record_pre_action 03:23:13 INFO - Running main action method: run_tests 03:23:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:13 INFO - 2016-05-06 03:23:13.991 screenresolution[6440:157258] starting screenresolution argv=screenresolution get 03:23:13 INFO - 2016-05-06 03:23:13.994 screenresolution[6440:157258] Display 0: 1600x1200x32@60 03:23:14 INFO - 2016-05-06 03:23:14.000 screenresolution[6441:157261] starting screenresolution argv=screenresolution list 03:23:14 INFO - Available Modes on Display 0 03:23:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:14 INFO - Intel Iris: 03:23:14 INFO - Chipset Model: Intel Iris 03:23:14 INFO - Type: GPU 03:23:14 INFO - Bus: Built-In 03:23:14 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:14 INFO - Vendor: Intel (0x8086) 03:23:14 INFO - Device ID: 0x0a2e 03:23:14 INFO - Revision ID: 0x0009 03:23:14 INFO - Displays: 03:23:14 INFO - Display: 03:23:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:14 INFO - Display Serial Number: 1600x1200 60 03:23:14 INFO - Main Display: Yes 03:23:14 INFO - Mirror: Off 03:23:14 INFO - Online: Yes 03:23:14 INFO - Rotation: Supported 03:23:14 INFO - Return code: 0 03:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:23:14 INFO - Python 2.7.3 03:23:14 INFO - Return code: 0 03:23:14 INFO - grabbing minidump binary from tooltool 03:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:23:14 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 0x108e15e00>, '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 0x108c7edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108e10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:23:14 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 03:23:14 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 03:23:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:23:14 INFO - Return code: 0 03:23:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:23:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:23:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:23:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:23:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:23:15 INFO - Return code: 0 03:23:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:23:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d4qHAvb2JY/Render', 03:23:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:15 INFO - 'HOME': '/Users/cltbld', 03:23:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:15 INFO - 'LOGNAME': 'cltbld', 03:23:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:23:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:15 INFO - 'MOZ_NO_REMOTE': '1', 03:23:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:23:15 INFO - 'NO_EM_RESTART': '1', 03:23:15 INFO - 'PAGER': '/bin/cat', 03:23:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:15 INFO - 'PWD': '/builds/slave/test', 03:23:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:23:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:15 INFO - 'SHELL': '/bin/bash', 03:23:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B4NU1jST6s/Listeners', 03:23:15 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 03:23:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:15 INFO - 'USER': 'cltbld', 03:23:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:15 INFO - 'XPC_FLAGS': '0x0', 03:23:15 INFO - 'XPC_SERVICE_NAME': '0', 03:23:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:23:15 INFO - mozversion application_buildid: 20160505123841 03:23:15 INFO - mozversion application_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:23:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:23:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:23:15 INFO - mozversion application_name: Firefox 03:23:15 INFO - mozversion application_remotingname: firefox-dev 03:23:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:23:15 INFO - mozversion application_vendor: Mozilla 03:23:15 INFO - mozversion application_version: 48.0a2 03:23:15 INFO - mozversion platform_buildid: 20160505123841 03:23:15 INFO - mozversion platform_changeset: 0123e9f41cedbcded87d8f6c3743ff6a4c1d8e5b 03:23:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:23:15 INFO - mozversion platform_version: 48.0a2 03:23:15 INFO - using testdate: 1462530195 03:23:15 INFO - actual date: 1462530195 03:23:15 INFO - starting webserver on 'localhost:57748' 03:23:15 INFO - SUITE-START | Running 2 tests 03:23:15 INFO - TEST-START | dromaeo_css 03:23:15 INFO - operating with platform_type : mac_ 03:23:15 INFO - Initialising browser for dromaeo_css test... 03:23:15 INFO - TEST-INFO | started process 6459 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpAfcXQW/profile http://localhost:57748/getInfo.html) 03:23:18 INFO - PROCESS | 6459 | __metrics Screen width/height:1600/1200 03:23:18 INFO - PROCESS | 6459 | colorDepth:24 03:23:18 INFO - PROCESS | 6459 | Browser inner width/height: 1024/689 03:23:18 INFO - PROCESS | 6459 | __metrics 03:23:18 ERROR - PROCESS | 6459 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:23:19 INFO - TEST-INFO | 6459: exit 0 03:23:19 INFO - Browser initialized. 03:23:19 INFO - Running cycle 1/1 for dromaeo_css test... 03:23:19 INFO - TEST-INFO | started process 6462 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpAfcXQW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:31 INFO - PROCESS | 6462 | RSS: Main: 221642752 03:23:31 INFO - PROCESS | 6462 | 03:24:20 INFO - PROCESS | 6462 | 1462530260857 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] 03:26:32 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57748/tests/dromaeo/cssquery-ext.html) 03:26:33 INFO - PROCESS | 6462 | RSS: Main: 232194048 03:26:33 INFO - PROCESS | 6462 | 03:29:35 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-ext.html (next: http://localhost:57748/tests/dromaeo/cssquery-jquery.html) 03:29:36 INFO - PROCESS | 6462 | RSS: Main: 243785728 03:29:36 INFO - PROCESS | 6462 | 03:32:38 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57748/tests/dromaeo/cssquery-mootools.html) 03:32:38 INFO - PROCESS | 6462 | RSS: Main: 245551104 03:32:38 INFO - PROCESS | 6462 | 03:35:40 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57748/tests/dromaeo/cssquery-prototype.html) 03:35:41 INFO - PROCESS | 6462 | RSS: Main: 248164352 03:35:41 INFO - PROCESS | 6462 | 03:38:44 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57748/tests/dromaeo/cssquery-yui.html) 03:38:44 INFO - PROCESS | 6462 | RSS: Main: 250298368 03:38:44 INFO - PROCESS | 6462 | 03:41:46 INFO - PROCESS | 6462 | Cycle 1(1): loaded http://localhost:57748/tests/dromaeo/cssquery-yui.html (next: http://localhost:57748/tests/dromaeo/cssquery-dojo.html) 03:41:47 INFO - PROCESS | 6462 | RSS: Main: 251170816 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | __start_tp_report 03:41:47 INFO - PROCESS | 6462 | _x_x_mozilla_page_load 03:41:47 INFO - PROCESS | 6462 | _x_x_mozilla_page_load_details 03:41:47 INFO - PROCESS | 6462 | |i|pagename|runs| 03:41:47 INFO - PROCESS | 6462 | |0;dojo.html;3398.9660103398965;3483.52972348733;3503.9124021899465;3522.2075033117553;3538.9115272118192;5884.4409781070835;5903.321118071418;5911.231539899818;5914.438128377803;5917.85205369866;5917.260342457193;5945.019071853139;5948.899837105154;5963.940360596393;5985.670788106652;5238.397584277809;5261.8947621047555;5338.06583847827;5345.198220266952;5347;5177.8867543478145;5298.523132918037;5300.257963885059;5307.681539107647;5403.945960540384;5303.840884773444;5308.628396012281;5320.494553017457;5331.57347412208;5336.30628018357;3963.4253033310297;4477.70894891831;4921.434035085975;5271;5355.036093503168;4380.649548036181;5207.427183009866;5217.660852044605;5283.841484755463;5336.332958380202;5210.635255532115;5677.318721753375;5794.072948328289;5929.73316200772;5932.436418540232;5160.664556803796;6135.845176233341;6161.507079433634;6214.130021796952;6240.157578726859;6257.843553911143;6283.403076707751;6290.21372328459;6291.465225455841;6306.274778400497;5858.355580886099;5944.494717949023;5978.641281523122;6000.33996260411;6018.127371531104;6401.263854656681;6406.583572067801;6463.7737679181;6477.870442591122;6496.415322620986;6150.876982460415;6169.5064394848305;6178.443940045418;6182.319944806068;6184.319724830265;6085.482733967574;6194.845128871724;6272.40412160844;6294.622322660655;6296.401841825019;6112.625067133413;6138.202033735586;6142.447179753844;6161.476274516626;6173.722182501798;6080.7567697291615;6154.630722156685;6156.784512542129;6164.969175154096;6182.6599537025895;4849.127157111683;4960.553550180501;5016.949830501721;5027.9692663004;5075.314832497639;3865.9806700966315;4441.866743997685;4460.598546130799;4480.350349199413;4516.07420478801;5918.704064796743;5975.581709280395;5983.461488466059;6010.459058684739;6020.4280593343565;6156.569040167235;6169.876602467925;6171.13604095427;6275.090111933744;6327.810165695037;5990.850228744316;6002.789902353396;6031.60794548353;6032.638041717511;6040.426159514839;5510.008198524304;5515.972420137874;5519.475649813261;5532.944670553324;5556.527695145877;6312.810615681537;6409.487241020707;6473.967630161819;6478.222613286247;6529.314421985623;6289.622622642656;6312.210973628296;6324.557280990287;6325.96837015812;6333.493320534439;5832.096025116114;6222.066689996536;6464.644444555594;6488.448481878904;6515.902261465987;6183.134361189527;6199.907001394892;6221.595596286227;6232.968835155795;6240.344763799736;6264.655443950626;6275.403836635513;6278.968605156945;6283.2460104787715;6284.465820405317;6082.574219804748;6141.785037523665;6194.194754681863;6222.968885155546;6274.309825919054;6065.302490213682;6123.755049797958;6127.311699316976;6153.094251360284;6217.751289948352;5932.8813423732145;6062.363451837494;6087.786927457518;6087.878242435215;6125.785597504066;6106.72519736604;6134.6625935573975;6136.478399336106;6160.44555989963;6203.96898015507;5130;5666.660000399989;6127.311699316976;6143.709820937654;6159.722812473539 03:41:47 INFO - PROCESS | 6462 | |1;ext.html;518.5203686589905;538.321714639554;540.5999560325358;569.3793764796372;588.693879182825;38857.22285554291;40143.99999999996;40145;40146.59853401465;40195.79902100489;34865.651343486556;41188.79405602975;41347.79326103365;41818.58181418185;42417.72746817593;9391.389559678633;9600.183984361338;9629.61481540741;9683.12851843334;9685.370450920704;9499.905000950006;9705.563249653747;9742;9757.902420975806;9765.023497650249;9680.322377433582;9685.32202745808;9692;9756;9770.804583908317;9635.759106022335;9660.033996600283;9690.515474226295;9721.222302215805;9755.463449510275;8331.916850809417;9537.046295370477;9573.904260957372;9641.517924103837;9658.37220580667;39254.214915701814;39463.81608551748;39840.60159398398;39925.80037099825;39991;3074.9077527674203;3145.6697046809986;3201.5357773123;3255.6744325567493;3339.699427051553;26994;28367.57448638272;28491.85754071237;29263;29815.95644152466;2726.400191957786;2743.7667798237176;2812.7890408219464;2839.3753374257835;2882.2650224192907;418177;425326;428810;429413;431186;35531.28937421237;36388.636113639055;67514.66242668756;68598.65700671564;68658.62682746317;38117.23765524674;38126.66556670503;38143;38425.42361864574;38635;12156.574519891938;14692.19192944377;15859.603509912115;15947.282372293275;16073.839261607469;11226.989570938655;12909.806352904714;13034.543790967271;13228.470861165639;13420.865791342158;7368.963155184297;8549;8604.053554109043;8612.956935215409;8655.355482458313;3529.3117842021074;3542.2384187399316;3568.3041806847937;3569.964300356963;3601.081724160375;984.6701355045997;1019.2712210769336;1026.0099004460583;1027.8972102789662;1029.361795686679;30200.84899575532;30514.69485305163;30771.384572308427;30924;31328;1044.5717255925185;1124.3366413815725;1127.7462570921477;1140.1790710688292;1173.8261738261738;13381.53164639233;16010.67978640444;16017.839821601869;16092.678146437005;16271.755923661154;464225;503069;595865.0206749025;604846;614918;58433.70783146057;62957;63351;63719.681401593625;64031;1327.9203247805162;1471.1320321010655;1519.3087145349061;1521.4522771801921;1599.5521254048886;25854.48291034131;30319.545206821473;30345.69654303517;30739.846300768353;31231.531527027568;54991.17513237385;55226.72386638202;55334.44665553293;55362.72318638381;55969.16046259391;575671;588900;590087;593462;594105;28384.716152838206;28763.28091797722;28924.98762543301;29178;29194.124176274745;46545.53454465412;46961;47929;48224.276635851194;49186;38944.80527597344;39045.414318785806;39174;39304.41043384409;39304.606953930095;38784.41823372708;39000.969253673946;39113;39284.6071539281;40027.79986100051;38158.61841381551;38321.23357532778;38697.6130238694;39022;39315.410268846565;32718.69125234964;34563;34735.30529389449;34841.9547413578;34847.303053938274;21166.894165529073;24261;25436;25970.740292597588;26271.080512181983 03:41:47 INFO - PROCESS | 6462 | |2;jquery.html;334.23293541322664;400.6274165026526;401.5482582095142;412.84931000184923;414.299833281754;19222.23111075557;20738.89630551845;21455.892720536394;21612.675809862863;21691.915404229803;17137.82862171378;19865.801341986575;20751.06620202091;21796.346109616738;21944.341669749934;5292.894142117156;5854.76580936762;6070.787522436715;6072.817815465543;6091.756329746805;5785.247917770705;5918;5931.584789064767;5997.280326360843;6003.219581454427;5721.914171287434;5829.504492118153;5937.198478205431;5940.554458415636;5969.22400087988;5803.361630220672;5829.883402331972;5855.590108692393;5872.500837428824;5941.10883367494;5126.53861152498;5185.066687996159;5210.973945130288;5227.085260079471;5286.127788914824;24443.755562444505;25977.830997605153;26613.60079598788;26723;26906.730932690618;2176.5538064696857;2284.725832900046;2287.565362581104;2367.4318163640773;2550.60465627828;9348.95325523368;10048.849267260997;13796.862031379758;14501.83985281175;14580.489682861049;1676.3881183368105;1707.6584683063313;1798.4065258464807;1812.6102887879038;1828.9268429262813;116206.41896790547;133713;136150;138819.99999999898;139076;10375.599294095351;11204.943975280072;11786.174967752175;11908.225965312227;12055.397230138458;12607;15490.148041857807;15594.766078508832;15604.531864044096;15622.140782256858;8925;9054.81890362189;9072.682456114004;9083.863742043875;9167.17495425415;7406.851862962711;8984.775380615536;8988.370814042953;9026.909730902607;9185.448873067504;4633.62930965522;4716.716996980193;4718.05638872227;4738.07607516531;4770.308305295713;1724.1379310344828;1959.7648282206226;1984.8312893403934;1986.1658103596524;2006.8795872247713;836.481381543447;881.4931414436596;943.4292253186724;1002.6490728245055;1024.8872624011353;16896.915515422344;17192.754852552218;20057.598848022957;21038.158473661188;21274;918.2975024106564;958.9712308630752;990.1534188268927;1019.8317277649105;1067.6636859389268;8768.78078048054;10843.024127828534;11038.96103896111;11145.498452569655;11232.382218978035;608766;615587;621285;632036.8398158072;633806;71554;133226.66773332143;141644;141648.29175854262;145277.27361363126;1005.8943810899897;1013.143893410082;1022.5245260953543;1029.3875144289136;1031.742064483879;14933.925330373278;15141.621459463602;18385.724214137066;18494.0752962354;18700;37661.435078474395;41223.58776412198;44298.892527687065;48250.75874620605;52499.73750131225;66291;73085.6345718268;74325;75142.24857751353;80717;5108.3103780989095;5136.1268584340005;7991;7992.480488768211;8294.976936186065;10941.507632156412;11604.593839215924;12257.387130643254;12516.186447880858;13266.933665331997;11073.889261107608;11136.610218642309;11175.38535380562;11332.263402878787;11367.976882080653;10976.176786740867;11097.223194376316;11233.269837460539;11324.150688698215;11384.601538946097;5435.076037073786;9414.57634406467;9741.853872191781;11410.201285909907;11624.767504650028;8277.420580559545;8329.167083291622;8424.199701028392;8473.152684731478;8529;9747.268954828274;11213.71965700864;11228.494717737885;11453.427328633701;11503.654890353302 03:41:47 INFO - PROCESS | 6462 | |3;mootools.html;1557.9688406231871;1602.046782164612;1858.3960212930792;1962.3622322745102;1990.3829812758038;8178.550179740112;8736.825263494726;8808.603612837422;9126.406783559072;9166.220871225945;8783.516906570136;8850.291976641869;8942.821143577125;8964.417312874659;9062.954685226581;2480.2063339731294;2495.950080998388;2496.6005439129744;2503.2240005598233;2508.4983003399393;2447.6940382452194;2510.0210917742097;2511.46003609224;2520.6345079963394;2526.014854206861;2487.1543675150447;2489.9751002489925;2502.2868482482495;2511.2466260121982;2543.504016716741;2444.6210837320154;2462.6429167770752;2466.1984854922134;2482;2486.477839653675;2470.839395439291;2472.876356182184;2490.750924907513;2498.537770512461;2504.211173480348;9039.954800226022;9065.22945549629;9124.996250412449;9129.863052054225;9152.771180720469;5436.048691479014;5616.747246373923;5641.266635337421;5653.236813030229;5674.602777805555;3572.07126147201;3586.3544561978833;3614.493970844084;3646.7264955128207;3670.687991520724;879.3097418526426;1017.9643712470028;1026.9794604107951;1035.585765693721;1044.890286519912;12109.697257568543;12179.08656850731;12220.938895305555;12230.449629766681;12246.816297755451;4241.300185469363;4937.1606826839525;4947.48051454592;4997.425296090996;5013.423456302572;10232.897671023344;10235.539400726837;10281.485925703684;10286.794264114676;10317.27779055474;5464.289642346549;5512.118061110203;5553.611247212656;5600.971995140079;5601.243832082698;4492.528284530143;4812.326274321597;4992.750362481861;5057.494250574913;5060.165072762953;4099.631033207026;4162.521310049323;4206.8737937861915;4245.532991370947;4256.829726810893;1347.5889853594551;1385.9514916977857;1388.673661689502;1394.5049507424922;1395.7069015506654;866.276658989738;949.6960972488876;965.1410244882061;973.3089506450358;986.1617625018677;4811.975940120347;5544.3346798383645;5635.464630860144;5983.521318294526;6154.169187159676;461.2942198436398;464.3220897489703;466.53346653346654;476.82357527714277;481.20601008335825;4017.6785857131945;4077.6941729369823;4111.588841115865;4144.233316836395;4173.165366926566;10508.947455262674;10991.340519568852;11072.114230861513;11083.778324433628;11115.777684446266;9320.906790932004;10353.223508236912;10979.615713449992;11067.280626759395;11075.446227688744;8219.260266576037;8287.254147126667;8315.459495132796;8370.455920365157;8381.28759055475;5900.881982360243;5914.749526939514;6060.484858786876;6139.324674285915;6216;8745.60644770974;9523.047695230422;9774.706758797247;9855.852162217428;10087.495625218857;10286.919873413482;10290;10293.176545876462;10317.123044541298;10317.17462602975;6878.518503704893;7299.890501642373;7503.9249607503225;7618.542887426772;7626.656800443882;7034.402075823612;8427;8449.11284315138;8503.149685031447;8511.319094472552;7409;7530.5827484987485;7541.095068591804;7703.229677032252;7763.301302882767;7454.888176677463;7491.88762168557;7562.941188233762;7604.80987975305;7646.464747467624;6962.756303529555;6992.16094068715;7006.439484841302;7023.192332881784;7060.858782824212;4945.307656928105;4984.102861484967;4998.625103117208;5154.381474223117;5168;4644.908446515134;4996.100701873697;5130.230465430215;5187.377514698261;5408.107662235687 03:41:47 INFO - PROCESS | 6462 | |4;prototype.html;278.7797639864507;300.50116806101863;301.1627675063325;306.70862680453575;307.7230492556699;5064.468230835764;5480.506754392104;5509.559235261179;5577.494076599321;5614.9999999999945;4306.784660766957;5078.984203159364;5425.755840987155;5472.288602481682;5515.448455154493;3965.6827453803626;4004.3392840181336;4088.5298190708154;4107.8973025674295;4134.338505839067;3439.4152993991083;3904.8285514345725;4135.193637240739;4160.583941605831;4181.644560212386;3440.088376580208;3465.6014558325737;4094.2425651254466;4126.793660316987;4133.256013917494;3302.1579497228295;3812.7902965336893;3834.6740527055235;3941.4087886817065;4078.859278355664;3150.842457877097;3309.749370428108;3508.6842184203542;3509.508668786372;3530.6998905093096;5336.359636843604;5342.038433082043;5343.759530821122;5348.304720386363;5358.732063396815;3949.328614135604;4119.279126152941;4283.957160428387;4707.529247075265;4724.83463078794;5829.912551311733;5849.590528662995;5851.342910680824;5876.29484461863;5890.499307558863;3314.5691060162267;3727.956172271768;3915.7650540967347;3926.764394136361;3936.114374265796;4428.114377124588;5548.889022219573;6003.309619393782;6163.87672246557;6214.805006620513;3268.50972354144;3341.047801376596;3899.493065901415;3957.3272543667645;3961.346377847623;3065.7700672449655;4011.7592944423427;4028.4964379452567;4034.2133284009924;4039.2325458162854;4539.6368290536675;4696.8590942271785;4712.340272361872;4716.033213191287;4720.8433933686465;4847.466778654345;5404;5470.398256191816;5657.12314591239;5874.265716785402;1447.8045463862454;1453.6147920800997;1461.8465061168638;1464.5093893545568;1471.6762312291278;889.213046453893;939.5631031570352;959.4339339789558;963.5760265483165;970.8301496696464;5283.630145889828;5431.565474762009;5510.559155267569;5873.177755114288;5877.764889404376;5656.575756818255;6150.169727086786;6293.622382657055;6549.2468366137555;6600.590784363672;5529.557635389239;5811.912821307684;5831.504322132666;5833.037548804485;5907.940920590825;3153.8265395403014;3407.9488807667904;3438.2494004796063;3441.8451169697423;3460.2387474755515;5882.794102206402;6210.534209934182;6330.525210609223;6420;6730.9326906731285;4861.565838077776;5352.732363381815;6195.349488303754;6221.222347206599;6260.619265596618;5403.567714582824;5817.970910145422;5839.328477225174;6336.714847831859;6389.233292004989;5249.7112658804135;6079.969600151971;6103.938960610337;6124.908126378019;6140.9078863816185;4902.534259245366;4926.039422312615;5230.895382092413;5651.406602306699;6022.795440911747;5808.796692115756;5866.2373891393845;6214.751409943552;6287.748490060347;6335.1447554579545;3087.6603573606435;3306.50402439636;3346.975080076616;3970.8213130409777;3975.34406822871;5073.579397768631;5793.04414771558;5805.042168042226;5965;6144.56988010835;5491.643043202179;5748.252727145445;5774.451427114417;5871.911921321269;5901;5149.407818100966;5757.021306377842;5843.707814609167;5845.883082338244;5880.470757631833;4754.667173297836;5195.155719719445;5713.885722285614;5739.397363276963;5753.568482363756;4664.673472857004;5141.7686204120155;5450.727463626724;5671.206031155559;5743.879943410995;5186.118359878754;5537.473939975857;5650.773969041356;5786.408737597026;5798.6810725410505 03:41:47 INFO - PROCESS | 6462 | |5;yui.html;543.307283213902;597.074534471569;652.8563715982483;695.5791745993673;727.9417646588273;13037;14703.33834977426;14727.48453804117;14833.184174870377;14889;13559.59321220365;13737.10708803927;14028.859711402883;14048.789268160986;14071.437142514287;726.7165805335924;918.3846822628838;930.2836815651943;937.9296552758536;942.8450148567983;853.185208126238;947.5167664491124;950.116391755667;950.9762255943622;954.7231302922145;944.7260294514582;967.8016006718606;980.5832521178506;987.782687808681;988.77258230607;948.6537413843967;979.0698836105728;982.8378317577591;983.2674657380262;984.3306551544947;699.1889408286414;722.0360818307565;728.3444899590359;734.1960553194244;735.7130719019533;14417.85582144186;14550;14616;14616.488422905148;14658.780118298235;12385.19496232742;12595.3072581008;12613.738626137312;12771.936140319332;12861.228326300452;9933.552990115462;10067.5469603868;10154.187664986783;10187.337823041478;10286.22853285999;3400.268942177444;3425.3491836550975;3500.072480792592;3535.8939231823088;3690.538682664667;72194.27805721929;73247.63376183138;78151;78433.60783196104;78735.60632196859;9579.521023948775;9657.179139773058;9716.3198576099;9758.512074396253;9797.80404391908;20942;21247.78752212489;21263.68104478434;21276.36170914875;21310.254141104986;7418.7032518699725;7448.851022979618;7568.697252109964;7576.356009739234;7754.991851059439;4052.979735101306;4149.070682132823;4150.335946248586;4193.454850869429;4265.658747300208;3676.2279921216846;3740.102375429877;3743.5507739071486;3748.325301445766;3749.3063783200196;667.8029981155571;673.2123415603658;681.1281559603616;683.0232767142934;684.1106561470069;542.3843937131385;563.0343960108434;581.6103210848742;594.9732262048218;608.0271565495173;7492.288232617892;8053.033635963722;8177.632006559719;8203.548804815793;8589.785255368666;543.13641310316;568.2754488027715;583.608981982073;607.0257237134335;612.9816105516842;3235.6764323567927;3812.599677033927;3847.5575308839843;3858.1704933439446;3884.3590807517007;79113.20886791173;93929;94753;95544;101222;80751.59624201959;81176.59411703022;83177;83266.33467330619;84226.57886710527;73785.26214737784;74126.6293668539;74289;74319.62840185764;74323.62838185775;6363.236411630634;7562.7353042645445;7661.731839385595;7798.571078590732;8022.759317220492;39865.80067099646;42596.5740342605;43662.908427288305;44094.11811763811;46642;63215.6839215801;63573.364266356744;63935.72128557496;64362.67818660877;67912;9451.243900488082;11111;11193.832092518782;11545.422728863692;11661.24201926872;11985.22096063753;12086.637400877988;12260.877391225973;12388.194767340094;12458.563950261698;11247.268927519684;11491.885081149416;11554.017908477537;11616.94191529037;11654.951054405145;11632.76734465289;11683.824742628698;11719.707007324543;11725.706857328634;11759.529618815493;9432.764180895258;10731.624393146203;10779.73050673708;10786.622468213574;10971.615993440191;9585.616575336908;9729.610815567297;9773.462459564793;9938.403695777988;10109.292349535463;9313.86029209576;9634.841202992673;9758.268129890144;9764.951175244363;9801.950990245003 03:41:47 INFO - PROCESS | 6462 | __end_tp_report 03:41:47 INFO - PROCESS | 6462 | __start_cc_report 03:41:47 INFO - PROCESS | 6462 | _x_x_mozilla_cycle_collect,414 03:41:47 INFO - PROCESS | 6462 | __end_cc_report 03:41:47 INFO - PROCESS | 6462 | __startTimestamp1462531307160__endTimestamp 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | ------- Summary: start ------- 03:41:47 INFO - PROCESS | 6462 | Number of tests: 6 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#0] dojo.html Cycles:165 Average:5795.56 Median:6080.76 stddev:670.75 (11.0%) stddev-sans-first:645.92 03:41:47 INFO - PROCESS | 6462 | Values: 3399.0 3483.5 3503.9 3522.2 3538.9 5884.4 5903.3 5911.2 5914.4 5917.9 5917.3 5945.0 5948.9 5963.9 5985.7 5238.4 5261.9 5338.1 5345.2 5347.0 5177.9 5298.5 5300.3 5307.7 5403.9 5303.8 5308.6 5320.5 5331.6 5336.3 3963.4 4477.7 4921.4 5271.0 5355.0 4380.6 5207.4 5217.7 5283.8 5336.3 5210.6 5677.3 5794.1 5929.7 5932.4 5160.7 6135.8 6161.5 6214.1 6240.2 6257.8 6283.4 6290.2 6291.5 6306.3 5858.4 5944.5 5978.6 6000.3 6018.1 6401.3 6406.6 6463.8 6477.9 6496.4 6150.9 6169.5 6178.4 6182.3 6184.3 6085.5 6194.8 6272.4 6294.6 6296.4 6112.6 6138.2 6142.4 6161.5 6173.7 6080.8 6154.6 6156.8 6165.0 6182.7 4849.1 4960.6 5016.9 5028.0 5075.3 3866.0 4441.9 4460.6 4480.4 4516.1 5918.7 5975.6 5983.5 6010.5 6020.4 6156.6 6169.9 6171.1 6275.1 6327.8 5990.9 6002.8 6031.6 6032.6 6040.4 5510.0 5516.0 5519.5 5532.9 5556.5 6312.8 6409.5 6474.0 6478.2 6529.3 6289.6 6312.2 6324.6 6326.0 6333.5 5832.1 6222.1 6464.6 6488.4 6515.9 6183.1 6199.9 6221.6 6233.0 6240.3 6264.7 6275.4 6279.0 6283.2 6284.5 6082.6 6141.8 6194.2 6223.0 6274.3 6065.3 6123.8 6127.3 6153.1 6217.8 5932.9 6062.4 6087.8 6087.9 6125.8 6106.7 6134.7 6136.5 6160.4 6204.0 5130.0 5666.7 6127.3 6143.7 6159.7 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#1] ext.html Cycles:180 Average:65505.74 Median:39079.21 stddev:141543.38 (362.2%) stddev-sans-first:141856.34 03:41:47 INFO - PROCESS | 6462 | Values: 518.5 538.3 540.6 569.4 588.7 38857.2 40144.0 40145.0 40146.6 40195.8 34865.7 41188.8 41347.8 41818.6 42417.7 9391.4 9600.2 9629.6 9683.1 9685.4 9499.9 9705.6 9742.0 9757.9 9765.0 9680.3 9685.3 9692.0 9756.0 9770.8 9635.8 9660.0 9690.5 9721.2 9755.5 8331.9 9537.0 9573.9 9641.5 9658.4 39254.2 39463.8 39840.6 39925.8 39991.0 3074.9 3145.7 3201.5 3255.7 3339.7 26994.0 28367.6 28491.9 29263.0 29816.0 2726.4 2743.8 2812.8 2839.4 2882.3 418177.0 425326.0 428810.0 429413.0 431186.0 35531.3 36388.6 67514.7 68598.7 68658.6 38117.2 38126.7 38143.0 38425.4 38635.0 12156.6 14692.2 15859.6 15947.3 16073.8 11227.0 12909.8 13034.5 13228.5 13420.9 7369.0 8549.0 8604.1 8613.0 8655.4 3529.3 3542.2 3568.3 3570.0 3601.1 984.7 1019.3 1026.0 1027.9 1029.4 30200.8 30514.7 30771.4 30924.0 31328.0 1044.6 1124.3 1127.7 1140.2 1173.8 13381.5 16010.7 16017.8 16092.7 16271.8 464225.0 503069.0 595865.0 604846.0 614918.0 58433.7 62957.0 63351.0 63719.7 64031.0 1327.9 1471.1 1519.3 1521.5 1599.6 25854.5 30319.5 30345.7 30739.8 31231.5 54991.2 55226.7 55334.4 55362.7 55969.2 575671.0 588900.0 590087.0 593462.0 594105.0 28384.7 28763.3 28925.0 29178.0 29194.1 46545.5 46961.0 47929.0 48224.3 49186.0 38944.8 39045.4 39174.0 39304.4 39304.6 38784.4 39001.0 39113.0 39284.6 40027.8 38158.6 38321.2 38697.6 39022.0 39315.4 32718.7 34563.0 34735.3 34842.0 34847.3 21166.9 24261.0 25436.0 25970.7 26271.1 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#2] jquery.html Cycles:180 Average:35688.61 Median:13405.59 stddev:104035.44 (776.1%) stddev-sans-first:104293.41 03:41:47 INFO - PROCESS | 6462 | Values: 334.2 400.6 401.5 412.8 414.3 19222.2 20738.9 21455.9 21612.7 21691.9 17137.8 19865.8 20751.1 21796.3 21944.3 5292.9 5854.8 6070.8 6072.8 6091.8 5785.2 5918.0 5931.6 5997.3 6003.2 5721.9 5829.5 5937.2 5940.6 5969.2 5803.4 5829.9 5855.6 5872.5 5941.1 5126.5 5185.1 5211.0 5227.1 5286.1 24443.8 25977.8 26613.6 26723.0 26906.7 2176.6 2284.7 2287.6 2367.4 2550.6 9349.0 10048.8 13796.9 14501.8 14580.5 1676.4 1707.7 1798.4 1812.6 1828.9 116206.4 133713.0 136150.0 138820.0 139076.0 10375.6 11204.9 11786.2 11908.2 12055.4 12607.0 15490.1 15594.8 15604.5 15622.1 8925.0 9054.8 9072.7 9083.9 9167.2 7406.9 8984.8 8988.4 9026.9 9185.4 4633.6 4716.7 4718.1 4738.1 4770.3 1724.1 1959.8 1984.8 1986.2 2006.9 836.5 881.5 943.4 1002.6 1024.9 16896.9 17192.8 20057.6 21038.2 21274.0 918.3 959.0 990.2 1019.8 1067.7 8768.8 10843.0 11039.0 11145.5 11232.4 608766.0 615587.0 621285.0 632036.8 633806.0 71554.0 133226.7 141644.0 141648.3 145277.3 1005.9 1013.1 1022.5 1029.4 1031.7 14933.9 15141.6 18385.7 18494.1 18700.0 37661.4 41223.6 44298.9 48250.8 52499.7 66291.0 73085.6 74325.0 75142.2 80717.0 5108.3 5136.1 7991.0 7992.5 8295.0 10941.5 11604.6 12257.4 12516.2 13266.9 11073.9 11136.6 11175.4 11332.3 11368.0 10976.2 11097.2 11233.3 11324.2 11384.6 5435.1 9414.6 9741.9 11410.2 11624.8 8277.4 8329.2 8424.2 8473.2 8529.0 9747.3 11213.7 11228.5 11453.4 11503.7 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#3] mootools.html Cycles:180 Average:5815.98 Median:4946.39 stddev:3245.28 (65.6%) stddev-sans-first:3238.61 03:41:47 INFO - PROCESS | 6462 | Values: 1558.0 1602.0 1858.4 1962.4 1990.4 8178.6 8736.8 8808.6 9126.4 9166.2 8783.5 8850.3 8942.8 8964.4 9063.0 2480.2 2496.0 2496.6 2503.2 2508.5 2447.7 2510.0 2511.5 2520.6 2526.0 2487.2 2490.0 2502.3 2511.2 2543.5 2444.6 2462.6 2466.2 2482.0 2486.5 2470.8 2472.9 2490.8 2498.5 2504.2 9040.0 9065.2 9125.0 9129.9 9152.8 5436.0 5616.7 5641.3 5653.2 5674.6 3572.1 3586.4 3614.5 3646.7 3670.7 879.3 1018.0 1027.0 1035.6 1044.9 12109.7 12179.1 12220.9 12230.4 12246.8 4241.3 4937.2 4947.5 4997.4 5013.4 10232.9 10235.5 10281.5 10286.8 10317.3 5464.3 5512.1 5553.6 5601.0 5601.2 4492.5 4812.3 4992.8 5057.5 5060.2 4099.6 4162.5 4206.9 4245.5 4256.8 1347.6 1386.0 1388.7 1394.5 1395.7 866.3 949.7 965.1 973.3 986.2 4812.0 5544.3 5635.5 5983.5 6154.2 461.3 464.3 466.5 476.8 481.2 4017.7 4077.7 4111.6 4144.2 4173.2 10508.9 10991.3 11072.1 11083.8 11115.8 9320.9 10353.2 10979.6 11067.3 11075.4 8219.3 8287.3 8315.5 8370.5 8381.3 5900.9 5914.7 6060.5 6139.3 6216.0 8745.6 9523.0 9774.7 9855.9 10087.5 10286.9 10290.0 10293.2 10317.1 10317.2 6878.5 7299.9 7503.9 7618.5 7626.7 7034.4 8427.0 8449.1 8503.1 8511.3 7409.0 7530.6 7541.1 7703.2 7763.3 7454.9 7491.9 7562.9 7604.8 7646.5 6962.8 6992.2 7006.4 7023.2 7060.9 4945.3 4984.1 4998.6 5154.4 5168.0 4644.9 4996.1 5130.2 5187.4 5408.1 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#4] prototype.html Cycles:180 Average:4682.92 Median:5346.03 stddev:1494.54 (28.0%) stddev-sans-first:1461.72 03:41:47 INFO - PROCESS | 6462 | Values: 278.8 300.5 301.2 306.7 307.7 5064.5 5480.5 5509.6 5577.5 5615.0 4306.8 5079.0 5425.8 5472.3 5515.4 3965.7 4004.3 4088.5 4107.9 4134.3 3439.4 3904.8 4135.2 4160.6 4181.6 3440.1 3465.6 4094.2 4126.8 4133.3 3302.2 3812.8 3834.7 3941.4 4078.9 3150.8 3309.7 3508.7 3509.5 3530.7 5336.4 5342.0 5343.8 5348.3 5358.7 3949.3 4119.3 4284.0 4707.5 4724.8 5829.9 5849.6 5851.3 5876.3 5890.5 3314.6 3728.0 3915.8 3926.8 3936.1 4428.1 5548.9 6003.3 6163.9 6214.8 3268.5 3341.0 3899.5 3957.3 3961.3 3065.8 4011.8 4028.5 4034.2 4039.2 4539.6 4696.9 4712.3 4716.0 4720.8 4847.5 5404.0 5470.4 5657.1 5874.3 1447.8 1453.6 1461.8 1464.5 1471.7 889.2 939.6 959.4 963.6 970.8 5283.6 5431.6 5510.6 5873.2 5877.8 5656.6 6150.2 6293.6 6549.2 6600.6 5529.6 5811.9 5831.5 5833.0 5907.9 3153.8 3407.9 3438.2 3441.8 3460.2 5882.8 6210.5 6330.5 6420.0 6730.9 4861.6 5352.7 6195.3 6221.2 6260.6 5403.6 5818.0 5839.3 6336.7 6389.2 5249.7 6080.0 6103.9 6124.9 6140.9 4902.5 4926.0 5230.9 5651.4 6022.8 5808.8 5866.2 6214.8 6287.7 6335.1 3087.7 3306.5 3347.0 3970.8 3975.3 5073.6 5793.0 5805.0 5965.0 6144.6 5491.6 5748.3 5774.5 5871.9 5901.0 5149.4 5757.0 5843.7 5845.9 5880.5 4754.7 5195.2 5713.9 5739.4 5753.6 4664.7 5141.8 5450.7 5671.2 5743.9 5186.1 5537.5 5650.8 5786.4 5798.7 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - PROCESS | 6462 | [#5] yui.html Cycles:180 Average:18111.73 Median:31914.33 stddev:25655.75 (80.4%) stddev-sans-first:25693.81 03:41:47 INFO - PROCESS | 6462 | Values: 543.3 597.1 652.9 695.6 727.9 13037.0 14703.3 14727.5 14833.2 14889.0 13559.6 13737.1 14028.9 14048.8 14071.4 726.7 918.4 930.3 937.9 942.8 853.2 947.5 950.1 951.0 954.7 944.7 967.8 980.6 987.8 988.8 948.7 979.1 982.8 983.3 984.3 699.2 722.0 728.3 734.2 735.7 14417.9 14550.0 14616.0 14616.5 14658.8 12385.2 12595.3 12613.7 12771.9 12861.2 9933.6 10067.5 10154.2 10187.3 10286.2 3400.3 3425.3 3500.1 3535.9 3690.5 72194.3 73247.6 78151.0 78433.6 78735.6 9579.5 9657.2 9716.3 9758.5 9797.8 20942.0 21247.8 21263.7 21276.4 21310.3 7418.7 7448.9 7568.7 7576.4 7755.0 4053.0 4149.1 4150.3 4193.5 4265.7 3676.2 3740.1 3743.6 3748.3 3749.3 667.8 673.2 681.1 683.0 684.1 542.4 563.0 581.6 595.0 608.0 7492.3 8053.0 8177.6 8203.5 8589.8 543.1 568.3 583.6 607.0 613.0 3235.7 3812.6 3847.6 3858.2 3884.4 79113.2 93929.0 94753.0 95544.0 101222.0 80751.6 81176.6 83177.0 83266.3 84226.6 73785.3 74126.6 74289.0 74319.6 74323.6 6363.2 7562.7 7661.7 7798.6 8022.8 39865.8 42596.6 43662.9 44094.1 46642.0 63215.7 63573.4 63935.7 64362.7 67912.0 9451.2 11111.0 11193.8 11545.4 11661.2 11985.2 12086.6 12260.9 12388.2 12458.6 11247.3 11491.9 11554.0 11616.9 11655.0 11632.8 11683.8 11719.7 11725.7 11759.5 9432.8 10731.6 10779.7 10786.6 10971.6 9585.6 9729.6 9773.5 9938.4 10109.3 9313.9 9634.8 9758.3 9765.0 9802.0 03:41:47 INFO - PROCESS | 6462 | -------- Summary: end -------- 03:41:47 INFO - PROCESS | 6462 | 03:41:47 INFO - TEST-INFO | 6462: exit 0 03:41:47 INFO - TEST-OK | dromaeo_css | took 1112077ms 03:41:47 INFO - TEST-START | kraken 03:41:47 INFO - operating with platform_type : mac_ 03:41:47 INFO - Initialising browser for kraken test... 03:41:47 INFO - TEST-INFO | started process 6489 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpL2tn57/profile http://localhost:57748/getInfo.html) 03:41:50 INFO - PROCESS | 6489 | __metrics Screen width/height:1600/1200 03:41:50 INFO - PROCESS | 6489 | colorDepth:24 03:41:50 INFO - PROCESS | 6489 | Browser inner width/height: 1024/689 03:41:50 INFO - PROCESS | 6489 | __metrics 03:41:50 ERROR - PROCESS | 6489 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:41:51 INFO - TEST-INFO | 6489: exit 0 03:41:51 INFO - Browser initialized. 03:41:51 INFO - Running cycle 1/1 for kraken test... 03:41:51 INFO - TEST-INFO | started process 6492 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpL2tn57/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:03 INFO - PROCESS | 6492 | RSS: Main: 221560832 03:42:03 INFO - PROCESS | 6492 | 03:42:06 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57748/tests/kraken/driver.html?testName=audio-beat-detection) 03:42:07 INFO - PROCESS | 6492 | RSS: Main: 233345024 03:42:07 INFO - PROCESS | 6492 | 03:42:09 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57748/tests/kraken/driver.html?testName=audio-dft) 03:42:10 INFO - PROCESS | 6492 | RSS: Main: 242708480 03:42:10 INFO - PROCESS | 6492 | 03:42:13 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57748/tests/kraken/driver.html?testName=audio-fft) 03:42:14 INFO - PROCESS | 6492 | RSS: Main: 245829632 03:42:14 INFO - PROCESS | 6492 | 03:42:16 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57748/tests/kraken/driver.html?testName=audio-oscillator) 03:42:17 INFO - PROCESS | 6492 | RSS: Main: 246374400 03:42:17 INFO - PROCESS | 6492 | 03:42:19 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57748/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:19 INFO - PROCESS | 6492 | RSS: Main: 246607872 03:42:19 INFO - PROCESS | 6492 | 03:42:22 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57748/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:23 INFO - PROCESS | 6492 | RSS: Main: 246460416 03:42:23 INFO - PROCESS | 6492 | 03:42:25 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57748/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:26 INFO - PROCESS | 6492 | RSS: Main: 247795712 03:42:26 INFO - PROCESS | 6492 | 03:42:29 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57748/tests/kraken/driver.html?testName=json-parse-financial) 03:42:30 INFO - PROCESS | 6492 | RSS: Main: 247894016 03:42:30 INFO - PROCESS | 6492 | 03:42:31 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57748/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:32 INFO - PROCESS | 6492 | RSS: Main: 247848960 03:42:32 INFO - PROCESS | 6492 | 03:42:34 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:35 INFO - PROCESS | 6492 | RSS: Main: 247939072 03:42:35 INFO - PROCESS | 6492 | 03:42:37 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:37 INFO - PROCESS | 6492 | RSS: Main: 247967744 03:42:37 INFO - PROCESS | 6492 | 03:42:40 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:41 INFO - PROCESS | 6492 | RSS: Main: 247980032 03:42:41 INFO - PROCESS | 6492 | 03:42:44 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:45 INFO - PROCESS | 6492 | RSS: Main: 247971840 03:42:45 INFO - PROCESS | 6492 | 03:42:46 INFO - PROCESS | 6492 | Cycle 1(1): loaded http://localhost:57748/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57748/tests/kraken/driver.html?testName=ai-astar) 03:42:47 INFO - PROCESS | 6492 | RSS: Main: 249733120 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | __start_tp_report 03:42:47 INFO - PROCESS | 6492 | _x_x_mozilla_page_load 03:42:47 INFO - PROCESS | 6492 | _x_x_mozilla_page_load_details 03:42:47 INFO - PROCESS | 6492 | |i|pagename|runs| 03:42:47 INFO - PROCESS | 6492 | |0;ai-astar;120;117;120;119;119;135;119;117;119;119 03:42:47 INFO - PROCESS | 6492 | |1;audio-beat-detection;155;126;171;187;125;142;129;187;192;125 03:42:47 INFO - PROCESS | 6492 | |2;audio-dft;200;197;201;203;199;196;202;231;232;223 03:42:47 INFO - PROCESS | 6492 | |3;audio-fft;64;67;83;146;57;57;60;57;144;72 03:42:47 INFO - PROCESS | 6492 | |4;audio-oscillator;81;83;128;79;77;82;120;77;79;120 03:42:47 INFO - PROCESS | 6492 | |5;imaging-gaussian-blur;89;114;103;88;93;86;90;89;90;88 03:42:47 INFO - PROCESS | 6492 | |6;imaging-darkroom;96;96;97;95;96;96;96;98;96;96 03:42:47 INFO - PROCESS | 6492 | |7;imaging-desaturate;117;119;90;97;90;93;88;90;92;92 03:42:47 INFO - PROCESS | 6492 | |8;json-parse-financial;61;67;62;84;92;96;101;90;63;63 03:42:47 INFO - PROCESS | 6492 | |9;json-stringify-tinderbox;64;68;67;70;72;83;74;73;68;71 03:42:47 INFO - PROCESS | 6492 | |10;stanford-crypto-aes;82;80;81;82;84;82;80;86;102;109 03:42:47 INFO - PROCESS | 6492 | |11;stanford-crypto-ccm;138;139;147;160;154;132;141;139;173;141 03:42:47 INFO - PROCESS | 6492 | |12;stanford-crypto-pbkdf2;169;186;226;183;172;171;168;178;169;220 03:42:47 INFO - PROCESS | 6492 | |13;stanford-crypto-sha256-iterative;67;70;82;96;97;98;94;75;69;69 03:42:47 INFO - PROCESS | 6492 | __end_tp_report 03:42:47 INFO - PROCESS | 6492 | __start_cc_report 03:42:47 INFO - PROCESS | 6492 | _x_x_mozilla_cycle_collect,1092 03:42:47 INFO - PROCESS | 6492 | __end_cc_report 03:42:47 INFO - PROCESS | 6492 | __startTimestamp1462531367115__endTimestamp 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | ------- Summary: start ------- 03:42:47 INFO - PROCESS | 6492 | Number of tests: 14 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#0] ai-astar Cycles:10 Average:120.40 Median:119.00 stddev:5.23 (4.4%) stddev-sans-first:5.55 03:42:47 INFO - PROCESS | 6492 | Values: 120.0 117.0 120.0 119.0 119.0 135.0 119.0 117.0 119.0 119.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#1] audio-beat-detection Cycles:10 Average:153.90 Median:163.00 stddev:28.18 (17.3%) stddev-sans-first:29.89 03:42:47 INFO - PROCESS | 6492 | Values: 155.0 126.0 171.0 187.0 125.0 142.0 129.0 187.0 192.0 125.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#2] audio-dft Cycles:10 Average:208.40 Median:202.50 stddev:14.33 (7.1%) stddev-sans-first:14.87 03:42:47 INFO - PROCESS | 6492 | Values: 200.0 197.0 201.0 203.0 199.0 196.0 202.0 231.0 232.0 223.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#3] audio-fft Cycles:10 Average:80.70 Median:62.00 stddev:34.85 (56.2%) stddev-sans-first:36.44 03:42:47 INFO - PROCESS | 6492 | Values: 64.0 67.0 83.0 146.0 57.0 57.0 60.0 57.0 144.0 72.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:79.00 stddev:20.95 (26.5%) stddev-sans-first:21.80 03:42:47 INFO - PROCESS | 6492 | Values: 81.0 83.0 128.0 79.0 77.0 82.0 120.0 77.0 79.0 120.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:89.00 stddev:8.76 (9.8%) stddev-sans-first:9.17 03:42:47 INFO - PROCESS | 6492 | Values: 89.0 114.0 103.0 88.0 93.0 86.0 90.0 89.0 90.0 88.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 03:42:47 INFO - PROCESS | 6492 | Values: 96.0 96.0 97.0 95.0 96.0 96.0 96.0 98.0 96.0 96.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:91.00 stddev:11.44 (12.6%) stddev-sans-first:9.51 03:42:47 INFO - PROCESS | 6492 | Values: 117.0 119.0 90.0 97.0 90.0 93.0 88.0 90.0 92.0 92.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#8] json-parse-financial Cycles:10 Average:77.90 Median:75.50 stddev:16.14 (21.4%) stddev-sans-first:15.92 03:42:47 INFO - PROCESS | 6492 | Values: 61.0 67.0 62.0 84.0 92.0 96.0 101.0 90.0 63.0 63.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:5.19 (7.3%) stddev-sans-first:4.84 03:42:47 INFO - PROCESS | 6492 | Values: 64.0 68.0 67.0 70.0 72.0 83.0 74.0 73.0 68.0 71.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:82.00 stddev:10.15 (12.4%) stddev-sans-first:10.62 03:42:47 INFO - PROCESS | 6492 | Values: 82.0 80.0 81.0 82.0 84.0 82.0 80.0 86.0 102.0 109.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:144.00 stddev:12.46 (8.7%) stddev-sans-first:12.84 03:42:47 INFO - PROCESS | 6492 | Values: 138.0 139.0 147.0 160.0 154.0 132.0 141.0 139.0 173.0 141.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:180.50 stddev:21.39 (11.9%) stddev-sans-first:21.97 03:42:47 INFO - PROCESS | 6492 | Values: 169.0 186.0 226.0 183.0 172.0 171.0 168.0 178.0 169.0 220.0 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - PROCESS | 6492 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.70 Median:88.00 stddev:13.23 (15.0%) stddev-sans-first:12.92 03:42:47 INFO - PROCESS | 6492 | Values: 67.0 70.0 82.0 96.0 97.0 98.0 94.0 75.0 69.0 69.0 03:42:47 INFO - PROCESS | 6492 | -------- Summary: end -------- 03:42:47 INFO - PROCESS | 6492 | 03:42:47 INFO - TEST-INFO | 6492: exit 0 03:42:47 INFO - TEST-OK | kraken | took 60154ms 03:42:47 INFO - SUITE-END | took 1172s 03:42:48 INFO - Completed test suite (00:19:32) 03:42:48 INFO - javascript benchmark 03:42:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.9660103398965, 3483.52972348733, 3503.9124021899465, 3522.2075033117553, 3538.9115272118192, 5884.4409781070835, 5903.321118071418, 5911.231539899818, 5914.438128377803, 5917.85205369866, 5917.260342457193, 5945.019071853139, 5948.899837105154, 5963.940360596393, 5985.670788106652, 5238.397584277809, 5261.8947621047555, 5338.06583847827, 5345.198220266952, 5347.0, 5177.8867543478145, 5298.523132918037, 5300.257963885059, 5307.681539107647, 5403.945960540384, 5303.840884773444, 5308.628396012281, 5320.494553017457, 5331.57347412208, 5336.30628018357, 3963.4253033310297, 4477.70894891831, 4921.434035085975, 5271.0, 5355.036093503168, 4380.649548036181, 5207.427183009866, 5217.660852044605, 5283.841484755463, 5336.332958380202, 5210.635255532115, 5677.318721753375, 5794.072948328289, 5929.73316200772, 5932.436418540232, 5160.664556803796, 6135.845176233341, 6161.507079433634, 6214.130021796952, 6240.157578726859, 6257.843553911143, 6283.403076707751, 6290.21372328459, 6291.465225455841, 6306.274778400497, 5858.355580886099, 5944.494717949023, 5978.641281523122, 6000.33996260411, 6018.127371531104, 6401.263854656681, 6406.583572067801, 6463.7737679181, 6477.870442591122, 6496.415322620986, 6150.876982460415, 6169.5064394848305, 6178.443940045418, 6182.319944806068, 6184.319724830265, 6085.482733967574, 6194.845128871724, 6272.40412160844, 6294.622322660655, 6296.401841825019, 6112.625067133413, 6138.202033735586, 6142.447179753844, 6161.476274516626, 6173.722182501798, 6080.7567697291615, 6154.630722156685, 6156.784512542129, 6164.969175154096, 6182.6599537025895, 4849.127157111683, 4960.553550180501, 5016.949830501721, 5027.9692663004, 5075.314832497639, 3865.9806700966315, 4441.866743997685, 4460.598546130799, 4480.350349199413, 4516.07420478801, 5918.704064796743, 5975.581709280395, 5983.461488466059, 6010.459058684739, 6020.4280593343565, 6156.569040167235, 6169.876602467925, 6171.13604095427, 6275.090111933744, 6327.810165695037, 5990.850228744316, 6002.789902353396, 6031.60794548353, 6032.638041717511, 6040.426159514839, 5510.008198524304, 5515.972420137874, 5519.475649813261, 5532.944670553324, 5556.527695145877, 6312.810615681537, 6409.487241020707, 6473.967630161819, 6478.222613286247, 6529.314421985623, 6289.622622642656, 6312.210973628296, 6324.557280990287, 6325.96837015812, 6333.493320534439, 5832.096025116114, 6222.066689996536, 6464.644444555594, 6488.448481878904, 6515.902261465987, 6183.134361189527, 6199.907001394892, 6221.595596286227, 6232.968835155795, 6240.344763799736, 6264.655443950626, 6275.403836635513, 6278.968605156945, 6283.2460104787715, 6284.465820405317, 6082.574219804748, 6141.785037523665, 6194.194754681863, 6222.968885155546, 6274.309825919054, 6065.302490213682, 6123.755049797958, 6127.311699316976, 6153.094251360284, 6217.751289948352, 5932.8813423732145, 6062.363451837494, 6087.786927457518, 6087.878242435215, 6125.785597504066, 6106.72519736604, 6134.6625935573975, 6136.478399336106, 6160.44555989963, 6203.96898015507, 5130.0, 5666.660000399989, 6127.311699316976, 6143.709820937654, 6159.722812473539], "value": 5752.976729555388, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.5203686589905, 538.321714639554, 540.5999560325358, 569.3793764796372, 588.693879182825, 38857.22285554291, 40143.99999999996, 40145.0, 40146.59853401465, 40195.79902100489, 34865.651343486556, 41188.79405602975, 41347.79326103365, 41818.58181418185, 42417.72746817593, 9391.389559678633, 9600.183984361338, 9629.61481540741, 9683.12851843334, 9685.370450920704, 9499.905000950006, 9705.563249653747, 9742.0, 9757.902420975806, 9765.023497650249, 9680.322377433582, 9685.32202745808, 9692.0, 9756.0, 9770.804583908317, 9635.759106022335, 9660.033996600283, 9690.515474226295, 9721.222302215805, 9755.463449510275, 8331.916850809417, 9537.046295370477, 9573.904260957372, 9641.517924103837, 9658.37220580667, 39254.214915701814, 39463.81608551748, 39840.60159398398, 39925.80037099825, 39991.0, 3074.9077527674203, 3145.6697046809986, 3201.5357773123, 3255.6744325567493, 3339.699427051553, 26994.0, 28367.57448638272, 28491.85754071237, 29263.0, 29815.95644152466, 2726.400191957786, 2743.7667798237176, 2812.7890408219464, 2839.3753374257835, 2882.2650224192907, 418177.0, 425326.0, 428810.0, 429413.0, 431186.0, 35531.28937421237, 36388.636113639055, 67514.66242668756, 68598.65700671564, 68658.62682746317, 38117.23765524674, 38126.66556670503, 38143.0, 38425.42361864574, 38635.0, 12156.574519891938, 14692.19192944377, 15859.603509912115, 15947.282372293275, 16073.839261607469, 11226.989570938655, 12909.806352904714, 13034.543790967271, 13228.470861165639, 13420.865791342158, 7368.963155184297, 8549.0, 8604.053554109043, 8612.956935215409, 8655.355482458313, 3529.3117842021074, 3542.2384187399316, 3568.3041806847937, 3569.964300356963, 3601.081724160375, 984.6701355045997, 1019.2712210769336, 1026.0099004460583, 1027.8972102789662, 1029.361795686679, 30200.84899575532, 30514.69485305163, 30771.384572308427, 30924.0, 31328.0, 1044.5717255925185, 1124.3366413815725, 1127.7462570921477, 1140.1790710688292, 1173.8261738261738, 13381.53164639233, 16010.67978640444, 16017.839821601869, 16092.678146437005, 16271.755923661154, 464225.0, 503069.0, 595865.0206749025, 604846.0, 614918.0, 58433.70783146057, 62957.0, 63351.0, 63719.681401593625, 64031.0, 1327.9203247805162, 1471.1320321010655, 1519.3087145349061, 1521.4522771801921, 1599.5521254048886, 25854.48291034131, 30319.545206821473, 30345.69654303517, 30739.846300768353, 31231.531527027568, 54991.17513237385, 55226.72386638202, 55334.44665553293, 55362.72318638381, 55969.16046259391, 575671.0, 588900.0, 590087.0, 593462.0, 594105.0, 28384.716152838206, 28763.28091797722, 28924.98762543301, 29178.0, 29194.124176274745, 46545.53454465412, 46961.0, 47929.0, 48224.276635851194, 49186.0, 38944.80527597344, 39045.414318785806, 39174.0, 39304.41043384409, 39304.606953930095, 38784.41823372708, 39000.969253673946, 39113.0, 39284.6071539281, 40027.79986100051, 38158.61841381551, 38321.23357532778, 38697.6130238694, 39022.0, 39315.410268846565, 32718.69125234964, 34563.0, 34735.30529389449, 34841.9547413578, 34847.303053938274, 21166.894165529073, 24261.0, 25436.0, 25970.740292597588, 26271.080512181983], "value": 18788.048997903883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.23293541322664, 400.6274165026526, 401.5482582095142, 412.84931000184923, 414.299833281754, 19222.23111075557, 20738.89630551845, 21455.892720536394, 21612.675809862863, 21691.915404229803, 17137.82862171378, 19865.801341986575, 20751.06620202091, 21796.346109616738, 21944.341669749934, 5292.894142117156, 5854.76580936762, 6070.787522436715, 6072.817815465543, 6091.756329746805, 5785.247917770705, 5918.0, 5931.584789064767, 5997.280326360843, 6003.219581454427, 5721.914171287434, 5829.504492118153, 5937.198478205431, 5940.554458415636, 5969.22400087988, 5803.361630220672, 5829.883402331972, 5855.590108692393, 5872.500837428824, 5941.10883367494, 5126.53861152498, 5185.066687996159, 5210.973945130288, 5227.085260079471, 5286.127788914824, 24443.755562444505, 25977.830997605153, 26613.60079598788, 26723.0, 26906.730932690618, 2176.5538064696857, 2284.725832900046, 2287.565362581104, 2367.4318163640773, 2550.60465627828, 9348.95325523368, 10048.849267260997, 13796.862031379758, 14501.83985281175, 14580.489682861049, 1676.3881183368105, 1707.6584683063313, 1798.4065258464807, 1812.6102887879038, 1828.9268429262813, 116206.41896790547, 133713.0, 136150.0, 138819.99999999898, 139076.0, 10375.599294095351, 11204.943975280072, 11786.174967752175, 11908.225965312227, 12055.397230138458, 12607.0, 15490.148041857807, 15594.766078508832, 15604.531864044096, 15622.140782256858, 8925.0, 9054.81890362189, 9072.682456114004, 9083.863742043875, 9167.17495425415, 7406.851862962711, 8984.775380615536, 8988.370814042953, 9026.909730902607, 9185.448873067504, 4633.62930965522, 4716.716996980193, 4718.05638872227, 4738.07607516531, 4770.308305295713, 1724.1379310344828, 1959.7648282206226, 1984.8312893403934, 1986.1658103596524, 2006.8795872247713, 836.481381543447, 881.4931414436596, 943.4292253186724, 1002.6490728245055, 1024.8872624011353, 16896.915515422344, 17192.754852552218, 20057.598848022957, 21038.158473661188, 21274.0, 918.2975024106564, 958.9712308630752, 990.1534188268927, 1019.8317277649105, 1067.6636859389268, 8768.78078048054, 10843.024127828534, 11038.96103896111, 11145.498452569655, 11232.382218978035, 608766.0, 615587.0, 621285.0, 632036.8398158072, 633806.0, 71554.0, 133226.66773332143, 141644.0, 141648.29175854262, 145277.27361363126, 1005.8943810899897, 1013.143893410082, 1022.5245260953543, 1029.3875144289136, 1031.742064483879, 14933.925330373278, 15141.621459463602, 18385.724214137066, 18494.0752962354, 18700.0, 37661.435078474395, 41223.58776412198, 44298.892527687065, 48250.75874620605, 52499.73750131225, 66291.0, 73085.6345718268, 74325.0, 75142.24857751353, 80717.0, 5108.3103780989095, 5136.1268584340005, 7991.0, 7992.480488768211, 8294.976936186065, 10941.507632156412, 11604.593839215924, 12257.387130643254, 12516.186447880858, 13266.933665331997, 11073.889261107608, 11136.610218642309, 11175.38535380562, 11332.263402878787, 11367.976882080653, 10976.176786740867, 11097.223194376316, 11233.269837460539, 11324.150688698215, 11384.601538946097, 5435.076037073786, 9414.57634406467, 9741.853872191781, 11410.201285909907, 11624.767504650028, 8277.420580559545, 8329.167083291622, 8424.199701028392, 8473.152684731478, 8529.0, 9747.268954828274, 11213.71965700864, 11228.494717737885, 11453.427328633701, 11503.654890353302], "value": 9543.67640845943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.9688406231871, 1602.046782164612, 1858.3960212930792, 1962.3622322745102, 1990.3829812758038, 8178.550179740112, 8736.825263494726, 8808.603612837422, 9126.406783559072, 9166.220871225945, 8783.516906570136, 8850.291976641869, 8942.821143577125, 8964.417312874659, 9062.954685226581, 2480.2063339731294, 2495.950080998388, 2496.6005439129744, 2503.2240005598233, 2508.4983003399393, 2447.6940382452194, 2510.0210917742097, 2511.46003609224, 2520.6345079963394, 2526.014854206861, 2487.1543675150447, 2489.9751002489925, 2502.2868482482495, 2511.2466260121982, 2543.504016716741, 2444.6210837320154, 2462.6429167770752, 2466.1984854922134, 2482.0, 2486.477839653675, 2470.839395439291, 2472.876356182184, 2490.750924907513, 2498.537770512461, 2504.211173480348, 9039.954800226022, 9065.22945549629, 9124.996250412449, 9129.863052054225, 9152.771180720469, 5436.048691479014, 5616.747246373923, 5641.266635337421, 5653.236813030229, 5674.602777805555, 3572.07126147201, 3586.3544561978833, 3614.493970844084, 3646.7264955128207, 3670.687991520724, 879.3097418526426, 1017.9643712470028, 1026.9794604107951, 1035.585765693721, 1044.890286519912, 12109.697257568543, 12179.08656850731, 12220.938895305555, 12230.449629766681, 12246.816297755451, 4241.300185469363, 4937.1606826839525, 4947.48051454592, 4997.425296090996, 5013.423456302572, 10232.897671023344, 10235.539400726837, 10281.485925703684, 10286.794264114676, 10317.27779055474, 5464.289642346549, 5512.118061110203, 5553.611247212656, 5600.971995140079, 5601.243832082698, 4492.528284530143, 4812.326274321597, 4992.750362481861, 5057.494250574913, 5060.165072762953, 4099.631033207026, 4162.521310049323, 4206.8737937861915, 4245.532991370947, 4256.829726810893, 1347.5889853594551, 1385.9514916977857, 1388.673661689502, 1394.5049507424922, 1395.7069015506654, 866.276658989738, 949.6960972488876, 965.1410244882061, 973.3089506450358, 986.1617625018677, 4811.975940120347, 5544.3346798383645, 5635.464630860144, 5983.521318294526, 6154.169187159676, 461.2942198436398, 464.3220897489703, 466.53346653346654, 476.82357527714277, 481.20601008335825, 4017.6785857131945, 4077.6941729369823, 4111.588841115865, 4144.233316836395, 4173.165366926566, 10508.947455262674, 10991.340519568852, 11072.114230861513, 11083.778324433628, 11115.777684446266, 9320.906790932004, 10353.223508236912, 10979.615713449992, 11067.280626759395, 11075.446227688744, 8219.260266576037, 8287.254147126667, 8315.459495132796, 8370.455920365157, 8381.28759055475, 5900.881982360243, 5914.749526939514, 6060.484858786876, 6139.324674285915, 6216.0, 8745.60644770974, 9523.047695230422, 9774.706758797247, 9855.852162217428, 10087.495625218857, 10286.919873413482, 10290.0, 10293.176545876462, 10317.123044541298, 10317.17462602975, 6878.518503704893, 7299.890501642373, 7503.9249607503225, 7618.542887426772, 7626.656800443882, 7034.402075823612, 8427.0, 8449.11284315138, 8503.149685031447, 8511.319094472552, 7409.0, 7530.5827484987485, 7541.095068591804, 7703.229677032252, 7763.301302882767, 7454.888176677463, 7491.88762168557, 7562.941188233762, 7604.80987975305, 7646.464747467624, 6962.756303529555, 6992.16094068715, 7006.439484841302, 7023.192332881784, 7060.858782824212, 4945.307656928105, 4984.102861484967, 4998.625103117208, 5154.381474223117, 5168.0, 4644.908446515134, 4996.100701873697, 5130.230465430215, 5187.377514698261, 5408.107662235687], "value": 4624.9575116369015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.7797639864507, 300.50116806101863, 301.1627675063325, 306.70862680453575, 307.7230492556699, 5064.468230835764, 5480.506754392104, 5509.559235261179, 5577.494076599321, 5614.9999999999945, 4306.784660766957, 5078.984203159364, 5425.755840987155, 5472.288602481682, 5515.448455154493, 3965.6827453803626, 4004.3392840181336, 4088.5298190708154, 4107.8973025674295, 4134.338505839067, 3439.4152993991083, 3904.8285514345725, 4135.193637240739, 4160.583941605831, 4181.644560212386, 3440.088376580208, 3465.6014558325737, 4094.2425651254466, 4126.793660316987, 4133.256013917494, 3302.1579497228295, 3812.7902965336893, 3834.6740527055235, 3941.4087886817065, 4078.859278355664, 3150.842457877097, 3309.749370428108, 3508.6842184203542, 3509.508668786372, 3530.6998905093096, 5336.359636843604, 5342.038433082043, 5343.759530821122, 5348.304720386363, 5358.732063396815, 3949.328614135604, 4119.279126152941, 4283.957160428387, 4707.529247075265, 4724.83463078794, 5829.912551311733, 5849.590528662995, 5851.342910680824, 5876.29484461863, 5890.499307558863, 3314.5691060162267, 3727.956172271768, 3915.7650540967347, 3926.764394136361, 3936.114374265796, 4428.114377124588, 5548.889022219573, 6003.309619393782, 6163.87672246557, 6214.805006620513, 3268.50972354144, 3341.047801376596, 3899.493065901415, 3957.3272543667645, 3961.346377847623, 3065.7700672449655, 4011.7592944423427, 4028.4964379452567, 4034.2133284009924, 4039.2325458162854, 4539.6368290536675, 4696.8590942271785, 4712.340272361872, 4716.033213191287, 4720.8433933686465, 4847.466778654345, 5404.0, 5470.398256191816, 5657.12314591239, 5874.265716785402, 1447.8045463862454, 1453.6147920800997, 1461.8465061168638, 1464.5093893545568, 1471.6762312291278, 889.213046453893, 939.5631031570352, 959.4339339789558, 963.5760265483165, 970.8301496696464, 5283.630145889828, 5431.565474762009, 5510.559155267569, 5873.177755114288, 5877.764889404376, 5656.575756818255, 6150.169727086786, 6293.622382657055, 6549.2468366137555, 6600.590784363672, 5529.557635389239, 5811.912821307684, 5831.504322132666, 5833.037548804485, 5907.940920590825, 3153.8265395403014, 3407.9488807667904, 3438.2494004796063, 3441.8451169697423, 3460.2387474755515, 5882.794102206402, 6210.534209934182, 6330.525210609223, 6420.0, 6730.9326906731285, 4861.565838077776, 5352.732363381815, 6195.349488303754, 6221.222347206599, 6260.619265596618, 5403.567714582824, 5817.970910145422, 5839.328477225174, 6336.714847831859, 6389.233292004989, 5249.7112658804135, 6079.969600151971, 6103.938960610337, 6124.908126378019, 6140.9078863816185, 4902.534259245366, 4926.039422312615, 5230.895382092413, 5651.406602306699, 6022.795440911747, 5808.796692115756, 5866.2373891393845, 6214.751409943552, 6287.748490060347, 6335.1447554579545, 3087.6603573606435, 3306.50402439636, 3346.975080076616, 3970.8213130409777, 3975.34406822871, 5073.579397768631, 5793.04414771558, 5805.042168042226, 5965.0, 6144.56988010835, 5491.643043202179, 5748.252727145445, 5774.451427114417, 5871.911921321269, 5901.0, 5149.407818100966, 5757.021306377842, 5843.707814609167, 5845.883082338244, 5880.470757631833, 4754.667173297836, 5195.155719719445, 5713.885722285614, 5739.397363276963, 5753.568482363756, 4664.673472857004, 5141.7686204120155, 5450.727463626724, 5671.206031155559, 5743.879943410995, 5186.118359878754, 5537.473939975857, 5650.773969041356, 5786.408737597026, 5798.6810725410505], "value": 4212.555857940083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.307283213902, 597.074534471569, 652.8563715982483, 695.5791745993673, 727.9417646588273, 13037.0, 14703.33834977426, 14727.48453804117, 14833.184174870377, 14889.0, 13559.59321220365, 13737.10708803927, 14028.859711402883, 14048.789268160986, 14071.437142514287, 726.7165805335924, 918.3846822628838, 930.2836815651943, 937.9296552758536, 942.8450148567983, 853.185208126238, 947.5167664491124, 950.116391755667, 950.9762255943622, 954.7231302922145, 944.7260294514582, 967.8016006718606, 980.5832521178506, 987.782687808681, 988.77258230607, 948.6537413843967, 979.0698836105728, 982.8378317577591, 983.2674657380262, 984.3306551544947, 699.1889408286414, 722.0360818307565, 728.3444899590359, 734.1960553194244, 735.7130719019533, 14417.85582144186, 14550.0, 14616.0, 14616.488422905148, 14658.780118298235, 12385.19496232742, 12595.3072581008, 12613.738626137312, 12771.936140319332, 12861.228326300452, 9933.552990115462, 10067.5469603868, 10154.187664986783, 10187.337823041478, 10286.22853285999, 3400.268942177444, 3425.3491836550975, 3500.072480792592, 3535.8939231823088, 3690.538682664667, 72194.27805721929, 73247.63376183138, 78151.0, 78433.60783196104, 78735.60632196859, 9579.521023948775, 9657.179139773058, 9716.3198576099, 9758.512074396253, 9797.80404391908, 20942.0, 21247.78752212489, 21263.68104478434, 21276.36170914875, 21310.254141104986, 7418.7032518699725, 7448.851022979618, 7568.697252109964, 7576.356009739234, 7754.991851059439, 4052.979735101306, 4149.070682132823, 4150.335946248586, 4193.454850869429, 4265.658747300208, 3676.2279921216846, 3740.102375429877, 3743.5507739071486, 3748.325301445766, 3749.3063783200196, 667.8029981155571, 673.2123415603658, 681.1281559603616, 683.0232767142934, 684.1106561470069, 542.3843937131385, 563.0343960108434, 581.6103210848742, 594.9732262048218, 608.0271565495173, 7492.288232617892, 8053.033635963722, 8177.632006559719, 8203.548804815793, 8589.785255368666, 543.13641310316, 568.2754488027715, 583.608981982073, 607.0257237134335, 612.9816105516842, 3235.6764323567927, 3812.599677033927, 3847.5575308839843, 3858.1704933439446, 3884.3590807517007, 79113.20886791173, 93929.0, 94753.0, 95544.0, 101222.0, 80751.59624201959, 81176.59411703022, 83177.0, 83266.33467330619, 84226.57886710527, 73785.26214737784, 74126.6293668539, 74289.0, 74319.62840185764, 74323.62838185775, 6363.236411630634, 7562.7353042645445, 7661.731839385595, 7798.571078590732, 8022.759317220492, 39865.80067099646, 42596.5740342605, 43662.908427288305, 44094.11811763811, 46642.0, 63215.6839215801, 63573.364266356744, 63935.72128557496, 64362.67818660877, 67912.0, 9451.243900488082, 11111.0, 11193.832092518782, 11545.422728863692, 11661.24201926872, 11985.22096063753, 12086.637400877988, 12260.877391225973, 12388.194767340094, 12458.563950261698, 11247.268927519684, 11491.885081149416, 11554.017908477537, 11616.94191529037, 11654.951054405145, 11632.76734465289, 11683.824742628698, 11719.707007324543, 11725.706857328634, 11759.529618815493, 9432.764180895258, 10731.624393146203, 10779.73050673708, 10786.622468213574, 10971.615993440191, 9585.616575336908, 9729.610815567297, 9773.462459564793, 9938.403695777988, 10109.292349535463, 9313.86029209576, 9634.841202992673, 9758.268129890144, 9764.951175244363, 9801.950990245003], "value": 6867.80748338687, "unit": "score"}], "name": "dromaeo_css", "value": 7188.979323403793, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 120.0, 119.0, 119.0, 135.0, 119.0, 117.0, 119.0, 119.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 171.0, 187.0, 125.0, 142.0, 129.0, 187.0, 192.0, 125.0], "value": 153.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 201.0, 203.0, 199.0, 196.0, 202.0, 231.0, 232.0, 223.0], "value": 208.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 83.0, 146.0, 57.0, 57.0, 60.0, 57.0, 144.0, 72.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 128.0, 79.0, 77.0, 82.0, 120.0, 77.0, 79.0, 120.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 114.0, 103.0, 88.0, 93.0, 86.0, 90.0, 89.0, 90.0, 88.0], "value": 93.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 95.0, 96.0, 96.0, 96.0, 98.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 90.0, 97.0, 90.0, 93.0, 88.0, 90.0, 92.0, 92.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 62.0, 84.0, 92.0, 96.0, 101.0, 90.0, 63.0, 63.0], "value": 77.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 67.0, 70.0, 72.0, 83.0, 74.0, 73.0, 68.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 82.0, 84.0, 82.0, 80.0, 86.0, 102.0, 109.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 147.0, 160.0, 154.0, 132.0, 141.0, 139.0, 173.0, 141.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 186.0, 226.0, 183.0, 172.0, 171.0, 168.0, 178.0, 169.0, 220.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 82.0, 96.0, 97.0, 98.0, 94.0, 75.0, 69.0, 69.0], "value": 81.7, "unit": "score"}], "name": "kraken", "value": 1590.0000000000002, "alertThreshold": 2.0}]} 03:42:48 INFO - Return code: 0 03:42:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:42:48 ERROR - # TBPL SUCCESS # 03:42:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:48 INFO - 2016-05-06 03:42:48.423 screenresolution[6497:160068] starting screenresolution argv=screenresolution get 03:42:48 INFO - 2016-05-06 03:42:48.425 screenresolution[6497:160068] Display 0: 1600x1200x32@60 03:42:48 INFO - 2016-05-06 03:42:48.431 screenresolution[6498:160071] starting screenresolution argv=screenresolution list 03:42:48 INFO - Available Modes on Display 0 03:42:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:48 INFO - Intel Iris: 03:42:48 INFO - Chipset Model: Intel Iris 03:42:48 INFO - Type: GPU 03:42:48 INFO - Bus: Built-In 03:42:48 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:48 INFO - Vendor: Intel (0x8086) 03:42:48 INFO - Device ID: 0x0a2e 03:42:48 INFO - Revision ID: 0x0009 03:42:48 INFO - Displays: 03:42:48 INFO - Display: 03:42:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:48 INFO - Display Serial Number: 1600x1200 60 03:42:48 INFO - Main Display: Yes 03:42:48 INFO - Mirror: Off 03:42:48 INFO - Online: Yes 03:42:48 INFO - Rotation: Supported 03:42:48 INFO - Return code: 0 03:42:48 INFO - Running post-action listener: _resource_record_post_action 03:42:48 INFO - Running post-run listener: _resource_record_post_run 03:42:49 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 29184; Write bytes: 656056320; Read time: 2; Write time: 2375 03:42:49 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352907776; Write bytes: 346863104; Read time: 28081; Write time: 667 03:42:49 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 28672; Write bytes: 299403776; Read time: 1; Write time: 1677 03:42:49 INFO - Running post-run listener: _upload_blobber_files 03:42:49 INFO - Blob upload gear active. 03:42:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:49 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 03:42:49 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:49 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:50 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:42:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:50 INFO - (blobuploader) - INFO - Done attempting. 03:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:42:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:52 INFO - (blobuploader) - INFO - Done attempting. 03:42:52 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:52 INFO - Return code: 0 03:42:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1a10b424429abdd2ba36e87c32af8a8ba6a913cee42efe50296811cfff2b132c00f4958bf0ff8f17f6257c3f0a6981a83d17e13b758f59791566fc9a52b62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffa659794d07cfbfd4dba798bc75dd8a02af62de20d908e5c0269d0120bfe9df2e753b7c0db3d1ef9e3643fb6466842f41f8c9f4c762be0349b62ca414a043fc"} 03:42:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:52 INFO - Contents: 03:42:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1a10b424429abdd2ba36e87c32af8a8ba6a913cee42efe50296811cfff2b132c00f4958bf0ff8f17f6257c3f0a6981a83d17e13b758f59791566fc9a52b62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffa659794d07cfbfd4dba798bc75dd8a02af62de20d908e5c0269d0120bfe9df2e753b7c0db3d1ef9e3643fb6466842f41f8c9f4c762be0349b62ca414a043fc"} 03:42:52 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:52 INFO - Copying logs to upload dir... 03:42:52 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.143635 ========= master_lag: 4.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-06 03:42:56.451041) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:56.452076) ========= 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.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1a10b424429abdd2ba36e87c32af8a8ba6a913cee42efe50296811cfff2b132c00f4958bf0ff8f17f6257c3f0a6981a83d17e13b758f59791566fc9a52b62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffa659794d07cfbfd4dba798bc75dd8a02af62de20d908e5c0269d0120bfe9df2e753b7c0db3d1ef9e3643fb6466842f41f8c9f4c762be0349b62ca414a043fc"} build_url:https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Kej6EClFTym5Td3ayIylNA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1a10b424429abdd2ba36e87c32af8a8ba6a913cee42efe50296811cfff2b132c00f4958bf0ff8f17f6257c3f0a6981a83d17e13b758f59791566fc9a52b62", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffa659794d07cfbfd4dba798bc75dd8a02af62de20d908e5c0269d0120bfe9df2e753b7c0db3d1ef9e3643fb6466842f41f8c9f4c762be0349b62ca414a043fc"}' ========= master_lag: 0.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 03:42:56.865151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-06 03:42:56.865517) ========= 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.d4qHAvb2JY/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.B4NU1jST6s/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.005297 ========= master_lag: 3.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-06 03:43:00.465983) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:43:00.466341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 03:43:00.471640) ========= ========= Total master_lag: 8.29 =========