builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0072 starttime: 1461281811.09 results: success (0) buildid: 20160421144315 builduid: b44aac0a02b642e6bb4d839693f363c8 revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.094048) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.094469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.094754) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.147333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.147747) ========= 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.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-21 16:36:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 72.8M=0s 2016-04-21 16:36:51 (72.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134182 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.314589) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.319992) ========= 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.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.027105 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:51.379088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 16:36:51.379431) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0891f0fa044cba28024849803e170ed7700e01e0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0891f0fa044cba28024849803e170ed7700e01e0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-21 16:36:51,459 truncating revision to first 12 chars 2016-04-21 16:36:51,459 Setting DEBUG logging. 2016-04-21 16:36:51,459 attempt 1/10 2016-04-21 16:36:51,459 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0891f0fa044c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 16:36:52,526 unpacking tar archive at: mozilla-central-0891f0fa044c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.309651 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 16:36:52.717346) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:52.717639) ========= script_repo_revision: 0891f0fa044cba28024849803e170ed7700e01e0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 16:36:52.718014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 6 secs) (at 2016-04-21 16:36:52.718277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 6 secs) (at 2016-04-21 16:36:59.256353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-21 16:36:59.256689) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:36:59 INFO - MultiFileLogger online at 20160421 16:36:59 in /builds/slave/test 16:36:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:36:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:36:59 INFO - {'append_to_log': False, 16:36:59 INFO - 'base_work_dir': '/builds/slave/test', 16:36:59 INFO - 'blob_upload_branch': 'Firefox', 16:36:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:36:59 INFO - 'branch': 'Firefox', 16:36:59 INFO - 'buildbot_json_path': 'buildprops.json', 16:36:59 INFO - 'config_files': ('talos/mac_config.py',), 16:36:59 INFO - 'default_actions': ('clobber', 16:36:59 INFO - 'read-buildbot-config', 16:36:59 INFO - 'download-and-extract', 16:36:59 INFO - 'populate-webroot', 16:36:59 INFO - 'create-virtualenv', 16:36:59 INFO - 'install', 16:36:59 INFO - 'run-tests'), 16:36:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:36:59 INFO - 'download_minidump_stackwalk': True, 16:36:59 INFO - 'download_symbols': 'ondemand', 16:36:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:36:59 INFO - 'tooltool.py': '/tools/tooltool.py', 16:36:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:36:59 INFO - '/tools/misc-python/virtualenv.py')}, 16:36:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:36:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:36:59 INFO - 'installer_path': 'installer.exe', 16:36:59 INFO - 'log_level': 'info', 16:36:59 INFO - 'log_name': 'talos', 16:36:59 INFO - 'log_to_console': True, 16:36:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:36:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:36:59 INFO - 'opt_config_files': (), 16:36:59 INFO - 'pip_index': False, 16:36:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:36:59 INFO - 'cmd': ('bash', 16:36:59 INFO - '-c', 16:36:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:36:59 INFO - 'enabled': True, 16:36:59 INFO - 'halt_on_failure': False, 16:36:59 INFO - 'name': 'check_screen_resolution'},), 16:36:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:36:59 INFO - 'cmd': ('bash', 16:36:59 INFO - '-c', 16:36:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:36:59 INFO - 'enabled': True, 16:36:59 INFO - 'halt_on_failure': False, 16:36:59 INFO - 'name': 'check_screen_resolution'},), 16:36:59 INFO - 'run_cmd_checks_enabled': True, 16:36:59 INFO - 'sps_profile': False, 16:36:59 INFO - 'sps_profile_interval': 0, 16:36:59 INFO - 'suite': 'dromaeojs', 16:36:59 INFO - 'system_bits': '32', 16:36:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:36:59 INFO - 'title': 't-yosemite-r7-0072', 16:36:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:36:59 INFO - 'use_talos_json': True, 16:36:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:36:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:36:59 INFO - 'work_dir': 'build'} 16:36:59 INFO - ##### 16:36:59 INFO - ##### Running clobber step. 16:36:59 INFO - ##### 16:36:59 INFO - Running pre-action listener: _resource_record_pre_action 16:36:59 INFO - Running main action method: clobber 16:36:59 INFO - rmtree: /builds/slave/test/build 16:36:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:37:00 INFO - Running post-action listener: _resource_record_post_action 16:37:00 INFO - ##### 16:37:00 INFO - ##### Running read-buildbot-config step. 16:37:00 INFO - ##### 16:37:00 INFO - Running pre-action listener: _resource_record_pre_action 16:37:00 INFO - Running main action method: read_buildbot_config 16:37:00 INFO - Using buildbot properties: 16:37:00 INFO - { 16:37:00 INFO - "project": "", 16:37:00 INFO - "product": "firefox", 16:37:00 INFO - "script_repo_revision": "production", 16:37:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:37:00 INFO - "repository": "", 16:37:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:37:00 INFO - "buildid": "20160421144315", 16:37:00 INFO - "pgo_build": "False", 16:37:00 INFO - "basedir": "/builds/slave/test", 16:37:00 INFO - "buildnumber": 223, 16:37:00 INFO - "slavename": "t-yosemite-r7-0072", 16:37:00 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:37:00 INFO - "platform": "yosemite_r7", 16:37:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:37:00 INFO - "branch": "mozilla-central", 16:37:00 INFO - "repo_path": "mozilla-central", 16:37:00 INFO - "revision": "0891f0fa044cba28024849803e170ed7700e01e0", 16:37:00 INFO - "stage_platform": "macosx64", 16:37:00 INFO - "builduid": "b44aac0a02b642e6bb4d839693f363c8", 16:37:00 INFO - "slavebuilddir": "test" 16:37:00 INFO - } 16:37:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 16:37:00 INFO - Running post-action listener: _resource_record_post_action 16:37:00 INFO - ##### 16:37:00 INFO - ##### Running download-and-extract step. 16:37:00 INFO - ##### 16:37:00 INFO - Running pre-action listener: _resource_record_pre_action 16:37:00 INFO - Running main action method: download_and_extract 16:37:00 INFO - mkdir: /builds/slave/test/build/tests 16:37:00 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')]} 16:37:00 INFO - https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:37:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:37:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:37:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:37:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 16:37:02 INFO - Downloaded 1199 bytes. 16:37:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:37:02 INFO - Using the following test package requirements: 16:37:02 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:37:02 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 16:37:02 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 16:37:02 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'jsshell-mac.zip'], 16:37:02 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 16:37:02 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:37:02 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 16:37:02 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 16:37:02 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 16:37:02 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:37:02 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 16:37:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:37: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')]} 16:37:02 INFO - https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:03 INFO - Downloaded 18105721 bytes. 16:37:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:06 INFO - Return code: 0 16:37:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:37:06 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')]} 16:37:06 INFO - https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:08 INFO - Downloaded 18105721 bytes. 16:37:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:12 INFO - Return code: 0 16:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:12 INFO - https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:37:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:37:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:37:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:37:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:37:13 INFO - Downloaded 10845952 bytes. 16:37:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:37:14 INFO - Return code: 0 16:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:14 INFO - https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:37:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:37:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:37:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 16:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 16:37:17 INFO - Downloaded 110044448 bytes. 16:37:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:37:17 INFO - mkdir: /builds/slave/test/properties 16:37:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:37:17 INFO - Writing to file /builds/slave/test/properties/build_url 16:37:17 INFO - Contents: 16:37:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:37:18 INFO - Running post-action listener: _resource_record_post_action 16:37:18 INFO - Running post-action listener: set_extra_try_arguments 16:37:18 INFO - ##### 16:37:18 INFO - ##### Running populate-webroot step. 16:37:18 INFO - ##### 16:37:18 INFO - Running pre-action listener: _resource_record_pre_action 16:37:18 INFO - Running main action method: populate_webroot 16:37:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:37:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:37:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:37:18 INFO - '--tppagecycles', 16:37:18 INFO - '7'], 16:37:18 INFO - 'tests': ['tsvgm']}}, 16:37:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:37:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:37:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:37:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:37:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['damp', 'tps']}, 16:37:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'tests': ['damp', 'tps']}, 16:37:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['dromaeo_dom']}, 16:37:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:37:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'other-e10s': {'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['a11yr', 16:37:18 INFO - 'ts_paint', 16:37:18 INFO - 'tpaint', 16:37:18 INFO - 'sessionrestore', 16:37:18 INFO - 'sessionrestore_no_auto_restore', 16:37:18 INFO - 'tabpaint']}, 16:37:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['tsvgx', 16:37:18 INFO - 'tsvgr_opacity', 16:37:18 INFO - 'tart', 16:37:18 INFO - 'tscrollx', 16:37:18 INFO - 'cart']}, 16:37:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:37:18 INFO - 'tsvgr_opacity', 16:37:18 INFO - 'tart', 16:37:18 INFO - 'tscrollx', 16:37:18 INFO - 'cart']}, 16:37:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'talos_options': ['--disable-e10s'], 16:37:18 INFO - 'tests': ['tp5o']}, 16:37:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'tests': ['tp5o']}, 16:37:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'talos_options': ['--disable-e10s', 16:37:18 INFO - '--xperf_path', 16:37:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:18 INFO - 'tests': ['tp5n']}, 16:37:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:18 INFO - 'talos_options': ['--xperf_path', 16:37:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:18 INFO - 'tests': ['tp5n']}}, 16:37:18 INFO - 'talos.zip': {'path': '', 16:37:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:37:18 INFO - Running post-action listener: _resource_record_post_action 16:37:18 INFO - ##### 16:37:18 INFO - ##### Running create-virtualenv step. 16:37:18 INFO - ##### 16:37:18 INFO - Running pre-action listener: _resource_record_pre_action 16:37:18 INFO - Running main action method: create_virtualenv 16:37:18 INFO - Creating virtualenv /builds/slave/test/build/venv 16:37:18 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 16:37:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:37:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:37:18 INFO - Using real prefix '/tools/python27' 16:37:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:37:19 INFO - Installing distribute.............................................................................................................................................................................................done. 16:37:22 INFO - Installing pip.................done. 16:37:22 INFO - Return code: 0 16:37:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:22 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:22 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 16:37:22 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 16:37:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:22 INFO - 'HOME': '/Users/cltbld', 16:37:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:22 INFO - 'LOGNAME': 'cltbld', 16:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:22 INFO - 'MOZ_NO_REMOTE': '1', 16:37:22 INFO - 'NO_EM_RESTART': '1', 16:37:22 INFO - 'PAGER': '/bin/cat', 16:37:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:22 INFO - 'PWD': '/builds/slave/test', 16:37:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:22 INFO - 'SHELL': '/bin/bash', 16:37:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:22 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:22 INFO - 'USER': 'cltbld', 16:37:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:22 INFO - 'XPC_FLAGS': '0x0', 16:37:22 INFO - 'XPC_SERVICE_NAME': '0', 16:37:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:22 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 16:37:22 INFO - Cleaning up... 16:37:22 INFO - Return code: 0 16:37:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:22 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:22 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 16:37:22 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 16:37:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:22 INFO - 'HOME': '/Users/cltbld', 16:37:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:22 INFO - 'LOGNAME': 'cltbld', 16:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:22 INFO - 'MOZ_NO_REMOTE': '1', 16:37:22 INFO - 'NO_EM_RESTART': '1', 16:37:22 INFO - 'PAGER': '/bin/cat', 16:37:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:22 INFO - 'PWD': '/builds/slave/test', 16:37:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:22 INFO - 'SHELL': '/bin/bash', 16:37:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:22 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:22 INFO - 'USER': 'cltbld', 16:37:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:22 INFO - 'XPC_FLAGS': '0x0', 16:37:22 INFO - 'XPC_SERVICE_NAME': '0', 16:37:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:22 INFO - Downloading/unpacking psutil>=0.7.1 16:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:25 INFO - Installing collected packages: psutil 16:37:25 INFO - Running setup.py install for psutil 16:37:25 INFO - building 'psutil._psutil_osx' extension 16:37:25 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 16:37:25 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 16:37:25 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 16:37:26 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 16:37:26 INFO - building 'psutil._psutil_posix' extension 16:37:26 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 16:37:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:37:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:37:26 INFO - ^ 16:37:26 INFO - 1 warning generated. 16:37:26 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 16:37:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:26 INFO - Successfully installed psutil 16:37:26 INFO - Cleaning up... 16:37:26 INFO - Return code: 0 16:37:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:37:26 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')]} 16:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:26 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')]} 16:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:26 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:26 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 16:37:26 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 16:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:26 INFO - 'HOME': '/Users/cltbld', 16:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:26 INFO - 'LOGNAME': 'cltbld', 16:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:26 INFO - 'MOZ_NO_REMOTE': '1', 16:37:26 INFO - 'NO_EM_RESTART': '1', 16:37:26 INFO - 'PAGER': '/bin/cat', 16:37:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:26 INFO - 'PWD': '/builds/slave/test', 16:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:26 INFO - 'SHELL': '/bin/bash', 16:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:26 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:26 INFO - 'USER': 'cltbld', 16:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:26 INFO - 'XPC_FLAGS': '0x0', 16:37:26 INFO - 'XPC_SERVICE_NAME': '0', 16:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:37:26 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 16:37:26 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 16:37:26 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 16:37:26 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 16:37:26 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 16:37:26 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 16:37:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:37:29 INFO - Installing collected packages: mozsystemmonitor 16:37:29 INFO - Running setup.py install for mozsystemmonitor 16:37:29 INFO - Successfully installed mozsystemmonitor 16:37:29 INFO - Cleaning up... 16:37:29 INFO - Return code: 0 16:37:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:30 INFO - 'HOME': '/Users/cltbld', 16:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:30 INFO - 'LOGNAME': 'cltbld', 16:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:30 INFO - 'MOZ_NO_REMOTE': '1', 16:37:30 INFO - 'NO_EM_RESTART': '1', 16:37:30 INFO - 'PAGER': '/bin/cat', 16:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:30 INFO - 'PWD': '/builds/slave/test', 16:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:30 INFO - 'SHELL': '/bin/bash', 16:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:30 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:30 INFO - 'USER': 'cltbld', 16:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:30 INFO - 'XPC_FLAGS': '0x0', 16:37:30 INFO - 'XPC_SERVICE_NAME': '0', 16:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:30 INFO - Downloading/unpacking blobuploader==1.2.4 16:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:33 INFO - Downloading blobuploader-1.2.4.tar.gz 16:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:37:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:37:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:34 INFO - Downloading docopt-0.6.1.tar.gz 16:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:37:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:37:34 INFO - Running setup.py install for blobuploader 16:37:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:37:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:37:34 INFO - Running setup.py install for requests 16:37:35 INFO - Running setup.py install for docopt 16:37:35 INFO - Successfully installed blobuploader requests docopt 16:37:35 INFO - Cleaning up... 16:37:35 INFO - Return code: 0 16:37:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:35 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 16:37:35 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 16:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:35 INFO - 'HOME': '/Users/cltbld', 16:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:35 INFO - 'LOGNAME': 'cltbld', 16:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:35 INFO - 'MOZ_NO_REMOTE': '1', 16:37:35 INFO - 'NO_EM_RESTART': '1', 16:37:35 INFO - 'PAGER': '/bin/cat', 16:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:35 INFO - 'PWD': '/builds/slave/test', 16:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:35 INFO - 'SHELL': '/bin/bash', 16:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:35 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:35 INFO - 'USER': 'cltbld', 16:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:35 INFO - 'XPC_FLAGS': '0x0', 16:37:35 INFO - 'XPC_SERVICE_NAME': '0', 16:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GkX7TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-IXcIv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-qxDXuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:35 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-kyZPRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-9va3Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-d15jZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ZTndA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-XDEljT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-O8slWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tRfRNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-a3lOt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-O8eZJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:36 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-2lNdTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:37 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-9DZzO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:37 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-rYHCKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:37 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-B3fwC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:37 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-M7MRFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:37:37 INFO - Running setup.py install for manifestparser 16:37:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:37:37 INFO - Running setup.py install for mozcrash 16:37:37 INFO - Running setup.py install for mozdebug 16:37:37 INFO - Running setup.py install for mozdevice 16:37:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Running setup.py install for mozfile 16:37:38 INFO - Running setup.py install for mozhttpd 16:37:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Running setup.py install for mozinfo 16:37:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Running setup.py install for mozInstall 16:37:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Running setup.py install for mozleak 16:37:38 INFO - Running setup.py install for mozlog 16:37:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:37:38 INFO - Running setup.py install for moznetwork 16:37:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:37:39 INFO - Running setup.py install for mozprocess 16:37:39 INFO - Running setup.py install for mozprofile 16:37:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:37:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:37:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:37:39 INFO - Running setup.py install for mozrunner 16:37:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:37:39 INFO - Running setup.py install for mozscreenshot 16:37:39 INFO - Running setup.py install for moztest 16:37:39 INFO - Running setup.py install for mozversion 16:37:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:37:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:37:40 INFO - Cleaning up... 16:37:40 INFO - Return code: 0 16:37:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:40 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:40 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 16:37:40 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 16:37:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:40 INFO - 'HOME': '/Users/cltbld', 16:37:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:40 INFO - 'LOGNAME': 'cltbld', 16:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:40 INFO - 'MOZ_NO_REMOTE': '1', 16:37:40 INFO - 'NO_EM_RESTART': '1', 16:37:40 INFO - 'PAGER': '/bin/cat', 16:37:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:40 INFO - 'PWD': '/builds/slave/test', 16:37:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:40 INFO - 'SHELL': '/bin/bash', 16:37:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:40 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:40 INFO - 'USER': 'cltbld', 16:37:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:40 INFO - 'XPC_FLAGS': '0x0', 16:37:40 INFO - 'XPC_SERVICE_NAME': '0', 16:37:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ZUIpkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-TrTEix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-z4KxFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ZeZnMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-X95BkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-9gq8pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:40 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)) 16:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:40 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-3XwQih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-rB9_z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-rHmerc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-EC_WNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-cEwxTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Uu9J5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-EZxLAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-QANQAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bRI2nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-uSrRVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:41 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)) 16:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:41 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vHBJlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:42 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)) 16:37:42 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)) 16:37:42 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)) 16:37:42 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)) 16:37:42 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)) 16:37:42 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)) 16:37:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:37:42 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 16:37:42 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 16:37:42 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 16:37:42 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 16:37:42 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 16:37:42 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 16:37:44 INFO - Downloading blessings-1.6.tar.gz 16:37:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:37:44 INFO - Installing collected packages: blessings 16:37:44 INFO - Running setup.py install for blessings 16:37:44 INFO - Successfully installed blessings 16:37:44 INFO - Cleaning up... 16:37:44 INFO - Return code: 0 16:37:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:37:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:45 INFO - Reading from file tmpfile_stdout 16:37:45 INFO - Current package versions: 16:37:45 INFO - blessings == 1.6 16:37:45 INFO - blobuploader == 1.2.4 16:37:45 INFO - docopt == 0.6.1 16:37:45 INFO - manifestparser == 1.1 16:37:45 INFO - mozInstall == 1.12 16:37:45 INFO - mozcrash == 0.17 16:37:45 INFO - mozdebug == 0.1 16:37:45 INFO - mozdevice == 0.48 16:37:45 INFO - mozfile == 1.2 16:37:45 INFO - mozhttpd == 0.7 16:37:45 INFO - mozinfo == 0.9 16:37:45 INFO - mozleak == 0.1 16:37:45 INFO - mozlog == 3.1 16:37:45 INFO - moznetwork == 0.27 16:37:45 INFO - mozprocess == 0.22 16:37:45 INFO - mozprofile == 0.28 16:37:45 INFO - mozrunner == 6.11 16:37:45 INFO - mozscreenshot == 0.1 16:37:45 INFO - mozsystemmonitor == 0.0 16:37:45 INFO - moztest == 0.7 16:37:45 INFO - mozversion == 1.4 16:37:45 INFO - psutil == 3.1.1 16:37:45 INFO - requests == 1.2.3 16:37:45 INFO - wsgiref == 0.1.2 16:37:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:45 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 16:37:45 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 16:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:45 INFO - 'HOME': '/Users/cltbld', 16:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:45 INFO - 'LOGNAME': 'cltbld', 16:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:45 INFO - 'MOZ_NO_REMOTE': '1', 16:37:45 INFO - 'NO_EM_RESTART': '1', 16:37:45 INFO - 'PAGER': '/bin/cat', 16:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:45 INFO - 'PWD': '/builds/slave/test', 16:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:45 INFO - 'SHELL': '/bin/bash', 16:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:45 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:45 INFO - 'USER': 'cltbld', 16:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:45 INFO - 'XPC_FLAGS': '0x0', 16:37:45 INFO - 'XPC_SERVICE_NAME': '0', 16:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 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)) 16:37:45 INFO - Cleaning up... 16:37:45 INFO - Return code: 0 16:37:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:45 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:45 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 16:37:45 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 16:37:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:45 INFO - 'HOME': '/Users/cltbld', 16:37:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:45 INFO - 'LOGNAME': 'cltbld', 16:37:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:45 INFO - 'MOZ_NO_REMOTE': '1', 16:37:45 INFO - 'NO_EM_RESTART': '1', 16:37:45 INFO - 'PAGER': '/bin/cat', 16:37:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:45 INFO - 'PWD': '/builds/slave/test', 16:37:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:45 INFO - 'SHELL': '/bin/bash', 16:37:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:45 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:45 INFO - 'USER': 'cltbld', 16:37:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:45 INFO - 'XPC_FLAGS': '0x0', 16:37:45 INFO - 'XPC_SERVICE_NAME': '0', 16:37:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:45 INFO - Downloading/unpacking jsonschema 16:37:45 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 16:37:45 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 16:37:45 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 16:37:45 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 16:37:45 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 16:37:45 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 16:37:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:37:48 INFO - Downloading/unpacking functools32 (from jsonschema) 16:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:48 INFO - Downloading functools32-3.2.3-2.tar.gz 16:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:37:48 INFO - warning: no files found matching '*.txt' 16:37:48 INFO - no previously-included directories found matching 'build' 16:37:48 INFO - no previously-included directories found matching 'dist' 16:37:48 INFO - no previously-included directories found matching '.git*' 16:37:48 INFO - Installing collected packages: jsonschema, functools32 16:37:48 INFO - Running setup.py install for functools32 16:37:48 INFO - warning: no files found matching '*.txt' 16:37:48 INFO - no previously-included directories found matching 'build' 16:37:48 INFO - no previously-included directories found matching 'dist' 16:37:48 INFO - no previously-included directories found matching '.git*' 16:37:48 INFO - Successfully installed jsonschema functools32 16:37:48 INFO - Cleaning up... 16:37:48 INFO - Return code: 0 16:37:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:37:48 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')]} 16:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:48 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')]} 16:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:48 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 0x109410a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109420420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd52d6e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, '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.Mv6GH7Bycs/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.raeiLECrOD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 16:37:48 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 16:37:48 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 16:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:48 INFO - 'HOME': '/Users/cltbld', 16:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:48 INFO - 'LOGNAME': 'cltbld', 16:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:48 INFO - 'MOZ_NO_REMOTE': '1', 16:37:48 INFO - 'NO_EM_RESTART': '1', 16:37:48 INFO - 'PAGER': '/bin/cat', 16:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:48 INFO - 'PWD': '/builds/slave/test', 16:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:48 INFO - 'SHELL': '/bin/bash', 16:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:37:48 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:48 INFO - 'USER': 'cltbld', 16:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:48 INFO - 'XPC_FLAGS': '0x0', 16:37:48 INFO - 'XPC_SERVICE_NAME': '0', 16:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:49 INFO - Downloading/unpacking flake8 16:37:49 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 16:37:49 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 16:37:49 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 16:37:49 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 16:37:49 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 16:37:49 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 16:37:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:37:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:37:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 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 16:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:37:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:37:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:37:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:37:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:37:52 INFO - no previously-included directories found matching 'docs/_build' 16:37:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:37:53 INFO - Running setup.py install for pep8 16:37:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:37:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:37:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:37:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:37:53 INFO - no previously-included directories found matching 'docs/_build' 16:37:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:37:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:37:53 INFO - Cleaning up... 16:37:53 INFO - Return code: 0 16:37:53 INFO - Running post-action listener: _resource_record_post_action 16:37:53 INFO - Running post-action listener: _start_resource_monitoring 16:37:53 INFO - Starting resource monitoring. 16:37:53 INFO - ##### 16:37:53 INFO - ##### Running install step. 16:37:53 INFO - ##### 16:37:53 INFO - Running pre-action listener: _resource_record_pre_action 16:37:53 INFO - Running main action method: install 16:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:53 INFO - Reading from file tmpfile_stdout 16:37:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:37:53 INFO - Reading from file tmpfile_stdout 16:37:53 INFO - Output received: 16:37:53 INFO - Usage: mozinstall [options] installer 16:37:53 INFO - Options: 16:37:53 INFO - -h, --help show this help message and exit 16:37:53 INFO - -d DEST, --destination=DEST 16:37:53 INFO - Directory to install application into. [default: 16:37:53 INFO - "/builds/slave/test"] 16:37:53 INFO - --app=APP Application being installed. [default: firefox] 16:37:53 INFO - mkdir: /builds/slave/test/build/application 16:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:38:26 INFO - Reading from file tmpfile_stdout 16:38:26 INFO - Output received: 16:38:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:38:26 INFO - Running post-action listener: _resource_record_post_action 16:38:26 INFO - ##### 16:38:26 INFO - ##### Running run-tests step. 16:38:26 INFO - ##### 16:38:26 INFO - Running pre-action listener: _resource_record_pre_action 16:38:26 INFO - Running main action method: run_tests 16:38:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:26 INFO - 2016-04-21 16:38:26.980 screenresolution[1645:7555] starting screenresolution argv=screenresolution get 16:38:26 INFO - 2016-04-21 16:38:26.983 screenresolution[1645:7555] Display 0: 1600x1200x32@60 16:38:26 INFO - 2016-04-21 16:38:26.988 screenresolution[1646:7557] starting screenresolution argv=screenresolution list 16:38:26 INFO - Available Modes on Display 0 16:38:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:27 INFO - Intel Iris: 16:38:27 INFO - Chipset Model: Intel Iris 16:38:27 INFO - Type: GPU 16:38:27 INFO - Bus: Built-In 16:38:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:27 INFO - Vendor: Intel (0x8086) 16:38:27 INFO - Device ID: 0x0a2e 16:38:27 INFO - Revision ID: 0x0009 16:38:27 INFO - Displays: 16:38:27 INFO - Display: 16:38:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:27 INFO - Display Serial Number: 1600x1200 60 16:38:27 INFO - Main Display: Yes 16:38:27 INFO - Mirror: Off 16:38:27 INFO - Online: Yes 16:38:27 INFO - Rotation: Supported 16:38:27 INFO - Return code: 0 16:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:38:27 INFO - Python 2.7.3 16:38:27 INFO - Return code: 0 16:38:27 INFO - grabbing minidump binary from tooltool 16:38:27 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')]} 16:38:27 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 0x1092afe00>, '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 0x1090d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1092aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:38:27 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 16:38:27 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 16:38:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:38:27 INFO - Return code: 0 16:38:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:38:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:38:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:38:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:38:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:38:28 INFO - Return code: 0 16:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 16:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 16:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Mv6GH7Bycs/Render', 16:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:28 INFO - 'HOME': '/Users/cltbld', 16:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:28 INFO - 'LOGNAME': 'cltbld', 16:38:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:38:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:28 INFO - 'MOZ_NO_REMOTE': '1', 16:38:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:38:28 INFO - 'NO_EM_RESTART': '1', 16:38:28 INFO - 'PAGER': '/bin/cat', 16:38:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:28 INFO - 'PWD': '/builds/slave/test', 16:38:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:28 INFO - 'SHELL': '/bin/bash', 16:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.raeiLECrOD/Listeners', 16:38:28 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 16:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:28 INFO - 'USER': 'cltbld', 16:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:28 INFO - 'XPC_FLAGS': '0x0', 16:38:28 INFO - 'XPC_SERVICE_NAME': '0', 16:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 16:38:28 INFO - mozversion application_buildid: 20160421144315 16:38:28 INFO - mozversion application_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 16:38:28 INFO - mozversion application_display_name: Nightly 16:38:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:38:28 INFO - mozversion application_name: Firefox 16:38:28 INFO - mozversion application_remotingname: firefox 16:38:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:38:28 INFO - mozversion application_vendor: Mozilla 16:38:28 INFO - mozversion application_version: 48.0a1 16:38:28 INFO - mozversion platform_buildid: 20160421144315 16:38:28 INFO - mozversion platform_changeset: 0891f0fa044cba28024849803e170ed7700e01e0 16:38:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:38:28 INFO - mozversion platform_version: 48.0a1 16:38:28 INFO - using testdate: 1461281908 16:38:28 INFO - actual date: 1461281908 16:38:28 INFO - starting webserver on 'localhost:49275' 16:38:28 INFO - SUITE-START | Running 2 tests 16:38:28 INFO - TEST-START | dromaeo_css 16:38:28 INFO - operating with platform_type : mac_ 16:38:28 INFO - Initialising browser for dromaeo_css test... 16:38:28 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp2FSHj2/profile http://localhost:49275/getInfo.html) 16:38:30 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 16:38:30 INFO - PROCESS | 1664 | colorDepth:24 16:38:30 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 16:38:30 INFO - PROCESS | 1664 | __metrics 16:38:31 INFO - PROCESS | 1664 | 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" 16:38:31 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:38:31 INFO - TEST-INFO | 1664: exit 0 16:38:31 INFO - Browser initialized. 16:38:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:38:31 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp2FSHj2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:43 INFO - PROCESS | 1665 | RSS: Main: 239104000 16:38:43 INFO - PROCESS | 1665 | 16:39:35 INFO - PROCESS | 1665 | 1461281975121 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] 16:41:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:41:47 INFO - PROCESS | 1665 | RSS: Main: 427180032 16:41:47 INFO - PROCESS | 1665 | 16:44:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:44:50 INFO - PROCESS | 1665 | RSS: Main: 379990016 16:44:50 INFO - PROCESS | 1665 | 16:47:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:47:53 INFO - PROCESS | 1665 | RSS: Main: 375386112 16:47:53 INFO - PROCESS | 1665 | 16:50:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:50:56 INFO - PROCESS | 1665 | RSS: Main: 379035648 16:50:56 INFO - PROCESS | 1665 | 16:53:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:54:00 INFO - PROCESS | 1665 | RSS: Main: 354263040 16:54:00 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:57:03 INFO - PROCESS | 1665 | RSS: Main: 381054976 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | __start_tp_report 16:57:03 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 16:57:03 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 16:57:03 INFO - PROCESS | 1665 | |i|pagename|runs| 16:57:03 INFO - PROCESS | 1665 | |0;dojo.html;2897.6377952755893;3405.506201600768;3446.7242620590355;3474.791512509248;3499.8775042873503;5605.439456054392;5707.172459993309;5754;5765.2216950711645;5766.538676905842;5800;5824.446677565625;5903.4391732785425;5921.259842519685;5946.940530594693;5132.923006154911;5138.254953031817;5167.276581278624;5194.610404219679;5280.735963201843;5294.10000299948;5304.230886521472;5319.867003324909;5328.20076988451;5369.650972686783;5107.872303192413;5128.282040514349;5223.138182199932;5240.475952404767;5240.9213861792105;5117.7185254810975;5171.250168725552;5176.844694659128;5185.455527169631;5187.870303242411;4322.697411181219;5101.668391554537;5162.767675454614;5165.276861239429;5178.689278643294;5635.380108188096;5690.2033715279895;5741.167530708066;5797.739101740432;5837.50381217597;6190.780155991331;6218.31598524162;6224.097505861625;6232.376762323776;6238.87522249557;6165.5992360496875;6224.470919971808;6229.501639868799;6251.093591429263;6251.093591429263;5771;5798.942010579883;5868.295804503444;5897.115432685132;5913.29040515141;6277.8744425111245;6278.215223097113;6283.465905398092;6294.15028971083;6295.244570651551;6163.722632481459;6170.10533472644;6171.7531298748445;6191.597546159485;6192.938070619326;6148.938510614837;6205.720742566596;6307.369263073748;6324.747010119636;6340.9365906340345;6154.261488621394;6200.906986395207;6227.283862355796;6227.844303892439;6257.217847769029;6144.556029333103;6231.750729970841;6290.685465726787;6305.4009869062365;6310.779122730774;4868.41579010521;4874.780634871368;4876.975615121902;4940.2342636891335;4956.256561515802;4409.933850992237;4427.180971520279;4436.44544431946;4444.977775111169;4462.9776851116185;5679.914801277984;5694.288213973254;5696.231008813757;5696.259486266758;5716.628419152742;6129.23653806838;6210.161628180228;6258.311585725566;6269.46709529686;6273.780417685451;4875.804967801319;5731.856703582361;5733.397993210736;5783.652980821164;5785;5367.838964831061;5371.274877891513;5421.945780542223;5439.347278326626;5449.809256675997;6233.314335423009;6339.080833279241;6350.936490635035;6366.840828979312;6375.2987171411105;6216.036514340284;6290.716917738804;6301.873962520815;6318.08387783778;6327.493800496041;6344.048392741126;6349.619022858643;6361.650109244036;6369.394907483782;6376.458001069961;6118.326984031664;6191.4118158774845;6227.252729672468;6240.095186198067;6241.719122639402;6191.102290167991;6278.937210627835;6294.024426213944;6307.085472606354;6316.810495685137;6148.938510614837;6179.258488981351;6188.1027251049245;6190.50475961931;6190.907136393048;5922.763089476372;5972.462478376967;6163.84590385244;6166.506679465721;6179.783707570214;5853.180554722428;5934.347221805511;5935.643861368331;5937.2578427696535;5953.315368732651;5629.380768115586;5642.520385767091;6040.637561746309;6092.086187071975;6113.816585502442;5959.523238141025;5961.821145365646;5963.373845746134;5970.432808883149;5972.70136493165 16:57:03 INFO - PROCESS | 1665 | |1;ext.html;499.6202885806788;507.15052287418564;529.9841004769858;580.1269090019522;612.8050301911271;30804;31002;31508.376536698288;37217.25565488689;38040.47838086473;39847.80076099623;40332.39501407481;40455;40606;40635;8586.441881277728;9436.150746432819;9662.758431039245;9662.903370966305;9690.70927872165;11425.200235983484;11801.822972655416;11816.7636647267;11825.29048257103;11827.112966527502;11527.827082593767;11643;11650.766984660302;11652.533898644044;11662.241954272988;9743.707688769347;11599.710007249801;11599.884001159964;11609.18735688502;11610.6516804496;11335.43322833855;11443;11455.25540839851;11463.140264480196;11470.139739519485;39151;39164;39596.40605390922;39614.801925990185;39626.81119566389;3210.7431405487505;3278.918027049319;3393.1856354474994;3524.524189234472;3737.9813100934593;23331;28038.859805701046;28198.85900570484;28533.578620062122;29040.41919161626;2672.5991101334957;2683.490136873988;2687.6506054213023;2869.9426011479654;2900.318425170083;358283.20858395804;358762;360654.9999999974;360666;361924;57267.71366143226;57650.423495765346;57674.711626441596;57708.422915771145;57796.71101644465;60677.39322606717;60892;60912.69543652253;60941.08588371178;61032.69483652553;14793.186374749277;16187;16388.75416868748;16418.589535261715;16423.507294781175;10962.232643714864;10985.450727463594;11003.779924401628;12722.681932951751;13156.947444204441;15070.698586028218;15111.773323400157;15201.695966080617;15250.923745381202;15307.46423875159;6387.361263873576;6630.071789949411;6684.86630267392;6734.797956061326;6771.4582833373215;919.6045700348914;975.8048390321964;976.6337623391229;1068.6580294305902;1074.7689246811974;27577.58633620497;28163.859180703967;28186.43627127487;28964.855175723987;29091.709082909325;1032.3289861590026;1093.606301731376;1162.627959053112;1182.6570294614467;1241.6337180531764;14531.273436328142;14546.781798273034;14648.267586620626;15004.949653524149;15030.549083527512;553291.2335438378;553336;560463;560523;561078;57696.71151644215;58274.70862645745;58297;58300.70849645809;58335;1119.8824123467082;1381.8341798983986;1455.3523681961426;1466.2448838848002;1466.4720700548007;27410.451790964467;28008.579871302354;28298;28471.28821779472;29055.983040593477;44592.77703611461;45964.77017614891;46135;46149.30776038429;46209.306860397795;409312;425042;425500;426420;426711;31787;35724;37129.62870371262;37696.43455348117;39523.604763953146;40951.59048409597;41879;42023.7898810504;42055.3691694631;42106.789466052476;34732.78435254754;37027.81486092552;37938.43092353562;38039.61960380361;38304;33635.831820840736;34482.137946551535;34559.65440345564;35102.473462897564;35462.46806297959;34108.658913410545;34678.306433870675;34987.30025399529;35019.824900875334;35409.46885796663;29184.854075729487;30590.541141883336;30633.693663063084;31659.84170079135;31735.84132079417;23552.293431197093;24017.00213517482;24059.759402406453;24268.63597046081;24347.878260608584 16:57:03 INFO - PROCESS | 1665 | |2;jquery.html;347.5586005772667;382.8698242597517;385.4507327058941;390.26435169705115;398.219489799992;15526.456574019912;15820;19645;20006.999650017497;20812;15947;19836.404907852753;20013.199472021137;20850.270240541584;21062.262820801276;5566.747481816599;5902.321233058189;6066.120412540179;6127.785527506516;6135.447809697126;5796.710164491758;6065.696715164247;6080.665563394034;6087.878242435148;6095.146679464879;5872.500837428803;5992.640441573498;6003.339632640428;6028.788992385267;6029.969850150743;5786.218860453827;5928.229330187091;5938.61399009063;5955.88088238235;5956.136360227764;4974.079795237856;5184.274201611777;5205.869853253661;5256.81601143962;5261.210818377236;20869.582608347748;25646.871765641237;25727;25903.09339173139;26109.869450652815;2145.7746936571593;2154.709114269569;2160.8487405881597;2172.446026263293;2209.414505156135;14646.853531464656;14899.627509312246;15044.849551504454;15137.940344175911;15326;1501.7522108852027;1703.667784781968;1708.3764425984425;1720.7160818464938;1728.7579738836573;78243;89018;112840.30739538913;115252;121819.3909030458;10697.090747286413;10821.9458902705;11209.159313051554;11285.104437409496;11547.249428787101;13730.176189428666;13903.957203209597;13906.235157066458;13907.443702252;13932.791008134885;8531.488110713377;8708.477491350413;8725.214730674143;8804.647814087493;8830.249428798496;8670.393072484865;8679;8764.342674299325;8830.735077947671;8888.822223555493;6522.706478208492;6634.601923884582;6642.501812364092;6642.601443913284;6642.6014439133805;1639.1722180299112;1660.1118401655056;1825.479738274612;1829.85361171106;1830.6340026080684;923.930705197113;1005.1104772276587;1031.9793604128026;1041.7916416716535;1070.844727514506;17303.30786768515;20155.899220503805;21267.781655953127;21384;21478.57042859134;864.4165188497739;957.4686049242598;988.6589126751403;994.9801003980025;1005.8239808033564;11180.608678696206;11404;11415.915488028624;11416.543338266543;11449.255798373079;506062;573764.522195451;594523;595047;605899;122322;125604.37197813952;127775.36112319565;129004.70995289927;130474;1022.447878145793;1057.7302787789217;1073.9355638661705;1091.028984204059;1094.5809760429381;18782.71825922585;18947.43157705271;18957.620847583246;19096.618067638847;19125.61748765045;47222;48785;49788.502114979834;50347.49652503428;50419.49580504148;69580;70746;72549.27450725425;72635;72852;6521.413072823469;7695.268949449794;8280.00639923213;8282.798994145936;8328.167183281623;11358.26171298863;12270.75458490843;12654.493820247088;12781.16922400041;12942.094053416547;10799.514021868878;10861.294015888941;10979.560817567528;11023.17326200522;11266.605668801552;10690.358578485315;10708.464576770975;10853.511591978222;11180.217384783313;11343.035841953526;10950.616728414467;11345.978861902468;11489.253198542068;11740;11767.588134415255;8049.154838741848;8124.593770311342;8288.875666865122;8313.875291870747;8529.914700852913;10562.15502759762;10691.198160137865;11156.665300041012;11448.427578621202;11487.770244594894 16:57:03 INFO - PROCESS | 1665 | |3;mootools.html;1421.4101148023572;1590.4910428662824;1593.3626549380251;1833.2025568877539;1957.9216831326748;7946.721864734735;8743.300535957123;8946.10538946105;8984.8652270216;8991.865122023166;8514.063453020162;8774.956125219365;8782.25350845179;8794.120587941203;8796.780080497974;2145.6245157097524;2370.2652177824843;2445.792107670846;2455.8280920335583;2479.2810085075307;2442.694663167104;2468.3582268610203;2469.7283298837115;2470.3206618180056;2473.183849329717;2419.322589674885;2453.264020793764;2454.2023842251338;2454.2759885833634;2455.1161581830615;2110.4196346004896;2327.790498855094;2393.6888204533475;2412.6863507743883;2413.5896897527464;2154.9030293636665;2329.091654254842;2392.234484964812;2412.2280870121567;2418.37122348189;8610.78473038179;8664.61009254585;8675.609597568062;8689.956550217272;8696.304295656395;5156.587473002151;5320.813771518017;5332.680039197622;5335.733213339317;5359.1425371940695;3388.4747864081105;3508.1054331145397;3509.929801403983;3550.467429885512;3564.518789963348;1004.9346792458467;1007.6372505897871;1018.9184865210765;1021.7496713305288;1023.6468418395716;11837.822432663603;11858.169928105037;11859.82210266838;11877.16859819813;11939.462724177432;4711.340412342275;4732.296558551449;4800.135975524439;4928.507149285043;4929.7535123244415;10051.69844904654;10155.136813370946;10167.983201679921;10171.542280597243;10171.54228059739;5433.103537916279;5441.755121019563;5456;5479.5342395896;5480.068388373986;4416.3596278539435;4426.756528390905;4564.995700945787;4583.977080114578;4920.926186107211;4034.7377420468165;4075.1238483726142;4082.9387559186634;4120.814563344657;4173.561776013536;1326.4826717580152;1331.17467170355;1336.565616174747;1337.7391408675214;1349.4534713441071;790.4624855098473;831.3183189784439;908.9136532029447;949.5015117063459;957.3633533700012;5854.092615644582;5914.792982245601;6078.6048906820915;6085.36103709113;6162.969185154046;445.1120015568961;458.44070234314086;464.47746285428894;465.44844359434177;465.62284549515;3916.784576848244;3976.76139431635;4006.2988976929505;4085.3871919212356;4114.897127571775;10389.94805025985;10836.29564078348;10901.454927253606;10922.617708380169;10932.945335273273;9768.902310976799;10310.484475776182;10759.677209683565;10852.511636976355;10856.674299771019;7739.419543534257;8123.593820309079;8138.348932085419;8214.219649133323;8233.588320583947;5935.762569497172;5947.197128387612;6023.093675402661;6122.479589234965;6163.8150855474405;9982.101610855058;10113.089821916063;10149.543270552695;10194.745131371776;10322.741931451774;10042.397456152654;10098.242631802497;10445.373277603368;10557.788844223227;10566.207534434794;6998.020277161303;7283.708651654087;7442.106947166375;7693.230676932262;7705.075390953121;8381.413301068867;8390.035145958293;8432.83134337322;8500.234978851686;8549.016863060579;7425.331720144996;7456.291652293024;7480.887786683095;7590.506617069873;7591.772246832604;7292.088488938883;7344.192138864613;7365.705371785069;7544.321011109027;7555.206703296075;7069.6111713856235;7131.750388736369;7150.284971502808;7156.785296441115;7169.892451613126;4762.761861906822;4983.07813054586;5030.874228144326;5044.470330615232;5056.82301119459;4485.273169829705;5009.273655320031;5018.799248030184;5050.873728156826;5270.89458210826 16:57:03 INFO - PROCESS | 1665 | |4;prototype.html;270.5103762190435;315.805779445641;319.75538712884645;323.51795824221904;333.1404975164077;6003.489703375213;6034.87930241396;6038.939610603893;6049.848753781158;6062;5943.246742211048;5967.373425790294;5978.282606087276;5980.61126026808;5985.132155837401;3590.407582748843;4265.146970605883;4436.66724995625;4460.550321145624;4522.660800439964;4416.668749843759;4487.147441986012;4488.1696886076015;4509.887252818673;4519.209138400784;3688.55737311523;4447.243968525342;4492.73043617384;4499.955000450002;4503.887402814923;4178.289690752579;4209.095044565418;4417.779111044435;4442.022754993896;4463.933041004388;3452.7065199458075;3803.9619603803885;3830.984789030909;3832.865849695247;3839.9040023999346;5473.31583552056;5730.34101078373;5733.226014488033;5755.798547050833;5775.545393207912;4544;4903.975480122576;5151.278820965068;5201.713905735183;5224;5207.427183009866;5704.687921778014;5756.913646295309;6203.968980155116;6295.244570651551;3913.923670990472;4079.224947260011;4323.82704691812;4324.416203812477;4344.283193273106;6047.455728984412;6233.719482623293;6418.812519683874;6701.363370479846;6728.831779205559;4210.642095421862;4264.701470897007;4276.3799249109325;4280.4863416390235;4287.378330142112;4196.517400498981;4361.208826351138;4368.344748287782;4377.212101821703;4381.7370957742;4897.094037603055;5089.9745501273;5099.286099946011;5119.950410165907;5125;5318.867028324323;6263.6241825490615;6269.8746025079245;6347.714352854133;6382.585131966407;1323.4706117552864;1438.7410266151983;1465.3918623771015;1492.6939977304626;1496.678214183934;817.5462618246811;921.9351648845594;929.3494553812359;951.7382719752123;971.4025874087386;5750.913736293959;5776.267119864024;5791.884162316815;5834.183214349994;5853.824385268449;5052.898942021213;5723.828285151452;5788.363280039192;6606.768763093269;6644.202695676549;5686.857828554236;5935.910961335583;5950.345461999176;5963.3302675251;5974.462298393166;3146.7482601391835;3174.349258402022;3461.532693086401;3463.930721385558;3467.289205712823;5505.284313039359;6096.793212683235;6478.319776423407;6668.133142691516;6865.897011544831;5421.322334708161;5904.881902361929;6451.967740161269;6801.285864984205;6841.350071743176;5467.617266791286;5657.377688454267;6896.103506544117;6909.792706218822;6927.272636373209;5512.173174023928;5725.599208055396;6540.345965403421;6601.33986601336;6620.337966203341;5792.8124734430185;6030.185924900083;6482.027695845472;6499.5775274607;6509.349065093453;6563.737450501926;6850.938104593796;6885;6887.276835932155;6891;4138.379243113557;4185.514142479376;4407.9118417630825;4411.691181617256;4476.552344765498;5568.387477377566;6350.968245158745;6513.348665133449;6568.704408301734;6574.8356291093105;5400;5478.863028424162;6124.8775024500155;6196.597221180609;6314.336994615499;6274.623522588659;6313.779017734541;6318.494520438446;6336.954402523532;6349.015902535114;5835.503982161564;5934.43622855828;6023.879522409439;6112.266528016667;6130.816075517741;5492.011437941209;6110.847228819315;6195.907061394173;6302.621842689453;6314.210723659543;5106.565941894832;5976.032169447029;6186.566940314315;6192.473639740672;6210.105917695147 16:57:03 INFO - PROCESS | 1665 | |5;yui.html;644.3105876711919;732.5018987088781;764.2968469008518;767.148465203624;795.6857041468617;15013.474528391494;15369.23153842309;15413.768793468107;15414.614634634127;15429;14063.367148478317;14101.2244326562;14126.858731412709;14137.646558836033;15047;881.0308660473476;912.6988093928987;922.4972390047441;923.607466826596;924.0528458330198;873.0832625742944;893.9865902011475;912.9726108216731;915.5010519266982;917.0325306801321;923.3167456082484;954.9045095490465;958.3291695812924;958.7075941837771;960.1694534227878;918.6968300460832;950.9049095090505;951.457669128597;951.9190868776162;953.4422362917702;700.3136925812681;701.4984286235315;702.0802748399612;702.3854127638316;702.6732569355223;14495.347709353104;14960.476383326532;15020.248987550578;15024;15025.248737563188;12213.084018698544;12260.570880019246;12431.689207769788;12472.50109995599;13237.205767653972;10044.19646428284;10226.846597301046;10228.079472847405;10230.539625716861;10442.582296708122;3434.793912365266;3440.518327434161;3505.6844883960566;3509.6841284284537;3577.678008979204;78395.82406263967;78652;78755;78801;79620.60189699015;9309.162175404246;9364.110409511086;9399.812003759887;9632.940376558572;9680.177184939359;20639.380818575468;21203;21203.045862936204;21214.151433942778;21267.46831329198;6750.527463077636;7484.663190156456;7514.4364172687265;7720.613969301512;7851.254130857559;4075.409065685459;4132.855350062793;4135.110951145458;4151.813168407429;4345.565443455693;3490.267043920805;3579.5167652367118;3585.1754096557415;3587.7309201809967;3750.287445385368;662.3674390956625;678.9762358317436;683.9213490448662;684.2199892122984;696.613379574331;515.2863284351125;516.0607693996965;525.5637820608886;543.5108402437837;557.4202829057815;7711.45521977746;7857.567833769198;8006.199380062064;8064.233897779702;8205.179482051866;494.40177385364115;549.3078720811806;550.180231455137;570.626150555992;577.4312302382234;3812.5234345706785;3893.435451859521;3897.7466464680274;3975.0658595230097;4036.1120553478186;93360.33198340055;93921.06078939259;94308;95870;95875.041249588;57136;67459.98810017854;69950.65024674844;79973.60013199897;82008;65193;66832;67037.66481167563;67762.32237677659;68596.41233198461;8051.516908985245;8087.676492940452;8152.918470815216;8218.178182181735;8380.748577542683;36043.45934811032;39005.41491877567;43148.13703725971;43245.567544324156;43417.91455213645;58828.70585647044;59279.70360148171;61140;61385;62007.689961549906;10622.132099066803;10909.672709818718;11016.06363459115;11066.336019838835;11214.943925280648;11904.226225295328;11960.820587691365;12085.214461060355;12413.255204687748;12533.74932501363;11209.15931305139;11402.543898243977;11541.711457213636;11587.073034157078;11885.5840045598;11319.660410187707;11348.489317980546;11440.771184576095;11573.016293615137;11703.946644802008;11236.101111911192;11238.943805280922;11382.430878456209;11465.770684586429;11608.30350178992;9457.290703197425;9572.282078843975;9778.266630002636;9816.24878002868;10036.996300370196;9583.808323833346;9642.95178524131;9670.081342272473;9683;9696.9030309696 16:57:03 INFO - PROCESS | 1665 | __end_tp_report 16:57:03 INFO - PROCESS | 1665 | __start_cc_report 16:57:03 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1855 16:57:03 INFO - PROCESS | 1665 | __end_cc_report 16:57:03 INFO - PROCESS | 1665 | __startTimestamp1461283023715__endTimestamp 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | ------- Summary: start ------- 16:57:03 INFO - PROCESS | 1665 | Number of tests: 6 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5751.56 Median:5953.32 stddev:660.47 (11.1%) stddev-sans-first:623.40 16:57:03 INFO - PROCESS | 1665 | Values: 2897.6 3405.5 3446.7 3474.8 3499.9 5605.4 5707.2 5754.0 5765.2 5766.5 5800.0 5824.4 5903.4 5921.3 5946.9 5132.9 5138.3 5167.3 5194.6 5280.7 5294.1 5304.2 5319.9 5328.2 5369.7 5107.9 5128.3 5223.1 5240.5 5240.9 5117.7 5171.3 5176.8 5185.5 5187.9 4322.7 5101.7 5162.8 5165.3 5178.7 5635.4 5690.2 5741.2 5797.7 5837.5 6190.8 6218.3 6224.1 6232.4 6238.9 6165.6 6224.5 6229.5 6251.1 6251.1 5771.0 5798.9 5868.3 5897.1 5913.3 6277.9 6278.2 6283.5 6294.2 6295.2 6163.7 6170.1 6171.8 6191.6 6192.9 6148.9 6205.7 6307.4 6324.7 6340.9 6154.3 6200.9 6227.3 6227.8 6257.2 6144.6 6231.8 6290.7 6305.4 6310.8 4868.4 4874.8 4877.0 4940.2 4956.3 4409.9 4427.2 4436.4 4445.0 4463.0 5679.9 5694.3 5696.2 5696.3 5716.6 6129.2 6210.2 6258.3 6269.5 6273.8 4875.8 5731.9 5733.4 5783.7 5785.0 5367.8 5371.3 5421.9 5439.3 5449.8 6233.3 6339.1 6350.9 6366.8 6375.3 6216.0 6290.7 6301.9 6318.1 6327.5 6344.0 6349.6 6361.7 6369.4 6376.5 6118.3 6191.4 6227.3 6240.1 6241.7 6191.1 6278.9 6294.0 6307.1 6316.8 6148.9 6179.3 6188.1 6190.5 6190.9 5922.8 5972.5 6163.8 6166.5 6179.8 5853.2 5934.3 5935.6 5937.3 5953.3 5629.4 5642.5 6040.6 6092.1 6113.8 5959.5 5961.8 5963.4 5970.4 5972.7 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:59174.05 Median:34520.90 stddev:120803.56 (349.9%) stddev-sans-first:121062.12 16:57:03 INFO - PROCESS | 1665 | Values: 499.6 507.2 530.0 580.1 612.8 30804.0 31002.0 31508.4 37217.3 38040.5 39847.8 40332.4 40455.0 40606.0 40635.0 8586.4 9436.2 9662.8 9662.9 9690.7 11425.2 11801.8 11816.8 11825.3 11827.1 11527.8 11643.0 11650.8 11652.5 11662.2 9743.7 11599.7 11599.9 11609.2 11610.7 11335.4 11443.0 11455.3 11463.1 11470.1 39151.0 39164.0 39596.4 39614.8 39626.8 3210.7 3278.9 3393.2 3524.5 3738.0 23331.0 28038.9 28198.9 28533.6 29040.4 2672.6 2683.5 2687.7 2869.9 2900.3 358283.2 358762.0 360655.0 360666.0 361924.0 57267.7 57650.4 57674.7 57708.4 57796.7 60677.4 60892.0 60912.7 60941.1 61032.7 14793.2 16187.0 16388.8 16418.6 16423.5 10962.2 10985.5 11003.8 12722.7 13156.9 15070.7 15111.8 15201.7 15250.9 15307.5 6387.4 6630.1 6684.9 6734.8 6771.5 919.6 975.8 976.6 1068.7 1074.8 27577.6 28163.9 28186.4 28964.9 29091.7 1032.3 1093.6 1162.6 1182.7 1241.6 14531.3 14546.8 14648.3 15004.9 15030.5 553291.2 553336.0 560463.0 560523.0 561078.0 57696.7 58274.7 58297.0 58300.7 58335.0 1119.9 1381.8 1455.4 1466.2 1466.5 27410.5 28008.6 28298.0 28471.3 29056.0 44592.8 45964.8 46135.0 46149.3 46209.3 409312.0 425042.0 425500.0 426420.0 426711.0 31787.0 35724.0 37129.6 37696.4 39523.6 40951.6 41879.0 42023.8 42055.4 42106.8 34732.8 37027.8 37938.4 38039.6 38304.0 33635.8 34482.1 34559.7 35102.5 35462.5 34108.7 34678.3 34987.3 35019.8 35409.5 29184.9 30590.5 30633.7 31659.8 31735.8 23552.3 24017.0 24059.8 24268.6 24347.9 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:33752.31 Median:21165.02 stddev:95956.29 (453.4%) stddev-sans-first:96192.69 16:57:03 INFO - PROCESS | 1665 | Values: 347.6 382.9 385.5 390.3 398.2 15526.5 15820.0 19645.0 20007.0 20812.0 15947.0 19836.4 20013.2 20850.3 21062.3 5566.7 5902.3 6066.1 6127.8 6135.4 5796.7 6065.7 6080.7 6087.9 6095.1 5872.5 5992.6 6003.3 6028.8 6030.0 5786.2 5928.2 5938.6 5955.9 5956.1 4974.1 5184.3 5205.9 5256.8 5261.2 20869.6 25646.9 25727.0 25903.1 26109.9 2145.8 2154.7 2160.8 2172.4 2209.4 14646.9 14899.6 15044.8 15137.9 15326.0 1501.8 1703.7 1708.4 1720.7 1728.8 78243.0 89018.0 112840.3 115252.0 121819.4 10697.1 10821.9 11209.2 11285.1 11547.2 13730.2 13904.0 13906.2 13907.4 13932.8 8531.5 8708.5 8725.2 8804.6 8830.2 8670.4 8679.0 8764.3 8830.7 8888.8 6522.7 6634.6 6642.5 6642.6 6642.6 1639.2 1660.1 1825.5 1829.9 1830.6 923.9 1005.1 1032.0 1041.8 1070.8 17303.3 20155.9 21267.8 21384.0 21478.6 864.4 957.5 988.7 995.0 1005.8 11180.6 11404.0 11415.9 11416.5 11449.3 506062.0 573764.5 594523.0 595047.0 605899.0 122322.0 125604.4 127775.4 129004.7 130474.0 1022.4 1057.7 1073.9 1091.0 1094.6 18782.7 18947.4 18957.6 19096.6 19125.6 47222.0 48785.0 49788.5 50347.5 50419.5 69580.0 70746.0 72549.3 72635.0 72852.0 6521.4 7695.3 8280.0 8282.8 8328.2 11358.3 12270.8 12654.5 12781.2 12942.1 10799.5 10861.3 10979.6 11023.2 11266.6 10690.4 10708.5 10853.5 11180.2 11343.0 10950.6 11346.0 11489.3 11740.0 11767.6 8049.2 8124.6 8288.9 8313.9 8529.9 10562.2 10691.2 11156.7 11448.4 11487.8 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5752.72 Median:464.96 stddev:3244.93 (697.9%) stddev-sans-first:3237.71 16:57:03 INFO - PROCESS | 1665 | Values: 1421.4 1590.5 1593.4 1833.2 1957.9 7946.7 8743.3 8946.1 8984.9 8991.9 8514.1 8775.0 8782.3 8794.1 8796.8 2145.6 2370.3 2445.8 2455.8 2479.3 2442.7 2468.4 2469.7 2470.3 2473.2 2419.3 2453.3 2454.2 2454.3 2455.1 2110.4 2327.8 2393.7 2412.7 2413.6 2154.9 2329.1 2392.2 2412.2 2418.4 8610.8 8664.6 8675.6 8690.0 8696.3 5156.6 5320.8 5332.7 5335.7 5359.1 3388.5 3508.1 3509.9 3550.5 3564.5 1004.9 1007.6 1018.9 1021.7 1023.6 11837.8 11858.2 11859.8 11877.2 11939.5 4711.3 4732.3 4800.1 4928.5 4929.8 10051.7 10155.1 10168.0 10171.5 10171.5 5433.1 5441.8 5456.0 5479.5 5480.1 4416.4 4426.8 4565.0 4584.0 4920.9 4034.7 4075.1 4082.9 4120.8 4173.6 1326.5 1331.2 1336.6 1337.7 1349.5 790.5 831.3 908.9 949.5 957.4 5854.1 5914.8 6078.6 6085.4 6163.0 445.1 458.4 464.5 465.4 465.6 3916.8 3976.8 4006.3 4085.4 4114.9 10389.9 10836.3 10901.5 10922.6 10932.9 9768.9 10310.5 10759.7 10852.5 10856.7 7739.4 8123.6 8138.3 8214.2 8233.6 5935.8 5947.2 6023.1 6122.5 6163.8 9982.1 10113.1 10149.5 10194.7 10322.7 10042.4 10098.2 10445.4 10557.8 10566.2 6998.0 7283.7 7442.1 7693.2 7705.1 8381.4 8390.0 8432.8 8500.2 8549.0 7425.3 7456.3 7480.9 7590.5 7591.8 7292.1 7344.2 7365.7 7544.3 7555.2 7069.6 7131.8 7150.3 7156.8 7169.9 4762.8 4983.1 5030.9 5044.5 5056.8 4485.3 5009.3 5018.8 5050.9 5270.9 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5081.52 Median:5753.36 stddev:1585.76 (27.6%) stddev-sans-first:1548.55 16:57:03 INFO - PROCESS | 1665 | Values: 270.5 315.8 319.8 323.5 333.1 6003.5 6034.9 6038.9 6049.8 6062.0 5943.2 5967.4 5978.3 5980.6 5985.1 3590.4 4265.1 4436.7 4460.6 4522.7 4416.7 4487.1 4488.2 4509.9 4519.2 3688.6 4447.2 4492.7 4500.0 4503.9 4178.3 4209.1 4417.8 4442.0 4463.9 3452.7 3804.0 3831.0 3832.9 3839.9 5473.3 5730.3 5733.2 5755.8 5775.5 4544.0 4904.0 5151.3 5201.7 5224.0 5207.4 5704.7 5756.9 6204.0 6295.2 3913.9 4079.2 4323.8 4324.4 4344.3 6047.5 6233.7 6418.8 6701.4 6728.8 4210.6 4264.7 4276.4 4280.5 4287.4 4196.5 4361.2 4368.3 4377.2 4381.7 4897.1 5090.0 5099.3 5120.0 5125.0 5318.9 6263.6 6269.9 6347.7 6382.6 1323.5 1438.7 1465.4 1492.7 1496.7 817.5 921.9 929.3 951.7 971.4 5750.9 5776.3 5791.9 5834.2 5853.8 5052.9 5723.8 5788.4 6606.8 6644.2 5686.9 5935.9 5950.3 5963.3 5974.5 3146.7 3174.3 3461.5 3463.9 3467.3 5505.3 6096.8 6478.3 6668.1 6865.9 5421.3 5904.9 6452.0 6801.3 6841.4 5467.6 5657.4 6896.1 6909.8 6927.3 5512.2 5725.6 6540.3 6601.3 6620.3 5792.8 6030.2 6482.0 6499.6 6509.3 6563.7 6850.9 6885.0 6887.3 6891.0 4138.4 4185.5 4407.9 4411.7 4476.6 5568.4 6351.0 6513.3 6568.7 6574.8 5400.0 5478.9 6124.9 6196.6 6314.3 6274.6 6313.8 6318.5 6337.0 6349.0 5835.5 5934.4 6023.9 6112.3 6130.8 5492.0 6110.8 6195.9 6302.6 6314.2 5106.6 5976.0 6186.6 6192.5 6210.1 16:57:03 INFO - PROCESS | 1665 | 16:57:03 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17608.40 Median:28856.72 stddev:24561.67 (85.1%) stddev-sans-first:24597.54 16:57:03 INFO - PROCESS | 1665 | Values: 644.3 732.5 764.3 767.1 795.7 15013.5 15369.2 15413.8 15414.6 15429.0 14063.4 14101.2 14126.9 14137.6 15047.0 881.0 912.7 922.5 923.6 924.1 873.1 894.0 913.0 915.5 917.0 923.3 954.9 958.3 958.7 960.2 918.7 950.9 951.5 951.9 953.4 700.3 701.5 702.1 702.4 702.7 14495.3 14960.5 15020.2 15024.0 15025.2 12213.1 12260.6 12431.7 12472.5 13237.2 10044.2 10226.8 10228.1 10230.5 10442.6 3434.8 3440.5 3505.7 3509.7 3577.7 78395.8 78652.0 78755.0 78801.0 79620.6 9309.2 9364.1 9399.8 9632.9 9680.2 20639.4 21203.0 21203.0 21214.2 21267.5 6750.5 7484.7 7514.4 7720.6 7851.3 4075.4 4132.9 4135.1 4151.8 4345.6 3490.3 3579.5 3585.2 3587.7 3750.3 662.4 679.0 683.9 684.2 696.6 515.3 516.1 525.6 543.5 557.4 7711.5 7857.6 8006.2 8064.2 8205.2 494.4 549.3 550.2 570.6 577.4 3812.5 3893.4 3897.7 3975.1 4036.1 93360.3 93921.1 94308.0 95870.0 95875.0 57136.0 67460.0 69950.7 79973.6 82008.0 65193.0 66832.0 67037.7 67762.3 68596.4 8051.5 8087.7 8152.9 8218.2 8380.7 36043.5 39005.4 43148.1 43245.6 43417.9 58828.7 59279.7 61140.0 61385.0 62007.7 10622.1 10909.7 11016.1 11066.3 11214.9 11904.2 11960.8 12085.2 12413.3 12533.7 11209.2 11402.5 11541.7 11587.1 11885.6 11319.7 11348.5 11440.8 11573.0 11703.9 11236.1 11238.9 11382.4 11465.8 11608.3 9457.3 9572.3 9778.3 9816.2 10037.0 9583.8 9643.0 9670.1 9683.0 9696.9 16:57:03 INFO - PROCESS | 1665 | -------- Summary: end -------- 16:57:03 INFO - PROCESS | 1665 | 16:57:04 INFO - TEST-INFO | 1665: exit 0 16:57:04 INFO - TEST-OK | dromaeo_css | took 1115519ms 16:57:04 INFO - TEST-START | kraken 16:57:04 INFO - operating with platform_type : mac_ 16:57:04 INFO - Initialising browser for kraken test... 16:57:04 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpnoUByq/profile http://localhost:49275/getInfo.html) 16:57:06 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 16:57:06 INFO - PROCESS | 1671 | colorDepth:24 16:57:06 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 16:57:06 INFO - PROCESS | 1671 | __metrics 16:57:06 INFO - PROCESS | 1671 | 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" 16:57:06 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:57:07 INFO - TEST-INFO | 1671: exit 0 16:57:07 INFO - Browser initialized. 16:57:07 INFO - Running cycle 1/1 for kraken test... 16:57:07 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpnoUByq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:19 INFO - PROCESS | 1673 | RSS: Main: 238727168 16:57:19 INFO - PROCESS | 1673 | 16:57:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:57:22 INFO - PROCESS | 1673 | RSS: Main: 285470720 16:57:22 INFO - PROCESS | 1673 | 16:57:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:57:26 INFO - PROCESS | 1673 | RSS: Main: 300445696 16:57:26 INFO - PROCESS | 1673 | 16:57:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:57:30 INFO - PROCESS | 1673 | RSS: Main: 381923328 16:57:30 INFO - PROCESS | 1673 | 16:57:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:57:33 INFO - PROCESS | 1673 | RSS: Main: 286289920 16:57:33 INFO - PROCESS | 1673 | 16:57:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:57:35 INFO - PROCESS | 1673 | RSS: Main: 290570240 16:57:35 INFO - PROCESS | 1673 | 16:57:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:57:39 INFO - PROCESS | 1673 | RSS: Main: 329162752 16:57:39 INFO - PROCESS | 1673 | 16:57:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:57:42 INFO - PROCESS | 1673 | RSS: Main: 330399744 16:57:42 INFO - PROCESS | 1673 | 16:57:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:57:46 INFO - PROCESS | 1673 | RSS: Main: 326750208 16:57:46 INFO - PROCESS | 1673 | 16:57:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:57:48 INFO - PROCESS | 1673 | RSS: Main: 320049152 16:57:48 INFO - PROCESS | 1673 | 16:57:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:57:50 INFO - PROCESS | 1673 | RSS: Main: 326262784 16:57:50 INFO - PROCESS | 1673 | 16:57:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:57:53 INFO - PROCESS | 1673 | RSS: Main: 329195520 16:57:53 INFO - PROCESS | 1673 | 16:57:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:57:57 INFO - PROCESS | 1673 | RSS: Main: 334303232 16:57:57 INFO - PROCESS | 1673 | 16:57:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:58:00 INFO - PROCESS | 1673 | RSS: Main: 331816960 16:58:00 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:58:02 INFO - PROCESS | 1673 | RSS: Main: 396292096 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | __start_tp_report 16:58:02 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 16:58:02 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 16:58:02 INFO - PROCESS | 1673 | |i|pagename|runs| 16:58:02 INFO - PROCESS | 1673 | |0;ai-astar;114;111;112;113;112;114;120;112;112;114 16:58:02 INFO - PROCESS | 1673 | |1;audio-beat-detection;176;148;179;161;120;142;140;121;206;129 16:58:02 INFO - PROCESS | 1673 | |2;audio-dft;186;186;184;186;187;255;182;267;192;187 16:58:02 INFO - PROCESS | 1673 | |3;audio-fft;82;75;86;90;56;61;61;77;84;55 16:58:02 INFO - PROCESS | 1673 | |4;audio-oscillator;93;89;133;77;80;80;124;78;78;127 16:58:02 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;86;89;85;88;85;88;85;87;84 16:58:02 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;93;93;92;105;151;91;133;147 16:58:02 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;85;85;83;86;86;84;85;84;83 16:58:02 INFO - PROCESS | 1673 | |8;json-parse-financial;61;60;61;60;59;61;61;80;61;59 16:58:02 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;64;63;68;78;68;67;68;68;68;69 16:58:02 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;82;81;82;81;82;81;81;81;82;81 16:58:02 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;136;137;139;135;136;138;142;129;180;138 16:58:02 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;170;170;170;181;172;172;170;171;167;170 16:58:02 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;68;69;67;69;69;67;70;68;66 16:58:02 INFO - PROCESS | 1673 | __end_tp_report 16:58:02 INFO - PROCESS | 1673 | __start_cc_report 16:58:02 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1940 16:58:02 INFO - PROCESS | 1673 | __end_cc_report 16:58:02 INFO - PROCESS | 1673 | __startTimestamp1461283082407__endTimestamp 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | ------- Summary: start ------- 16:58:02 INFO - PROCESS | 1673 | Number of tests: 14 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.50 stddev:2.55 (2.2%) stddev-sans-first:2.69 16:58:02 INFO - PROCESS | 1673 | Values: 114.0 111.0 112.0 113.0 112.0 114.0 120.0 112.0 112.0 114.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:152.20 Median:154.50 stddev:28.04 (18.1%) stddev-sans-first:28.39 16:58:02 INFO - PROCESS | 1673 | Values: 176.0 148.0 179.0 161.0 120.0 142.0 140.0 121.0 206.0 129.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:201.20 Median:187.00 stddev:31.74 (17.0%) stddev-sans-first:33.19 16:58:02 INFO - PROCESS | 1673 | Values: 186.0 186.0 184.0 186.0 187.0 255.0 182.0 267.0 192.0 187.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:72.70 Median:79.50 stddev:13.25 (16.7%) stddev-sans-first:13.62 16:58:02 INFO - PROCESS | 1673 | Values: 82.0 75.0 86.0 90.0 56.0 61.0 61.0 77.0 84.0 55.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:79.00 stddev:22.83 (28.9%) stddev-sans-first:24.20 16:58:02 INFO - PROCESS | 1673 | Values: 93.0 89.0 133.0 77.0 80.0 80.0 124.0 78.0 78.0 127.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.84 (2.1%) stddev-sans-first:1.73 16:58:02 INFO - PROCESS | 1673 | Values: 89.0 86.0 89.0 85.0 88.0 85.0 88.0 85.0 87.0 84.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:109.10 Median:92.50 stddev:24.58 (26.6%) stddev-sans-first:25.37 16:58:02 INFO - PROCESS | 1673 | Values: 93.0 93.0 93.0 93.0 92.0 105.0 151.0 91.0 133.0 147.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.13 16:58:02 INFO - PROCESS | 1673 | Values: 86.0 85.0 85.0 83.0 86.0 86.0 84.0 85.0 84.0 83.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:61.00 stddev:6.27 (10.3%) stddev-sans-first:6.64 16:58:02 INFO - PROCESS | 1673 | Values: 61.0 60.0 61.0 60.0 59.0 61.0 61.0 80.0 61.0 59.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:3.98 (5.9%) stddev-sans-first:3.94 16:58:02 INFO - PROCESS | 1673 | Values: 64.0 63.0 68.0 78.0 68.0 67.0 68.0 68.0 68.0 69.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:0.52 (0.6%) stddev-sans-first:0.50 16:58:02 INFO - PROCESS | 1673 | Values: 82.0 81.0 82.0 81.0 82.0 81.0 81.0 81.0 82.0 81.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:138.00 stddev:14.10 (10.2%) stddev-sans-first:14.84 16:58:02 INFO - PROCESS | 1673 | Values: 136.0 137.0 139.0 135.0 136.0 138.0 142.0 129.0 180.0 138.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:170.50 stddev:3.68 (2.2%) stddev-sans-first:3.88 16:58:02 INFO - PROCESS | 1673 | Values: 170.0 170.0 170.0 181.0 172.0 172.0 170.0 171.0 167.0 170.0 16:58:02 INFO - PROCESS | 1673 | 16:58:02 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 16:58:02 INFO - PROCESS | 1673 | Values: 69.0 68.0 69.0 67.0 69.0 69.0 67.0 70.0 68.0 66.0 16:58:02 INFO - PROCESS | 1673 | -------- Summary: end -------- 16:58:02 INFO - PROCESS | 1673 | 16:58:03 INFO - TEST-INFO | 1673: exit 0 16:58:03 INFO - TEST-OK | kraken | took 58883ms 16:58:03 INFO - SUITE-END | took 1174s 16:58:03 INFO - Completed test suite (00:19:34) 16:58:03 INFO - javascript benchmark 16:58:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2897.6377952755893, 3405.506201600768, 3446.7242620590355, 3474.791512509248, 3499.8775042873503, 5605.439456054392, 5707.172459993309, 5754.0, 5765.2216950711645, 5766.538676905842, 5800.0, 5824.446677565625, 5903.4391732785425, 5921.259842519685, 5946.940530594693, 5132.923006154911, 5138.254953031817, 5167.276581278624, 5194.610404219679, 5280.735963201843, 5294.10000299948, 5304.230886521472, 5319.867003324909, 5328.20076988451, 5369.650972686783, 5107.872303192413, 5128.282040514349, 5223.138182199932, 5240.475952404767, 5240.9213861792105, 5117.7185254810975, 5171.250168725552, 5176.844694659128, 5185.455527169631, 5187.870303242411, 4322.697411181219, 5101.668391554537, 5162.767675454614, 5165.276861239429, 5178.689278643294, 5635.380108188096, 5690.2033715279895, 5741.167530708066, 5797.739101740432, 5837.50381217597, 6190.780155991331, 6218.31598524162, 6224.097505861625, 6232.376762323776, 6238.87522249557, 6165.5992360496875, 6224.470919971808, 6229.501639868799, 6251.093591429263, 6251.093591429263, 5771.0, 5798.942010579883, 5868.295804503444, 5897.115432685132, 5913.29040515141, 6277.8744425111245, 6278.215223097113, 6283.465905398092, 6294.15028971083, 6295.244570651551, 6163.722632481459, 6170.10533472644, 6171.7531298748445, 6191.597546159485, 6192.938070619326, 6148.938510614837, 6205.720742566596, 6307.369263073748, 6324.747010119636, 6340.9365906340345, 6154.261488621394, 6200.906986395207, 6227.283862355796, 6227.844303892439, 6257.217847769029, 6144.556029333103, 6231.750729970841, 6290.685465726787, 6305.4009869062365, 6310.779122730774, 4868.41579010521, 4874.780634871368, 4876.975615121902, 4940.2342636891335, 4956.256561515802, 4409.933850992237, 4427.180971520279, 4436.44544431946, 4444.977775111169, 4462.9776851116185, 5679.914801277984, 5694.288213973254, 5696.231008813757, 5696.259486266758, 5716.628419152742, 6129.23653806838, 6210.161628180228, 6258.311585725566, 6269.46709529686, 6273.780417685451, 4875.804967801319, 5731.856703582361, 5733.397993210736, 5783.652980821164, 5785.0, 5367.838964831061, 5371.274877891513, 5421.945780542223, 5439.347278326626, 5449.809256675997, 6233.314335423009, 6339.080833279241, 6350.936490635035, 6366.840828979312, 6375.2987171411105, 6216.036514340284, 6290.716917738804, 6301.873962520815, 6318.08387783778, 6327.493800496041, 6344.048392741126, 6349.619022858643, 6361.650109244036, 6369.394907483782, 6376.458001069961, 6118.326984031664, 6191.4118158774845, 6227.252729672468, 6240.095186198067, 6241.719122639402, 6191.102290167991, 6278.937210627835, 6294.024426213944, 6307.085472606354, 6316.810495685137, 6148.938510614837, 6179.258488981351, 6188.1027251049245, 6190.50475961931, 6190.907136393048, 5922.763089476372, 5972.462478376967, 6163.84590385244, 6166.506679465721, 6179.783707570214, 5853.180554722428, 5934.347221805511, 5935.643861368331, 5937.2578427696535, 5953.315368732651, 5629.380768115586, 5642.520385767091, 6040.637561746309, 6092.086187071975, 6113.816585502442, 5959.523238141025, 5961.821145365646, 5963.373845746134, 5970.432808883149, 5972.70136493165], "value": 5707.914534254692, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.6202885806788, 507.15052287418564, 529.9841004769858, 580.1269090019522, 612.8050301911271, 30804.0, 31002.0, 31508.376536698288, 37217.25565488689, 38040.47838086473, 39847.80076099623, 40332.39501407481, 40455.0, 40606.0, 40635.0, 8586.441881277728, 9436.150746432819, 9662.758431039245, 9662.903370966305, 9690.70927872165, 11425.200235983484, 11801.822972655416, 11816.7636647267, 11825.29048257103, 11827.112966527502, 11527.827082593767, 11643.0, 11650.766984660302, 11652.533898644044, 11662.241954272988, 9743.707688769347, 11599.710007249801, 11599.884001159964, 11609.18735688502, 11610.6516804496, 11335.43322833855, 11443.0, 11455.25540839851, 11463.140264480196, 11470.139739519485, 39151.0, 39164.0, 39596.40605390922, 39614.801925990185, 39626.81119566389, 3210.7431405487505, 3278.918027049319, 3393.1856354474994, 3524.524189234472, 3737.9813100934593, 23331.0, 28038.859805701046, 28198.85900570484, 28533.578620062122, 29040.41919161626, 2672.5991101334957, 2683.490136873988, 2687.6506054213023, 2869.9426011479654, 2900.318425170083, 358283.20858395804, 358762.0, 360654.9999999974, 360666.0, 361924.0, 57267.71366143226, 57650.423495765346, 57674.711626441596, 57708.422915771145, 57796.71101644465, 60677.39322606717, 60892.0, 60912.69543652253, 60941.08588371178, 61032.69483652553, 14793.186374749277, 16187.0, 16388.75416868748, 16418.589535261715, 16423.507294781175, 10962.232643714864, 10985.450727463594, 11003.779924401628, 12722.681932951751, 13156.947444204441, 15070.698586028218, 15111.773323400157, 15201.695966080617, 15250.923745381202, 15307.46423875159, 6387.361263873576, 6630.071789949411, 6684.86630267392, 6734.797956061326, 6771.4582833373215, 919.6045700348914, 975.8048390321964, 976.6337623391229, 1068.6580294305902, 1074.7689246811974, 27577.58633620497, 28163.859180703967, 28186.43627127487, 28964.855175723987, 29091.709082909325, 1032.3289861590026, 1093.606301731376, 1162.627959053112, 1182.6570294614467, 1241.6337180531764, 14531.273436328142, 14546.781798273034, 14648.267586620626, 15004.949653524149, 15030.549083527512, 553291.2335438378, 553336.0, 560463.0, 560523.0, 561078.0, 57696.71151644215, 58274.70862645745, 58297.0, 58300.70849645809, 58335.0, 1119.8824123467082, 1381.8341798983986, 1455.3523681961426, 1466.2448838848002, 1466.4720700548007, 27410.451790964467, 28008.579871302354, 28298.0, 28471.28821779472, 29055.983040593477, 44592.77703611461, 45964.77017614891, 46135.0, 46149.30776038429, 46209.306860397795, 409312.0, 425042.0, 425500.0, 426420.0, 426711.0, 31787.0, 35724.0, 37129.62870371262, 37696.43455348117, 39523.604763953146, 40951.59048409597, 41879.0, 42023.7898810504, 42055.3691694631, 42106.789466052476, 34732.78435254754, 37027.81486092552, 37938.43092353562, 38039.61960380361, 38304.0, 33635.831820840736, 34482.137946551535, 34559.65440345564, 35102.473462897564, 35462.46806297959, 34108.658913410545, 34678.306433870675, 34987.30025399529, 35019.824900875334, 35409.46885796663, 29184.854075729487, 30590.541141883336, 30633.693663063084, 31659.84170079135, 31735.84132079417, 23552.293431197093, 24017.00213517482, 24059.759402406453, 24268.63597046081, 24347.878260608584], "value": 19269.410013101082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.5586005772667, 382.8698242597517, 385.4507327058941, 390.26435169705115, 398.219489799992, 15526.456574019912, 15820.0, 19645.0, 20006.999650017497, 20812.0, 15947.0, 19836.404907852753, 20013.199472021137, 20850.270240541584, 21062.262820801276, 5566.747481816599, 5902.321233058189, 6066.120412540179, 6127.785527506516, 6135.447809697126, 5796.710164491758, 6065.696715164247, 6080.665563394034, 6087.878242435148, 6095.146679464879, 5872.500837428803, 5992.640441573498, 6003.339632640428, 6028.788992385267, 6029.969850150743, 5786.218860453827, 5928.229330187091, 5938.61399009063, 5955.88088238235, 5956.136360227764, 4974.079795237856, 5184.274201611777, 5205.869853253661, 5256.81601143962, 5261.210818377236, 20869.582608347748, 25646.871765641237, 25727.0, 25903.09339173139, 26109.869450652815, 2145.7746936571593, 2154.709114269569, 2160.8487405881597, 2172.446026263293, 2209.414505156135, 14646.853531464656, 14899.627509312246, 15044.849551504454, 15137.940344175911, 15326.0, 1501.7522108852027, 1703.667784781968, 1708.3764425984425, 1720.7160818464938, 1728.7579738836573, 78243.0, 89018.0, 112840.30739538913, 115252.0, 121819.3909030458, 10697.090747286413, 10821.9458902705, 11209.159313051554, 11285.104437409496, 11547.249428787101, 13730.176189428666, 13903.957203209597, 13906.235157066458, 13907.443702252, 13932.791008134885, 8531.488110713377, 8708.477491350413, 8725.214730674143, 8804.647814087493, 8830.249428798496, 8670.393072484865, 8679.0, 8764.342674299325, 8830.735077947671, 8888.822223555493, 6522.706478208492, 6634.601923884582, 6642.501812364092, 6642.601443913284, 6642.6014439133805, 1639.1722180299112, 1660.1118401655056, 1825.479738274612, 1829.85361171106, 1830.6340026080684, 923.930705197113, 1005.1104772276587, 1031.9793604128026, 1041.7916416716535, 1070.844727514506, 17303.30786768515, 20155.899220503805, 21267.781655953127, 21384.0, 21478.57042859134, 864.4165188497739, 957.4686049242598, 988.6589126751403, 994.9801003980025, 1005.8239808033564, 11180.608678696206, 11404.0, 11415.915488028624, 11416.543338266543, 11449.255798373079, 506062.0, 573764.522195451, 594523.0, 595047.0, 605899.0, 122322.0, 125604.37197813952, 127775.36112319565, 129004.70995289927, 130474.0, 1022.447878145793, 1057.7302787789217, 1073.9355638661705, 1091.028984204059, 1094.5809760429381, 18782.71825922585, 18947.43157705271, 18957.620847583246, 19096.618067638847, 19125.61748765045, 47222.0, 48785.0, 49788.502114979834, 50347.49652503428, 50419.49580504148, 69580.0, 70746.0, 72549.27450725425, 72635.0, 72852.0, 6521.413072823469, 7695.268949449794, 8280.00639923213, 8282.798994145936, 8328.167183281623, 11358.26171298863, 12270.75458490843, 12654.493820247088, 12781.16922400041, 12942.094053416547, 10799.514021868878, 10861.294015888941, 10979.560817567528, 11023.17326200522, 11266.605668801552, 10690.358578485315, 10708.464576770975, 10853.511591978222, 11180.217384783313, 11343.035841953526, 10950.616728414467, 11345.978861902468, 11489.253198542068, 11740.0, 11767.588134415255, 8049.154838741848, 8124.593770311342, 8288.875666865122, 8313.875291870747, 8529.914700852913, 10562.15502759762, 10691.198160137865, 11156.665300041012, 11448.427578621202, 11487.770244594894], "value": 9622.931338656012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.4101148023572, 1590.4910428662824, 1593.3626549380251, 1833.2025568877539, 1957.9216831326748, 7946.721864734735, 8743.300535957123, 8946.10538946105, 8984.8652270216, 8991.865122023166, 8514.063453020162, 8774.956125219365, 8782.25350845179, 8794.120587941203, 8796.780080497974, 2145.6245157097524, 2370.2652177824843, 2445.792107670846, 2455.8280920335583, 2479.2810085075307, 2442.694663167104, 2468.3582268610203, 2469.7283298837115, 2470.3206618180056, 2473.183849329717, 2419.322589674885, 2453.264020793764, 2454.2023842251338, 2454.2759885833634, 2455.1161581830615, 2110.4196346004896, 2327.790498855094, 2393.6888204533475, 2412.6863507743883, 2413.5896897527464, 2154.9030293636665, 2329.091654254842, 2392.234484964812, 2412.2280870121567, 2418.37122348189, 8610.78473038179, 8664.61009254585, 8675.609597568062, 8689.956550217272, 8696.304295656395, 5156.587473002151, 5320.813771518017, 5332.680039197622, 5335.733213339317, 5359.1425371940695, 3388.4747864081105, 3508.1054331145397, 3509.929801403983, 3550.467429885512, 3564.518789963348, 1004.9346792458467, 1007.6372505897871, 1018.9184865210765, 1021.7496713305288, 1023.6468418395716, 11837.822432663603, 11858.169928105037, 11859.82210266838, 11877.16859819813, 11939.462724177432, 4711.340412342275, 4732.296558551449, 4800.135975524439, 4928.507149285043, 4929.7535123244415, 10051.69844904654, 10155.136813370946, 10167.983201679921, 10171.542280597243, 10171.54228059739, 5433.103537916279, 5441.755121019563, 5456.0, 5479.5342395896, 5480.068388373986, 4416.3596278539435, 4426.756528390905, 4564.995700945787, 4583.977080114578, 4920.926186107211, 4034.7377420468165, 4075.1238483726142, 4082.9387559186634, 4120.814563344657, 4173.561776013536, 1326.4826717580152, 1331.17467170355, 1336.565616174747, 1337.7391408675214, 1349.4534713441071, 790.4624855098473, 831.3183189784439, 908.9136532029447, 949.5015117063459, 957.3633533700012, 5854.092615644582, 5914.792982245601, 6078.6048906820915, 6085.36103709113, 6162.969185154046, 445.1120015568961, 458.44070234314086, 464.47746285428894, 465.44844359434177, 465.62284549515, 3916.784576848244, 3976.76139431635, 4006.2988976929505, 4085.3871919212356, 4114.897127571775, 10389.94805025985, 10836.29564078348, 10901.454927253606, 10922.617708380169, 10932.945335273273, 9768.902310976799, 10310.484475776182, 10759.677209683565, 10852.511636976355, 10856.674299771019, 7739.419543534257, 8123.593820309079, 8138.348932085419, 8214.219649133323, 8233.588320583947, 5935.762569497172, 5947.197128387612, 6023.093675402661, 6122.479589234965, 6163.8150855474405, 9982.101610855058, 10113.089821916063, 10149.543270552695, 10194.745131371776, 10322.741931451774, 10042.397456152654, 10098.242631802497, 10445.373277603368, 10557.788844223227, 10566.207534434794, 6998.020277161303, 7283.708651654087, 7442.106947166375, 7693.230676932262, 7705.075390953121, 8381.413301068867, 8390.035145958293, 8432.83134337322, 8500.234978851686, 8549.016863060579, 7425.331720144996, 7456.291652293024, 7480.887786683095, 7590.506617069873, 7591.772246832604, 7292.088488938883, 7344.192138864613, 7365.705371785069, 7544.321011109027, 7555.206703296075, 7069.6111713856235, 7131.750388736369, 7150.284971502808, 7156.785296441115, 7169.892451613126, 4762.761861906822, 4983.07813054586, 5030.874228144326, 5044.470330615232, 5056.82301119459, 4485.273169829705, 5009.273655320031, 5018.799248030184, 5050.873728156826, 5270.89458210826], "value": 4543.459576106139, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.5103762190435, 315.805779445641, 319.75538712884645, 323.51795824221904, 333.1404975164077, 6003.489703375213, 6034.87930241396, 6038.939610603893, 6049.848753781158, 6062.0, 5943.246742211048, 5967.373425790294, 5978.282606087276, 5980.61126026808, 5985.132155837401, 3590.407582748843, 4265.146970605883, 4436.66724995625, 4460.550321145624, 4522.660800439964, 4416.668749843759, 4487.147441986012, 4488.1696886076015, 4509.887252818673, 4519.209138400784, 3688.55737311523, 4447.243968525342, 4492.73043617384, 4499.955000450002, 4503.887402814923, 4178.289690752579, 4209.095044565418, 4417.779111044435, 4442.022754993896, 4463.933041004388, 3452.7065199458075, 3803.9619603803885, 3830.984789030909, 3832.865849695247, 3839.9040023999346, 5473.31583552056, 5730.34101078373, 5733.226014488033, 5755.798547050833, 5775.545393207912, 4544.0, 4903.975480122576, 5151.278820965068, 5201.713905735183, 5224.0, 5207.427183009866, 5704.687921778014, 5756.913646295309, 6203.968980155116, 6295.244570651551, 3913.923670990472, 4079.224947260011, 4323.82704691812, 4324.416203812477, 4344.283193273106, 6047.455728984412, 6233.719482623293, 6418.812519683874, 6701.363370479846, 6728.831779205559, 4210.642095421862, 4264.701470897007, 4276.3799249109325, 4280.4863416390235, 4287.378330142112, 4196.517400498981, 4361.208826351138, 4368.344748287782, 4377.212101821703, 4381.7370957742, 4897.094037603055, 5089.9745501273, 5099.286099946011, 5119.950410165907, 5125.0, 5318.867028324323, 6263.6241825490615, 6269.8746025079245, 6347.714352854133, 6382.585131966407, 1323.4706117552864, 1438.7410266151983, 1465.3918623771015, 1492.6939977304626, 1496.678214183934, 817.5462618246811, 921.9351648845594, 929.3494553812359, 951.7382719752123, 971.4025874087386, 5750.913736293959, 5776.267119864024, 5791.884162316815, 5834.183214349994, 5853.824385268449, 5052.898942021213, 5723.828285151452, 5788.363280039192, 6606.768763093269, 6644.202695676549, 5686.857828554236, 5935.910961335583, 5950.345461999176, 5963.3302675251, 5974.462298393166, 3146.7482601391835, 3174.349258402022, 3461.532693086401, 3463.930721385558, 3467.289205712823, 5505.284313039359, 6096.793212683235, 6478.319776423407, 6668.133142691516, 6865.897011544831, 5421.322334708161, 5904.881902361929, 6451.967740161269, 6801.285864984205, 6841.350071743176, 5467.617266791286, 5657.377688454267, 6896.103506544117, 6909.792706218822, 6927.272636373209, 5512.173174023928, 5725.599208055396, 6540.345965403421, 6601.33986601336, 6620.337966203341, 5792.8124734430185, 6030.185924900083, 6482.027695845472, 6499.5775274607, 6509.349065093453, 6563.737450501926, 6850.938104593796, 6885.0, 6887.276835932155, 6891.0, 4138.379243113557, 4185.514142479376, 4407.9118417630825, 4411.691181617256, 4476.552344765498, 5568.387477377566, 6350.968245158745, 6513.348665133449, 6568.704408301734, 6574.8356291093105, 5400.0, 5478.863028424162, 6124.8775024500155, 6196.597221180609, 6314.336994615499, 6274.623522588659, 6313.779017734541, 6318.494520438446, 6336.954402523532, 6349.015902535114, 5835.503982161564, 5934.43622855828, 6023.879522409439, 6112.266528016667, 6130.816075517741, 5492.011437941209, 6110.847228819315, 6195.907061394173, 6302.621842689453, 6314.210723659543, 5106.565941894832, 5976.032169447029, 6186.566940314315, 6192.473639740672, 6210.105917695147], "value": 4558.791316341606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.3105876711919, 732.5018987088781, 764.2968469008518, 767.148465203624, 795.6857041468617, 15013.474528391494, 15369.23153842309, 15413.768793468107, 15414.614634634127, 15429.0, 14063.367148478317, 14101.2244326562, 14126.858731412709, 14137.646558836033, 15047.0, 881.0308660473476, 912.6988093928987, 922.4972390047441, 923.607466826596, 924.0528458330198, 873.0832625742944, 893.9865902011475, 912.9726108216731, 915.5010519266982, 917.0325306801321, 923.3167456082484, 954.9045095490465, 958.3291695812924, 958.7075941837771, 960.1694534227878, 918.6968300460832, 950.9049095090505, 951.457669128597, 951.9190868776162, 953.4422362917702, 700.3136925812681, 701.4984286235315, 702.0802748399612, 702.3854127638316, 702.6732569355223, 14495.347709353104, 14960.476383326532, 15020.248987550578, 15024.0, 15025.248737563188, 12213.084018698544, 12260.570880019246, 12431.689207769788, 12472.50109995599, 13237.205767653972, 10044.19646428284, 10226.846597301046, 10228.079472847405, 10230.539625716861, 10442.582296708122, 3434.793912365266, 3440.518327434161, 3505.6844883960566, 3509.6841284284537, 3577.678008979204, 78395.82406263967, 78652.0, 78755.0, 78801.0, 79620.60189699015, 9309.162175404246, 9364.110409511086, 9399.812003759887, 9632.940376558572, 9680.177184939359, 20639.380818575468, 21203.0, 21203.045862936204, 21214.151433942778, 21267.46831329198, 6750.527463077636, 7484.663190156456, 7514.4364172687265, 7720.613969301512, 7851.254130857559, 4075.409065685459, 4132.855350062793, 4135.110951145458, 4151.813168407429, 4345.565443455693, 3490.267043920805, 3579.5167652367118, 3585.1754096557415, 3587.7309201809967, 3750.287445385368, 662.3674390956625, 678.9762358317436, 683.9213490448662, 684.2199892122984, 696.613379574331, 515.2863284351125, 516.0607693996965, 525.5637820608886, 543.5108402437837, 557.4202829057815, 7711.45521977746, 7857.567833769198, 8006.199380062064, 8064.233897779702, 8205.179482051866, 494.40177385364115, 549.3078720811806, 550.180231455137, 570.626150555992, 577.4312302382234, 3812.5234345706785, 3893.435451859521, 3897.7466464680274, 3975.0658595230097, 4036.1120553478186, 93360.33198340055, 93921.06078939259, 94308.0, 95870.0, 95875.041249588, 57136.0, 67459.98810017854, 69950.65024674844, 79973.60013199897, 82008.0, 65193.0, 66832.0, 67037.66481167563, 67762.32237677659, 68596.41233198461, 8051.516908985245, 8087.676492940452, 8152.918470815216, 8218.178182181735, 8380.748577542683, 36043.45934811032, 39005.41491877567, 43148.13703725971, 43245.567544324156, 43417.91455213645, 58828.70585647044, 59279.70360148171, 61140.0, 61385.0, 62007.689961549906, 10622.132099066803, 10909.672709818718, 11016.06363459115, 11066.336019838835, 11214.943925280648, 11904.226225295328, 11960.820587691365, 12085.214461060355, 12413.255204687748, 12533.74932501363, 11209.15931305139, 11402.543898243977, 11541.711457213636, 11587.073034157078, 11885.5840045598, 11319.660410187707, 11348.489317980546, 11440.771184576095, 11573.016293615137, 11703.946644802008, 11236.101111911192, 11238.943805280922, 11382.430878456209, 11465.770684586429, 11608.30350178992, 9457.290703197425, 9572.282078843975, 9778.266630002636, 9816.24878002868, 10036.996300370196, 9583.808323833346, 9642.95178524131, 9670.081342272473, 9683.0, 9696.9030309696], "value": 6827.842125979809, "unit": "score"}], "name": "dromaeo_css", "value": 7286.779366396043, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 112.0, 113.0, 112.0, 114.0, 120.0, 112.0, 112.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 148.0, 179.0, 161.0, 120.0, 142.0, 140.0, 121.0, 206.0, 129.0], "value": 152.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 186.0, 187.0, 255.0, 182.0, 267.0, 192.0, 187.0], "value": 201.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 86.0, 90.0, 56.0, 61.0, 61.0, 77.0, 84.0, 55.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 133.0, 77.0, 80.0, 80.0, 124.0, 78.0, 78.0, 127.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 88.0, 85.0, 88.0, 85.0, 87.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 105.0, 151.0, 91.0, 133.0, 147.0], "value": 109.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 83.0, 86.0, 86.0, 84.0, 85.0, 84.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 60.0, 59.0, 61.0, 61.0, 80.0, 61.0, 59.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 68.0, 78.0, 68.0, 67.0, 68.0, 68.0, 68.0, 69.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 81.0, 82.0, 81.0, 81.0, 81.0, 82.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 139.0, 135.0, 136.0, 138.0, 142.0, 129.0, 180.0, 138.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 181.0, 172.0, 172.0, 170.0, 171.0, 167.0, 170.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 69.0, 69.0, 67.0, 70.0, 68.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1508.1000000000001, "alertThreshold": 2.0}]} 16:58:03 INFO - Return code: 0 16:58:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:58:03 ERROR - # TBPL SUCCESS # 16:58:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:58:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:58:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:58:03 INFO - 2016-04-21 16:58:03.727 screenresolution[1677:9610] starting screenresolution argv=screenresolution get 16:58:03 INFO - 2016-04-21 16:58:03.730 screenresolution[1677:9610] Display 0: 1600x1200x32@60 16:58:03 INFO - 2016-04-21 16:58:03.736 screenresolution[1678:9613] starting screenresolution argv=screenresolution list 16:58:03 INFO - Available Modes on Display 0 16:58:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:58:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:58:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:58:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:58:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:58:03 INFO - Intel Iris: 16:58:03 INFO - Chipset Model: Intel Iris 16:58:03 INFO - Type: GPU 16:58:03 INFO - Bus: Built-In 16:58:03 INFO - VRAM (Dynamic, Max): 1536 MB 16:58:03 INFO - Vendor: Intel (0x8086) 16:58:03 INFO - Device ID: 0x0a2e 16:58:03 INFO - Revision ID: 0x0009 16:58:03 INFO - Displays: 16:58:03 INFO - Display: 16:58:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:58:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:58:03 INFO - Display Serial Number: 1600x1200 60 16:58:03 INFO - Main Display: Yes 16:58:03 INFO - Mirror: Off 16:58:03 INFO - Online: Yes 16:58:03 INFO - Rotation: Supported 16:58:03 INFO - Return code: 0 16:58:03 INFO - Running post-action listener: _resource_record_post_action 16:58:03 INFO - Running post-run listener: _resource_record_post_run 16:58:05 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37619200; Write bytes: 690603520; Read time: 391; Write time: 1960 16:58:05 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 387449344; Write bytes: 376477696; Read time: 28269; Write time: 680 16:58:05 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31362560; Write bytes: 293084672; Read time: 308; Write time: 1235 16:58:05 INFO - Running post-run listener: _upload_blobber_files 16:58:05 INFO - Blob upload gear active. 16:58:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:58:05 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 16:58:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:58:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:58:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:58:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:06 INFO - (blobuploader) - INFO - Done attempting. 16:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:07 INFO - (blobuploader) - INFO - Done attempting. 16:58:07 INFO - (blobuploader) - INFO - Iteration through files over. 16:58:07 INFO - Return code: 0 16:58:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:58:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:58:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30716f5506ccf206655300e702031efe27518b8e899f8403733c97e4bfc9753d34af6ca2451c358672f63882c1e899cf7c11deffb7d56db92a8d3b4653d4cdd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d7ff297f04efdab0e795104e855d4ecfd199e6d2d4251d08ebbbc81bae8e9f420ab18b240a63ccbe40041d9a5c70ba1f796ed17b4085030f533e1db33fffbb"} 16:58:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:58:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:58:07 INFO - Contents: 16:58:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30716f5506ccf206655300e702031efe27518b8e899f8403733c97e4bfc9753d34af6ca2451c358672f63882c1e899cf7c11deffb7d56db92a8d3b4653d4cdd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d7ff297f04efdab0e795104e855d4ecfd199e6d2d4251d08ebbbc81bae8e9f420ab18b240a63ccbe40041d9a5c70ba1f796ed17b4085030f533e1db33fffbb"} 16:58:07 INFO - Running post-run listener: copy_logs_to_upload_dir 16:58:07 INFO - Copying logs to upload dir... 16:58:07 INFO - mkdir: /builds/slave/test/build/upload/logs 16:58:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.208589 ========= master_lag: 0.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-21 16:58:08.241474) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-21 16:58:08.245961) ========= 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.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/Firefox/sha512/30716f5506ccf206655300e702031efe27518b8e899f8403733c97e4bfc9753d34af6ca2451c358672f63882c1e899cf7c11deffb7d56db92a8d3b4653d4cdd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d7ff297f04efdab0e795104e855d4ecfd199e6d2d4251d08ebbbc81bae8e9f420ab18b240a63ccbe40041d9a5c70ba1f796ed17b4085030f533e1db33fffbb"} build_url:https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/Sz_lYRg6SrCh7TQXyvGy1Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30716f5506ccf206655300e702031efe27518b8e899f8403733c97e4bfc9753d34af6ca2451c358672f63882c1e899cf7c11deffb7d56db92a8d3b4653d4cdd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d7ff297f04efdab0e795104e855d4ecfd199e6d2d4251d08ebbbc81bae8e9f420ab18b240a63ccbe40041d9a5c70ba1f796ed17b4085030f533e1db33fffbb"}' ========= master_lag: 5.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-21 16:58:13.703455) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-21 16:58:13.703781) ========= 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.Mv6GH7Bycs/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.raeiLECrOD/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005249 ========= master_lag: 7.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-04-21 16:58:20.747256) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 16:58:20.750614) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 16:58:21.074004) ========= ========= Total master_lag: 13.35 =========