builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0141 starttime: 1461149910.06 results: success (0) buildid: 20160420015138 builduid: 183ae379932d45e48385b8cf76c476a6 revision: efa0fb87773ed6e6850dcaf794d53651429decca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.063214) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.063824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.064189) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.086210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.086614) ========= 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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-20 03:58:30-- 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% 47.1M=0s 2016-04-20 03:58:30 (47.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.338223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.442441) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.442787) ========= 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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.027597 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:30.491922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 03:58:30.492350) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efa0fb87773ed6e6850dcaf794d53651429decca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efa0fb87773ed6e6850dcaf794d53651429decca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-20 03:58:30,566 truncating revision to first 12 chars 2016-04-20 03:58:30,566 Setting DEBUG logging. 2016-04-20 03:58:30,566 attempt 1/10 2016-04-20 03:58:30,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/efa0fb87773e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 03:58:31,459 unpacking tar archive at: mozilla-aurora-efa0fb87773e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.139713 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 03:58:31.648968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:31.649310) ========= script_repo_revision: efa0fb87773ed6e6850dcaf794d53651429decca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:31.649771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:31.650039) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 03:58:31.663496) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-20 03:58:31.663826) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:58:31 INFO - MultiFileLogger online at 20160420 03:58:31 in /builds/slave/test 03:58:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:58:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:58:31 INFO - {'append_to_log': False, 03:58:31 INFO - 'base_work_dir': '/builds/slave/test', 03:58:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:58:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:58:31 INFO - 'branch': 'Mozilla-Aurora', 03:58:31 INFO - 'buildbot_json_path': 'buildprops.json', 03:58:31 INFO - 'config_files': ('talos/mac_config.py',), 03:58:31 INFO - 'default_actions': ('clobber', 03:58:31 INFO - 'read-buildbot-config', 03:58:31 INFO - 'download-and-extract', 03:58:31 INFO - 'populate-webroot', 03:58:31 INFO - 'create-virtualenv', 03:58:31 INFO - 'install', 03:58:31 INFO - 'run-tests'), 03:58:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:58:31 INFO - 'download_minidump_stackwalk': True, 03:58:31 INFO - 'download_symbols': 'ondemand', 03:58:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:58:31 INFO - 'tooltool.py': '/tools/tooltool.py', 03:58:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:58:31 INFO - '/tools/misc-python/virtualenv.py')}, 03:58:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:58:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:58:31 INFO - 'installer_path': 'installer.exe', 03:58:31 INFO - 'log_level': 'info', 03:58:31 INFO - 'log_name': 'talos', 03:58:31 INFO - 'log_to_console': True, 03:58:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:58:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:58:31 INFO - 'opt_config_files': (), 03:58:31 INFO - 'pip_index': False, 03:58:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:31 INFO - 'cmd': ('bash', 03:58:31 INFO - '-c', 03:58:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:31 INFO - 'enabled': True, 03:58:31 INFO - 'halt_on_failure': False, 03:58:31 INFO - 'name': 'check_screen_resolution'},), 03:58:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:31 INFO - 'cmd': ('bash', 03:58:31 INFO - '-c', 03:58:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:31 INFO - 'enabled': True, 03:58:31 INFO - 'halt_on_failure': False, 03:58:31 INFO - 'name': 'check_screen_resolution'},), 03:58:31 INFO - 'run_cmd_checks_enabled': True, 03:58:31 INFO - 'sps_profile': False, 03:58:31 INFO - 'sps_profile_interval': 0, 03:58:31 INFO - 'suite': 'dromaeojs', 03:58:31 INFO - 'system_bits': '32', 03:58:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:58:31 INFO - 'title': 't-yosemite-r7-0141', 03:58:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:58:31 INFO - 'use_talos_json': True, 03:58:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:58:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:58:31 INFO - 'work_dir': 'build'} 03:58:31 INFO - ##### 03:58:31 INFO - ##### Running clobber step. 03:58:31 INFO - ##### 03:58:31 INFO - Running pre-action listener: _resource_record_pre_action 03:58:31 INFO - Running main action method: clobber 03:58:31 INFO - rmtree: /builds/slave/test/build 03:58:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:58:33 INFO - Running post-action listener: _resource_record_post_action 03:58:33 INFO - ##### 03:58:33 INFO - ##### Running read-buildbot-config step. 03:58:33 INFO - ##### 03:58:33 INFO - Running pre-action listener: _resource_record_pre_action 03:58:33 INFO - Running main action method: read_buildbot_config 03:58:33 INFO - Using buildbot properties: 03:58:33 INFO - { 03:58:33 INFO - "project": "", 03:58:33 INFO - "product": "firefox", 03:58:33 INFO - "script_repo_revision": "production", 03:58:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:58:33 INFO - "repository": "", 03:58:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:58:33 INFO - "buildid": "20160420015138", 03:58:33 INFO - "pgo_build": "False", 03:58:33 INFO - "basedir": "/builds/slave/test", 03:58:33 INFO - "buildnumber": 296, 03:58:33 INFO - "slavename": "t-yosemite-r7-0141", 03:58:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:58:33 INFO - "platform": "yosemite_r7", 03:58:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:58:33 INFO - "branch": "mozilla-aurora", 03:58:33 INFO - "repo_path": "releases/mozilla-aurora", 03:58:33 INFO - "revision": "efa0fb87773ed6e6850dcaf794d53651429decca", 03:58:33 INFO - "stage_platform": "macosx64", 03:58:33 INFO - "builduid": "183ae379932d45e48385b8cf76c476a6", 03:58:33 INFO - "slavebuilddir": "test" 03:58:33 INFO - } 03:58:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:58:33 INFO - Running post-action listener: _resource_record_post_action 03:58:33 INFO - ##### 03:58:33 INFO - ##### Running download-and-extract step. 03:58:33 INFO - ##### 03:58:33 INFO - Running pre-action listener: _resource_record_pre_action 03:58:33 INFO - Running main action method: download_and_extract 03:58:33 INFO - mkdir: /builds/slave/test/build/tests 03:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:33 INFO - https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:58:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:58:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:58:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:58:34 INFO - Downloaded 1277 bytes. 03:58:34 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:58:34 INFO - Using the following test package requirements: 03:58:34 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:58:34 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:58:34 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:58:34 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'jsshell-mac.zip'], 03:58:34 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:58:34 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:58:34 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:58:34 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:58:34 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:58:34 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:58:34 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:58:34 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:58:34 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:34 INFO - https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:58:35 INFO - Downloaded 17911384 bytes. 03:58:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:58:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:58:38 INFO - Return code: 0 03:58:38 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:38 INFO - https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:58:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:58:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:58:40 INFO - Downloaded 17911384 bytes. 03:58:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:58:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:58:43 INFO - Return code: 0 03:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:43 INFO - https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:58:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:58:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:58:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:58:44 INFO - Downloaded 10845168 bytes. 03:58:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:58:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:58:44 INFO - Return code: 0 03:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:44 INFO - https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:58:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:58:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:58:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:58:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:58:49 INFO - Downloaded 115254627 bytes. 03:58:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:58:49 INFO - mkdir: /builds/slave/test/properties 03:58:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:58:49 INFO - Writing to file /builds/slave/test/properties/build_url 03:58:49 INFO - Contents: 03:58:49 INFO - build_url:https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:58:50 INFO - Running post-action listener: _resource_record_post_action 03:58:50 INFO - Running post-action listener: set_extra_try_arguments 03:58:50 INFO - ##### 03:58:50 INFO - ##### Running populate-webroot step. 03:58:50 INFO - ##### 03:58:50 INFO - Running pre-action listener: _resource_record_pre_action 03:58:50 INFO - Running main action method: populate_webroot 03:58:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:58:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:58:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:58:50 INFO - '--tppagecycles', 03:58:50 INFO - '7'], 03:58:50 INFO - 'tests': ['tsvgm']}}, 03:58:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:58:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:58:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:58:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:58:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:58:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:58:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'tests': ['damp', 'tps']}, 03:58:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['damp', 'tps']}, 03:58:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:58:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:58:50 INFO - 'other': {'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'other_l64': {'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'other_nol64': {'tests': ['a11yr', 03:58:50 INFO - 'ts_paint', 03:58:50 INFO - 'tpaint', 03:58:50 INFO - 'sessionrestore', 03:58:50 INFO - 'sessionrestore_no_auto_restore', 03:58:50 INFO - 'tabpaint']}, 03:58:50 INFO - 'svgr': {'tests': ['tsvgx', 03:58:50 INFO - 'tsvgr_opacity', 03:58:50 INFO - 'tart', 03:58:50 INFO - 'tscrollx', 03:58:50 INFO - 'cart']}, 03:58:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['tsvgx', 03:58:50 INFO - 'tsvgr_opacity', 03:58:50 INFO - 'tart', 03:58:50 INFO - 'tscrollx', 03:58:50 INFO - 'cart']}, 03:58:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'tests': ['tp5o']}, 03:58:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'talos_options': ['--e10s'], 03:58:50 INFO - 'tests': ['tp5o']}, 03:58:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'talos_options': ['--xperf_path', 03:58:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:58:50 INFO - 'tests': ['tp5n']}, 03:58:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:58:50 INFO - 'talos_options': ['--e10s', 03:58:50 INFO - '--xperf_path', 03:58:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:58:50 INFO - 'tests': ['tp5n']}}, 03:58:50 INFO - 'talos.zip': {'path': '', 03:58:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:58:50 INFO - Running post-action listener: _resource_record_post_action 03:58:50 INFO - ##### 03:58:50 INFO - ##### Running create-virtualenv step. 03:58:50 INFO - ##### 03:58:50 INFO - Running pre-action listener: _resource_record_pre_action 03:58:50 INFO - Running main action method: create_virtualenv 03:58:50 INFO - Creating virtualenv /builds/slave/test/build/venv 03:58:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:58:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:58:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:58:50 INFO - Using real prefix '/tools/python27' 03:58:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:58:51 INFO - Installing distribute.............................................................................................................................................................................................done. 03:58:54 INFO - Installing pip.................done. 03:58:54 INFO - Return code: 0 03:58:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:58:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:58:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:54 INFO - 'HOME': '/Users/cltbld', 03:58:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:54 INFO - 'LOGNAME': 'cltbld', 03:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:54 INFO - 'MOZ_NO_REMOTE': '1', 03:58:54 INFO - 'NO_EM_RESTART': '1', 03:58:54 INFO - 'PAGER': '/bin/cat', 03:58:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:54 INFO - 'PWD': '/builds/slave/test', 03:58:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:54 INFO - 'SHELL': '/bin/bash', 03:58:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:58:54 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:58:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:54 INFO - 'USER': 'cltbld', 03:58:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:54 INFO - 'XPC_FLAGS': '0x0', 03:58:54 INFO - 'XPC_SERVICE_NAME': '0', 03:58:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:58:54 INFO - Cleaning up... 03:58:54 INFO - Return code: 0 03:58:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:58:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:58:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:54 INFO - 'HOME': '/Users/cltbld', 03:58:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:54 INFO - 'LOGNAME': 'cltbld', 03:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:54 INFO - 'MOZ_NO_REMOTE': '1', 03:58:54 INFO - 'NO_EM_RESTART': '1', 03:58:54 INFO - 'PAGER': '/bin/cat', 03:58:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:54 INFO - 'PWD': '/builds/slave/test', 03:58:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:54 INFO - 'SHELL': '/bin/bash', 03:58:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:58:54 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:58:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:54 INFO - 'USER': 'cltbld', 03:58:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:54 INFO - 'XPC_FLAGS': '0x0', 03:58:54 INFO - 'XPC_SERVICE_NAME': '0', 03:58:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:54 INFO - Downloading/unpacking psutil>=0.7.1 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58: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 03:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:58:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:58:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:58:58 INFO - Installing collected packages: psutil 03:58:58 INFO - Running setup.py install for psutil 03:58:58 INFO - building 'psutil._psutil_osx' extension 03:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:58:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:58:59 INFO - building 'psutil._psutil_posix' extension 03:58:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:58:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:58:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:58:59 INFO - ^ 03:58:59 INFO - 1 warning generated. 03:58:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:58:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:58:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:58:59 INFO - Successfully installed psutil 03:58:59 INFO - Cleaning up... 03:58:59 INFO - Return code: 0 03:58:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:58:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:58:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:58:59 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:58:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:58:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:58:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:58:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:59 INFO - 'HOME': '/Users/cltbld', 03:58:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:59 INFO - 'LOGNAME': 'cltbld', 03:58:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:59 INFO - 'MOZ_NO_REMOTE': '1', 03:58:59 INFO - 'NO_EM_RESTART': '1', 03:58:59 INFO - 'PAGER': '/bin/cat', 03:58:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:59 INFO - 'PWD': '/builds/slave/test', 03:58:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:59 INFO - 'SHELL': '/bin/bash', 03:58:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:58:59 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:58:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:59 INFO - 'USER': 'cltbld', 03:58:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:59 INFO - 'XPC_FLAGS': '0x0', 03:58:59 INFO - 'XPC_SERVICE_NAME': '0', 03:58:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:59:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:59:02 INFO - Installing collected packages: mozsystemmonitor 03:59:02 INFO - Running setup.py install for mozsystemmonitor 03:59:02 INFO - Successfully installed mozsystemmonitor 03:59:02 INFO - Cleaning up... 03:59:02 INFO - Return code: 0 03:59:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:02 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:59:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:02 INFO - 'HOME': '/Users/cltbld', 03:59:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:02 INFO - 'LOGNAME': 'cltbld', 03:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:02 INFO - 'MOZ_NO_REMOTE': '1', 03:59:02 INFO - 'NO_EM_RESTART': '1', 03:59:02 INFO - 'PAGER': '/bin/cat', 03:59:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:02 INFO - 'PWD': '/builds/slave/test', 03:59:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:02 INFO - 'SHELL': '/bin/bash', 03:59:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:02 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:02 INFO - 'USER': 'cltbld', 03:59:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:02 INFO - 'XPC_FLAGS': '0x0', 03:59:02 INFO - 'XPC_SERVICE_NAME': '0', 03:59:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:02 INFO - Downloading/unpacking blobuploader==1.2.4 03:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:59:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:59:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:06 INFO - Downloading docopt-0.6.1.tar.gz 03:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:59:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:59:06 INFO - Running setup.py install for blobuploader 03:59:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:59:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:59:06 INFO - Running setup.py install for requests 03:59:07 INFO - Running setup.py install for docopt 03:59:07 INFO - Successfully installed blobuploader requests docopt 03:59:07 INFO - Cleaning up... 03:59:07 INFO - Return code: 0 03:59:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:07 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:07 INFO - 'HOME': '/Users/cltbld', 03:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:07 INFO - 'LOGNAME': 'cltbld', 03:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:07 INFO - 'MOZ_NO_REMOTE': '1', 03:59:07 INFO - 'NO_EM_RESTART': '1', 03:59:07 INFO - 'PAGER': '/bin/cat', 03:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:07 INFO - 'PWD': '/builds/slave/test', 03:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:07 INFO - 'SHELL': '/bin/bash', 03:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:07 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:07 INFO - 'USER': 'cltbld', 03:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:07 INFO - 'XPC_FLAGS': '0x0', 03:59:07 INFO - 'XPC_SERVICE_NAME': '0', 03:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:07 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-nPbiV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:07 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-wH4anK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:07 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-2YZC5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:07 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5pMQ46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:07 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-d3ErGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-WGSkhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7ld7uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-4JjNig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-t3ierb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-H45z3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-YUxcR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-YkQiNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-3Nu9Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:08 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-avn3H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:09 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-jfGHfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:09 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-QMpEk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:09 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ZW37Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:59:09 INFO - Running setup.py install for manifestparser 03:59:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:59:09 INFO - Running setup.py install for mozcrash 03:59:09 INFO - Running setup.py install for mozdebug 03:59:09 INFO - Running setup.py install for mozdevice 03:59:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:59:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:59:09 INFO - Running setup.py install for mozfile 03:59:10 INFO - Running setup.py install for mozhttpd 03:59:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Running setup.py install for mozinfo 03:59:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Running setup.py install for mozInstall 03:59:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Running setup.py install for mozleak 03:59:10 INFO - Running setup.py install for mozlog 03:59:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Running setup.py install for moznetwork 03:59:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:59:10 INFO - Running setup.py install for mozprocess 03:59:11 INFO - Running setup.py install for mozprofile 03:59:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:59:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:59:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:59:11 INFO - Running setup.py install for mozrunner 03:59:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:59:11 INFO - Running setup.py install for mozscreenshot 03:59:11 INFO - Running setup.py install for moztest 03:59:11 INFO - Running setup.py install for mozversion 03:59:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:59:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:59:11 INFO - Cleaning up... 03:59:11 INFO - Return code: 0 03:59:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:11 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:11 INFO - 'HOME': '/Users/cltbld', 03:59:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:11 INFO - 'LOGNAME': 'cltbld', 03:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:11 INFO - 'MOZ_NO_REMOTE': '1', 03:59:11 INFO - 'NO_EM_RESTART': '1', 03:59:11 INFO - 'PAGER': '/bin/cat', 03:59:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:11 INFO - 'PWD': '/builds/slave/test', 03:59:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:11 INFO - 'SHELL': '/bin/bash', 03:59:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:11 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:11 INFO - 'USER': 'cltbld', 03:59:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:11 INFO - 'XPC_FLAGS': '0x0', 03:59:11 INFO - 'XPC_SERVICE_NAME': '0', 03:59:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SDPV6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-pJvptc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-lKAyg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_DdBH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-qDP82b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Vti_k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-go1ZGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-KHheFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Rbr09b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-fi3i2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-axmDX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sw4Mm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-551G8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-cbZ0_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-L4uj57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-0ooaV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-H6jTDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:16 INFO - Downloading blessings-1.6.tar.gz 03:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:59:16 INFO - Installing collected packages: blessings 03:59:16 INFO - Running setup.py install for blessings 03:59:16 INFO - Successfully installed blessings 03:59:16 INFO - Cleaning up... 03:59:16 INFO - Return code: 0 03:59:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:59:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:17 INFO - Reading from file tmpfile_stdout 03:59:17 INFO - Current package versions: 03:59:17 INFO - blessings == 1.6 03:59:17 INFO - blobuploader == 1.2.4 03:59:17 INFO - docopt == 0.6.1 03:59:17 INFO - manifestparser == 1.1 03:59:17 INFO - mozInstall == 1.12 03:59:17 INFO - mozcrash == 0.17 03:59:17 INFO - mozdebug == 0.1 03:59:17 INFO - mozdevice == 0.48 03:59:17 INFO - mozfile == 1.2 03:59:17 INFO - mozhttpd == 0.7 03:59:17 INFO - mozinfo == 0.9 03:59:17 INFO - mozleak == 0.1 03:59:17 INFO - mozlog == 3.1 03:59:17 INFO - moznetwork == 0.27 03:59:17 INFO - mozprocess == 0.22 03:59:17 INFO - mozprofile == 0.28 03:59:17 INFO - mozrunner == 6.11 03:59:17 INFO - mozscreenshot == 0.1 03:59:17 INFO - mozsystemmonitor == 0.0 03:59:17 INFO - moztest == 0.7 03:59:17 INFO - mozversion == 1.4 03:59:17 INFO - psutil == 3.1.1 03:59:17 INFO - requests == 1.2.3 03:59:17 INFO - wsgiref == 0.1.2 03:59:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:17 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:17 INFO - 'HOME': '/Users/cltbld', 03:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:17 INFO - 'LOGNAME': 'cltbld', 03:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:17 INFO - 'MOZ_NO_REMOTE': '1', 03:59:17 INFO - 'NO_EM_RESTART': '1', 03:59:17 INFO - 'PAGER': '/bin/cat', 03:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:17 INFO - 'PWD': '/builds/slave/test', 03:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:17 INFO - 'SHELL': '/bin/bash', 03:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:17 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:17 INFO - 'USER': 'cltbld', 03:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:17 INFO - 'XPC_FLAGS': '0x0', 03:59:17 INFO - 'XPC_SERVICE_NAME': '0', 03:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:59:17 INFO - Cleaning up... 03:59:17 INFO - Return code: 0 03:59:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:17 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:17 INFO - 'HOME': '/Users/cltbld', 03:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:17 INFO - 'LOGNAME': 'cltbld', 03:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:17 INFO - 'MOZ_NO_REMOTE': '1', 03:59:17 INFO - 'NO_EM_RESTART': '1', 03:59:17 INFO - 'PAGER': '/bin/cat', 03:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:17 INFO - 'PWD': '/builds/slave/test', 03:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:17 INFO - 'SHELL': '/bin/bash', 03:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:17 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:17 INFO - 'USER': 'cltbld', 03:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:17 INFO - 'XPC_FLAGS': '0x0', 03:59:17 INFO - 'XPC_SERVICE_NAME': '0', 03:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:17 INFO - Downloading/unpacking jsonschema 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:59:20 INFO - Downloading/unpacking functools32 (from jsonschema) 03:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:20 INFO - Downloading functools32-3.2.3-2.tar.gz 03:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:59:20 INFO - warning: no files found matching '*.txt' 03:59:20 INFO - no previously-included directories found matching 'build' 03:59:20 INFO - no previously-included directories found matching 'dist' 03:59:20 INFO - no previously-included directories found matching '.git*' 03:59:20 INFO - Installing collected packages: jsonschema, functools32 03:59:20 INFO - Running setup.py install for functools32 03:59:20 INFO - warning: no files found matching '*.txt' 03:59:20 INFO - no previously-included directories found matching 'build' 03:59:20 INFO - no previously-included directories found matching 'dist' 03:59:20 INFO - no previously-included directories found matching '.git*' 03:59:20 INFO - Successfully installed jsonschema functools32 03:59:20 INFO - Cleaning up... 03:59:20 INFO - Return code: 0 03:59:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:59:20 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 0x107a42a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a494c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa88cc9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, '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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:20 INFO - 'HOME': '/Users/cltbld', 03:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:20 INFO - 'LOGNAME': 'cltbld', 03:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:20 INFO - 'MOZ_NO_REMOTE': '1', 03:59:20 INFO - 'NO_EM_RESTART': '1', 03:59:20 INFO - 'PAGER': '/bin/cat', 03:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:20 INFO - 'PWD': '/builds/slave/test', 03:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:20 INFO - 'SHELL': '/bin/bash', 03:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:20 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:20 INFO - 'USER': 'cltbld', 03:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:20 INFO - 'XPC_FLAGS': '0x0', 03:59:20 INFO - 'XPC_SERVICE_NAME': '0', 03:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:21 INFO - Downloading/unpacking flake8 03:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:59:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:59:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:59:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:59:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:59:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:59:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:59:24 INFO - no previously-included directories found matching 'docs/_build' 03:59:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:59:24 INFO - Running setup.py install for pep8 03:59:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:59:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:59:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:59:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:59:24 INFO - no previously-included directories found matching 'docs/_build' 03:59:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:59:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:59:24 INFO - Cleaning up... 03:59:25 INFO - Return code: 0 03:59:25 INFO - Running post-action listener: _resource_record_post_action 03:59:25 INFO - Running post-action listener: _start_resource_monitoring 03:59:25 INFO - Starting resource monitoring. 03:59:25 INFO - ##### 03:59:25 INFO - ##### Running install step. 03:59:25 INFO - ##### 03:59:25 INFO - Running pre-action listener: _resource_record_pre_action 03:59:25 INFO - Running main action method: install 03:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:25 INFO - Reading from file tmpfile_stdout 03:59:25 INFO - Detecting whether we're running mozinstall >=1.0... 03:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:59:25 INFO - Reading from file tmpfile_stdout 03:59:25 INFO - Output received: 03:59:25 INFO - Usage: mozinstall [options] installer 03:59:25 INFO - Options: 03:59:25 INFO - -h, --help show this help message and exit 03:59:25 INFO - -d DEST, --destination=DEST 03:59:25 INFO - Directory to install application into. [default: 03:59:25 INFO - "/builds/slave/test"] 03:59:25 INFO - --app=APP Application being installed. [default: firefox] 03:59:25 INFO - mkdir: /builds/slave/test/build/application 03:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:59:57 INFO - Reading from file tmpfile_stdout 03:59:57 INFO - Output received: 03:59:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:59:57 INFO - Running post-action listener: _resource_record_post_action 03:59:57 INFO - ##### 03:59:57 INFO - ##### Running run-tests step. 03:59:57 INFO - ##### 03:59:57 INFO - Running pre-action listener: _resource_record_pre_action 03:59:57 INFO - Running main action method: run_tests 03:59:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:59:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:59:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:59:57 INFO - 2016-04-20 03:59:57.496 screenresolution[1638:7365] starting screenresolution argv=screenresolution get 03:59:57 INFO - 2016-04-20 03:59:57.499 screenresolution[1638:7365] Display 0: 1600x1200x32@60 03:59:57 INFO - 2016-04-20 03:59:57.504 screenresolution[1639:7367] starting screenresolution argv=screenresolution list 03:59:57 INFO - Available Modes on Display 0 03:59:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:59:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:59:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:59:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:59:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:59:57 INFO - Intel Iris: 03:59:57 INFO - Chipset Model: Intel Iris 03:59:57 INFO - Type: GPU 03:59:57 INFO - Bus: Built-In 03:59:57 INFO - VRAM (Dynamic, Max): 1536 MB 03:59:57 INFO - Vendor: Intel (0x8086) 03:59:57 INFO - Device ID: 0x0a2e 03:59:57 INFO - Revision ID: 0x0009 03:59:57 INFO - Displays: 03:59:57 INFO - Display: 03:59:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:59:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:59:57 INFO - Display Serial Number: 1600x1200 60 03:59:57 INFO - Main Display: Yes 03:59:57 INFO - Mirror: Off 03:59:57 INFO - Online: Yes 03:59:57 INFO - Rotation: Supported 03:59:57 INFO - Return code: 0 03:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:59:57 INFO - Python 2.7.3 03:59:57 INFO - Return code: 0 03:59:57 INFO - grabbing minidump binary from tooltool 03:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:57 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 0x1078e6d30>, '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 0x10774adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1078ddb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:59:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:59:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:59:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:59:57 INFO - Return code: 0 03:59:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:59:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:59:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:59:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:59:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:59:59 INFO - Return code: 0 03:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXIpfL2WCQ/Render', 03:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:59 INFO - 'HOME': '/Users/cltbld', 03:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:59 INFO - 'LOGNAME': 'cltbld', 03:59:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:59:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:59 INFO - 'MOZ_NO_REMOTE': '1', 03:59:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:59:59 INFO - 'NO_EM_RESTART': '1', 03:59:59 INFO - 'PAGER': '/bin/cat', 03:59:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:59 INFO - 'PWD': '/builds/slave/test', 03:59:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:59 INFO - 'SHELL': '/bin/bash', 03:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idTw6K0VE6/Listeners', 03:59:59 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 03:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:59 INFO - 'USER': 'cltbld', 03:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:59 INFO - 'XPC_FLAGS': '0x0', 03:59:59 INFO - 'XPC_SERVICE_NAME': '0', 03:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:59:59 INFO - mozversion application_buildid: 20160420015138 03:59:59 INFO - mozversion application_changeset: efa0fb87773ed6e6850dcaf794d53651429decca 03:59:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:59:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:59:59 INFO - mozversion application_name: Firefox 03:59:59 INFO - mozversion application_remotingname: firefox-dev 03:59:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:59:59 INFO - mozversion application_vendor: Mozilla 03:59:59 INFO - mozversion application_version: 47.0a2 03:59:59 INFO - mozversion platform_buildid: 20160420015138 03:59:59 INFO - mozversion platform_changeset: efa0fb87773ed6e6850dcaf794d53651429decca 03:59:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:59:59 INFO - mozversion platform_version: 47.0a2 03:59:59 INFO - using testdate: 1461149999 03:59:59 INFO - actual date: 1461149999 03:59:59 INFO - starting webserver on 'localhost:49270' 03:59:59 INFO - SUITE-START | Running 2 tests 03:59:59 INFO - TEST-START | dromaeo_css 03:59:59 INFO - operating with platform_type : mac_ 03:59:59 INFO - Initialising browser for dromaeo_css test... 03:59:59 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpYtfOGh/profile http://localhost:49270/getInfo.html) 04:00:01 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:00:01 INFO - PROCESS | 1657 | colorDepth:24 04:00:01 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:00:01 INFO - PROCESS | 1657 | __metrics 04:00:01 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:00:01 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:00:02 INFO - TEST-INFO | 1657: exit 0 04:00:02 INFO - Browser initialized. 04:00:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:00:02 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpYtfOGh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:14 INFO - PROCESS | 1659 | RSS: Main: 207785984 04:00:14 INFO - PROCESS | 1659 | 04:01:05 INFO - PROCESS | 1659 | 1461150065407 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] 04:03:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:03:16 INFO - PROCESS | 1659 | RSS: Main: 321798144 04:03:16 INFO - PROCESS | 1659 | 04:06:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:06:19 INFO - PROCESS | 1659 | RSS: Main: 343769088 04:06:19 INFO - PROCESS | 1659 | 04:09:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:09:22 INFO - PROCESS | 1659 | RSS: Main: 346808320 04:09:22 INFO - PROCESS | 1659 | 04:12:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:12:25 INFO - PROCESS | 1659 | RSS: Main: 305577984 04:12:25 INFO - PROCESS | 1659 | 04:15:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 04:15:28 INFO - PROCESS | 1659 | RSS: Main: 305340416 04:15:28 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 04:18:31 INFO - PROCESS | 1659 | RSS: Main: 356798464 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | __start_tp_report 04:18:31 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:18:31 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:18:31 INFO - PROCESS | 1659 | |i|pagename|runs| 04:18:31 INFO - PROCESS | 1659 | |0;dojo.html;2862.613547171132;2864.3555200079973;3483.808390538521;3492.8428220730066;3540.4866294387316;5598.356189038262;5598.720063996804;5602.1036634138545;5611.943880561198;5629.577781666376;5576.553875689945;5614.214010038598;5619.129034999572;5633.126865335869;5672.064109421951;5044.697318160904;5081.644284900059;5100.821471248489;5139.794408223666;5141.4087379951225;5077.619178561604;5169.715665638389;5170.224466330043;5171.896562068757;5181.989512045153;5109.182530795074;5161.922571161417;5188.0920838853235;5194.71429071401;5201.90759940411;5130.846074617749;5197.428282888879;5201.271821944931;5206.323177986837;5239.449857764918;4946.72792996385;5109.412417571953;5115.386153661584;5118.718470484122;5177.378714554277;5493.835184944458;5508.504234618904;5515.09001014832;5525.281713377275;5526.972365138189;6051.757929682807;6171.87656246877;6176.135341052256;6185.690715464209;6203;6144.033909149074;6167.7532898684;6183.7835675751585;6256.1866957295715;6287.717052732638;5761.683107429089;5812.593118481708;5847.8245652630485;5849.590528662995;5851.268591426072;5736.541076713853;6095.262850087704;6164.260288765287;6179.938200618026;6300.475799018527;6113.755449782047;6121.47967422774;6122.877542449215;6130.795626845448;6132.110843927606;6230.813075607739;6269.62382257066;6275.96862015687;6285.2457705075685;6298.401651843067;6071.757129714851;6118.388161183846;6129.509639228851;6191.504679625619;6212.192414986023;6090.592334494773;6121.056693972082;6125.052321061979;6133.395871484143;6173.567850250529;4828.903421931612;4841.491643377394;4842.055799119138;4876.853694389174;4878.609711223094;4334.934975975363;4348.9565104349185;4402.823887044547;4405.691601587858;4496.820127194941;5618.52242559387;5629.831105066854;5636.718164091779;5667.603267771299;5674.06377947634;6200.720967556471;6213.378662133841;6231.470325022424;6235.812925612239;6238.469730072995;5532.30846144232;5596.1605759136455;5648.406917273709;5651.463111004448;5722.113072473773;5276.5251127398715;5318.946810531923;5320.414754377015;5324.201369794484;5329.973350133302;6257.311695713468;6273.623582584968;6286.402791734778;6296.087067375205;6317.273513546001;6112.80745551641;6132.38676132401;6204.937950620617;6213.720382582705;6257.749690012349;6319.747210111544;6334.841628959313;6342.682865856596;6347.333529979285;6349.9682501587195;6098.420650038239;6106.297775755667;6110.969445152924;6115.694215289129;6118.449339559461;6053.939460605337;6137.560853825989;6225.190725205694;6253.937460625336;6279.96860015697;5936.753281810696;5984.1921340618455;5985.102234664835;6000.669963152069;6036.7585296589405;5824.330202026821;5876.882462350643;5998.34018258;6002.69986500682;6021.939780602138;5691.800786972436;5765.596408251382;5817.418258174149;5943.108533719977;5981.970090149695;5950.434708702666;5954.612950158225;5957.285125784933;5985.401459854154;6012.9098063529955;5750.25246717924;5773.653580785166;5777.566682498746;5803.564732645153;5817.0983497557945 04:18:31 INFO - PROCESS | 1659 | |1;ext.html;494.96535242533037;598.3298705449897;599.8872092268841;627.8461776864668;675.922268939072;29183.854080729594;36066.45900311497;37391.06522336943;39848.40227396591;40353;44567.885802854944;45095.32357014647;45204;45231.86920326993;45281;9767.365121267112;10017.39895606266;10027.59889604415;10029.849552256723;10040.598376064985;9831;9950.800983980316;9968;9984.900150998506;10011.349262297927;9903.653372131941;9945.204383649327;9969.10278074973;9970.302078854484;9974.800503989916;8749.999999999969;9779.511024448784;9982.700518984442;9983.75040623983;9995.300328976975;9848.556814943344;9965.900340996643;9967.152792012688;9975.90024099757;10004;43126.137477250595;43526.911827204574;43581.56418435807;43647.56352436467;43713.78143109296;3597.7661452005536;3598.982005089958;3604.0269127335737;3605.9098522536883;4044.5550989391145;27355.042573509832;28228.858855705796;28658.14025579236;28830.84676612933;28937.710622893712;2785.5543113101808;2795.3989892173076;2822.703616120298;2831.7451429371454;2886.480433521965;380995;382613;383280;383677;384419.0779046115;48273.27590086152;72944.27055729374;73253.26746732465;73276;73299;38579;47726.28410573844;47748.761256193495;47793;47822.04355912932;16837.242324095445;17048.31806727717;17056.744148837777;17109.059001755024;17173;13624.863751362558;13734.45062197501;13747.587572372844;13749.175049497062;13759.10565813219;14053.929730351283;15350.539483815503;15435.38258469671;15685.764713529306;15778.60553486149;6672.366125218096;6753.45972322213;6808.659567021629;6817.045613614098;6820.556663816836;964.1467301438278;1013.8834034086027;1056.4295280548572;1107.9390633515072;1134.500819639356;30598.69401306003;30914.381712365626;31032.379352412827;31335.23628290497;31524.842375788434;1073.1361254190451;1180.1738782852037;1180.2505409065134;1238.7832129377502;1258.5406326690875;13299.335033248299;15472.613184670248;15718.685626287639;15844;15999.960002599793;528048;535062;543447;543553;544280;63893.36106638967;64081.71836563336;64189.39526511874;64502;64568;1444.6821699226152;1562.3203906300796;1578.3134336563633;1603.2464741571443;1605.7430811070406;30237.848810755804;30923.15688469502;31412.214694632818;31994.68005319917;32009;51726;51841.481585185174;51901.480985189664;52037.47962520326;52043.73978130085;345072;346913;347430;348247;348681;28732;34417.82791086029;34765.826170869994;34781.65218347784;34843.9546813596;42981.710548683586;45177.54822451865;50054;51086.74456627693;52817.471825282795;37126.25747485089;37296.44055339226;38233.617663823;39015;39218;38980.135610745194;39173.41239881461;39250;39296.82109536719;40090.39864402095;38661.6133838658;39110.21779564336;40001.79999099986;40054.399184012844;40109.79945100256;33833.49249761206;34180.145496361794;34183;34650.30699386049;34712.13219669528;26193.083242086435;26287.868560657073;26391.944322227664;26486.205413837615;26548.336291592866 04:18:31 INFO - PROCESS | 1659 | |2;jquery.html;375.9849606015759;380.20537077508004;384.02266232438444;387.73052728353787;389.8986263571474;20075.393968482524;20936.476588085305;21175.258865939657;21671.783282167173;21685.241016564425;12148.75702485952;12519.561815336465;21450.785492145074;21712.3486295411;21751.12995480179;5356.4107948125575;5503.504684578387;5554.333479982408;5563;5563.388027316992;4199.517055538621;4285.507166675826;4386.144701783153;4665.743384113873;6818.352256535622;6588.176477940257;6749.2913244109395;6757.628330441824;6780.796576102724;6788.762393316235;6735.797926062226;6742.325767423267;6748.6625668716615;6752.628605426699;6756.324367563254;4001.279769641463;5665.688387138705;5858.619189752652;5935;5935.050391937312;13848.930755346159;13941.512047078406;13970.51103211393;20161.495962600904;25680.972761089535;2122.2890331738886;2183.9344819655435;2535.29011876673;2758.4207316463567;2772.4039331543813;9281.35030547862;9757.609695612167;10139.746506337327;10215.080642742114;10349;1709.0941800845571;1753.833385828357;1765.2233017472272;1778.413123669199;1841.8066103059255;104900;105730.47134764354;106564.4671776644;108976.91023089747;110938.44530777294;11435.256708313933;11736.295822250522;11775.23460975034;11792.056635469142;11895.940520297343;14529.491467798576;14539.927300363643;14669.33987970544;14682;14682.853171468363;9084.90915090854;9093.681721139728;9150.679726209552;9160.404573702688;9221.677241296522;9427.010163932826;9429.764255893657;9430.905690943002;9466.148046675833;9472.147506724428;4551.8634440966825;4593.747343896051;4636.003259299267;4650.441946966318;4678.695884767547;1891.1962415973157;1922.528980399783;1936.331965471938;1943.9319623813099;1945.066368143299;901.6663834381189;956.8229877472689;982.4792859784326;1048.648702684594;1067.4182570498942;21885.562288754136;21948.89025554862;22187.001584928716;22267.554648907255;22284.888575557343;1013.7972405518926;1021.3769600543662;1055.546115170484;1106.0211712634377;1116.9776604467866;11387.943060284646;11679;11731.296122232694;11774.352410617326;11781.705457363634;535599;568717;605769;645027;647110;95793.52103239579;101861;122795;128421.35789320993;130966.34516827355;1071.7106381277013;1077.407425915759;1088.7659153282082;1090.5528733219342;1091.7161538000178;18606;19140.138693758538;19505.317313893946;19509;19804.50488737793;45802;48328.27507587459;49400.752996236224;50259.497405026945;50483.74758126186;70213.29786702068;70230;72253.638731806;72414;72468;8181.713640022571;8252;8278.71024514139;8401.915980840113;8649.3512986525;9434.962154162899;11250.437478126225;13556.661083472993;13857.03000789935;14076.436942522594;11488.597899073826;11913.868182522647;11949.522019119138;11974.042076633674;12168.634940951786;11574.363410012193;11660;11934.820977685515;11934.985526230368;11987.520499180284;11718.70703232426;11823;12084.818727719266;12092.637220883387;12260.38698065111;8087.069986951576;8137.226963438464;8300.2114799094;8424.873626895724;8453.154684531497;9682.854757178507;10929.453527323762;12079.335636540074;12196.81704774447;12209.633710988684 04:18:31 INFO - PROCESS | 1659 | |3;mootools.html;1499.9625009374759;1658.5770628489736;1683.3182561062774;2056.7223424837644;2124.895054571623;8180.222878826509;8726.258268047215;8903.154200350973;8920.643174273022;8923.107689231074;8623.310135189186;8882.866756998634;8896.955515222431;8913.286937045037;8929.91070089299;2266.5920134375806;2496.425822060927;2539.0478570536;2571.2286314105786;2576.3559110222445;2226.309843948373;2230.9330720078424;2232.475368288451;2233.620284551622;2237.8097861681777;2335.6146235871147;2340.3447034830274;2351.8000969917575;2426.5632186206476;2431.1126438849783;2505.7619526144986;2536.8604726740023;2546.2615841406155;2553.3233693071256;2574.897004119833;2312.861228326306;2388.450656349032;2529.2791554406904;2560.372708686365;2567.8459292442512;8960.910390896073;9046.185843274072;9052.140046695553;9060.592273347715;9061.954690226507;5503.2020357048405;5653.378128405872;5663.461971112779;5671.319441666985;5674.347450043257;3609.187932715118;3654.689351405134;3719.6094410087094;3730.570984336809;3731.179140589093;1060.496264274471;1075.0700643943012;1086.0442810326863;1091.2088735666657;1113.9498722557505;10191.949040254825;12072;12105.939470302592;12107.213031152947;12124.696882577919;4879.56083952446;4922.50774922512;4968.90062198754;4972.55247027762;5017.096922553902;10207.234457415723;10223.233257505717;10230.23273254509;10237.48812559369;10243.743906402251;5591.4688104629995;5593.74828132735;5615.831525054255;5619.8595035124445;5639.154126881001;4602.401687780634;4632.513586073481;4691.46048204454;4702.717836929795;5070.847874563769;3924;3944.447777311179;4029.8112056943273;4032.1935612876955;4042.292598795199;1133.1331531378503;1329.2489743295007;1338.464614154346;1344.9058565900489;1349.0017387133646;1220.3166226912956;1292.883640472362;1371.4102935737528;1454.2655958740768;1457.5991602309239;8255.133211012877;8340.332773378115;8342.916570834335;8418.873716894252;8454.61954212062;1251.5431867368345;1258.7985922252396;1265.9810102848464;1288.4910460368103;1298.5455090718362;6290.433860952445;6315.147455093504;6420.871582568416;6435.646039467781;6438;10740.409277489813;10929.344239345664;10951;11059.834102488468;11068.169887258488;11041.282316649393;11084.889151108548;11091.223614346918;11096.944515277371;11112.11103111749;8436.072032076589;8484.703035393732;8508.574571271412;8593.269572086318;8615.181557752003;7981.800454988672;7993;8045.83908321842;8061.596920154087;8063.516189028677;9605.423674579548;9709.126178643955;9796.755081123029;9860.112589866947;10043.196544276294;9887.802243955224;9971.284531967754;10084.042016008467;10109.595616175271;10322.535485903003;6814.727410903508;7550;7619.161892191743;7654.425918056279;7668.6549105291515;6963.7562685305775;7649;7768.689252429839;8500.277476414574;8503.489790612335;7430.182679905098;7495.175530691728;7527.6988920444755;7593.962030189814;7629.656665450179;7294.562326260441;7318.377937875127;7343.771788282771;7416.480846340704;7424.554526728413;7882.975213222245;7940.761777146695;7968.681252749825;7972.322352600094;7982.241687039722;7188.856222875409;7215;7252.347288743827;7255.854882902418;7259.782206533812;7052.0479735235085;7209.206987231296;7229.819254518679;7235.457340699363;7240.96379518099 04:18:31 INFO - PROCESS | 1659 | |4;prototype.html;334.80581262867537;338.73747845419797;344.7776184361088;344.94653328734057;350.2749308930513;6740.561863478876;6753.054572359867;6775.83060423489;6781.966090169542;6827.692753826077;4870.439899411569;6087.847803804907;6126.846828829282;6371.6300995286;6697.765578204764;4698.882527936812;4816.277558366238;4821.879453013667;4909.214525675892;4952.504749525046;3999.3800960850967;4018.276710192164;4869.561739443449;4899.485554016813;4918.606511479073;3966.3852102924093;4857.781399837016;4874.878128046792;4879.121758083544;4885.364902562662;4653.092646933849;4765.3328534005095;4859.951400485986;4859.975700121477;4867.172580661296;4186.476690413699;4207.242696314662;4215.32554791235;4217.641500472463;4219.936700949488;6345.714442850082;6349.52378571609;6439.195100612424;6447.935520644827;6464.353564643545;5075.619328550373;5870.236869206976;5885.352611212763;5889.499392551638;5911.994960856665;6767.898481522781;6860.5197636165485;7075.2570980047385;7106.502544821865;7115.359617634438;3937.5668676445566;4746.715197088186;4780.498047704976;4786.157329428195;4797.664163508556;5646;6631.192385520507;7069.116360454943;7371.963140184318;7581.658825352873;4020.7386519876113;4870.975645121752;4878.048780487842;4879.975600122048;4880.950595621887;4960.156773348539;4970.15507363749;4971.37857767779;4971.676841005323;4978.153713868651;5872.354041055481;5875.618084824473;5889.646621202656;5895.469407753323;5905.966455870205;6832.897506537406;7154.964225178945;7170.749023784142;7178.928210717931;7180.389666878374;1684.4525529203058;1725.9827401726072;1730.1608719770902;1732.1685590916431;1732.6101627134046;952.2477242978108;1036.8237399642078;1075.5536452372178;1104.6465131157952;1114.3870871020906;6415.647139407284;6505.41451269388;6527.184101987252;6537.215534135839;6672.065910772496;7535.849283014309;7537.434692398092;7539.359154471931;7556.319931206218;7556.811079723051;6698.296678848748;6712.026756120335;6726.39462448382;6742.9662851685425;6748.662566871637;3801.125741079567;3914.980425097913;3946.2304850553865;3954.189391174802;3954.5649978502342;7095.609741464269;7763.767086987399;7795.725795166044;7800.258975397442;7811.960940195377;7645.732399366107;7701.922980770232;7716.8456630868195;7741.148473667892;7744.0319960005;7773.823329467758;7774.2614451627005;7798.415118866108;7801.648925798239;7813.335866451415;7129.536580122275;7469.365103966006;7483.363914067365;7494.925050749423;7496;6000.729967151401;7207.423406127392;7289.781306560812;7300.780976570712;7313.158986716383;7731.304182623591;7735.18780528059;7742.187070357757;7758;7769;4655.3947986761505;4695.882602934954;4706.552877476634;4720.291956206596;4720.315554244684;5746.482816546531;6567.2447668518735;6898.585789912955;7317.780466586011;7392.778216653509;7097.467689923174;7128.394086502705;7138.0363650906465;7139.643017849191;7146.785596432115;7065.894011589728;7074.045003924404;7075.610841403773;7087.751928682472;7136.250693677089;6962.547434416747;6964.199117101395;6967.338102880219;6983.44132469391;6987.7204911802955;6859.794206173823;7100.183478899786;7102.857942841218;7112.857742845011;7113.359797618239;6716.832079197864;6865.519413641197;6896.586204827727;6960.756373526902;6965.512414131165 04:18:31 INFO - PROCESS | 1659 | |5;yui.html;695.6834640238692;718.7196993172662;782.4640121516761;785.171643915669;798.3294033012269;15506;15550.144742039183;15772.921135394336;15774.684506309866;15808.367665293403;14454.132752034886;14709.11745295283;14733.557993260192;14750.262486875667;14752.409903603842;822.62159406673;1009.7728011197458;1015.1219195396003;1024.8001639680265;1025.0723095598496;803.8311954489565;805.1827395193883;809.522381794741;818.251300060444;825.9380546459009;1080.0495563903753;1081.0324759340385;1083.263380900987;1084.322298563398;1085.7177133945193;1069.1660504806264;1071.276888100533;1074.8656417947757;1078.1751959750798;1078.811208038594;636.9331220221902;656.6093174561177;788.6569342336102;791.3392317414908;801.6272433318477;15505.844941550553;15512;15540.222988850512;15561.066336019874;15569.987950783163;11069.612563560317;12605.820369300669;13094.541691040768;13368;13601.931990340085;10277.229207809443;10389;10565.683029509128;10578.312409693346;10653.84019239712;3543;3559.5906470755936;3625.9818700906326;3676.246369494274;3822.5412950446125;82837.58581207116;83009.58495207546;83993.16006839914;84290.57854710749;85076;9835.262355323237;9885.483411825287;9943.552540135712;10050.748731281776;10272.897271027194;23090.769092309198;23105.653415198787;23130.421739456648;23146.189883354004;23167.53664926692;7602.543847369176;7709.498882572615;7847.489913155627;7864.4888082274465;7913.762587122395;4199.076203235283;4211.241976444208;4234.915301693949;4239.321708526621;4263.275243208662;3783.375743002429;3791.810409479515;3797;3823.9044023899623;3824.0057585028094;652.9477641788645;657.3557913245047;660.207750699163;669.0866966590569;674.956127851688;523.7512181713655;594.46201187925;599.5293694449923;611.541343992006;657.3459407889085;8250.38122140842;8436.53599052058;8535.274501667303;8674.39279250459;8745.562721863882;586.9413058694182;593.7179839576253;614.6926536731634;615.286267929209;621.9582199815292;3379.9662003380145;3886.689064874803;3986.7807270599815;3987.760734355948;4003.7597744134864;87786;88380;89178;89607.55196224108;92129.07870921338;82690;83530.58234708788;83609.58195209107;84726;85598.86002849978;63792.36207637957;66416;68227.63544729077;71914.64042679858;73606;7954.602269886367;7982.560959147303;8116.715914942949;8195.918040819515;8287.212714792086;34308.65691343055;51850;52213.47786522086;52816.151434699605;53587;63760;67904.32095679178;69119.65440172768;69328.30671693219;69767;10294.845577316497;10539.57841686324;10625.840612390666;10725.839112413163;10898.583184186005;10876.247127751663;11046.226764126755;11447.084233261485;11575.42122893835;11703.941480292544;10684.62603808894;10733.248672592843;10842.891571084188;10952.178586606196;10967.780644386909;10789.460526973462;10856.34861908288;10919.017288444078;10978.945105274743;11237.606683766357;10020.09819116283;10466.110380617733;10466.372017678963;10501.422421766876;10565.418901960466;8765.737027889174;9002.234810040958;9008.774780630536;9166.312526560669;9246.768830779016;9065.184133428023;9197.126273004054;9215.717372772086;9229.123233292827;9357.20463760588 04:18:31 INFO - PROCESS | 1659 | __end_tp_report 04:18:31 INFO - PROCESS | 1659 | __start_cc_report 04:18:31 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1064 04:18:31 INFO - PROCESS | 1659 | __end_cc_report 04:18:31 INFO - PROCESS | 1659 | __startTimestamp1461151111634__endTimestamp 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:18:31 INFO - PROCESS | 1659 | Number of tests: 6 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5677.85 Median:5847.82 stddev:648.83 (11.1%) stddev-sans-first:612.08 04:18:31 INFO - PROCESS | 1659 | Values: 2862.6 2864.4 3483.8 3492.8 3540.5 5598.4 5598.7 5602.1 5611.9 5629.6 5576.6 5614.2 5619.1 5633.1 5672.1 5044.7 5081.6 5100.8 5139.8 5141.4 5077.6 5169.7 5170.2 5171.9 5182.0 5109.2 5161.9 5188.1 5194.7 5201.9 5130.8 5197.4 5201.3 5206.3 5239.4 4946.7 5109.4 5115.4 5118.7 5177.4 5493.8 5508.5 5515.1 5525.3 5527.0 6051.8 6171.9 6176.1 6185.7 6203.0 6144.0 6167.8 6183.8 6256.2 6287.7 5761.7 5812.6 5847.8 5849.6 5851.3 5736.5 6095.3 6164.3 6179.9 6300.5 6113.8 6121.5 6122.9 6130.8 6132.1 6230.8 6269.6 6276.0 6285.2 6298.4 6071.8 6118.4 6129.5 6191.5 6212.2 6090.6 6121.1 6125.1 6133.4 6173.6 4828.9 4841.5 4842.1 4876.9 4878.6 4334.9 4349.0 4402.8 4405.7 4496.8 5618.5 5629.8 5636.7 5667.6 5674.1 6200.7 6213.4 6231.5 6235.8 6238.5 5532.3 5596.2 5648.4 5651.5 5722.1 5276.5 5318.9 5320.4 5324.2 5330.0 6257.3 6273.6 6286.4 6296.1 6317.3 6112.8 6132.4 6204.9 6213.7 6257.7 6319.7 6334.8 6342.7 6347.3 6350.0 6098.4 6106.3 6111.0 6115.7 6118.4 6053.9 6137.6 6225.2 6253.9 6280.0 5936.8 5984.2 5985.1 6000.7 6036.8 5824.3 5876.9 5998.3 6002.7 6021.9 5691.8 5765.6 5817.4 5943.1 5982.0 5950.4 5954.6 5957.3 5985.4 6012.9 5750.3 5773.7 5777.6 5803.6 5817.1 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:58435.22 Median:210423.31 stddev:114331.16 (54.3%) stddev-sans-first:114569.13 04:18:31 INFO - PROCESS | 1659 | Values: 495.0 598.3 599.9 627.8 675.9 29183.9 36066.5 37391.1 39848.4 40353.0 44567.9 45095.3 45204.0 45231.9 45281.0 9767.4 10017.4 10027.6 10029.8 10040.6 9831.0 9950.8 9968.0 9984.9 10011.3 9903.7 9945.2 9969.1 9970.3 9974.8 8750.0 9779.5 9982.7 9983.8 9995.3 9848.6 9965.9 9967.2 9975.9 10004.0 43126.1 43526.9 43581.6 43647.6 43713.8 3597.8 3599.0 3604.0 3605.9 4044.6 27355.0 28228.9 28658.1 28830.8 28937.7 2785.6 2795.4 2822.7 2831.7 2886.5 380995.0 382613.0 383280.0 383677.0 384419.1 48273.3 72944.3 73253.3 73276.0 73299.0 38579.0 47726.3 47748.8 47793.0 47822.0 16837.2 17048.3 17056.7 17109.1 17173.0 13624.9 13734.5 13747.6 13749.2 13759.1 14053.9 15350.5 15435.4 15685.8 15778.6 6672.4 6753.5 6808.7 6817.0 6820.6 964.1 1013.9 1056.4 1107.9 1134.5 30598.7 30914.4 31032.4 31335.2 31524.8 1073.1 1180.2 1180.3 1238.8 1258.5 13299.3 15472.6 15718.7 15844.0 16000.0 528048.0 535062.0 543447.0 543553.0 544280.0 63893.4 64081.7 64189.4 64502.0 64568.0 1444.7 1562.3 1578.3 1603.2 1605.7 30237.8 30923.2 31412.2 31994.7 32009.0 51726.0 51841.5 51901.5 52037.5 52043.7 345072.0 346913.0 347430.0 348247.0 348681.0 28732.0 34417.8 34765.8 34781.7 34844.0 42981.7 45177.5 50054.0 51086.7 52817.5 37126.3 37296.4 38233.6 39015.0 39218.0 38980.1 39173.4 39250.0 39296.8 40090.4 38661.6 39110.2 40001.8 40054.4 40109.8 33833.5 34180.1 34183.0 34650.3 34712.1 26193.1 26287.9 26391.9 26486.2 26548.3 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34127.45 Median:21731.74 stddev:99859.80 (459.5%) stddev-sans-first:100107.78 04:18:31 INFO - PROCESS | 1659 | Values: 376.0 380.2 384.0 387.7 389.9 20075.4 20936.5 21175.3 21671.8 21685.2 12148.8 12519.6 21450.8 21712.3 21751.1 5356.4 5503.5 5554.3 5563.0 5563.4 4199.5 4285.5 4386.1 4665.7 6818.4 6588.2 6749.3 6757.6 6780.8 6788.8 6735.8 6742.3 6748.7 6752.6 6756.3 4001.3 5665.7 5858.6 5935.0 5935.1 13848.9 13941.5 13970.5 20161.5 25681.0 2122.3 2183.9 2535.3 2758.4 2772.4 9281.4 9757.6 10139.7 10215.1 10349.0 1709.1 1753.8 1765.2 1778.4 1841.8 104900.0 105730.5 106564.5 108976.9 110938.4 11435.3 11736.3 11775.2 11792.1 11895.9 14529.5 14539.9 14669.3 14682.0 14682.9 9084.9 9093.7 9150.7 9160.4 9221.7 9427.0 9429.8 9430.9 9466.1 9472.1 4551.9 4593.7 4636.0 4650.4 4678.7 1891.2 1922.5 1936.3 1943.9 1945.1 901.7 956.8 982.5 1048.6 1067.4 21885.6 21948.9 22187.0 22267.6 22284.9 1013.8 1021.4 1055.5 1106.0 1117.0 11387.9 11679.0 11731.3 11774.4 11781.7 535599.0 568717.0 605769.0 645027.0 647110.0 95793.5 101861.0 122795.0 128421.4 130966.3 1071.7 1077.4 1088.8 1090.6 1091.7 18606.0 19140.1 19505.3 19509.0 19804.5 45802.0 48328.3 49400.8 50259.5 50483.7 70213.3 70230.0 72253.6 72414.0 72468.0 8181.7 8252.0 8278.7 8401.9 8649.4 9435.0 11250.4 13556.7 13857.0 14076.4 11488.6 11913.9 11949.5 11974.0 12168.6 11574.4 11660.0 11934.8 11935.0 11987.5 11718.7 11823.0 12084.8 12092.6 12260.4 8087.1 8137.2 8300.2 8424.9 8453.2 9682.9 10929.5 12079.3 12196.8 12209.6 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:6165.11 Median:4970.73 stddev:3232.81 (65.0%) stddev-sans-first:3222.86 04:18:31 INFO - PROCESS | 1659 | Values: 1500.0 1658.6 1683.3 2056.7 2124.9 8180.2 8726.3 8903.2 8920.6 8923.1 8623.3 8882.9 8897.0 8913.3 8929.9 2266.6 2496.4 2539.0 2571.2 2576.4 2226.3 2230.9 2232.5 2233.6 2237.8 2335.6 2340.3 2351.8 2426.6 2431.1 2505.8 2536.9 2546.3 2553.3 2574.9 2312.9 2388.5 2529.3 2560.4 2567.8 8960.9 9046.2 9052.1 9060.6 9062.0 5503.2 5653.4 5663.5 5671.3 5674.3 3609.2 3654.7 3719.6 3730.6 3731.2 1060.5 1075.1 1086.0 1091.2 1113.9 10191.9 12072.0 12105.9 12107.2 12124.7 4879.6 4922.5 4968.9 4972.6 5017.1 10207.2 10223.2 10230.2 10237.5 10243.7 5591.5 5593.7 5615.8 5619.9 5639.2 4602.4 4632.5 4691.5 4702.7 5070.8 3924.0 3944.4 4029.8 4032.2 4042.3 1133.1 1329.2 1338.5 1344.9 1349.0 1220.3 1292.9 1371.4 1454.3 1457.6 8255.1 8340.3 8342.9 8418.9 8454.6 1251.5 1258.8 1266.0 1288.5 1298.5 6290.4 6315.1 6420.9 6435.6 6438.0 10740.4 10929.3 10951.0 11059.8 11068.2 11041.3 11084.9 11091.2 11096.9 11112.1 8436.1 8484.7 8508.6 8593.3 8615.2 7981.8 7993.0 8045.8 8061.6 8063.5 9605.4 9709.1 9796.8 9860.1 10043.2 9887.8 9971.3 10084.0 10109.6 10322.5 6814.7 7550.0 7619.2 7654.4 7668.7 6963.8 7649.0 7768.7 8500.3 8503.5 7430.2 7495.2 7527.7 7594.0 7629.7 7294.6 7318.4 7343.8 7416.5 7424.6 7883.0 7940.8 7968.7 7972.3 7982.2 7188.9 7215.0 7252.3 7255.9 7259.8 7052.0 7209.2 7229.8 7235.5 7241.0 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5803.90 Median:6599.22 stddev:1861.82 (28.2%) stddev-sans-first:1821.23 04:18:31 INFO - PROCESS | 1659 | Values: 334.8 338.7 344.8 344.9 350.3 6740.6 6753.1 6775.8 6782.0 6827.7 4870.4 6087.8 6126.8 6371.6 6697.8 4698.9 4816.3 4821.9 4909.2 4952.5 3999.4 4018.3 4869.6 4899.5 4918.6 3966.4 4857.8 4874.9 4879.1 4885.4 4653.1 4765.3 4860.0 4860.0 4867.2 4186.5 4207.2 4215.3 4217.6 4219.9 6345.7 6349.5 6439.2 6447.9 6464.4 5075.6 5870.2 5885.4 5889.5 5912.0 6767.9 6860.5 7075.3 7106.5 7115.4 3937.6 4746.7 4780.5 4786.2 4797.7 5646.0 6631.2 7069.1 7372.0 7581.7 4020.7 4871.0 4878.0 4880.0 4881.0 4960.2 4970.2 4971.4 4971.7 4978.2 5872.4 5875.6 5889.6 5895.5 5906.0 6832.9 7155.0 7170.7 7178.9 7180.4 1684.5 1726.0 1730.2 1732.2 1732.6 952.2 1036.8 1075.6 1104.6 1114.4 6415.6 6505.4 6527.2 6537.2 6672.1 7535.8 7537.4 7539.4 7556.3 7556.8 6698.3 6712.0 6726.4 6743.0 6748.7 3801.1 3915.0 3946.2 3954.2 3954.6 7095.6 7763.8 7795.7 7800.3 7812.0 7645.7 7701.9 7716.8 7741.1 7744.0 7773.8 7774.3 7798.4 7801.6 7813.3 7129.5 7469.4 7483.4 7494.9 7496.0 6000.7 7207.4 7289.8 7300.8 7313.2 7731.3 7735.2 7742.2 7758.0 7769.0 4655.4 4695.9 4706.6 4720.3 4720.3 5746.5 6567.2 6898.6 7317.8 7392.8 7097.5 7128.4 7138.0 7139.6 7146.8 7065.9 7074.0 7075.6 7087.8 7136.3 6962.5 6964.2 6967.3 6983.4 6987.7 6859.8 7100.2 7102.9 7112.9 7113.4 6716.8 6865.5 6896.6 6960.8 6965.5 04:18:31 INFO - PROCESS | 1659 | 04:18:31 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18470.28 Median:4251.30 stddev:26031.92 (612.3%) stddev-sans-first:26070.73 04:18:31 INFO - PROCESS | 1659 | Values: 695.7 718.7 782.5 785.2 798.3 15506.0 15550.1 15772.9 15774.7 15808.4 14454.1 14709.1 14733.6 14750.3 14752.4 822.6 1009.8 1015.1 1024.8 1025.1 803.8 805.2 809.5 818.3 825.9 1080.0 1081.0 1083.3 1084.3 1085.7 1069.2 1071.3 1074.9 1078.2 1078.8 636.9 656.6 788.7 791.3 801.6 15505.8 15512.0 15540.2 15561.1 15570.0 11069.6 12605.8 13094.5 13368.0 13601.9 10277.2 10389.0 10565.7 10578.3 10653.8 3543.0 3559.6 3626.0 3676.2 3822.5 82837.6 83009.6 83993.2 84290.6 85076.0 9835.3 9885.5 9943.6 10050.7 10272.9 23090.8 23105.7 23130.4 23146.2 23167.5 7602.5 7709.5 7847.5 7864.5 7913.8 4199.1 4211.2 4234.9 4239.3 4263.3 3783.4 3791.8 3797.0 3823.9 3824.0 652.9 657.4 660.2 669.1 675.0 523.8 594.5 599.5 611.5 657.3 8250.4 8436.5 8535.3 8674.4 8745.6 586.9 593.7 614.7 615.3 622.0 3380.0 3886.7 3986.8 3987.8 4003.8 87786.0 88380.0 89178.0 89607.6 92129.1 82690.0 83530.6 83609.6 84726.0 85598.9 63792.4 66416.0 68227.6 71914.6 73606.0 7954.6 7982.6 8116.7 8195.9 8287.2 34308.7 51850.0 52213.5 52816.2 53587.0 63760.0 67904.3 69119.7 69328.3 69767.0 10294.8 10539.6 10625.8 10725.8 10898.6 10876.2 11046.2 11447.1 11575.4 11703.9 10684.6 10733.2 10842.9 10952.2 10967.8 10789.5 10856.3 10919.0 10978.9 11237.6 10020.1 10466.1 10466.4 10501.4 10565.4 8765.7 9002.2 9008.8 9166.3 9246.8 9065.2 9197.1 9215.7 9229.1 9357.2 04:18:31 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:18:31 INFO - PROCESS | 1659 | 04:18:32 INFO - TEST-INFO | 1659: exit 0 04:18:32 INFO - TEST-OK | dromaeo_css | took 1112901ms 04:18:32 INFO - TEST-START | kraken 04:18:32 INFO - operating with platform_type : mac_ 04:18:32 INFO - Initialising browser for kraken test... 04:18:32 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpJqv35a/profile http://localhost:49270/getInfo.html) 04:18:34 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 04:18:34 INFO - PROCESS | 1669 | colorDepth:24 04:18:34 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 04:18:34 INFO - PROCESS | 1669 | __metrics 04:18:34 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:18:34 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:34 INFO - TEST-INFO | 1669: exit 0 04:18:34 INFO - Browser initialized. 04:18:34 INFO - Running cycle 1/1 for kraken test... 04:18:34 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpJqv35a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:46 INFO - PROCESS | 1671 | RSS: Main: 208125952 04:18:46 INFO - PROCESS | 1671 | 04:18:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 04:18:50 INFO - PROCESS | 1671 | RSS: Main: 262877184 04:18:50 INFO - PROCESS | 1671 | 04:18:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 04:18:53 INFO - PROCESS | 1671 | RSS: Main: 273162240 04:18:53 INFO - PROCESS | 1671 | 04:18:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 04:18:57 INFO - PROCESS | 1671 | RSS: Main: 263720960 04:18:57 INFO - PROCESS | 1671 | 04:18:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 04:18:59 INFO - PROCESS | 1671 | RSS: Main: 264957952 04:18:59 INFO - PROCESS | 1671 | 04:19:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:19:02 INFO - PROCESS | 1671 | RSS: Main: 264564736 04:19:02 INFO - PROCESS | 1671 | 04:19:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 04:19:05 INFO - PROCESS | 1671 | RSS: Main: 265011200 04:19:05 INFO - PROCESS | 1671 | 04:19:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 04:19:09 INFO - PROCESS | 1671 | RSS: Main: 264478720 04:19:09 INFO - PROCESS | 1671 | 04:19:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 04:19:12 INFO - PROCESS | 1671 | RSS: Main: 266297344 04:19:12 INFO - PROCESS | 1671 | 04:19:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:19:14 INFO - PROCESS | 1671 | RSS: Main: 298799104 04:19:14 INFO - PROCESS | 1671 | 04:19:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:19:17 INFO - PROCESS | 1671 | RSS: Main: 299982848 04:19:17 INFO - PROCESS | 1671 | 04:19:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:19:19 INFO - PROCESS | 1671 | RSS: Main: 307048448 04:19:19 INFO - PROCESS | 1671 | 04:19:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:19:22 INFO - PROCESS | 1671 | RSS: Main: 307449856 04:19:22 INFO - PROCESS | 1671 | 04:19:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:19:25 INFO - PROCESS | 1671 | RSS: Main: 307568640 04:19:25 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 04:19:27 INFO - PROCESS | 1671 | RSS: Main: 370515968 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | __start_tp_report 04:19:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 04:19:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 04:19:27 INFO - PROCESS | 1671 | |i|pagename|runs| 04:19:27 INFO - PROCESS | 1671 | |0;ai-astar;111;109;110;110;112;112;118;110;109;109 04:19:27 INFO - PROCESS | 1671 | |1;audio-beat-detection;157;119;157;173;151;170;115;149;151;121 04:19:27 INFO - PROCESS | 1671 | |2;audio-dft;186;183;184;188;184;182;182;183;182;185 04:19:27 INFO - PROCESS | 1671 | |3;audio-fft;74;72;108;116;56;57;57;58;140;84 04:19:27 INFO - PROCESS | 1671 | |4;audio-oscillator;91;95;140;65;77;114;156;116;99;122 04:19:27 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;85;89;85;88;84;88;85;88;85 04:19:27 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;92;93;92;95;92;92;93;92;96 04:19:27 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;86;84;86;84;87;84;84;84;81 04:19:27 INFO - PROCESS | 1671 | |8;json-parse-financial;54;58;59;59;58;57;59;66;52;53 04:19:27 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;61;63;66;70;73;69;67;66;67;67 04:19:27 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;74;78;81;81;81;80;80;77;81;86 04:19:27 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;121;132;119;125;127;133;128;123;116;123 04:19:27 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;135;124;134;135;135;123;126;158;135;135 04:19:27 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;65;64;64;67;65;66;66;66;65;65 04:19:27 INFO - PROCESS | 1671 | __end_tp_report 04:19:27 INFO - PROCESS | 1671 | __start_cc_report 04:19:27 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1193 04:19:27 INFO - PROCESS | 1671 | __end_cc_report 04:19:27 INFO - PROCESS | 1671 | __startTimestamp1461151167789__endTimestamp 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | ------- Summary: start ------- 04:19:27 INFO - PROCESS | 1671 | Number of tests: 14 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.50 stddev:2.71 (2.5%) stddev-sans-first:2.87 04:19:27 INFO - PROCESS | 1671 | Values: 111.0 109.0 110.0 110.0 112.0 112.0 118.0 110.0 109.0 109.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:154.00 stddev:20.87 (13.6%) stddev-sans-first:21.77 04:19:27 INFO - PROCESS | 1671 | Values: 157.0 119.0 157.0 173.0 151.0 170.0 115.0 149.0 151.0 121.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.97 (1.1%) stddev-sans-first:1.94 04:19:27 INFO - PROCESS | 1671 | Values: 186.0 183.0 184.0 188.0 184.0 182.0 182.0 183.0 182.0 185.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:82.20 Median:57.50 stddev:29.56 (51.4%) stddev-sans-first:31.21 04:19:27 INFO - PROCESS | 1671 | Values: 74.0 72.0 108.0 116.0 56.0 57.0 57.0 58.0 140.0 84.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:107.50 Median:71.00 stddev:27.83 (39.2%) stddev-sans-first:28.87 04:19:27 INFO - PROCESS | 1671 | Values: 91.0 95.0 140.0 65.0 77.0 114.0 156.0 116.0 99.0 122.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:88.00 stddev:1.84 (2.1%) stddev-sans-first:1.87 04:19:27 INFO - PROCESS | 1671 | Values: 88.0 85.0 89.0 85.0 88.0 84.0 88.0 85.0 88.0 85.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:92.50 stddev:1.45 (1.6%) stddev-sans-first:1.50 04:19:27 INFO - PROCESS | 1671 | Values: 92.0 92.0 93.0 92.0 95.0 92.0 92.0 93.0 92.0 96.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:1.65 (2.0%) stddev-sans-first:1.74 04:19:27 INFO - PROCESS | 1671 | Values: 85.0 86.0 84.0 86.0 84.0 87.0 84.0 84.0 84.0 81.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.50 stddev:3.98 (6.8%) stddev-sans-first:4.01 04:19:27 INFO - PROCESS | 1671 | Values: 54.0 58.0 59.0 59.0 58.0 57.0 59.0 66.0 52.0 53.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:3.38 (5.0%) stddev-sans-first:2.83 04:19:27 INFO - PROCESS | 1671 | Values: 61.0 63.0 66.0 70.0 73.0 69.0 67.0 66.0 67.0 67.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:81.00 stddev:3.14 (3.9%) stddev-sans-first:2.51 04:19:27 INFO - PROCESS | 1671 | Values: 74.0 78.0 81.0 81.0 81.0 80.0 80.0 77.0 81.0 86.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:126.00 stddev:5.44 (4.3%) stddev-sans-first:5.60 04:19:27 INFO - PROCESS | 1671 | Values: 121.0 132.0 119.0 125.0 127.0 133.0 128.0 123.0 116.0 123.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.00 Median:135.00 stddev:9.81 (7.3%) stddev-sans-first:10.40 04:19:27 INFO - PROCESS | 1671 | Values: 135.0 124.0 134.0 135.0 135.0 123.0 126.0 158.0 135.0 135.0 04:19:27 INFO - PROCESS | 1671 | 04:19:27 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 04:19:27 INFO - PROCESS | 1671 | Values: 65.0 64.0 64.0 67.0 65.0 66.0 66.0 66.0 65.0 65.0 04:19:27 INFO - PROCESS | 1671 | -------- Summary: end -------- 04:19:27 INFO - PROCESS | 1671 | 04:19:28 INFO - TEST-INFO | 1671: exit 0 04:19:28 INFO - TEST-OK | kraken | took 56355ms 04:19:28 INFO - SUITE-END | took 1169s 04:19:28 INFO - Completed test suite (00:19:29) 04:19:28 INFO - javascript benchmark 04:19:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2862.613547171132, 2864.3555200079973, 3483.808390538521, 3492.8428220730066, 3540.4866294387316, 5598.356189038262, 5598.720063996804, 5602.1036634138545, 5611.943880561198, 5629.577781666376, 5576.553875689945, 5614.214010038598, 5619.129034999572, 5633.126865335869, 5672.064109421951, 5044.697318160904, 5081.644284900059, 5100.821471248489, 5139.794408223666, 5141.4087379951225, 5077.619178561604, 5169.715665638389, 5170.224466330043, 5171.896562068757, 5181.989512045153, 5109.182530795074, 5161.922571161417, 5188.0920838853235, 5194.71429071401, 5201.90759940411, 5130.846074617749, 5197.428282888879, 5201.271821944931, 5206.323177986837, 5239.449857764918, 4946.72792996385, 5109.412417571953, 5115.386153661584, 5118.718470484122, 5177.378714554277, 5493.835184944458, 5508.504234618904, 5515.09001014832, 5525.281713377275, 5526.972365138189, 6051.757929682807, 6171.87656246877, 6176.135341052256, 6185.690715464209, 6203.0, 6144.033909149074, 6167.7532898684, 6183.7835675751585, 6256.1866957295715, 6287.717052732638, 5761.683107429089, 5812.593118481708, 5847.8245652630485, 5849.590528662995, 5851.268591426072, 5736.541076713853, 6095.262850087704, 6164.260288765287, 6179.938200618026, 6300.475799018527, 6113.755449782047, 6121.47967422774, 6122.877542449215, 6130.795626845448, 6132.110843927606, 6230.813075607739, 6269.62382257066, 6275.96862015687, 6285.2457705075685, 6298.401651843067, 6071.757129714851, 6118.388161183846, 6129.509639228851, 6191.504679625619, 6212.192414986023, 6090.592334494773, 6121.056693972082, 6125.052321061979, 6133.395871484143, 6173.567850250529, 4828.903421931612, 4841.491643377394, 4842.055799119138, 4876.853694389174, 4878.609711223094, 4334.934975975363, 4348.9565104349185, 4402.823887044547, 4405.691601587858, 4496.820127194941, 5618.52242559387, 5629.831105066854, 5636.718164091779, 5667.603267771299, 5674.06377947634, 6200.720967556471, 6213.378662133841, 6231.470325022424, 6235.812925612239, 6238.469730072995, 5532.30846144232, 5596.1605759136455, 5648.406917273709, 5651.463111004448, 5722.113072473773, 5276.5251127398715, 5318.946810531923, 5320.414754377015, 5324.201369794484, 5329.973350133302, 6257.311695713468, 6273.623582584968, 6286.402791734778, 6296.087067375205, 6317.273513546001, 6112.80745551641, 6132.38676132401, 6204.937950620617, 6213.720382582705, 6257.749690012349, 6319.747210111544, 6334.841628959313, 6342.682865856596, 6347.333529979285, 6349.9682501587195, 6098.420650038239, 6106.297775755667, 6110.969445152924, 6115.694215289129, 6118.449339559461, 6053.939460605337, 6137.560853825989, 6225.190725205694, 6253.937460625336, 6279.96860015697, 5936.753281810696, 5984.1921340618455, 5985.102234664835, 6000.669963152069, 6036.7585296589405, 5824.330202026821, 5876.882462350643, 5998.34018258, 6002.69986500682, 6021.939780602138, 5691.800786972436, 5765.596408251382, 5817.418258174149, 5943.108533719977, 5981.970090149695, 5950.434708702666, 5954.612950158225, 5957.285125784933, 5985.401459854154, 6012.9098063529955, 5750.25246717924, 5773.653580785166, 5777.566682498746, 5803.564732645153, 5817.0983497557945], "unit": "score", "name": "dojo.html", "value": 5634.303544824171}, {"lowerIsBetter": false, "replicates": [494.96535242533037, 598.3298705449897, 599.8872092268841, 627.8461776864668, 675.922268939072, 29183.854080729594, 36066.45900311497, 37391.06522336943, 39848.40227396591, 40353.0, 44567.885802854944, 45095.32357014647, 45204.0, 45231.86920326993, 45281.0, 9767.365121267112, 10017.39895606266, 10027.59889604415, 10029.849552256723, 10040.598376064985, 9831.0, 9950.800983980316, 9968.0, 9984.900150998506, 10011.349262297927, 9903.653372131941, 9945.204383649327, 9969.10278074973, 9970.302078854484, 9974.800503989916, 8749.999999999969, 9779.511024448784, 9982.700518984442, 9983.75040623983, 9995.300328976975, 9848.556814943344, 9965.900340996643, 9967.152792012688, 9975.90024099757, 10004.0, 43126.137477250595, 43526.911827204574, 43581.56418435807, 43647.56352436467, 43713.78143109296, 3597.7661452005536, 3598.982005089958, 3604.0269127335737, 3605.9098522536883, 4044.5550989391145, 27355.042573509832, 28228.858855705796, 28658.14025579236, 28830.84676612933, 28937.710622893712, 2785.5543113101808, 2795.3989892173076, 2822.703616120298, 2831.7451429371454, 2886.480433521965, 380995.0, 382613.0, 383280.0, 383677.0, 384419.0779046115, 48273.27590086152, 72944.27055729374, 73253.26746732465, 73276.0, 73299.0, 38579.0, 47726.28410573844, 47748.761256193495, 47793.0, 47822.04355912932, 16837.242324095445, 17048.31806727717, 17056.744148837777, 17109.059001755024, 17173.0, 13624.863751362558, 13734.45062197501, 13747.587572372844, 13749.175049497062, 13759.10565813219, 14053.929730351283, 15350.539483815503, 15435.38258469671, 15685.764713529306, 15778.60553486149, 6672.366125218096, 6753.45972322213, 6808.659567021629, 6817.045613614098, 6820.556663816836, 964.1467301438278, 1013.8834034086027, 1056.4295280548572, 1107.9390633515072, 1134.500819639356, 30598.69401306003, 30914.381712365626, 31032.379352412827, 31335.23628290497, 31524.842375788434, 1073.1361254190451, 1180.1738782852037, 1180.2505409065134, 1238.7832129377502, 1258.5406326690875, 13299.335033248299, 15472.613184670248, 15718.685626287639, 15844.0, 15999.960002599793, 528048.0, 535062.0, 543447.0, 543553.0, 544280.0, 63893.36106638967, 64081.71836563336, 64189.39526511874, 64502.0, 64568.0, 1444.6821699226152, 1562.3203906300796, 1578.3134336563633, 1603.2464741571443, 1605.7430811070406, 30237.848810755804, 30923.15688469502, 31412.214694632818, 31994.68005319917, 32009.0, 51726.0, 51841.481585185174, 51901.480985189664, 52037.47962520326, 52043.73978130085, 345072.0, 346913.0, 347430.0, 348247.0, 348681.0, 28732.0, 34417.82791086029, 34765.826170869994, 34781.65218347784, 34843.9546813596, 42981.710548683586, 45177.54822451865, 50054.0, 51086.74456627693, 52817.471825282795, 37126.25747485089, 37296.44055339226, 38233.617663823, 39015.0, 39218.0, 38980.135610745194, 39173.41239881461, 39250.0, 39296.82109536719, 40090.39864402095, 38661.6133838658, 39110.21779564336, 40001.79999099986, 40054.399184012844, 40109.79945100256, 33833.49249761206, 34180.145496361794, 34183.0, 34650.30699386049, 34712.13219669528, 26193.083242086435, 26287.868560657073, 26391.944322227664, 26486.205413837615, 26548.336291592866], "unit": "score", "name": "ext.html", "value": 19885.867491244793}, {"lowerIsBetter": false, "replicates": [375.9849606015759, 380.20537077508004, 384.02266232438444, 387.73052728353787, 389.8986263571474, 20075.393968482524, 20936.476588085305, 21175.258865939657, 21671.783282167173, 21685.241016564425, 12148.75702485952, 12519.561815336465, 21450.785492145074, 21712.3486295411, 21751.12995480179, 5356.4107948125575, 5503.504684578387, 5554.333479982408, 5563.0, 5563.388027316992, 4199.517055538621, 4285.507166675826, 4386.144701783153, 4665.743384113873, 6818.352256535622, 6588.176477940257, 6749.2913244109395, 6757.628330441824, 6780.796576102724, 6788.762393316235, 6735.797926062226, 6742.325767423267, 6748.6625668716615, 6752.628605426699, 6756.324367563254, 4001.279769641463, 5665.688387138705, 5858.619189752652, 5935.0, 5935.050391937312, 13848.930755346159, 13941.512047078406, 13970.51103211393, 20161.495962600904, 25680.972761089535, 2122.2890331738886, 2183.9344819655435, 2535.29011876673, 2758.4207316463567, 2772.4039331543813, 9281.35030547862, 9757.609695612167, 10139.746506337327, 10215.080642742114, 10349.0, 1709.0941800845571, 1753.833385828357, 1765.2233017472272, 1778.413123669199, 1841.8066103059255, 104900.0, 105730.47134764354, 106564.4671776644, 108976.91023089747, 110938.44530777294, 11435.256708313933, 11736.295822250522, 11775.23460975034, 11792.056635469142, 11895.940520297343, 14529.491467798576, 14539.927300363643, 14669.33987970544, 14682.0, 14682.853171468363, 9084.90915090854, 9093.681721139728, 9150.679726209552, 9160.404573702688, 9221.677241296522, 9427.010163932826, 9429.764255893657, 9430.905690943002, 9466.148046675833, 9472.147506724428, 4551.8634440966825, 4593.747343896051, 4636.003259299267, 4650.441946966318, 4678.695884767547, 1891.1962415973157, 1922.528980399783, 1936.331965471938, 1943.9319623813099, 1945.066368143299, 901.6663834381189, 956.8229877472689, 982.4792859784326, 1048.648702684594, 1067.4182570498942, 21885.562288754136, 21948.89025554862, 22187.001584928716, 22267.554648907255, 22284.888575557343, 1013.7972405518926, 1021.3769600543662, 1055.546115170484, 1106.0211712634377, 1116.9776604467866, 11387.943060284646, 11679.0, 11731.296122232694, 11774.352410617326, 11781.705457363634, 535599.0, 568717.0, 605769.0, 645027.0, 647110.0, 95793.52103239579, 101861.0, 122795.0, 128421.35789320993, 130966.34516827355, 1071.7106381277013, 1077.407425915759, 1088.7659153282082, 1090.5528733219342, 1091.7161538000178, 18606.0, 19140.138693758538, 19505.317313893946, 19509.0, 19804.50488737793, 45802.0, 48328.27507587459, 49400.752996236224, 50259.497405026945, 50483.74758126186, 70213.29786702068, 70230.0, 72253.638731806, 72414.0, 72468.0, 8181.713640022571, 8252.0, 8278.71024514139, 8401.915980840113, 8649.3512986525, 9434.962154162899, 11250.437478126225, 13556.661083472993, 13857.03000789935, 14076.436942522594, 11488.597899073826, 11913.868182522647, 11949.522019119138, 11974.042076633674, 12168.634940951786, 11574.363410012193, 11660.0, 11934.820977685515, 11934.985526230368, 11987.520499180284, 11718.70703232426, 11823.0, 12084.818727719266, 12092.637220883387, 12260.38698065111, 8087.069986951576, 8137.226963438464, 8300.2114799094, 8424.873626895724, 8453.154684531497, 9682.854757178507, 10929.453527323762, 12079.335636540074, 12196.81704774447, 12209.633710988684], "unit": "score", "name": "jquery.html", "value": 9613.430085407834}, {"lowerIsBetter": false, "replicates": [1499.9625009374759, 1658.5770628489736, 1683.3182561062774, 2056.7223424837644, 2124.895054571623, 8180.222878826509, 8726.258268047215, 8903.154200350973, 8920.643174273022, 8923.107689231074, 8623.310135189186, 8882.866756998634, 8896.955515222431, 8913.286937045037, 8929.91070089299, 2266.5920134375806, 2496.425822060927, 2539.0478570536, 2571.2286314105786, 2576.3559110222445, 2226.309843948373, 2230.9330720078424, 2232.475368288451, 2233.620284551622, 2237.8097861681777, 2335.6146235871147, 2340.3447034830274, 2351.8000969917575, 2426.5632186206476, 2431.1126438849783, 2505.7619526144986, 2536.8604726740023, 2546.2615841406155, 2553.3233693071256, 2574.897004119833, 2312.861228326306, 2388.450656349032, 2529.2791554406904, 2560.372708686365, 2567.8459292442512, 8960.910390896073, 9046.185843274072, 9052.140046695553, 9060.592273347715, 9061.954690226507, 5503.2020357048405, 5653.378128405872, 5663.461971112779, 5671.319441666985, 5674.347450043257, 3609.187932715118, 3654.689351405134, 3719.6094410087094, 3730.570984336809, 3731.179140589093, 1060.496264274471, 1075.0700643943012, 1086.0442810326863, 1091.2088735666657, 1113.9498722557505, 10191.949040254825, 12072.0, 12105.939470302592, 12107.213031152947, 12124.696882577919, 4879.56083952446, 4922.50774922512, 4968.90062198754, 4972.55247027762, 5017.096922553902, 10207.234457415723, 10223.233257505717, 10230.23273254509, 10237.48812559369, 10243.743906402251, 5591.4688104629995, 5593.74828132735, 5615.831525054255, 5619.8595035124445, 5639.154126881001, 4602.401687780634, 4632.513586073481, 4691.46048204454, 4702.717836929795, 5070.847874563769, 3924.0, 3944.447777311179, 4029.8112056943273, 4032.1935612876955, 4042.292598795199, 1133.1331531378503, 1329.2489743295007, 1338.464614154346, 1344.9058565900489, 1349.0017387133646, 1220.3166226912956, 1292.883640472362, 1371.4102935737528, 1454.2655958740768, 1457.5991602309239, 8255.133211012877, 8340.332773378115, 8342.916570834335, 8418.873716894252, 8454.61954212062, 1251.5431867368345, 1258.7985922252396, 1265.9810102848464, 1288.4910460368103, 1298.5455090718362, 6290.433860952445, 6315.147455093504, 6420.871582568416, 6435.646039467781, 6438.0, 10740.409277489813, 10929.344239345664, 10951.0, 11059.834102488468, 11068.169887258488, 11041.282316649393, 11084.889151108548, 11091.223614346918, 11096.944515277371, 11112.11103111749, 8436.072032076589, 8484.703035393732, 8508.574571271412, 8593.269572086318, 8615.181557752003, 7981.800454988672, 7993.0, 8045.83908321842, 8061.596920154087, 8063.516189028677, 9605.423674579548, 9709.126178643955, 9796.755081123029, 9860.112589866947, 10043.196544276294, 9887.802243955224, 9971.284531967754, 10084.042016008467, 10109.595616175271, 10322.535485903003, 6814.727410903508, 7550.0, 7619.161892191743, 7654.425918056279, 7668.6549105291515, 6963.7562685305775, 7649.0, 7768.689252429839, 8500.277476414574, 8503.489790612335, 7430.182679905098, 7495.175530691728, 7527.6988920444755, 7593.962030189814, 7629.656665450179, 7294.562326260441, 7318.377937875127, 7343.771788282771, 7416.480846340704, 7424.554526728413, 7882.975213222245, 7940.761777146695, 7968.681252749825, 7972.322352600094, 7982.241687039722, 7188.856222875409, 7215.0, 7252.347288743827, 7255.854882902418, 7259.782206533812, 7052.0479735235085, 7209.206987231296, 7229.819254518679, 7235.457340699363, 7240.96379518099], "unit": "score", "name": "mootools.html", "value": 5042.979399177635}, {"lowerIsBetter": false, "replicates": [334.80581262867537, 338.73747845419797, 344.7776184361088, 344.94653328734057, 350.2749308930513, 6740.561863478876, 6753.054572359867, 6775.83060423489, 6781.966090169542, 6827.692753826077, 4870.439899411569, 6087.847803804907, 6126.846828829282, 6371.6300995286, 6697.765578204764, 4698.882527936812, 4816.277558366238, 4821.879453013667, 4909.214525675892, 4952.504749525046, 3999.3800960850967, 4018.276710192164, 4869.561739443449, 4899.485554016813, 4918.606511479073, 3966.3852102924093, 4857.781399837016, 4874.878128046792, 4879.121758083544, 4885.364902562662, 4653.092646933849, 4765.3328534005095, 4859.951400485986, 4859.975700121477, 4867.172580661296, 4186.476690413699, 4207.242696314662, 4215.32554791235, 4217.641500472463, 4219.936700949488, 6345.714442850082, 6349.52378571609, 6439.195100612424, 6447.935520644827, 6464.353564643545, 5075.619328550373, 5870.236869206976, 5885.352611212763, 5889.499392551638, 5911.994960856665, 6767.898481522781, 6860.5197636165485, 7075.2570980047385, 7106.502544821865, 7115.359617634438, 3937.5668676445566, 4746.715197088186, 4780.498047704976, 4786.157329428195, 4797.664163508556, 5646.0, 6631.192385520507, 7069.116360454943, 7371.963140184318, 7581.658825352873, 4020.7386519876113, 4870.975645121752, 4878.048780487842, 4879.975600122048, 4880.950595621887, 4960.156773348539, 4970.15507363749, 4971.37857767779, 4971.676841005323, 4978.153713868651, 5872.354041055481, 5875.618084824473, 5889.646621202656, 5895.469407753323, 5905.966455870205, 6832.897506537406, 7154.964225178945, 7170.749023784142, 7178.928210717931, 7180.389666878374, 1684.4525529203058, 1725.9827401726072, 1730.1608719770902, 1732.1685590916431, 1732.6101627134046, 952.2477242978108, 1036.8237399642078, 1075.5536452372178, 1104.6465131157952, 1114.3870871020906, 6415.647139407284, 6505.41451269388, 6527.184101987252, 6537.215534135839, 6672.065910772496, 7535.849283014309, 7537.434692398092, 7539.359154471931, 7556.319931206218, 7556.811079723051, 6698.296678848748, 6712.026756120335, 6726.39462448382, 6742.9662851685425, 6748.662566871637, 3801.125741079567, 3914.980425097913, 3946.2304850553865, 3954.189391174802, 3954.5649978502342, 7095.609741464269, 7763.767086987399, 7795.725795166044, 7800.258975397442, 7811.960940195377, 7645.732399366107, 7701.922980770232, 7716.8456630868195, 7741.148473667892, 7744.0319960005, 7773.823329467758, 7774.2614451627005, 7798.415118866108, 7801.648925798239, 7813.335866451415, 7129.536580122275, 7469.365103966006, 7483.363914067365, 7494.925050749423, 7496.0, 6000.729967151401, 7207.423406127392, 7289.781306560812, 7300.780976570712, 7313.158986716383, 7731.304182623591, 7735.18780528059, 7742.187070357757, 7758.0, 7769.0, 4655.3947986761505, 4695.882602934954, 4706.552877476634, 4720.291956206596, 4720.315554244684, 5746.482816546531, 6567.2447668518735, 6898.585789912955, 7317.780466586011, 7392.778216653509, 7097.467689923174, 7128.394086502705, 7138.0363650906465, 7139.643017849191, 7146.785596432115, 7065.894011589728, 7074.045003924404, 7075.610841403773, 7087.751928682472, 7136.250693677089, 6962.547434416747, 6964.199117101395, 6967.338102880219, 6983.44132469391, 6987.7204911802955, 6859.794206173823, 7100.183478899786, 7102.857942841218, 7112.857742845011, 7113.359797618239, 6716.832079197864, 6865.519413641197, 6896.586204827727, 6960.756373526902, 6965.512414131165], "unit": "score", "name": "prototype.html", "value": 5189.314826325303}, {"lowerIsBetter": false, "replicates": [695.6834640238692, 718.7196993172662, 782.4640121516761, 785.171643915669, 798.3294033012269, 15506.0, 15550.144742039183, 15772.921135394336, 15774.684506309866, 15808.367665293403, 14454.132752034886, 14709.11745295283, 14733.557993260192, 14750.262486875667, 14752.409903603842, 822.62159406673, 1009.7728011197458, 1015.1219195396003, 1024.8001639680265, 1025.0723095598496, 803.8311954489565, 805.1827395193883, 809.522381794741, 818.251300060444, 825.9380546459009, 1080.0495563903753, 1081.0324759340385, 1083.263380900987, 1084.322298563398, 1085.7177133945193, 1069.1660504806264, 1071.276888100533, 1074.8656417947757, 1078.1751959750798, 1078.811208038594, 636.9331220221902, 656.6093174561177, 788.6569342336102, 791.3392317414908, 801.6272433318477, 15505.844941550553, 15512.0, 15540.222988850512, 15561.066336019874, 15569.987950783163, 11069.612563560317, 12605.820369300669, 13094.541691040768, 13368.0, 13601.931990340085, 10277.229207809443, 10389.0, 10565.683029509128, 10578.312409693346, 10653.84019239712, 3543.0, 3559.5906470755936, 3625.9818700906326, 3676.246369494274, 3822.5412950446125, 82837.58581207116, 83009.58495207546, 83993.16006839914, 84290.57854710749, 85076.0, 9835.262355323237, 9885.483411825287, 9943.552540135712, 10050.748731281776, 10272.897271027194, 23090.769092309198, 23105.653415198787, 23130.421739456648, 23146.189883354004, 23167.53664926692, 7602.543847369176, 7709.498882572615, 7847.489913155627, 7864.4888082274465, 7913.762587122395, 4199.076203235283, 4211.241976444208, 4234.915301693949, 4239.321708526621, 4263.275243208662, 3783.375743002429, 3791.810409479515, 3797.0, 3823.9044023899623, 3824.0057585028094, 652.9477641788645, 657.3557913245047, 660.207750699163, 669.0866966590569, 674.956127851688, 523.7512181713655, 594.46201187925, 599.5293694449923, 611.541343992006, 657.3459407889085, 8250.38122140842, 8436.53599052058, 8535.274501667303, 8674.39279250459, 8745.562721863882, 586.9413058694182, 593.7179839576253, 614.6926536731634, 615.286267929209, 621.9582199815292, 3379.9662003380145, 3886.689064874803, 3986.7807270599815, 3987.760734355948, 4003.7597744134864, 87786.0, 88380.0, 89178.0, 89607.55196224108, 92129.07870921338, 82690.0, 83530.58234708788, 83609.58195209107, 84726.0, 85598.86002849978, 63792.36207637957, 66416.0, 68227.63544729077, 71914.64042679858, 73606.0, 7954.602269886367, 7982.560959147303, 8116.715914942949, 8195.918040819515, 8287.212714792086, 34308.65691343055, 51850.0, 52213.47786522086, 52816.151434699605, 53587.0, 63760.0, 67904.32095679178, 69119.65440172768, 69328.30671693219, 69767.0, 10294.845577316497, 10539.57841686324, 10625.840612390666, 10725.839112413163, 10898.583184186005, 10876.247127751663, 11046.226764126755, 11447.084233261485, 11575.42122893835, 11703.941480292544, 10684.62603808894, 10733.248672592843, 10842.891571084188, 10952.178586606196, 10967.780644386909, 10789.460526973462, 10856.34861908288, 10919.017288444078, 10978.945105274743, 11237.606683766357, 10020.09819116283, 10466.110380617733, 10466.372017678963, 10501.422421766876, 10565.418901960466, 8765.737027889174, 9002.234810040958, 9008.774780630536, 9166.312526560669, 9246.768830779016, 9065.184133428023, 9197.126273004054, 9215.717372772086, 9229.123233292827, 9357.20463760588], "unit": "score", "name": "yui.html", "value": 7023.5683357879125}], "name": "dromaeo_css", "value": 7634.409158205437}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 109.0, 110.0, 110.0, 112.0, 112.0, 118.0, 110.0, 109.0, 109.0], "unit": "score", "name": "ai-astar", "value": 111.0}, {"lowerIsBetter": true, "replicates": [157.0, 119.0, 157.0, 173.0, 151.0, 170.0, 115.0, 149.0, 151.0, 121.0], "unit": "score", "name": "audio-beat-detection", "value": 146.3}, {"lowerIsBetter": true, "replicates": [186.0, 183.0, 184.0, 188.0, 184.0, 182.0, 182.0, 183.0, 182.0, 185.0], "unit": "score", "name": "audio-dft", "value": 183.9}, {"lowerIsBetter": true, "replicates": [74.0, 72.0, 108.0, 116.0, 56.0, 57.0, 57.0, 58.0, 140.0, 84.0], "unit": "score", "name": "audio-fft", "value": 82.2}, {"lowerIsBetter": true, "replicates": [91.0, 95.0, 140.0, 65.0, 77.0, 114.0, 156.0, 116.0, 99.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 107.5}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 89.0, 85.0, 88.0, 84.0, 88.0, 85.0, 88.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 93.0, 92.0, 95.0, 92.0, 92.0, 93.0, 92.0, 96.0], "unit": "score", "name": "imaging-darkroom", "value": 92.9}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 84.0, 84.0, 81.0], "unit": "score", "name": "imaging-desaturate", "value": 84.5}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 59.0, 59.0, 58.0, 57.0, 59.0, 66.0, 52.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 66.0, 70.0, 73.0, 69.0, 67.0, 66.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.9}, {"lowerIsBetter": true, "replicates": [74.0, 78.0, 81.0, 81.0, 81.0, 80.0, 80.0, 77.0, 81.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.9}, {"lowerIsBetter": true, "replicates": [121.0, 132.0, 119.0, 125.0, 127.0, 133.0, 128.0, 123.0, 116.0, 123.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.7}, {"lowerIsBetter": true, "replicates": [135.0, 124.0, 134.0, 135.0, 135.0, 123.0, 126.0, 158.0, 135.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.0}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 67.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1423.1000000000001}]} 04:19:29 INFO - Return code: 0 04:19:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:19:29 INFO - # TBPL SUCCESS # 04:19:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:29 INFO - 2016-04-20 04:19:29.102 screenresolution[1675:9589] starting screenresolution argv=screenresolution get 04:19:29 INFO - 2016-04-20 04:19:29.104 screenresolution[1675:9589] Display 0: 1600x1200x32@60 04:19:29 INFO - 2016-04-20 04:19:29.110 screenresolution[1676:9592] starting screenresolution argv=screenresolution list 04:19:29 INFO - Available Modes on Display 0 04:19:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:29 INFO - Intel Iris: 04:19:29 INFO - Chipset Model: Intel Iris 04:19:29 INFO - Type: GPU 04:19:29 INFO - Bus: Built-In 04:19:29 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:29 INFO - Vendor: Intel (0x8086) 04:19:29 INFO - Device ID: 0x0a2e 04:19:29 INFO - Revision ID: 0x0009 04:19:29 INFO - Displays: 04:19:29 INFO - Display: 04:19:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:29 INFO - Display Serial Number: 1600x1200 60 04:19:29 INFO - Main Display: Yes 04:19:29 INFO - Mirror: Off 04:19:29 INFO - Online: Yes 04:19:29 INFO - Rotation: Supported 04:19:29 INFO - Return code: 0 04:19:29 INFO - Running post-action listener: _resource_record_post_action 04:19:29 INFO - Running post-run listener: _resource_record_post_run 04:19:30 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 37742080; Write bytes: 664115200; Read time: 390; Write time: 1907 04:19:30 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358601216; Write bytes: 354256384; Read time: 27993; Write time: 661 04:19:30 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 35927552; Write bytes: 298680832; Read time: 371; Write time: 1211 04:19:30 INFO - Running post-run listener: _upload_blobber_files 04:19:30 INFO - Blob upload gear active. 04:19:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:19:30 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 04:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:19:30 INFO - (blobuploader) - INFO - Open directory for files ... 04:19:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:19:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:19:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:32 INFO - (blobuploader) - INFO - Done attempting. 04:19:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:19:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:19:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:33 INFO - (blobuploader) - INFO - Done attempting. 04:19:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:19:33 INFO - Return code: 0 04:19:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:19:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:19:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c2049a3bec5332f5aa470cacc631fabe01a7ecdcb04aab8d09085ec8cfda7b888e4375b1e1328ac6fdca98282fdf9e0d27bab20df66b4e87f67c7f35479bcf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e124c502bbd4394656b153d5a5d63980d063a7be9e7e1d66c51917dd8bd5e09a16b1985b5d1afdbc8f726306ed2c51c15f81e08e97082effd556a2c87fb1c262"} 04:19:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:19:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:19:33 INFO - Contents: 04:19:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c2049a3bec5332f5aa470cacc631fabe01a7ecdcb04aab8d09085ec8cfda7b888e4375b1e1328ac6fdca98282fdf9e0d27bab20df66b4e87f67c7f35479bcf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e124c502bbd4394656b153d5a5d63980d063a7be9e7e1d66c51917dd8bd5e09a16b1985b5d1afdbc8f726306ed2c51c15f81e08e97082effd556a2c87fb1c262"} 04:19:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:19:33 INFO - Copying logs to upload dir... 04:19:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:19:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.621805 ========= master_lag: 2.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-04-20 04:19:35.500583) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-20 04:19:35.505080) ========= 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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c2049a3bec5332f5aa470cacc631fabe01a7ecdcb04aab8d09085ec8cfda7b888e4375b1e1328ac6fdca98282fdf9e0d27bab20df66b4e87f67c7f35479bcf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e124c502bbd4394656b153d5a5d63980d063a7be9e7e1d66c51917dd8bd5e09a16b1985b5d1afdbc8f726306ed2c51c15f81e08e97082effd556a2c87fb1c262"} build_url:https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010873 build_url: 'https://queue.taskcluster.net/v1/task/ZlsDm4AeT3KXL29q87sd7w/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c2049a3bec5332f5aa470cacc631fabe01a7ecdcb04aab8d09085ec8cfda7b888e4375b1e1328ac6fdca98282fdf9e0d27bab20df66b4e87f67c7f35479bcf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e124c502bbd4394656b153d5a5d63980d063a7be9e7e1d66c51917dd8bd5e09a16b1985b5d1afdbc8f726306ed2c51c15f81e08e97082effd556a2c87fb1c262"}' ========= master_lag: 6.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-20 04:19:41.599247) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:19:41.599655) ========= 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.kXIpfL2WCQ/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.idTw6K0VE6/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005089 ========= master_lag: 0.88 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 04:19:42.484154) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 04:19:42.484482) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 04:19:42.523460) ========= ========= Total master_lag: 9.23 =========