builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0054 starttime: 1467032268.78 results: success (0) buildid: 20160624143557 builduid: 67b95f07284347e7aea2678b4c3b20e6 revision: 229741b7de0485ca56b91e0e657fe5d37dbbc98f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:48.784591) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:48.784981) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:48.785253) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:48.845175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:48.845563) ========= 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.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-06-27 05:57:48-- 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% 165M=0s 2016-06-27 05:57:49 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162110 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:49.034501) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:49.034936) ========= 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.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.029938 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:49.085697) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 05:57:49.086048) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 229741b7de0485ca56b91e0e657fe5d37dbbc98f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 229741b7de0485ca56b91e0e657fe5d37dbbc98f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-06-27 05:57:49,163 truncating revision to first 12 chars 2016-06-27 05:57:49,163 Setting DEBUG logging. 2016-06-27 05:57:49,163 attempt 1/10 2016-06-27 05:57:49,163 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/229741b7de04?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 05:57:50,231 unpacking tar archive at: mozilla-beta-229741b7de04/testing/mozharness/ program finished with exit code 0 elapsedTime=1.421831 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 05:57:50.532033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:50.532330) ========= script_repo_revision: 229741b7de0485ca56b91e0e657fe5d37dbbc98f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:50.532793) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:50.533058) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 05:57:50.551965) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-06-27 05:57:50.552298) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:57:50 INFO - MultiFileLogger online at 20160627 05:57:50 in /builds/slave/test 05:57:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:57:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:50 INFO - {'append_to_log': False, 05:57:50 INFO - 'base_work_dir': '/builds/slave/test', 05:57:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:57:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:50 INFO - 'branch': 'Mozilla-Beta', 05:57:50 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:50 INFO - 'config_files': ('talos/mac_config.py',), 05:57:50 INFO - 'default_actions': ('clobber', 05:57:50 INFO - 'read-buildbot-config', 05:57:50 INFO - 'download-and-extract', 05:57:50 INFO - 'populate-webroot', 05:57:50 INFO - 'create-virtualenv', 05:57:50 INFO - 'install', 05:57:50 INFO - 'run-tests'), 05:57:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:50 INFO - 'download_minidump_stackwalk': True, 05:57:50 INFO - 'download_symbols': 'ondemand', 05:57:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:50 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:50 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:50 INFO - 'installer_path': 'installer.exe', 05:57:50 INFO - 'log_level': 'info', 05:57:50 INFO - 'log_name': 'talos', 05:57:50 INFO - 'log_to_console': True, 05:57:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:57:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:57:50 INFO - 'opt_config_files': (), 05:57:50 INFO - 'pip_index': False, 05:57:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:50 INFO - 'cmd': ('bash', 05:57:50 INFO - '-c', 05:57:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:50 INFO - 'enabled': True, 05:57:50 INFO - 'halt_on_failure': False, 05:57:50 INFO - 'name': 'check_screen_resolution'},), 05:57:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:57:50 INFO - 'cmd': ('bash', 05:57:50 INFO - '-c', 05:57:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:57:50 INFO - 'enabled': True, 05:57:50 INFO - 'halt_on_failure': False, 05:57:50 INFO - 'name': 'check_screen_resolution'},), 05:57:50 INFO - 'run_cmd_checks_enabled': True, 05:57:50 INFO - 'sps_profile': False, 05:57:50 INFO - 'sps_profile_interval': 0, 05:57:50 INFO - 'suite': 'dromaeojs-e10s', 05:57:50 INFO - 'system_bits': '32', 05:57:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:50 INFO - 'title': 't-yosemite-r7-0054', 05:57:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:50 INFO - 'use_talos_json': True, 05:57:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:50 INFO - 'work_dir': 'build'} 05:57:50 INFO - ##### 05:57:50 INFO - ##### Running clobber step. 05:57:50 INFO - ##### 05:57:50 INFO - Running pre-action listener: _resource_record_pre_action 05:57:50 INFO - Running main action method: clobber 05:57:50 INFO - rmtree: /builds/slave/test/build 05:57:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:52 INFO - Running post-action listener: _resource_record_post_action 05:57:52 INFO - ##### 05:57:52 INFO - ##### Running read-buildbot-config step. 05:57:52 INFO - ##### 05:57:52 INFO - Running pre-action listener: _resource_record_pre_action 05:57:52 INFO - Running main action method: read_buildbot_config 05:57:52 INFO - Using buildbot properties: 05:57:52 INFO - { 05:57:52 INFO - "project": "", 05:57:52 INFO - "product": "firefox", 05:57:52 INFO - "script_repo_revision": "production", 05:57:52 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:57:52 INFO - "repository": "", 05:57:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:57:52 INFO - "buildid": "20160624143557", 05:57:52 INFO - "pgo_build": "False", 05:57:52 INFO - "basedir": "/builds/slave/test", 05:57:52 INFO - "buildnumber": 14, 05:57:52 INFO - "slavename": "t-yosemite-r7-0054", 05:57:52 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:57:52 INFO - "platform": "yosemite_r7", 05:57:52 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:57:52 INFO - "branch": "mozilla-beta", 05:57:52 INFO - "repo_path": "releases/mozilla-beta", 05:57:52 INFO - "revision": "229741b7de0485ca56b91e0e657fe5d37dbbc98f", 05:57:52 INFO - "stage_platform": "macosx64", 05:57:52 INFO - "builduid": "67b95f07284347e7aea2678b4c3b20e6", 05:57:52 INFO - "slavebuilddir": "test" 05:57:52 INFO - } 05:57:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 05:57:52 INFO - Running post-action listener: _resource_record_post_action 05:57:52 INFO - ##### 05:57:52 INFO - ##### Running download-and-extract step. 05:57:52 INFO - ##### 05:57:52 INFO - Running pre-action listener: _resource_record_pre_action 05:57:52 INFO - Running main action method: download_and_extract 05:57:52 INFO - mkdir: /builds/slave/test/build/tests 05:57: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')]} 05:57:52 INFO - https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:57:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:57:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:57:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:57:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 05:58:22 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json: timed out 05:58:22 INFO - retry: attempt #1 caught exception: timed out 05:58:22 INFO - retry: Failed, sleeping 30 seconds before retrying 05:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #2 05:58:53 INFO - Downloaded 1165 bytes. 05:58:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:58:53 INFO - Using the following test package requirements: 05:58:53 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:58:53 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 05:58:53 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 05:58:53 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:58:53 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 05:58:53 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:58:53 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 05:58:53 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 05:58:53 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 05:58:53 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:58:53 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 05:58:53 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 05:58: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')]} 05:58:53 INFO - https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:58:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:58:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:58:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:59:06 INFO - Downloaded 18344069 bytes. 05:59:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:59:10 INFO - Return code: 0 05:59:10 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:10 INFO - https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:59:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:59:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:59:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:59:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:59:15 INFO - Downloaded 18344069 bytes. 05:59:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:59:19 INFO - Return code: 0 05:59: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')]} 05:59:19 INFO - https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:59:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:59:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:59:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 05:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 05:59:20 INFO - Downloaded 10840145 bytes. 05:59:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:59:21 INFO - Return code: 0 05:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:21 INFO - https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 05:59:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:59:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:59:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 05:59:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 05:59:23 INFO - Downloaded 86227039 bytes. 05:59:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:59:23 INFO - mkdir: /builds/slave/test/properties 05:59:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:23 INFO - Contents: 05:59:23 INFO - build_url:https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:59:24 INFO - Running post-action listener: _resource_record_post_action 05:59:24 INFO - Running post-action listener: set_extra_try_arguments 05:59:24 INFO - ##### 05:59:24 INFO - ##### Running populate-webroot step. 05:59:24 INFO - ##### 05:59:24 INFO - Running pre-action listener: _resource_record_pre_action 05:59:24 INFO - Running main action method: populate_webroot 05:59:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:24 INFO - '--tppagecycles', 05:59:24 INFO - '7'], 05:59:24 INFO - 'tests': ['tsvgm']}}, 05:59:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['damp', 'tps']}, 05:59:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'tests': ['damp', 'tps']}, 05:59:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['dromaeo_dom']}, 05:59:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['a11yr', 05:59:24 INFO - 'ts_paint', 05:59:24 INFO - 'tpaint', 05:59:24 INFO - 'sessionrestore', 05:59:24 INFO - 'sessionrestore_no_auto_restore', 05:59:24 INFO - 'tabpaint']}, 05:59:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['tsvgx', 05:59:24 INFO - 'tsvgr_opacity', 05:59:24 INFO - 'tart', 05:59:24 INFO - 'tscrollx', 05:59:24 INFO - 'cart']}, 05:59:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:24 INFO - 'tsvgr_opacity', 05:59:24 INFO - 'tart', 05:59:24 INFO - 'tscrollx', 05:59:24 INFO - 'cart']}, 05:59:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'talos_options': ['--disable-e10s'], 05:59:24 INFO - 'tests': ['tp5o']}, 05:59:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'tests': ['tp5o']}, 05:59:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'talos_options': ['--disable-e10s', 05:59:24 INFO - '--xperf_path', 05:59:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:24 INFO - 'tests': ['tp5n']}, 05:59:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:24 INFO - 'talos_options': ['--xperf_path', 05:59:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:24 INFO - 'tests': ['tp5n']}}, 05:59:24 INFO - 'talos.zip': {'path': '', 05:59:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:24 INFO - Running post-action listener: _resource_record_post_action 05:59:24 INFO - ##### 05:59:24 INFO - ##### Running create-virtualenv step. 05:59:24 INFO - ##### 05:59:24 INFO - Running pre-action listener: _resource_record_pre_action 05:59:24 INFO - Running main action method: create_virtualenv 05:59:24 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:59:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:25 INFO - Using real prefix '/tools/python27' 05:59:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:25 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:28 INFO - Installing pip.................done. 05:59:28 INFO - Return code: 0 05:59:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:59:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:28 INFO - 'HOME': '/Users/cltbld', 05:59:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:28 INFO - 'LOGNAME': 'cltbld', 05:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:28 INFO - 'MOZ_NO_REMOTE': '1', 05:59:28 INFO - 'NO_EM_RESTART': '1', 05:59:28 INFO - 'PAGER': '/bin/cat', 05:59:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:28 INFO - 'PWD': '/builds/slave/test', 05:59:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:28 INFO - 'SHELL': '/bin/bash', 05:59:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:28 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:28 INFO - 'USER': 'cltbld', 05:59:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:28 INFO - 'XPC_FLAGS': '0x0', 05:59:28 INFO - 'XPC_SERVICE_NAME': '0', 05:59:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:59:28 INFO - Cleaning up... 05:59:29 INFO - Return code: 0 05:59:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:29 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:29 INFO - 'HOME': '/Users/cltbld', 05:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:29 INFO - 'LOGNAME': 'cltbld', 05:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:29 INFO - 'MOZ_NO_REMOTE': '1', 05:59:29 INFO - 'NO_EM_RESTART': '1', 05:59:29 INFO - 'PAGER': '/bin/cat', 05:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:29 INFO - 'PWD': '/builds/slave/test', 05:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:29 INFO - 'SHELL': '/bin/bash', 05:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:29 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:29 INFO - 'USER': 'cltbld', 05:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:29 INFO - 'XPC_FLAGS': '0x0', 05:59:29 INFO - 'XPC_SERVICE_NAME': '0', 05:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:29 INFO - Downloading/unpacking psutil>=0.7.1 05:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:32 INFO - Installing collected packages: psutil 05:59:32 INFO - Running setup.py install for psutil 05:59:32 INFO - building 'psutil._psutil_osx' extension 05:59:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:59:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:59:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:59:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:59:33 INFO - building 'psutil._psutil_posix' extension 05:59:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:59:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:59:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:59:33 INFO - ^ 05:59:33 INFO - 1 warning generated. 05:59:33 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:59:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:33 INFO - Successfully installed psutil 05:59:33 INFO - Cleaning up... 05:59:33 INFO - Return code: 0 05:59:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:33 INFO - 'HOME': '/Users/cltbld', 05:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:33 INFO - 'LOGNAME': 'cltbld', 05:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:33 INFO - 'MOZ_NO_REMOTE': '1', 05:59:33 INFO - 'NO_EM_RESTART': '1', 05:59:33 INFO - 'PAGER': '/bin/cat', 05:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:33 INFO - 'PWD': '/builds/slave/test', 05:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:33 INFO - 'SHELL': '/bin/bash', 05:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:33 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:33 INFO - 'USER': 'cltbld', 05:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:33 INFO - 'XPC_FLAGS': '0x0', 05:59:33 INFO - 'XPC_SERVICE_NAME': '0', 05:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:59:36 INFO - Installing collected packages: mozsystemmonitor 05:59:36 INFO - Running setup.py install for mozsystemmonitor 05:59:36 INFO - Successfully installed mozsystemmonitor 05:59:36 INFO - Cleaning up... 05:59:36 INFO - Return code: 0 05:59:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:36 INFO - 'HOME': '/Users/cltbld', 05:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:36 INFO - 'LOGNAME': 'cltbld', 05:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:36 INFO - 'MOZ_NO_REMOTE': '1', 05:59:36 INFO - 'NO_EM_RESTART': '1', 05:59:36 INFO - 'PAGER': '/bin/cat', 05:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:36 INFO - 'PWD': '/builds/slave/test', 05:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:36 INFO - 'SHELL': '/bin/bash', 05:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:36 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:36 INFO - 'USER': 'cltbld', 05:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:36 INFO - 'XPC_FLAGS': '0x0', 05:59:36 INFO - 'XPC_SERVICE_NAME': '0', 05:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:37 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:40 INFO - Downloading docopt-0.6.1.tar.gz 05:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:40 INFO - Running setup.py install for blobuploader 05:59:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:40 INFO - Running setup.py install for requests 05:59:41 INFO - Running setup.py install for docopt 05:59:41 INFO - Successfully installed blobuploader requests docopt 05:59:41 INFO - Cleaning up... 05:59:41 INFO - Return code: 0 05:59:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:41 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:41 INFO - 'HOME': '/Users/cltbld', 05:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:41 INFO - 'LOGNAME': 'cltbld', 05:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:41 INFO - 'MOZ_NO_REMOTE': '1', 05:59:41 INFO - 'NO_EM_RESTART': '1', 05:59:41 INFO - 'PAGER': '/bin/cat', 05:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:41 INFO - 'PWD': '/builds/slave/test', 05:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:41 INFO - 'SHELL': '/bin/bash', 05:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:41 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:41 INFO - 'USER': 'cltbld', 05:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:41 INFO - 'XPC_FLAGS': '0x0', 05:59:41 INFO - 'XPC_SERVICE_NAME': '0', 05:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:41 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-dCHgZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:41 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-kUHPwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-31DVRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MU3lfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Tlczid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-_qQMCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-9F4AZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-vzdP8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-5yUo4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-V2IloA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-3zjxAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:42 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-EKhPQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:43 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-XYrqaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:43 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-uOS1wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:43 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-UAXC7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:43 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-_tmmct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:43 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-GEi1az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:43 INFO - Running setup.py install for manifestparser 05:59:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:43 INFO - Running setup.py install for mozcrash 05:59:43 INFO - Running setup.py install for mozdebug 05:59:44 INFO - Running setup.py install for mozdevice 05:59:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozfile 05:59:44 INFO - Running setup.py install for mozhttpd 05:59:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozinfo 05:59:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozInstall 05:59:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozleak 05:59:44 INFO - Running setup.py install for mozlog 05:59:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for moznetwork 05:59:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozprocess 05:59:45 INFO - Running setup.py install for mozprofile 05:59:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozrunner 05:59:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozscreenshot 05:59:45 INFO - Running setup.py install for moztest 05:59:46 INFO - Running setup.py install for mozversion 05:59:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:46 INFO - Cleaning up... 05:59:46 INFO - Return code: 0 05:59:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:46 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:46 INFO - 'HOME': '/Users/cltbld', 05:59:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:46 INFO - 'LOGNAME': 'cltbld', 05:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:46 INFO - 'MOZ_NO_REMOTE': '1', 05:59:46 INFO - 'NO_EM_RESTART': '1', 05:59:46 INFO - 'PAGER': '/bin/cat', 05:59:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:46 INFO - 'PWD': '/builds/slave/test', 05:59:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:46 INFO - 'SHELL': '/bin/bash', 05:59:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:46 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:46 INFO - 'USER': 'cltbld', 05:59:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:46 INFO - 'XPC_FLAGS': '0x0', 05:59:46 INFO - 'XPC_SERVICE_NAME': '0', 05:59:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:46 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-JCKZC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:46 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-chZuA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:46 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hxIbUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:46 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-69PVKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:46 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-vjTa2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-8wouYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-MYq3qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-BSFnXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-CmmVEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-SDo8nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-j1tuEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-T4kAzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-PRhvSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-T6sT_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:47 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-1Eto6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:48 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hwDQ4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:48 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-ySym2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:50 INFO - Downloading blessings-1.6.tar.gz 05:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:50 INFO - Installing collected packages: blessings 05:59:50 INFO - Running setup.py install for blessings 05:59:50 INFO - Successfully installed blessings 05:59:50 INFO - Cleaning up... 05:59:51 INFO - Return code: 0 05:59:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:51 INFO - Reading from file tmpfile_stdout 05:59:51 INFO - Current package versions: 05:59:51 INFO - blessings == 1.6 05:59:51 INFO - blobuploader == 1.2.4 05:59:51 INFO - docopt == 0.6.1 05:59:51 INFO - manifestparser == 1.1 05:59:51 INFO - mozInstall == 1.12 05:59:51 INFO - mozcrash == 0.17 05:59:51 INFO - mozdebug == 0.1 05:59:51 INFO - mozdevice == 0.48 05:59:51 INFO - mozfile == 1.2 05:59:51 INFO - mozhttpd == 0.7 05:59:51 INFO - mozinfo == 0.9 05:59:51 INFO - mozleak == 0.1 05:59:51 INFO - mozlog == 3.1 05:59:51 INFO - moznetwork == 0.27 05:59:51 INFO - mozprocess == 0.22 05:59:51 INFO - mozprofile == 0.28 05:59:51 INFO - mozrunner == 6.11 05:59:51 INFO - mozscreenshot == 0.1 05:59:51 INFO - mozsystemmonitor == 0.0 05:59:51 INFO - moztest == 0.7 05:59:51 INFO - mozversion == 1.4 05:59:51 INFO - psutil == 3.1.1 05:59:51 INFO - requests == 1.2.3 05:59:51 INFO - wsgiref == 0.1.2 05:59:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:51 INFO - 'HOME': '/Users/cltbld', 05:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:51 INFO - 'LOGNAME': 'cltbld', 05:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:51 INFO - 'MOZ_NO_REMOTE': '1', 05:59:51 INFO - 'NO_EM_RESTART': '1', 05:59:51 INFO - 'PAGER': '/bin/cat', 05:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:51 INFO - 'PWD': '/builds/slave/test', 05:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:51 INFO - 'SHELL': '/bin/bash', 05:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:51 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:51 INFO - 'USER': 'cltbld', 05:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:51 INFO - 'XPC_FLAGS': '0x0', 05:59:51 INFO - 'XPC_SERVICE_NAME': '0', 05:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:59:51 INFO - Cleaning up... 05:59:51 INFO - Return code: 0 05:59:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:51 INFO - 'HOME': '/Users/cltbld', 05:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:51 INFO - 'LOGNAME': 'cltbld', 05:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:51 INFO - 'MOZ_NO_REMOTE': '1', 05:59:51 INFO - 'NO_EM_RESTART': '1', 05:59:51 INFO - 'PAGER': '/bin/cat', 05:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:51 INFO - 'PWD': '/builds/slave/test', 05:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:51 INFO - 'SHELL': '/bin/bash', 05:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:51 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:51 INFO - 'USER': 'cltbld', 05:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:51 INFO - 'XPC_FLAGS': '0x0', 05:59:51 INFO - 'XPC_SERVICE_NAME': '0', 05:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:51 INFO - Downloading/unpacking jsonschema 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:54 INFO - Downloading/unpacking functools32 (from jsonschema) 05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:54 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:54 INFO - warning: no files found matching '*.txt' 05:59:54 INFO - no previously-included directories found matching 'build' 05:59:54 INFO - no previously-included directories found matching 'dist' 05:59:54 INFO - no previously-included directories found matching '.git*' 05:59:54 INFO - Installing collected packages: jsonschema, functools32 05:59:54 INFO - Running setup.py install for functools32 05:59:54 INFO - warning: no files found matching '*.txt' 05:59:54 INFO - no previously-included directories found matching 'build' 05:59:54 INFO - no previously-included directories found matching 'dist' 05:59:54 INFO - no previously-included directories found matching '.git*' 05:59:54 INFO - Successfully installed jsonschema functools32 05:59:54 INFO - Cleaning up... 05:59:55 INFO - Return code: 0 05:59:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:55 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 0x107f5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf01494340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, '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.AXuHdROCWT/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.HomwE6Q9GB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:59:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 05:59:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:55 INFO - 'HOME': '/Users/cltbld', 05:59:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:55 INFO - 'LOGNAME': 'cltbld', 05:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:55 INFO - 'MOZ_NO_REMOTE': '1', 05:59:55 INFO - 'NO_EM_RESTART': '1', 05:59:55 INFO - 'PAGER': '/bin/cat', 05:59:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:55 INFO - 'PWD': '/builds/slave/test', 05:59:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:55 INFO - 'SHELL': '/bin/bash', 05:59:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 05:59:55 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 05:59:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:55 INFO - 'USER': 'cltbld', 05:59:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:55 INFO - 'XPC_FLAGS': '0x0', 05:59:55 INFO - 'XPC_SERVICE_NAME': '0', 05:59:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:55 INFO - Downloading/unpacking flake8 05:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:59:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:59:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:59:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:59 INFO - no previously-included directories found matching 'docs/_build' 05:59:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:59:59 INFO - Running setup.py install for pep8 05:59:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:59:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:59:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:59:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:59:59 INFO - no previously-included directories found matching 'docs/_build' 05:59:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:59:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:59:59 INFO - Cleaning up... 05:59:59 INFO - Return code: 0 05:59:59 INFO - Running post-action listener: _resource_record_post_action 05:59:59 INFO - Running post-action listener: _start_resource_monitoring 05:59:59 INFO - Starting resource monitoring. 05:59:59 INFO - ##### 05:59:59 INFO - ##### Running install step. 05:59:59 INFO - ##### 05:59:59 INFO - Running pre-action listener: _resource_record_pre_action 05:59:59 INFO - Running main action method: install 05:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:00:00 INFO - Reading from file tmpfile_stdout 06:00:00 INFO - Detecting whether we're running mozinstall >=1.0... 06:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:00:00 INFO - Reading from file tmpfile_stdout 06:00:00 INFO - Output received: 06:00:00 INFO - Usage: mozinstall [options] installer 06:00:00 INFO - Options: 06:00:00 INFO - -h, --help show this help message and exit 06:00:00 INFO - -d DEST, --destination=DEST 06:00:00 INFO - Directory to install application into. [default: 06:00:00 INFO - "/builds/slave/test"] 06:00:00 INFO - --app=APP Application being installed. [default: firefox] 06:00:00 INFO - mkdir: /builds/slave/test/build/application 06:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 06:00:21 INFO - Reading from file tmpfile_stdout 06:00:21 INFO - Output received: 06:00:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:00:21 INFO - Running post-action listener: _resource_record_post_action 06:00:21 INFO - ##### 06:00:21 INFO - ##### Running run-tests step. 06:00:21 INFO - ##### 06:00:21 INFO - Running pre-action listener: _resource_record_pre_action 06:00:21 INFO - Running main action method: run_tests 06:00:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:22 INFO - 2016-06-27 06:00:22.000 screenresolution[1651:7759] starting screenresolution argv=screenresolution get 06:00:22 INFO - 2016-06-27 06:00:22.002 screenresolution[1651:7759] Display 0: 1600x1200x32@60 06:00:22 INFO - 2016-06-27 06:00:22.008 screenresolution[1652:7761] starting screenresolution argv=screenresolution list 06:00:22 INFO - Available Modes on Display 0 06:00:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:22 INFO - Intel Iris: 06:00:22 INFO - Chipset Model: Intel Iris 06:00:22 INFO - Type: GPU 06:00:22 INFO - Bus: Built-In 06:00:22 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:22 INFO - Vendor: Intel (0x8086) 06:00:22 INFO - Device ID: 0x0a2e 06:00:22 INFO - Revision ID: 0x0009 06:00:22 INFO - Displays: 06:00:22 INFO - Display: 06:00:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:22 INFO - Display Serial Number: 1600x1200 60 06:00:22 INFO - Main Display: Yes 06:00:22 INFO - Mirror: Off 06:00:22 INFO - Online: Yes 06:00:22 INFO - Rotation: Supported 06:00:22 INFO - Return code: 0 06:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:00:22 INFO - Python 2.7.3 06:00:22 INFO - Return code: 0 06:00:22 INFO - grabbing minidump binary from tooltool 06:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:22 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 0x107dfde00>, '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 0x107c66db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107df8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:00:22 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 06:00:22 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 06:00:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:00:22 INFO - Return code: 0 06:00:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:00:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:00:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:00:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:00:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:00:23 INFO - Return code: 0 06:00:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 06:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0054 --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 06:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AXuHdROCWT/Render', 06:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:23 INFO - 'HOME': '/Users/cltbld', 06:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:23 INFO - 'LOGNAME': 'cltbld', 06:00:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:23 INFO - 'MOZ_NO_REMOTE': '1', 06:00:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:23 INFO - 'NO_EM_RESTART': '1', 06:00:23 INFO - 'PAGER': '/bin/cat', 06:00:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:23 INFO - 'PWD': '/builds/slave/test', 06:00:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:23 INFO - 'SHELL': '/bin/bash', 06:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HomwE6Q9GB/Listeners', 06:00:23 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 06:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:23 INFO - 'USER': 'cltbld', 06:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:23 INFO - 'XPC_FLAGS': '0x0', 06:00:23 INFO - 'XPC_SERVICE_NAME': '0', 06:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 06:00:23 INFO - mozversion application_buildid: 20160624143557 06:00:23 INFO - mozversion application_changeset: 229741b7de0485ca56b91e0e657fe5d37dbbc98f 06:00:23 INFO - mozversion application_display_name: Firefox 06:00:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:00:23 INFO - mozversion application_name: Firefox 06:00:23 INFO - mozversion application_remotingname: firefox 06:00:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:00:23 INFO - mozversion application_vendor: Mozilla 06:00:23 INFO - mozversion application_version: 48.0 06:00:23 INFO - mozversion platform_buildid: 20160624143557 06:00:23 INFO - mozversion platform_changeset: 229741b7de0485ca56b91e0e657fe5d37dbbc98f 06:00:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:00:23 INFO - mozversion platform_version: 48.0 06:00:23 INFO - using testdate: 1467032423 06:00:23 INFO - actual date: 1467032423 06:00:23 INFO - starting webserver on 'localhost:49271' 06:00:23 INFO - SUITE-START | Running 2 tests 06:00:23 INFO - TEST-START | dromaeo_css 06:00:23 INFO - operating with platform_type : mac_ 06:00:23 INFO - Initialising browser for dromaeo_css test... 06:00:23 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpDFctjE/profile http://localhost:49271/getInfo.html) 06:00:26 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 06:00:26 INFO - PROCESS | 1670 | colorDepth:24 06:00:26 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 06:00:26 INFO - PROCESS | 1670 | __metrics 06:00:26 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:00:26 INFO - TEST-INFO | 1670: exit 0 06:00:26 INFO - Browser initialized. 06:00:26 INFO - Running cycle 1/1 for dromaeo_css test... 06:00:26 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpDFctjE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:38 INFO - PROCESS | 1675 | RSS: Main: 170950656 06:00:38 INFO - PROCESS | 1675 | 06:01:28 INFO - PROCESS | 1675 | 1467032488399 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] 06:03:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 06:03:39 INFO - PROCESS | 1675 | RSS: Main: 181678080 06:03:39 INFO - PROCESS | 1675 | 06:06:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 06:06:42 INFO - PROCESS | 1675 | RSS: Main: 192409600 06:06:42 INFO - PROCESS | 1675 | 06:09:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 06:09:44 INFO - PROCESS | 1675 | RSS: Main: 194207744 06:09:44 INFO - PROCESS | 1675 | 06:12:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 06:12:47 INFO - PROCESS | 1675 | RSS: Main: 197967872 06:12:47 INFO - PROCESS | 1675 | 06:15:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 06:15:50 INFO - PROCESS | 1675 | RSS: Main: 197869568 06:15:50 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 06:18:52 INFO - PROCESS | 1675 | RSS: Main: 201859072 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | __start_tp_report 06:18:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 06:18:52 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 06:18:52 INFO - PROCESS | 1675 | |i|pagename|runs| 06:18:52 INFO - PROCESS | 1675 | |0;dojo.html;3286.359159963808;3672.761270517416;3729.720270979677;3731.888043358699;3746.306933217353;6054.273487181539;6082.817515474532;6107.175531303278;6126.326104128542;6137.81586552403;6106.847328816771;6121.112438696387;6128.417800308964;6153.0155175171985;6153.4154255345675;5442.292501974738;5518.558515318785;5568.9443105569035;5578.075563930458;5614.157876318564;5442.727863606824;5528.944710552903;5544.584156188282;5577.9163312550145;5628;5514.338279406476;5519.006578815812;5567.4432556744405;5590.413006634306;5601.271834661488;5506.504414602685;5526.419725928759;5552.86117847053;5559.555235581144;5575.609707320489;5308.097623404031;5368.892622147574;5463.125899856043;5525.392206857243;5530.972345138289;5910.143029260733;6004.12940123684;6005.129256257818;6049.395060493959;6080.2399700037495;6407.327230640809;6413.615183089029;6449.871002579969;6475.417212450902;6476.805695829133;6350.26971898233;6453.806385808433;6503.512236582275;6506.284308726036;6507.93492065078;6129.664444256052;6219.968900155515;6221.06683997404;6230.87538249237;6257.7184026718905;6609.6364699941005;6645.269020407752;6659.966700166468;6673.799786006428;6689.732410703615;6318.368163183737;6322.841928951813;6387.329330420332;6391.2330520337855;6447.451966582893;6524.6737663116655;6562.146920900348;6575.210974682973;6581.835454113685;6584.539082264196;6306.89629314868;6448.451881590117;6458.063580780855;6459.741610335628;6482.935170648233;5215.634905556651;6399.5840270383205;6438.710258038306;6454.290028096905;6456.580322279036;5121.436641969381;5203.869903252374;5218.947810521922;5230.816921407809;5231.555317797954;3670.853165873335;4114.917701645951;4683.8360657376825;4726.456457507431;4734.857954261378;5080.263361812516;6050.273967123885;6172.537059720449;6241.656708881055;6244.2819075805955;5350;6481.09264702942;6497.772577959749;6515.73937042513;6524.6737663116655;6122.2761798032625;6146.661933593696;6161.4146656067605;6187.16473276111;6219.782307619212;4817;5676.7161641918565;5702.600817942704;5730.140478928194;5746.798862039808;6714.899276510856;6757.155355580552;6767.560108592926;6775.356341147583;6801.149856267966;6415.262244841903;6444.871102578016;6464.482841372773;6560.671966401756;6595.175603049619;6701.66491675424;6709.865802684017;6714.563553369016;6721.126253587003;6738.35985581369;6420.1974753155855;6439.098526206241;6453.612783232833;6486.643234622141;6496.675166241764;6352.110704501466;6470.773522926675;6514.6416947068365;6543.312952140147;6595.241547221939;6228.875422491616;6278.843028924313;6363.427291543783;6375.458086062737;6388.265349484869;6364.93635063662;6369.554131210771;6378.1070650108095;6380.138681277968;6450.838729031811;6110.78612248587;6114.540688159484;6147.72335244924;6235.532335074797;6244.937550624436;6331.366863313816;6385.169927909342;6388.840278993062;6403.423691867754;6464.191976003;6175.382461753788;6211.534134939807;6231.158793562811;6242.219722534684;6281.434670879643 06:18:52 INFO - PROCESS | 1675 | |1;ext.html;554.2203002715955;560.9270794796676;570.1647087017518;581.9592628516004;650.5056157320435;33987.66012339876;34158.82920585394;40923.18153636925;42137.78931105348;42227.788861055655;40464.595354046454;41164.58835411645;41212.79393603028;41243.58756412435;41275;9845.26160537959;9954.502274886263;9999.650012249573;10004;10046.799064018714;9928.255380846429;9996.250281228937;10056.19550435967;10058.34620749649;10064.597416103383;9943.154831839302;10000.149987251092;10022;10145.746356341077;10187.286889917708;10039.347442416256;10120.797584048352;10163.79672406555;10182.59269629214;10187.898121018769;8629.784255393603;10024.097831195155;10130.89869101307;10133.442660653662;10137.138343240833;43533.91165220863;43861.12277754427;43886.34170487445;43952;44241.77879110616;3421.8802341917913;3424.6404127566743;3450.292689998544;3546.8758593449365;3945.28984782739;28825.855870720723;28884.133475995754;28890.566641500394;29356.706432935825;29500.852495737385;2779.374640705845;2840.3751174741524;2869.2253091665343;2922.6346706661666;2927.1072322941495;395875;413865;436269;441576;442047;67582;67694;67870.66064669644;69260;69333.65333173302;64406;71630.64184679148;72933.63533182406;76396;77280.61359693277;18627.534311642048;19038.85766853993;19171.712424313646;19257.32599359016;19279.710804337945;14224.14655120696;14567.708645827237;14606.926965365104;14607.78088328676;14701.41194352214;20825.37523874286;21042.842643654745;21050.684239736416;21116.47208819792;21222.151113955268;7029;8305.916940830635;8437.620307086196;8459.70391036323;8542.658293668184;974.5614473486959;1006.1447769396101;1008.9445080520495;1033.3902997231519;1118.9496472658586;29526;30050.849745751133;30063.398732025238;30777.692223077484;31126.377472450426;877.863931090682;1119.1214896306524;1131.1459847814847;1221.584661215191;1227.8403807504967;11153.609623663133;14467;14801.851981480047;15818.288177032062;15820.525384238492;650280;658609;665130;668190;668710;67619.32380676229;67651;68302.3169768306;68440.97338539826;68468;1546.242341252778;1555.2845690982274;1558.6415124521197;1570.6937147256176;1572.8977616454895;31098.533521996735;31233;31272.530912036793;31625.683743162273;31971.880984165444;57538.7123064382;64657.35342646513;64925;65715;65880.34119658743;588156;589942;599042;599773;600465;38683.41974870323;46379.07241855212;46740;46926;47027;47104.7644761774;48022.75988620034;48568.75715621399;48977.77555561139;49138.75430622824;32790.672093278765;38210.80894595509;38233.617663823;39579.60420395759;39584.40623390709;37842.81078594589;38468.23063538769;38784.6121538781;38803.80598097105;39520;38876;39724.411023558736;39775;40331.38674452989;40821.591784082964;35250;36129.09677257985;36239.45640815337;36480.270394591425;36509.63490365062;23158;26575.60136597914;26619;27023.594646080717;27162 06:18:52 INFO - PROCESS | 1675 | |2;jquery.html;418.3933296719757;420.77067997941117;424.4482173174872;428.5585846578025;434.78260869565196;21134.894325528352;21170.470738231554;21310;21409.715417074985;22195.44511387216;20564.971751412406;20776.68834967473;20916.163353465843;21027.053782579784;21242.25652102177;6092.268927728679;6093.299270583873;6303.3066362700065;6363.07735378371;6378.425941665249;4593.563611456906;6237.594556353823;6295.968520157393;6716;7014.929850701505;6960.860782784342;6970.7560235391775;6986.301369862998;6988.685509152075;6990.126234220723;5728.054870946289;6812.693428795691;6984;6991.405730512913;7035.577865328097;5694.886102277973;5947.851303717398;5962.433568810956;6137.846553836145;6151.507879369639;14047.859521404758;14049.78925316121;14123.152610843383;14125.929370353186;14374.425022999067;2192.4190089626263;2270.262164796448;2506.8969653352465;2618.4681961052806;2863.828170309788;9641.180499657537;9722.513874306327;10052.045055719695;10074.546645400975;10117.08946194846;1819.3177558415593;1836.2654938024764;1837.1549087419803;1869.2896699254331;1877.4461533847411;114810.42594787056;127400;127795;130192.34903825421;130757.34621326928;11844.230125041844;11962;12121.333326666942;12129.15095943293;12241.69395765113;15247.085174889542;15277.854160937752;15281.388744450098;15300.770488442682;15307.005044672062;9260.490673012915;11639.301641901513;11686.76626467466;11809.763804723858;12201.633950981484;9947.303688741864;9981.80036399268;10006.44964526958;10149.695509134737;10444.791104177873;7407.370373518203;9264.629414823332;9755.560999755027;10401.895981040092;10445.68662940113;4017.3773065174946;4073.0856497446152;4168.812403441852;4213.894652633709;4215.304474761691;968.9127978481971;969.486172328667;1008.3848852200152;1039.4698703661188;1048.109107258824;20366.490837729176;20442.773433594033;20884.89557552233;21420.03609837561;21426.464338391666;989.1839232633107;1008.606643409071;1028.1671845804922;1032.2825636182931;1036.891126431729;11063.059639930536;11513.539458421737;11549.01833344175;11580.6525804226;11619.535218591162;669068;673384;679610;697483;702603.4869825618;118417.81582184068;132522;133666;135126;139922.30038849742;1074.99462502687;1086.7446150154708;1092.1808643517204;1094.7974624694457;1099.9770213701174;18258.36095736696;18959.715604266225;18960.146793394055;18986.810131898503;19267.421977340702;51016.97966040584;52719.472805271456;52859;53172.46827531675;53763.462365375846;63120.36879631145;75539;77261;78051.2194878044;79039.60480197563;8403.747887563382;8465.322774178174;8627.43921645091;8660.17728315809;8858.557072146496;11978.495268359562;12587.307698076695;12739.363031848185;12767.74464510686;12848.743025139258;9998.100170984646;10629.25595208328;10745.892541074489;11450.198486105892;11588.47851846652;9167.541622918961;10746.143351873092;11114.22200445961;11337.546498140311;11423.485943132413;11196.608118715805;11362.886371136183;11494.02300804441;11592.478338474957;11693.298402095901;8318.83362332762;8373.120822313569;8581.914180858112;8613.956930215309;8771.517566533901;10980.560777568808;11134.777304453704;11278.661640150809;11306.94346528262;11497.885021149681 06:18:52 INFO - PROCESS | 1675 | |3;mootools.html;1337.4783834304621;1479.7791821747055;1663.376233912283;1976.5571133072856;1989.303743689708;8436.873446898286;9118.772030699236;9267.795186625737;9412.717618471439;9472.85790713139;9122.54387280635;9124.863127053082;9133.543322833864;9244.8613270801;9250.982391936881;2556.8849401776965;2559.09152250951;2563.2566555698827;2568.9100881469153;2581.174024312221;2517.6223566465087;2577.690677118748;2583.4962182374443;2592.4296654735926;2593.753593408623;2553.74462553745;2571.897124115033;2605.3747100695887;2608.1523504860997;2609.9217023489327;2559.0787316566116;2588.339973306804;2599.480103979212;2600.2069368842494;2600.986995065022;2536.4800215955684;2576.124117799938;2585.7026441959038;2592.8185027048116;2601.2196341097783;9181.760462337566;9211.032841551607;9306.627734890664;9326.347155699104;9332.953335233347;5748.8850222995725;5767.394423585547;5837;5853.707314634251;5943.881122377528;3705.555333360014;3804.029972357031;3841.117852252404;3846.6728978502374;3882.5340959085092;1050.1913526584563;1072.8068947589431;1073.3881687438163;1100.5157730598512;1112.4215407987801;12371.13402061856;12498.437570309266;12509.749805003848;12513.124081314312;12625.368731563476;5034.546890779848;5134.409542902539;5172.637915345965;5176.886969301543;5278.736063196825;10723;10739.785204295871;10757.193210509244;10770.838437423445;10796.244262901679;5338.46615338463;5450.31871016123;5624.831255062273;5685.772569097273;5715.171300161536;4666.183417901853;5122.410922743858;5139.306193663808;5264.868378290574;5295.179247216687;4254.617084462413;4285.3786201000685;4317.309230523164;4356.782160891943;4397.626201772821;1221.8350522679502;1390.4021270853427;1392.3943084758162;1412.9081609695336;1424.7008128292966;778.8559116563454;891.1730951549428;925.879635647375;970.6069042037985;988.9159421449114;5295;6124.35694252106;6138.754449821957;6189.442950134419;6295.559310848196;983.8130755156498;999.5402115027169;1014.6803756816579;1028.4857571214393;1029.6859457865273;4066.898327541835;4081.918361632751;4103.343465045578;4133.586641335903;4135.276326642826;11210.66368008961;11495.42522873853;11531.538738450368;11696;11703.063754899587;10570.577176912991;10574.52414641343;11100.722481937855;11480.425978701032;11516.654500365003;7347.742829000959;8674.653013879373;8786.912130878609;8967.372283940062;9018.819623607491;5178.559822414986;5260.842174734764;5401.567874570103;6081.939180608138;6279.058141278662;9109.908900910907;9752.268579856398;9944.204463642747;10185.694429167137;10368.740781480523;10295.794084118126;10382.844257335995;10675.039246467855;10747.193960453154;10793.784124317313;5521.779128834802;5889.352171261072;6754.2570317266045;7471.925280747123;7509.061367329084;8562.871556926775;8606.78483037904;8641.82716345657;8679.739607811776;8715.52064636451;7526.24737526243;7685;7701.653425595974;7709.2290770922455;7749.186335434923;7592.924070759222;7694.653740581799;7719.498232615087;7804.0635123785505;7861.606919654108;6119.204503414528;7277.053982982178;7420.591867447126;7423.109226892808;7430.219826918095;4976.825811096594;4995.300657907823;5109.6678715883345;5148.9742551287;5168.896622067613;5147.613928955268;5161.354830646169;5187.014467251211;5189.532942035235;5258.842234732964 06:18:52 INFO - PROCESS | 1675 | |4;prototype.html;337.24464164026244;339.5704433891127;340.6083004544775;345.76833521540567;350.2766786585699;6861.794146175623;6929.025227810075;7020.929790702098;7044.577325360482;7057.047298614685;5879.971005074106;6770.458363330934;6993.44052475802;7017.68420421081;7078.009078728982;5170.663906846043;5198.584113270948;5211.270422140903;5214.139666954948;5234.267202591621;5202.92195617066;5209.947900521003;5222.399424066224;5223.895522089556;5231.633785635007;5133.486651334874;5136.922946155811;5152.433232344439;5170.689758614476;5174.586033117342;3990.501187351581;4943.530364615356;5049.065922804293;5138.332016837825;5149.2791009258735;4235.237657221699;4251.532331443539;4261.211675839949;4264.87205383839;4268.786560671954;6578.605283682947;6680.231773346079;6680.83297917551;6682.298358672367;6703.296153903867;4365.3451982202605;5988.544696674386;6002.189704389895;6058.4244496772735;6059.939400605982;7422.814429639248;7543.962280188619;7548.396128309722;7548.886766698504;7554.8866766998535;4641.443026836801;4786.21027530457;4787.4494433139935;4805.663603547753;4809.278608208761;6308.905366419507;7974.202579741979;8005.919940800517;8015.278624923785;8015.799605009863;4893.975530122327;4914.287428322873;4918.827841025547;4923.48303428142;4942.233953737176;5011.173156429221;5027.095122877844;5035.269885866529;5035.5468007878735;5040.596752259884;6022.969885150546;6051.183090282843;6055.818325450244;6063.211782468251;6071.787487437919;7410.925890741024;7609.619519024137;7611.1627720950655;7642;7655.540667559964;1451.7877572227308;1716.6652502762092;1724.3964612385564;1739.4086010756403;1739.9043052632226;1018.2464975917916;1055.2454994678687;1059.1209296284067;1076.9946150269354;1097.5116073347283;7051.365377116084;7158.856822863513;7176.569405835667;7180.461465390116;7182.533135346186;7844.294013538809;7846.3330616897065;7851.528908265523;7855.332296754726;7872.763817085496;7119.252478489788;7125.42996560274;7130.25132361105;7152.642367881585;7181.8922716159295;3632.8546858125906;3754.817232571731;4119.958800412018;4153.750774953452;4166.58334166587;6522.771702990372;8125.634346454423;8200.01599808027;8201.056878458934;8228.382871284677;6510.7721229756735;6854.931450685529;8118.10700822909;8127.715529956423;8128.268455839003;8168.305694016075;8171.387145964077;8177.550234737147;8185.549794761345;8205.538338498427;6258.43674069336;6827.634473105498;7834;7869.960650196713;7878.960605196938;7686.192949740197;7694.576798275924;7710.190430004769;7719;7720.189380115232;8217.876731849146;8218.547979861165;8220.260176584135;8250.422470427013;8339.583020848811;4944.381952255968;4954.752262386794;4956;4956.77694503741;4975.651704380659;7563.584002879895;7872.763817085496;7877.2122787723065;7967.32277756374;7974.92025079765;6178.042403427476;6945.888657814726;7582.54504729718;7645.464817462725;7657.846843062996;7110.680019399036;7666;7672.347850432776;7679.769606911802;7686.807829804299;7187.425005999612;7415.740449084472;7419.258074192538;7472.962635186789;7514.098308203051;7287.453440991841;7642.579658118634;7647.158812530506;7652.885206721792;7671.8849217262905;7296.59868707205;7441.4046876248685;7476.887846682195;7494.100707915085;7519.436042296959 06:18:52 INFO - PROCESS | 1675 | |5;yui.html;699.6396855619354;710.7299226294004;784.5763287824575;789.0610173893067;790.9683612655494;13115.54095606654;15465.536033918987;15680.607984800386;15689.372425102982;15762.842371576295;14386.424543018267;14501.129932204049;14531.128132312042;14532.564023079298;14544.490942817003;748.2554857916354;943.2312665177875;956.086936975187;961.2502248246379;962.2927148545848;817.4849844597895;902.8645703144515;902.8916530016375;943.2642538819697;943.4669411782355;938.8122375524888;964.3876138651937;993;995.4027583449908;1005.8239808033564;943.9150476457118;983.616389608057;994.4480813148699;1000.1148983149892;1007.6070332570304;708.890122031086;709.1383968478272;742.4728442805615;748.4611080022376;763.2977660552306;15253.237338133049;15264.46574369903;15280.465183718516;15550.533483995385;15578.909476336661;13368.197908125543;13371.33143342829;13389.196648201041;13495.86504134956;13526.864731352658;10336.5348559315;10374.325668831501;10388.046631273615;10437.165026797838;10717.142628589694;3546.5744110706623;3582.531311144874;3602.35157671619;3655.3603119454256;3710.2023065041144;85725;86238.56880715556;86426.56786716089;86462;88915;10079.69760907174;10170.389776613427;10251.794964100825;10353.223508236912;10357.896421035844;22928.656070158962;23316.950737216866;23324.883375583013;23333.18333858307;23922.7607723924;7991.120976692559;7993;8045.436819422584;8077;8122.959385203154;4000.4199391088127;4083.934792808458;4090.8568200112854;4124.463819703479;4124.52567954689;3881.184951160288;3902.751119641744;3967.7104940894324;3969.226000929791;4065.6137666921595;722.241646271413;724.3263764698776;728.8360118973188;739.2533541123504;742.2651574940878;573.7676241122352;598.7874304621884;601.5969300568629;629.3140476880222;633.6134957675814;8063.27430531255;8245.95877020623;8441.366897482714;8577.528235946958;8582.957085214659;455.6628095209521;611.8715069835296;617.2839506172818;620.4787978098419;635.5138319185827;3889.202713443737;3914.373700207953;3939.1333906540517;3978.5623581406603;4098.7130900836655;100712;101625.49187254017;102504;103298.48350758199;103699;71978.84063478104;83802.90492737609;86871;87222.56388718016;89379;68145;76342.61828690821;76680;77054.84417733851;77698.6115069421;7730.226977302224;7871.803204919923;7897;7994.2805147537;8018.191115943605;42184.57815421929;53059.93880122299;53330.4666953341;53538;53980.73009634927;66959.8680593725;74659;74666.62666686632;74751;75155.62422187853;10148.746281343025;10435.634752783617;10469.172559235827;10717.839232411676;10823.296485728402;11094.334339939629;11097.38964356968;11279.35461017679;11295.717607059889;11306.265092768943;10616;10639.361638301727;10720;10884.129269658566;11025.17311201647;10313.587456501657;10525.421101839165;10572.20708446885;10715.464226788474;10839.891601083888;10071.496425178859;10186.592536298465;10548.683539493828;10875.238733288594;11122.943320384551;8011.359091272568;9064.728058158265;9171.35800493959;9427.15155635996;9531.428114313163;8932.910670893469;9124.77188070277;9173.35786494939;9188.173064424234;9199.954000230224 06:18:52 INFO - PROCESS | 1675 | __end_tp_report 06:18:52 INFO - PROCESS | 1675 | __start_cc_report 06:18:52 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,398 06:18:52 INFO - PROCESS | 1675 | __end_cc_report 06:18:52 INFO - PROCESS | 1675 | __startTimestamp1467033532782__endTimestamp 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | ------- Summary: start ------- 06:18:52 INFO - PROCESS | 1675 | Number of tests: 6 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:6024.53 Median:6241.66 stddev:681.30 (10.9%) stddev-sans-first:648.65 06:18:52 INFO - PROCESS | 1675 | Values: 3286.4 3672.8 3729.7 3731.9 3746.3 6054.3 6082.8 6107.2 6126.3 6137.8 6106.8 6121.1 6128.4 6153.0 6153.4 5442.3 5518.6 5568.9 5578.1 5614.2 5442.7 5528.9 5544.6 5577.9 5628.0 5514.3 5519.0 5567.4 5590.4 5601.3 5506.5 5526.4 5552.9 5559.6 5575.6 5308.1 5368.9 5463.1 5525.4 5531.0 5910.1 6004.1 6005.1 6049.4 6080.2 6407.3 6413.6 6449.9 6475.4 6476.8 6350.3 6453.8 6503.5 6506.3 6507.9 6129.7 6220.0 6221.1 6230.9 6257.7 6609.6 6645.3 6660.0 6673.8 6689.7 6318.4 6322.8 6387.3 6391.2 6447.5 6524.7 6562.1 6575.2 6581.8 6584.5 6306.9 6448.5 6458.1 6459.7 6482.9 5215.6 6399.6 6438.7 6454.3 6456.6 5121.4 5203.9 5218.9 5230.8 5231.6 3670.9 4114.9 4683.8 4726.5 4734.9 5080.3 6050.3 6172.5 6241.7 6244.3 5350.0 6481.1 6497.8 6515.7 6524.7 6122.3 6146.7 6161.4 6187.2 6219.8 4817.0 5676.7 5702.6 5730.1 5746.8 6714.9 6757.2 6767.6 6775.4 6801.1 6415.3 6444.9 6464.5 6560.7 6595.2 6701.7 6709.9 6714.6 6721.1 6738.4 6420.2 6439.1 6453.6 6486.6 6496.7 6352.1 6470.8 6514.6 6543.3 6595.2 6228.9 6278.8 6363.4 6375.5 6388.3 6364.9 6369.6 6378.1 6380.1 6450.8 6110.8 6114.5 6147.7 6235.5 6244.9 6331.4 6385.2 6388.8 6403.4 6464.2 6175.4 6211.5 6231.2 6242.2 6281.4 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:71687.77 Median:38350.92 stddev:152186.10 (396.8%) stddev-sans-first:152519.31 06:18:52 INFO - PROCESS | 1675 | Values: 554.2 560.9 570.2 582.0 650.5 33987.7 34158.8 40923.2 42137.8 42227.8 40464.6 41164.6 41212.8 41243.6 41275.0 9845.3 9954.5 9999.7 10004.0 10046.8 9928.3 9996.3 10056.2 10058.3 10064.6 9943.2 10000.1 10022.0 10145.7 10187.3 10039.3 10120.8 10163.8 10182.6 10187.9 8629.8 10024.1 10130.9 10133.4 10137.1 43533.9 43861.1 43886.3 43952.0 44241.8 3421.9 3424.6 3450.3 3546.9 3945.3 28825.9 28884.1 28890.6 29356.7 29500.9 2779.4 2840.4 2869.2 2922.6 2927.1 395875.0 413865.0 436269.0 441576.0 442047.0 67582.0 67694.0 67870.7 69260.0 69333.7 64406.0 71630.6 72933.6 76396.0 77280.6 18627.5 19038.9 19171.7 19257.3 19279.7 14224.1 14567.7 14606.9 14607.8 14701.4 20825.4 21042.8 21050.7 21116.5 21222.2 7029.0 8305.9 8437.6 8459.7 8542.7 974.6 1006.1 1008.9 1033.4 1118.9 29526.0 30050.8 30063.4 30777.7 31126.4 877.9 1119.1 1131.1 1221.6 1227.8 11153.6 14467.0 14801.9 15818.3 15820.5 650280.0 658609.0 665130.0 668190.0 668710.0 67619.3 67651.0 68302.3 68441.0 68468.0 1546.2 1555.3 1558.6 1570.7 1572.9 31098.5 31233.0 31272.5 31625.7 31971.9 57538.7 64657.4 64925.0 65715.0 65880.3 588156.0 589942.0 599042.0 599773.0 600465.0 38683.4 46379.1 46740.0 46926.0 47027.0 47104.8 48022.8 48568.8 48977.8 49138.8 32790.7 38210.8 38233.6 39579.6 39584.4 37842.8 38468.2 38784.6 38803.8 39520.0 38876.0 39724.4 39775.0 40331.4 40821.6 35250.0 36129.1 36239.5 36480.3 36509.6 23158.0 26575.6 26619.0 27023.6 27162.0 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:37886.90 Median:20830.79 stddev:113673.86 (545.7%) stddev-sans-first:113957.93 06:18:52 INFO - PROCESS | 1675 | Values: 418.4 420.8 424.4 428.6 434.8 21134.9 21170.5 21310.0 21409.7 22195.4 20565.0 20776.7 20916.2 21027.1 21242.3 6092.3 6093.3 6303.3 6363.1 6378.4 4593.6 6237.6 6296.0 6716.0 7014.9 6960.9 6970.8 6986.3 6988.7 6990.1 5728.1 6812.7 6984.0 6991.4 7035.6 5694.9 5947.9 5962.4 6137.8 6151.5 14047.9 14049.8 14123.2 14125.9 14374.4 2192.4 2270.3 2506.9 2618.5 2863.8 9641.2 9722.5 10052.0 10074.5 10117.1 1819.3 1836.3 1837.2 1869.3 1877.4 114810.4 127400.0 127795.0 130192.3 130757.3 11844.2 11962.0 12121.3 12129.2 12241.7 15247.1 15277.9 15281.4 15300.8 15307.0 9260.5 11639.3 11686.8 11809.8 12201.6 9947.3 9981.8 10006.4 10149.7 10444.8 7407.4 9264.6 9755.6 10401.9 10445.7 4017.4 4073.1 4168.8 4213.9 4215.3 968.9 969.5 1008.4 1039.5 1048.1 20366.5 20442.8 20884.9 21420.0 21426.5 989.2 1008.6 1028.2 1032.3 1036.9 11063.1 11513.5 11549.0 11580.7 11619.5 669068.0 673384.0 679610.0 697483.0 702603.5 118417.8 132522.0 133666.0 135126.0 139922.3 1075.0 1086.7 1092.2 1094.8 1100.0 18258.4 18959.7 18960.1 18986.8 19267.4 51017.0 52719.5 52859.0 53172.5 53763.5 63120.4 75539.0 77261.0 78051.2 79039.6 8403.7 8465.3 8627.4 8660.2 8858.6 11978.5 12587.3 12739.4 12767.7 12848.7 9998.1 10629.3 10745.9 11450.2 11588.5 9167.5 10746.1 11114.2 11337.5 11423.5 11196.6 11362.9 11494.0 11592.5 11693.3 8318.8 8373.1 8581.9 8614.0 8771.5 10980.6 11134.8 11278.7 11306.9 11497.9 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5972.74 Median:5128.41 stddev:3338.71 (65.1%) stddev-sans-first:3329.90 06:18:52 INFO - PROCESS | 1675 | Values: 1337.5 1479.8 1663.4 1976.6 1989.3 8436.9 9118.8 9267.8 9412.7 9472.9 9122.5 9124.9 9133.5 9244.9 9251.0 2556.9 2559.1 2563.3 2568.9 2581.2 2517.6 2577.7 2583.5 2592.4 2593.8 2553.7 2571.9 2605.4 2608.2 2609.9 2559.1 2588.3 2599.5 2600.2 2601.0 2536.5 2576.1 2585.7 2592.8 2601.2 9181.8 9211.0 9306.6 9326.3 9333.0 5748.9 5767.4 5837.0 5853.7 5943.9 3705.6 3804.0 3841.1 3846.7 3882.5 1050.2 1072.8 1073.4 1100.5 1112.4 12371.1 12498.4 12509.7 12513.1 12625.4 5034.5 5134.4 5172.6 5176.9 5278.7 10723.0 10739.8 10757.2 10770.8 10796.2 5338.5 5450.3 5624.8 5685.8 5715.2 4666.2 5122.4 5139.3 5264.9 5295.2 4254.6 4285.4 4317.3 4356.8 4397.6 1221.8 1390.4 1392.4 1412.9 1424.7 778.9 891.2 925.9 970.6 988.9 5295.0 6124.4 6138.8 6189.4 6295.6 983.8 999.5 1014.7 1028.5 1029.7 4066.9 4081.9 4103.3 4133.6 4135.3 11210.7 11495.4 11531.5 11696.0 11703.1 10570.6 10574.5 11100.7 11480.4 11516.7 7347.7 8674.7 8786.9 8967.4 9018.8 5178.6 5260.8 5401.6 6081.9 6279.1 9109.9 9752.3 9944.2 10185.7 10368.7 10295.8 10382.8 10675.0 10747.2 10793.8 5521.8 5889.4 6754.3 7471.9 7509.1 8562.9 8606.8 8641.8 8679.7 8715.5 7526.2 7685.0 7701.7 7709.2 7749.2 7592.9 7694.7 7719.5 7804.1 7861.6 6119.2 7277.1 7420.6 7423.1 7430.2 4976.8 4995.3 5109.7 5149.0 5168.9 5147.6 5161.4 5187.0 5189.5 5258.8 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:6121.83 Median:6937.46 stddev:1998.11 (28.8%) stddev-sans-first:1955.98 06:18:52 INFO - PROCESS | 1675 | Values: 337.2 339.6 340.6 345.8 350.3 6861.8 6929.0 7020.9 7044.6 7057.0 5880.0 6770.5 6993.4 7017.7 7078.0 5170.7 5198.6 5211.3 5214.1 5234.3 5202.9 5209.9 5222.4 5223.9 5231.6 5133.5 5136.9 5152.4 5170.7 5174.6 3990.5 4943.5 5049.1 5138.3 5149.3 4235.2 4251.5 4261.2 4264.9 4268.8 6578.6 6680.2 6680.8 6682.3 6703.3 4365.3 5988.5 6002.2 6058.4 6059.9 7422.8 7544.0 7548.4 7548.9 7554.9 4641.4 4786.2 4787.4 4805.7 4809.3 6308.9 7974.2 8005.9 8015.3 8015.8 4894.0 4914.3 4918.8 4923.5 4942.2 5011.2 5027.1 5035.3 5035.5 5040.6 6023.0 6051.2 6055.8 6063.2 6071.8 7410.9 7609.6 7611.2 7642.0 7655.5 1451.8 1716.7 1724.4 1739.4 1739.9 1018.2 1055.2 1059.1 1077.0 1097.5 7051.4 7158.9 7176.6 7180.5 7182.5 7844.3 7846.3 7851.5 7855.3 7872.8 7119.3 7125.4 7130.3 7152.6 7181.9 3632.9 3754.8 4120.0 4153.8 4166.6 6522.8 8125.6 8200.0 8201.1 8228.4 6510.8 6854.9 8118.1 8127.7 8128.3 8168.3 8171.4 8177.6 8185.5 8205.5 6258.4 6827.6 7834.0 7870.0 7879.0 7686.2 7694.6 7710.2 7719.0 7720.2 8217.9 8218.5 8220.3 8250.4 8339.6 4944.4 4954.8 4956.0 4956.8 4975.7 7563.6 7872.8 7877.2 7967.3 7974.9 6178.0 6945.9 7582.5 7645.5 7657.8 7110.7 7666.0 7672.3 7679.8 7686.8 7187.4 7415.7 7419.3 7473.0 7514.1 7287.5 7642.6 7647.2 7652.9 7671.9 7296.6 7441.4 7476.9 7494.1 7519.4 06:18:52 INFO - PROCESS | 1675 | 06:18:52 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:19280.93 Median:53195.20 stddev:27967.07 (52.6%) stddev-sans-first:28010.72 06:18:52 INFO - PROCESS | 1675 | Values: 699.6 710.7 784.6 789.1 791.0 13115.5 15465.5 15680.6 15689.4 15762.8 14386.4 14501.1 14531.1 14532.6 14544.5 748.3 943.2 956.1 961.3 962.3 817.5 902.9 902.9 943.3 943.5 938.8 964.4 993.0 995.4 1005.8 943.9 983.6 994.4 1000.1 1007.6 708.9 709.1 742.5 748.5 763.3 15253.2 15264.5 15280.5 15550.5 15578.9 13368.2 13371.3 13389.2 13495.9 13526.9 10336.5 10374.3 10388.0 10437.2 10717.1 3546.6 3582.5 3602.4 3655.4 3710.2 85725.0 86238.6 86426.6 86462.0 88915.0 10079.7 10170.4 10251.8 10353.2 10357.9 22928.7 23317.0 23324.9 23333.2 23922.8 7991.1 7993.0 8045.4 8077.0 8123.0 4000.4 4083.9 4090.9 4124.5 4124.5 3881.2 3902.8 3967.7 3969.2 4065.6 722.2 724.3 728.8 739.3 742.3 573.8 598.8 601.6 629.3 633.6 8063.3 8246.0 8441.4 8577.5 8583.0 455.7 611.9 617.3 620.5 635.5 3889.2 3914.4 3939.1 3978.6 4098.7 100712.0 101625.5 102504.0 103298.5 103699.0 71978.8 83802.9 86871.0 87222.6 89379.0 68145.0 76342.6 76680.0 77054.8 77698.6 7730.2 7871.8 7897.0 7994.3 8018.2 42184.6 53059.9 53330.5 53538.0 53980.7 66959.9 74659.0 74666.6 74751.0 75155.6 10148.7 10435.6 10469.2 10717.8 10823.3 11094.3 11097.4 11279.4 11295.7 11306.3 10616.0 10639.4 10720.0 10884.1 11025.2 10313.6 10525.4 10572.2 10715.5 10839.9 10071.5 10186.6 10548.7 10875.2 11122.9 8011.4 9064.7 9171.4 9427.2 9531.4 8932.9 9124.8 9173.4 9188.2 9200.0 06:18:52 INFO - PROCESS | 1675 | -------- Summary: end -------- 06:18:52 INFO - PROCESS | 1675 | 06:18:53 INFO - TEST-INFO | 1675: exit 0 06:18:53 INFO - TEST-OK | dromaeo_css | took 1109674ms 06:18:53 INFO - TEST-START | kraken 06:18:53 INFO - operating with platform_type : mac_ 06:18:53 INFO - Initialising browser for kraken test... 06:18:53 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpRhRcM5/profile http://localhost:49271/getInfo.html) 06:18:55 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 06:18:55 INFO - PROCESS | 1682 | colorDepth:24 06:18:55 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 06:18:55 INFO - PROCESS | 1682 | __metrics 06:18:55 ERROR - PROCESS | 1682 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 06:18:56 INFO - TEST-INFO | 1682: exit 0 06:18:56 INFO - Browser initialized. 06:18:56 INFO - Running cycle 1/1 for kraken test... 06:18:56 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpRhRcM5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:08 INFO - PROCESS | 1686 | RSS: Main: 170692608 06:19:08 INFO - PROCESS | 1686 | 06:19:11 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:12 INFO - PROCESS | 1686 | RSS: Main: 183414784 06:19:12 INFO - PROCESS | 1686 | 06:19:14 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 06:19:15 INFO - PROCESS | 1686 | RSS: Main: 192528384 06:19:15 INFO - PROCESS | 1686 | 06:19:18 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 06:19:19 INFO - PROCESS | 1686 | RSS: Main: 195870720 06:19:19 INFO - PROCESS | 1686 | 06:19:21 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 06:19:21 INFO - PROCESS | 1686 | RSS: Main: 196009984 06:19:21 INFO - PROCESS | 1686 | 06:19:23 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:19:24 INFO - PROCESS | 1686 | RSS: Main: 196341760 06:19:24 INFO - PROCESS | 1686 | 06:19:27 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:27 INFO - PROCESS | 1686 | RSS: Main: 198529024 06:19:27 INFO - PROCESS | 1686 | 06:19:30 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:31 INFO - PROCESS | 1686 | RSS: Main: 198385664 06:19:31 INFO - PROCESS | 1686 | 06:19:34 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 06:19:34 INFO - PROCESS | 1686 | RSS: Main: 198381568 06:19:34 INFO - PROCESS | 1686 | 06:19:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:19:37 INFO - PROCESS | 1686 | RSS: Main: 197533696 06:19:37 INFO - PROCESS | 1686 | 06:19:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:19:39 INFO - PROCESS | 1686 | RSS: Main: 197652480 06:19:39 INFO - PROCESS | 1686 | 06:19:41 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:19:42 INFO - PROCESS | 1686 | RSS: Main: 197935104 06:19:42 INFO - PROCESS | 1686 | 06:19:45 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:19:46 INFO - PROCESS | 1686 | RSS: Main: 198074368 06:19:46 INFO - PROCESS | 1686 | 06:19:48 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:19:49 INFO - PROCESS | 1686 | RSS: Main: 197996544 06:19:49 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 06:19:51 INFO - PROCESS | 1686 | RSS: Main: 200015872 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | __start_tp_report 06:19:51 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 06:19:51 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 06:19:51 INFO - PROCESS | 1686 | |i|pagename|runs| 06:19:51 INFO - PROCESS | 1686 | |0;ai-astar;117;114;117;114;117;131;115;115;117;118 06:19:51 INFO - PROCESS | 1686 | |1;audio-beat-detection;159;144;180;196;123;142;120;158;153;142 06:19:51 INFO - PROCESS | 1686 | |2;audio-dft;200;200;197;201;199;197;203;200;199;199 06:19:51 INFO - PROCESS | 1686 | |3;audio-fft;62;69;78;143;55;56;61;66;122;58 06:19:51 INFO - PROCESS | 1686 | |4;audio-oscillator;78;82;116;78;122;126;169;117;79;123 06:19:51 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;88;87;89;87;88;85;88;85;88;85 06:19:51 INFO - PROCESS | 1686 | |6;imaging-darkroom;94;94;94;103;138;94;115;120;94;94 06:19:51 INFO - PROCESS | 1686 | |7;imaging-desaturate;142;147;92;91;97;91;90;91;89;90 06:19:51 INFO - PROCESS | 1686 | |8;json-parse-financial;67;91;63;64;65;65;64;81;62;59 06:19:51 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;63;65;63;77;61;96;106;94;96;76 06:19:51 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;79;80;82;70;94;108;105;105;77;100 06:19:51 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;118;161;154;131;141;151;128;128;148;166 06:19:51 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;166;174;163;139;158;217;220;208;159;156 06:19:51 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;65;66;70;66;70;64;70;73;101;99 06:19:51 INFO - PROCESS | 1686 | __end_tp_report 06:19:51 INFO - PROCESS | 1686 | __start_cc_report 06:19:51 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,1030 06:19:51 INFO - PROCESS | 1686 | __end_cc_report 06:19:51 INFO - PROCESS | 1686 | __startTimestamp1467033591588__endTimestamp 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | ------- Summary: start ------- 06:19:51 INFO - PROCESS | 1686 | Number of tests: 14 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:117.50 Median:117.00 stddev:4.95 (4.2%) stddev-sans-first:5.25 06:19:51 INFO - PROCESS | 1686 | Values: 117.0 114.0 117.0 114.0 117.0 131.0 115.0 115.0 117.0 118.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:155.50 stddev:23.41 (15.1%) stddev-sans-first:24.69 06:19:51 INFO - PROCESS | 1686 | Values: 159.0 144.0 180.0 196.0 123.0 142.0 120.0 158.0 153.0 142.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:199.50 Median:200.00 stddev:1.78 (0.9%) stddev-sans-first:1.88 06:19:51 INFO - PROCESS | 1686 | Values: 200.0 200.0 197.0 201.0 199.0 197.0 203.0 200.0 199.0 199.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:77.00 Median:61.50 stddev:30.43 (49.5%) stddev-sans-first:31.79 06:19:51 INFO - PROCESS | 1686 | Values: 62.0 69.0 78.0 143.0 55.0 56.0 61.0 66.0 122.0 58.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:123.50 stddev:29.66 (24.0%) stddev-sans-first:29.26 06:19:51 INFO - PROCESS | 1686 | Values: 78.0 82.0 116.0 78.0 122.0 126.0 169.0 117.0 79.0 123.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.49 (1.7%) stddev-sans-first:1.54 06:19:51 INFO - PROCESS | 1686 | Values: 88.0 87.0 89.0 87.0 88.0 85.0 88.0 85.0 88.0 85.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:94.00 stddev:15.40 (16.4%) stddev-sans-first:15.90 06:19:51 INFO - PROCESS | 1686 | Values: 94.0 94.0 94.0 103.0 138.0 94.0 115.0 120.0 94.0 94.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:102.00 Median:91.00 stddev:22.53 (24.8%) stddev-sans-first:18.68 06:19:51 INFO - PROCESS | 1686 | Values: 142.0 147.0 92.0 91.0 97.0 91.0 90.0 91.0 89.0 90.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:65.00 stddev:9.95 (15.3%) stddev-sans-first:10.54 06:19:51 INFO - PROCESS | 1686 | Values: 67.0 91.0 63.0 64.0 65.0 65.0 64.0 81.0 62.0 59.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:76.50 stddev:16.91 (22.1%) stddev-sans-first:16.82 06:19:51 INFO - PROCESS | 1686 | Values: 63.0 65.0 63.0 77.0 61.0 96.0 106.0 94.0 96.0 76.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:78.00 stddev:13.92 (17.8%) stddev-sans-first:14.18 06:19:51 INFO - PROCESS | 1686 | Values: 79.0 80.0 82.0 70.0 94.0 108.0 105.0 105.0 77.0 100.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:149.50 stddev:15.93 (10.7%) stddev-sans-first:14.20 06:19:51 INFO - PROCESS | 1686 | Values: 118.0 161.0 154.0 131.0 141.0 151.0 128.0 128.0 148.0 166.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:170.00 stddev:28.47 (16.7%) stddev-sans-first:29.97 06:19:51 INFO - PROCESS | 1686 | Values: 166.0 174.0 163.0 139.0 158.0 217.0 220.0 208.0 159.0 156.0 06:19:51 INFO - PROCESS | 1686 | 06:19:51 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:70.00 stddev:13.79 (19.7%) stddev-sans-first:14.20 06:19:51 INFO - PROCESS | 1686 | Values: 65.0 66.0 70.0 66.0 70.0 64.0 70.0 73.0 101.0 99.0 06:19:51 INFO - PROCESS | 1686 | -------- Summary: end -------- 06:19:51 INFO - PROCESS | 1686 | 06:19:52 INFO - TEST-INFO | 1686: exit 0 06:19:52 INFO - TEST-OK | kraken | took 58991ms 06:19:52 INFO - SUITE-END | took 1168s 06:19:52 INFO - Completed test suite (00:19:29) 06:19:52 INFO - javascript benchmark 06:19:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.359159963808, 3672.761270517416, 3729.720270979677, 3731.888043358699, 3746.306933217353, 6054.273487181539, 6082.817515474532, 6107.175531303278, 6126.326104128542, 6137.81586552403, 6106.847328816771, 6121.112438696387, 6128.417800308964, 6153.0155175171985, 6153.4154255345675, 5442.292501974738, 5518.558515318785, 5568.9443105569035, 5578.075563930458, 5614.157876318564, 5442.727863606824, 5528.944710552903, 5544.584156188282, 5577.9163312550145, 5628.0, 5514.338279406476, 5519.006578815812, 5567.4432556744405, 5590.413006634306, 5601.271834661488, 5506.504414602685, 5526.419725928759, 5552.86117847053, 5559.555235581144, 5575.609707320489, 5308.097623404031, 5368.892622147574, 5463.125899856043, 5525.392206857243, 5530.972345138289, 5910.143029260733, 6004.12940123684, 6005.129256257818, 6049.395060493959, 6080.2399700037495, 6407.327230640809, 6413.615183089029, 6449.871002579969, 6475.417212450902, 6476.805695829133, 6350.26971898233, 6453.806385808433, 6503.512236582275, 6506.284308726036, 6507.93492065078, 6129.664444256052, 6219.968900155515, 6221.06683997404, 6230.87538249237, 6257.7184026718905, 6609.6364699941005, 6645.269020407752, 6659.966700166468, 6673.799786006428, 6689.732410703615, 6318.368163183737, 6322.841928951813, 6387.329330420332, 6391.2330520337855, 6447.451966582893, 6524.6737663116655, 6562.146920900348, 6575.210974682973, 6581.835454113685, 6584.539082264196, 6306.89629314868, 6448.451881590117, 6458.063580780855, 6459.741610335628, 6482.935170648233, 5215.634905556651, 6399.5840270383205, 6438.710258038306, 6454.290028096905, 6456.580322279036, 5121.436641969381, 5203.869903252374, 5218.947810521922, 5230.816921407809, 5231.555317797954, 3670.853165873335, 4114.917701645951, 4683.8360657376825, 4726.456457507431, 4734.857954261378, 5080.263361812516, 6050.273967123885, 6172.537059720449, 6241.656708881055, 6244.2819075805955, 5350.0, 6481.09264702942, 6497.772577959749, 6515.73937042513, 6524.6737663116655, 6122.2761798032625, 6146.661933593696, 6161.4146656067605, 6187.16473276111, 6219.782307619212, 4817.0, 5676.7161641918565, 5702.600817942704, 5730.140478928194, 5746.798862039808, 6714.899276510856, 6757.155355580552, 6767.560108592926, 6775.356341147583, 6801.149856267966, 6415.262244841903, 6444.871102578016, 6464.482841372773, 6560.671966401756, 6595.175603049619, 6701.66491675424, 6709.865802684017, 6714.563553369016, 6721.126253587003, 6738.35985581369, 6420.1974753155855, 6439.098526206241, 6453.612783232833, 6486.643234622141, 6496.675166241764, 6352.110704501466, 6470.773522926675, 6514.6416947068365, 6543.312952140147, 6595.241547221939, 6228.875422491616, 6278.843028924313, 6363.427291543783, 6375.458086062737, 6388.265349484869, 6364.93635063662, 6369.554131210771, 6378.1070650108095, 6380.138681277968, 6450.838729031811, 6110.78612248587, 6114.540688159484, 6147.72335244924, 6235.532335074797, 6244.937550624436, 6331.366863313816, 6385.169927909342, 6388.840278993062, 6403.423691867754, 6464.191976003, 6175.382461753788, 6211.534134939807, 6231.158793562811, 6242.219722534684, 6281.434670879643], "value": 5982.125155815102, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.2203002715955, 560.9270794796676, 570.1647087017518, 581.9592628516004, 650.5056157320435, 33987.66012339876, 34158.82920585394, 40923.18153636925, 42137.78931105348, 42227.788861055655, 40464.595354046454, 41164.58835411645, 41212.79393603028, 41243.58756412435, 41275.0, 9845.26160537959, 9954.502274886263, 9999.650012249573, 10004.0, 10046.799064018714, 9928.255380846429, 9996.250281228937, 10056.19550435967, 10058.34620749649, 10064.597416103383, 9943.154831839302, 10000.149987251092, 10022.0, 10145.746356341077, 10187.286889917708, 10039.347442416256, 10120.797584048352, 10163.79672406555, 10182.59269629214, 10187.898121018769, 8629.784255393603, 10024.097831195155, 10130.89869101307, 10133.442660653662, 10137.138343240833, 43533.91165220863, 43861.12277754427, 43886.34170487445, 43952.0, 44241.77879110616, 3421.8802341917913, 3424.6404127566743, 3450.292689998544, 3546.8758593449365, 3945.28984782739, 28825.855870720723, 28884.133475995754, 28890.566641500394, 29356.706432935825, 29500.852495737385, 2779.374640705845, 2840.3751174741524, 2869.2253091665343, 2922.6346706661666, 2927.1072322941495, 395875.0, 413865.0, 436269.0, 441576.0, 442047.0, 67582.0, 67694.0, 67870.66064669644, 69260.0, 69333.65333173302, 64406.0, 71630.64184679148, 72933.63533182406, 76396.0, 77280.61359693277, 18627.534311642048, 19038.85766853993, 19171.712424313646, 19257.32599359016, 19279.710804337945, 14224.14655120696, 14567.708645827237, 14606.926965365104, 14607.78088328676, 14701.41194352214, 20825.37523874286, 21042.842643654745, 21050.684239736416, 21116.47208819792, 21222.151113955268, 7029.0, 8305.916940830635, 8437.620307086196, 8459.70391036323, 8542.658293668184, 974.5614473486959, 1006.1447769396101, 1008.9445080520495, 1033.3902997231519, 1118.9496472658586, 29526.0, 30050.849745751133, 30063.398732025238, 30777.692223077484, 31126.377472450426, 877.863931090682, 1119.1214896306524, 1131.1459847814847, 1221.584661215191, 1227.8403807504967, 11153.609623663133, 14467.0, 14801.851981480047, 15818.288177032062, 15820.525384238492, 650280.0, 658609.0, 665130.0, 668190.0, 668710.0, 67619.32380676229, 67651.0, 68302.3169768306, 68440.97338539826, 68468.0, 1546.242341252778, 1555.2845690982274, 1558.6415124521197, 1570.6937147256176, 1572.8977616454895, 31098.533521996735, 31233.0, 31272.530912036793, 31625.683743162273, 31971.880984165444, 57538.7123064382, 64657.35342646513, 64925.0, 65715.0, 65880.34119658743, 588156.0, 589942.0, 599042.0, 599773.0, 600465.0, 38683.41974870323, 46379.07241855212, 46740.0, 46926.0, 47027.0, 47104.7644761774, 48022.75988620034, 48568.75715621399, 48977.77555561139, 49138.75430622824, 32790.672093278765, 38210.80894595509, 38233.617663823, 39579.60420395759, 39584.40623390709, 37842.81078594589, 38468.23063538769, 38784.6121538781, 38803.80598097105, 39520.0, 38876.0, 39724.411023558736, 39775.0, 40331.38674452989, 40821.591784082964, 35250.0, 36129.09677257985, 36239.45640815337, 36480.270394591425, 36509.63490365062, 23158.0, 26575.60136597914, 26619.0, 27023.594646080717, 27162.0], "value": 21255.29613790457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.3933296719757, 420.77067997941117, 424.4482173174872, 428.5585846578025, 434.78260869565196, 21134.894325528352, 21170.470738231554, 21310.0, 21409.715417074985, 22195.44511387216, 20564.971751412406, 20776.68834967473, 20916.163353465843, 21027.053782579784, 21242.25652102177, 6092.268927728679, 6093.299270583873, 6303.3066362700065, 6363.07735378371, 6378.425941665249, 4593.563611456906, 6237.594556353823, 6295.968520157393, 6716.0, 7014.929850701505, 6960.860782784342, 6970.7560235391775, 6986.301369862998, 6988.685509152075, 6990.126234220723, 5728.054870946289, 6812.693428795691, 6984.0, 6991.405730512913, 7035.577865328097, 5694.886102277973, 5947.851303717398, 5962.433568810956, 6137.846553836145, 6151.507879369639, 14047.859521404758, 14049.78925316121, 14123.152610843383, 14125.929370353186, 14374.425022999067, 2192.4190089626263, 2270.262164796448, 2506.8969653352465, 2618.4681961052806, 2863.828170309788, 9641.180499657537, 9722.513874306327, 10052.045055719695, 10074.546645400975, 10117.08946194846, 1819.3177558415593, 1836.2654938024764, 1837.1549087419803, 1869.2896699254331, 1877.4461533847411, 114810.42594787056, 127400.0, 127795.0, 130192.34903825421, 130757.34621326928, 11844.230125041844, 11962.0, 12121.333326666942, 12129.15095943293, 12241.69395765113, 15247.085174889542, 15277.854160937752, 15281.388744450098, 15300.770488442682, 15307.005044672062, 9260.490673012915, 11639.301641901513, 11686.76626467466, 11809.763804723858, 12201.633950981484, 9947.303688741864, 9981.80036399268, 10006.44964526958, 10149.695509134737, 10444.791104177873, 7407.370373518203, 9264.629414823332, 9755.560999755027, 10401.895981040092, 10445.68662940113, 4017.3773065174946, 4073.0856497446152, 4168.812403441852, 4213.894652633709, 4215.304474761691, 968.9127978481971, 969.486172328667, 1008.3848852200152, 1039.4698703661188, 1048.109107258824, 20366.490837729176, 20442.773433594033, 20884.89557552233, 21420.03609837561, 21426.464338391666, 989.1839232633107, 1008.606643409071, 1028.1671845804922, 1032.2825636182931, 1036.891126431729, 11063.059639930536, 11513.539458421737, 11549.01833344175, 11580.6525804226, 11619.535218591162, 669068.0, 673384.0, 679610.0, 697483.0, 702603.4869825618, 118417.81582184068, 132522.0, 133666.0, 135126.0, 139922.30038849742, 1074.99462502687, 1086.7446150154708, 1092.1808643517204, 1094.7974624694457, 1099.9770213701174, 18258.36095736696, 18959.715604266225, 18960.146793394055, 18986.810131898503, 19267.421977340702, 51016.97966040584, 52719.472805271456, 52859.0, 53172.46827531675, 53763.462365375846, 63120.36879631145, 75539.0, 77261.0, 78051.2194878044, 79039.60480197563, 8403.747887563382, 8465.322774178174, 8627.43921645091, 8660.17728315809, 8858.557072146496, 11978.495268359562, 12587.307698076695, 12739.363031848185, 12767.74464510686, 12848.743025139258, 9998.100170984646, 10629.25595208328, 10745.892541074489, 11450.198486105892, 11588.47851846652, 9167.541622918961, 10746.143351873092, 11114.22200445961, 11337.546498140311, 11423.485943132413, 11196.608118715805, 11362.886371136183, 11494.02300804441, 11592.478338474957, 11693.298402095901, 8318.83362332762, 8373.120822313569, 8581.914180858112, 8613.956930215309, 8771.517566533901, 10980.560777568808, 11134.777304453704, 11278.661640150809, 11306.94346528262, 11497.885021149681], "value": 10352.873434624897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.4783834304621, 1479.7791821747055, 1663.376233912283, 1976.5571133072856, 1989.303743689708, 8436.873446898286, 9118.772030699236, 9267.795186625737, 9412.717618471439, 9472.85790713139, 9122.54387280635, 9124.863127053082, 9133.543322833864, 9244.8613270801, 9250.982391936881, 2556.8849401776965, 2559.09152250951, 2563.2566555698827, 2568.9100881469153, 2581.174024312221, 2517.6223566465087, 2577.690677118748, 2583.4962182374443, 2592.4296654735926, 2593.753593408623, 2553.74462553745, 2571.897124115033, 2605.3747100695887, 2608.1523504860997, 2609.9217023489327, 2559.0787316566116, 2588.339973306804, 2599.480103979212, 2600.2069368842494, 2600.986995065022, 2536.4800215955684, 2576.124117799938, 2585.7026441959038, 2592.8185027048116, 2601.2196341097783, 9181.760462337566, 9211.032841551607, 9306.627734890664, 9326.347155699104, 9332.953335233347, 5748.8850222995725, 5767.394423585547, 5837.0, 5853.707314634251, 5943.881122377528, 3705.555333360014, 3804.029972357031, 3841.117852252404, 3846.6728978502374, 3882.5340959085092, 1050.1913526584563, 1072.8068947589431, 1073.3881687438163, 1100.5157730598512, 1112.4215407987801, 12371.13402061856, 12498.437570309266, 12509.749805003848, 12513.124081314312, 12625.368731563476, 5034.546890779848, 5134.409542902539, 5172.637915345965, 5176.886969301543, 5278.736063196825, 10723.0, 10739.785204295871, 10757.193210509244, 10770.838437423445, 10796.244262901679, 5338.46615338463, 5450.31871016123, 5624.831255062273, 5685.772569097273, 5715.171300161536, 4666.183417901853, 5122.410922743858, 5139.306193663808, 5264.868378290574, 5295.179247216687, 4254.617084462413, 4285.3786201000685, 4317.309230523164, 4356.782160891943, 4397.626201772821, 1221.8350522679502, 1390.4021270853427, 1392.3943084758162, 1412.9081609695336, 1424.7008128292966, 778.8559116563454, 891.1730951549428, 925.879635647375, 970.6069042037985, 988.9159421449114, 5295.0, 6124.35694252106, 6138.754449821957, 6189.442950134419, 6295.559310848196, 983.8130755156498, 999.5402115027169, 1014.6803756816579, 1028.4857571214393, 1029.6859457865273, 4066.898327541835, 4081.918361632751, 4103.343465045578, 4133.586641335903, 4135.276326642826, 11210.66368008961, 11495.42522873853, 11531.538738450368, 11696.0, 11703.063754899587, 10570.577176912991, 10574.52414641343, 11100.722481937855, 11480.425978701032, 11516.654500365003, 7347.742829000959, 8674.653013879373, 8786.912130878609, 8967.372283940062, 9018.819623607491, 5178.559822414986, 5260.842174734764, 5401.567874570103, 6081.939180608138, 6279.058141278662, 9109.908900910907, 9752.268579856398, 9944.204463642747, 10185.694429167137, 10368.740781480523, 10295.794084118126, 10382.844257335995, 10675.039246467855, 10747.193960453154, 10793.784124317313, 5521.779128834802, 5889.352171261072, 6754.2570317266045, 7471.925280747123, 7509.061367329084, 8562.871556926775, 8606.78483037904, 8641.82716345657, 8679.739607811776, 8715.52064636451, 7526.24737526243, 7685.0, 7701.653425595974, 7709.2290770922455, 7749.186335434923, 7592.924070759222, 7694.653740581799, 7719.498232615087, 7804.0635123785505, 7861.606919654108, 6119.204503414528, 7277.053982982178, 7420.591867447126, 7423.109226892808, 7430.219826918095, 4976.825811096594, 4995.300657907823, 5109.6678715883345, 5148.9742551287, 5168.896622067613, 5147.613928955268, 5161.354830646169, 5187.014467251211, 5189.532942035235, 5258.842234732964], "value": 4821.473847033643, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.24464164026244, 339.5704433891127, 340.6083004544775, 345.76833521540567, 350.2766786585699, 6861.794146175623, 6929.025227810075, 7020.929790702098, 7044.577325360482, 7057.047298614685, 5879.971005074106, 6770.458363330934, 6993.44052475802, 7017.68420421081, 7078.009078728982, 5170.663906846043, 5198.584113270948, 5211.270422140903, 5214.139666954948, 5234.267202591621, 5202.92195617066, 5209.947900521003, 5222.399424066224, 5223.895522089556, 5231.633785635007, 5133.486651334874, 5136.922946155811, 5152.433232344439, 5170.689758614476, 5174.586033117342, 3990.501187351581, 4943.530364615356, 5049.065922804293, 5138.332016837825, 5149.2791009258735, 4235.237657221699, 4251.532331443539, 4261.211675839949, 4264.87205383839, 4268.786560671954, 6578.605283682947, 6680.231773346079, 6680.83297917551, 6682.298358672367, 6703.296153903867, 4365.3451982202605, 5988.544696674386, 6002.189704389895, 6058.4244496772735, 6059.939400605982, 7422.814429639248, 7543.962280188619, 7548.396128309722, 7548.886766698504, 7554.8866766998535, 4641.443026836801, 4786.21027530457, 4787.4494433139935, 4805.663603547753, 4809.278608208761, 6308.905366419507, 7974.202579741979, 8005.919940800517, 8015.278624923785, 8015.799605009863, 4893.975530122327, 4914.287428322873, 4918.827841025547, 4923.48303428142, 4942.233953737176, 5011.173156429221, 5027.095122877844, 5035.269885866529, 5035.5468007878735, 5040.596752259884, 6022.969885150546, 6051.183090282843, 6055.818325450244, 6063.211782468251, 6071.787487437919, 7410.925890741024, 7609.619519024137, 7611.1627720950655, 7642.0, 7655.540667559964, 1451.7877572227308, 1716.6652502762092, 1724.3964612385564, 1739.4086010756403, 1739.9043052632226, 1018.2464975917916, 1055.2454994678687, 1059.1209296284067, 1076.9946150269354, 1097.5116073347283, 7051.365377116084, 7158.856822863513, 7176.569405835667, 7180.461465390116, 7182.533135346186, 7844.294013538809, 7846.3330616897065, 7851.528908265523, 7855.332296754726, 7872.763817085496, 7119.252478489788, 7125.42996560274, 7130.25132361105, 7152.642367881585, 7181.8922716159295, 3632.8546858125906, 3754.817232571731, 4119.958800412018, 4153.750774953452, 4166.58334166587, 6522.771702990372, 8125.634346454423, 8200.01599808027, 8201.056878458934, 8228.382871284677, 6510.7721229756735, 6854.931450685529, 8118.10700822909, 8127.715529956423, 8128.268455839003, 8168.305694016075, 8171.387145964077, 8177.550234737147, 8185.549794761345, 8205.538338498427, 6258.43674069336, 6827.634473105498, 7834.0, 7869.960650196713, 7878.960605196938, 7686.192949740197, 7694.576798275924, 7710.190430004769, 7719.0, 7720.189380115232, 8217.876731849146, 8218.547979861165, 8220.260176584135, 8250.422470427013, 8339.583020848811, 4944.381952255968, 4954.752262386794, 4956.0, 4956.77694503741, 4975.651704380659, 7563.584002879895, 7872.763817085496, 7877.2122787723065, 7967.32277756374, 7974.92025079765, 6178.042403427476, 6945.888657814726, 7582.54504729718, 7645.464817462725, 7657.846843062996, 7110.680019399036, 7666.0, 7672.347850432776, 7679.769606911802, 7686.807829804299, 7187.425005999612, 7415.740449084472, 7419.258074192538, 7472.962635186789, 7514.098308203051, 7287.453440991841, 7642.579658118634, 7647.158812530506, 7652.885206721792, 7671.8849217262905, 7296.59868707205, 7441.4046876248685, 7476.887846682195, 7494.100707915085, 7519.436042296959], "value": 5445.515066177679, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.6396855619354, 710.7299226294004, 784.5763287824575, 789.0610173893067, 790.9683612655494, 13115.54095606654, 15465.536033918987, 15680.607984800386, 15689.372425102982, 15762.842371576295, 14386.424543018267, 14501.129932204049, 14531.128132312042, 14532.564023079298, 14544.490942817003, 748.2554857916354, 943.2312665177875, 956.086936975187, 961.2502248246379, 962.2927148545848, 817.4849844597895, 902.8645703144515, 902.8916530016375, 943.2642538819697, 943.4669411782355, 938.8122375524888, 964.3876138651937, 993.0, 995.4027583449908, 1005.8239808033564, 943.9150476457118, 983.616389608057, 994.4480813148699, 1000.1148983149892, 1007.6070332570304, 708.890122031086, 709.1383968478272, 742.4728442805615, 748.4611080022376, 763.2977660552306, 15253.237338133049, 15264.46574369903, 15280.465183718516, 15550.533483995385, 15578.909476336661, 13368.197908125543, 13371.33143342829, 13389.196648201041, 13495.86504134956, 13526.864731352658, 10336.5348559315, 10374.325668831501, 10388.046631273615, 10437.165026797838, 10717.142628589694, 3546.5744110706623, 3582.531311144874, 3602.35157671619, 3655.3603119454256, 3710.2023065041144, 85725.0, 86238.56880715556, 86426.56786716089, 86462.0, 88915.0, 10079.69760907174, 10170.389776613427, 10251.794964100825, 10353.223508236912, 10357.896421035844, 22928.656070158962, 23316.950737216866, 23324.883375583013, 23333.18333858307, 23922.7607723924, 7991.120976692559, 7993.0, 8045.436819422584, 8077.0, 8122.959385203154, 4000.4199391088127, 4083.934792808458, 4090.8568200112854, 4124.463819703479, 4124.52567954689, 3881.184951160288, 3902.751119641744, 3967.7104940894324, 3969.226000929791, 4065.6137666921595, 722.241646271413, 724.3263764698776, 728.8360118973188, 739.2533541123504, 742.2651574940878, 573.7676241122352, 598.7874304621884, 601.5969300568629, 629.3140476880222, 633.6134957675814, 8063.27430531255, 8245.95877020623, 8441.366897482714, 8577.528235946958, 8582.957085214659, 455.6628095209521, 611.8715069835296, 617.2839506172818, 620.4787978098419, 635.5138319185827, 3889.202713443737, 3914.373700207953, 3939.1333906540517, 3978.5623581406603, 4098.7130900836655, 100712.0, 101625.49187254017, 102504.0, 103298.48350758199, 103699.0, 71978.84063478104, 83802.90492737609, 86871.0, 87222.56388718016, 89379.0, 68145.0, 76342.61828690821, 76680.0, 77054.84417733851, 77698.6115069421, 7730.226977302224, 7871.803204919923, 7897.0, 7994.2805147537, 8018.191115943605, 42184.57815421929, 53059.93880122299, 53330.4666953341, 53538.0, 53980.73009634927, 66959.8680593725, 74659.0, 74666.62666686632, 74751.0, 75155.62422187853, 10148.746281343025, 10435.634752783617, 10469.172559235827, 10717.839232411676, 10823.296485728402, 11094.334339939629, 11097.38964356968, 11279.35461017679, 11295.717607059889, 11306.265092768943, 10616.0, 10639.361638301727, 10720.0, 10884.129269658566, 11025.17311201647, 10313.587456501657, 10525.421101839165, 10572.20708446885, 10715.464226788474, 10839.891601083888, 10071.496425178859, 10186.592536298465, 10548.683539493828, 10875.238733288594, 11122.943320384551, 8011.359091272568, 9064.728058158265, 9171.35800493959, 9427.15155635996, 9531.428114313163, 8932.910670893469, 9124.77188070277, 9173.35786494939, 9188.173064424234, 9199.954000230224], "value": 7081.581921367356, "unit": "score"}], "name": "dromaeo_css", "value": 7909.120899745654, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 117.0, 114.0, 117.0, 131.0, 115.0, 115.0, 117.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 144.0, 180.0, 196.0, 123.0, 142.0, 120.0, 158.0, 153.0, 142.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 197.0, 201.0, 199.0, 197.0, 203.0, 200.0, 199.0, 199.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 78.0, 143.0, 55.0, 56.0, 61.0, 66.0, 122.0, 58.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 116.0, 78.0, 122.0, 126.0, 169.0, 117.0, 79.0, 123.0], "value": 109.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 87.0, 88.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 103.0, 138.0, 94.0, 115.0, 120.0, 94.0, 94.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 92.0, 91.0, 97.0, 91.0, 90.0, 91.0, 89.0, 90.0], "value": 102.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 91.0, 63.0, 64.0, 65.0, 65.0, 64.0, 81.0, 62.0, 59.0], "value": 68.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 77.0, 61.0, 96.0, 106.0, 94.0, 96.0, 76.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 82.0, 70.0, 94.0, 108.0, 105.0, 105.0, 77.0, 100.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 161.0, 154.0, 131.0, 141.0, 151.0, 128.0, 128.0, 148.0, 166.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 174.0, 163.0, 139.0, 158.0, 217.0, 220.0, 208.0, 159.0, 156.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 70.0, 66.0, 70.0, 64.0, 70.0, 73.0, 101.0, 99.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1578.5, "alertThreshold": 2.0}]} 06:19:52 INFO - Return code: 0 06:19:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:19:52 ERROR - # TBPL SUCCESS # 06:19:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:52 INFO - 2016-06-27 06:19:52.876 screenresolution[1691:10471] starting screenresolution argv=screenresolution get 06:19:52 INFO - 2016-06-27 06:19:52.879 screenresolution[1691:10471] Display 0: 1600x1200x32@60 06:19:52 INFO - 2016-06-27 06:19:52.885 screenresolution[1692:10474] starting screenresolution argv=screenresolution list 06:19:52 INFO - Available Modes on Display 0 06:19:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:53 INFO - Intel Iris: 06:19:53 INFO - Chipset Model: Intel Iris 06:19:53 INFO - Type: GPU 06:19:53 INFO - Bus: Built-In 06:19:53 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:53 INFO - Vendor: Intel (0x8086) 06:19:53 INFO - Device ID: 0x0a2e 06:19:53 INFO - Revision ID: 0x0009 06:19:53 INFO - Displays: 06:19:53 INFO - Display: 06:19:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:53 INFO - Display Serial Number: 1600x1200 60 06:19:53 INFO - Main Display: Yes 06:19:53 INFO - Mirror: Off 06:19:53 INFO - Online: Yes 06:19:53 INFO - Rotation: Supported 06:19:53 INFO - Return code: 0 06:19:53 INFO - Running post-action listener: _resource_record_post_action 06:19:53 INFO - Running post-run listener: _resource_record_post_run 06:19:54 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 32505344; Write bytes: 495202304; Read time: 320; Write time: 1537 06:19:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194785792; Write bytes: 194490368; Read time: 17634; Write time: 421 06:19:54 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 30707200; Write bytes: 299704320; Read time: 300; Write time: 1113 06:19:54 INFO - Running post-run listener: _upload_blobber_files 06:19:54 INFO - Blob upload gear active. 06:19:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:19:54 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 06:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:19:54 INFO - (blobuploader) - INFO - Open directory for files ... 06:19:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:19:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:19:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:55 INFO - (blobuploader) - INFO - Done attempting. 06:19:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:19:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:19:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:19:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:19:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:19:56 INFO - (blobuploader) - INFO - Done attempting. 06:19:56 INFO - (blobuploader) - INFO - Iteration through files over. 06:19:56 INFO - Return code: 0 06:19:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:19:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:19:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed96c262ca60e4bf7dc7cf536803d08f75f177a60e3ec73d78ae405add190039c3240b7fd0b32125c33d9d4b15c977648a8be156c64f3d4ef7633e0b418e5e32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ae30601ed1f396fcc4465c4b37d630205081aa5cb02b06548cadd5f7a8d65d20bc3e42c6f56b6c877a45a13d91a4db7c0e7dfd921a13c4b8b428a769b7ceb90"} 06:19:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:19:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:19:56 INFO - Contents: 06:19:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed96c262ca60e4bf7dc7cf536803d08f75f177a60e3ec73d78ae405add190039c3240b7fd0b32125c33d9d4b15c977648a8be156c64f3d4ef7633e0b418e5e32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ae30601ed1f396fcc4465c4b37d630205081aa5cb02b06548cadd5f7a8d65d20bc3e42c6f56b6c877a45a13d91a4db7c0e7dfd921a13c4b8b428a769b7ceb90"} 06:19:56 INFO - Running post-run listener: copy_logs_to_upload_dir 06:19:56 INFO - Copying logs to upload dir... 06:19:56 INFO - mkdir: /builds/slave/test/build/upload/logs 06:19:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1325.651436 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-06-27 06:19:56.241255) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 06:19:56.245087) ========= 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.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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-Beta/sha512/ed96c262ca60e4bf7dc7cf536803d08f75f177a60e3ec73d78ae405add190039c3240b7fd0b32125c33d9d4b15c977648a8be156c64f3d4ef7633e0b418e5e32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ae30601ed1f396fcc4465c4b37d630205081aa5cb02b06548cadd5f7a8d65d20bc3e42c6f56b6c877a45a13d91a4db7c0e7dfd921a13c4b8b428a769b7ceb90"} build_url:https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010910 build_url: 'https://queue.taskcluster.net/v1/task/BtGTOhxQTu-sRUUi3HVwTA/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ed96c262ca60e4bf7dc7cf536803d08f75f177a60e3ec73d78ae405add190039c3240b7fd0b32125c33d9d4b15c977648a8be156c64f3d4ef7633e0b418e5e32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ae30601ed1f396fcc4465c4b37d630205081aa5cb02b06548cadd5f7a8d65d20bc3e42c6f56b6c877a45a13d91a4db7c0e7dfd921a13c4b8b428a769b7ceb90"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 06:19:56.277055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 06:19:56.277348) ========= 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.AXuHdROCWT/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.HomwE6Q9GB/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 06:19:56.325180) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 06:19:56.325465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 06:19:56.325757) ========= ========= Total master_lag: 0.17 =========