builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0363 starttime: 1464648646.0 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.002284) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.002859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.003495) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.080615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.080992) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 15:50:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-05-30 15:50:46 (18.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.208057 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.321098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.321453) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.025879 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.370546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:46.370947) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 15:50:46,455 truncating revision to first 12 chars 2016-05-30 15:50:46,455 Setting DEBUG logging. 2016-05-30 15:50:46,455 attempt 1/10 2016-05-30 15:50:46,455 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 15:50:47,135 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964360 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:47.352997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:47.353366) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:47.353816) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:47.354996) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:47.373339) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-30 15:50:47.373743) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:50:47 INFO - MultiFileLogger online at 20160530 15:50:47 in /builds/slave/test 15:50:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:50:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:47 INFO - {'append_to_log': False, 15:50:47 INFO - 'base_work_dir': '/builds/slave/test', 15:50:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:50:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:47 INFO - 'branch': 'Mozilla-Aurora', 15:50:47 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:47 INFO - 'config_files': ('talos/mac_config.py',), 15:50:47 INFO - 'default_actions': ('clobber', 15:50:47 INFO - 'read-buildbot-config', 15:50:47 INFO - 'download-and-extract', 15:50:47 INFO - 'populate-webroot', 15:50:47 INFO - 'create-virtualenv', 15:50:47 INFO - 'install', 15:50:47 INFO - 'run-tests'), 15:50:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:47 INFO - 'download_minidump_stackwalk': True, 15:50:47 INFO - 'download_symbols': 'ondemand', 15:50:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:47 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:47 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:47 INFO - 'installer_path': 'installer.exe', 15:50:47 INFO - 'log_level': 'info', 15:50:47 INFO - 'log_name': 'talos', 15:50:47 INFO - 'log_to_console': True, 15:50:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:47 INFO - 'opt_config_files': (), 15:50:47 INFO - 'pip_index': False, 15:50:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:47 INFO - 'cmd': ('bash', 15:50:47 INFO - '-c', 15:50:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:47 INFO - 'enabled': True, 15:50:47 INFO - 'halt_on_failure': False, 15:50:47 INFO - 'name': 'check_screen_resolution'},), 15:50:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:47 INFO - 'cmd': ('bash', 15:50:47 INFO - '-c', 15:50:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:47 INFO - 'enabled': True, 15:50:47 INFO - 'halt_on_failure': False, 15:50:47 INFO - 'name': 'check_screen_resolution'},), 15:50:47 INFO - 'run_cmd_checks_enabled': True, 15:50:47 INFO - 'sps_profile': False, 15:50:47 INFO - 'sps_profile_interval': 0, 15:50:47 INFO - 'suite': 'dromaeojs', 15:50:47 INFO - 'system_bits': '32', 15:50:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:47 INFO - 'title': 't-yosemite-r7-0363', 15:50:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:47 INFO - 'use_talos_json': True, 15:50:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:47 INFO - 'work_dir': 'build'} 15:50:47 INFO - ##### 15:50:47 INFO - ##### Running clobber step. 15:50:47 INFO - ##### 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: clobber 15:50:47 INFO - rmtree: /builds/slave/test/build 15:50:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:49 INFO - Running post-action listener: _resource_record_post_action 15:50:49 INFO - ##### 15:50:49 INFO - ##### Running read-buildbot-config step. 15:50:49 INFO - ##### 15:50:49 INFO - Running pre-action listener: _resource_record_pre_action 15:50:49 INFO - Running main action method: read_buildbot_config 15:50:49 INFO - Using buildbot properties: 15:50:49 INFO - { 15:50:49 INFO - "project": "", 15:50:49 INFO - "product": "firefox", 15:50:49 INFO - "script_repo_revision": "production", 15:50:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:50:49 INFO - "repository": "", 15:50:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:50:49 INFO - "buildid": "20160530120549", 15:50:49 INFO - "pgo_build": "False", 15:50:49 INFO - "basedir": "/builds/slave/test", 15:50:49 INFO - "buildnumber": 364, 15:50:49 INFO - "slavename": "t-yosemite-r7-0363", 15:50:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:50:49 INFO - "platform": "yosemite_r7", 15:50:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:50:49 INFO - "branch": "mozilla-aurora", 15:50:49 INFO - "repo_path": "releases/mozilla-aurora", 15:50:49 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 15:50:49 INFO - "stage_platform": "macosx64", 15:50:49 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 15:50:49 INFO - "slavebuilddir": "test" 15:50:49 INFO - } 15:50:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:50:49 INFO - Running post-action listener: _resource_record_post_action 15:50:49 INFO - ##### 15:50:49 INFO - ##### Running download-and-extract step. 15:50:49 INFO - ##### 15:50:49 INFO - Running pre-action listener: _resource_record_pre_action 15:50:49 INFO - Running main action method: download_and_extract 15:50:49 INFO - mkdir: /builds/slave/test/build/tests 15:50:49 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')]} 15:50:49 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:50:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:50:59 INFO - Downloaded 1199 bytes. 15:50:59 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:59 INFO - Using the following test package requirements: 15:50:59 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:50:59 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:50:59 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:50:59 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'jsshell-mac.zip'], 15:50:59 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:50:59 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:50:59 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:50:59 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:50:59 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:50:59 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:50:59 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:50:59 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:59 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:50:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:50:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:01 INFO - Downloaded 18463801 bytes. 15:51:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:04 INFO - Return code: 0 15:51:04 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:04 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:51:06 INFO - Downloaded 18463801 bytes. 15:51:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:51:09 INFO - Return code: 0 15:51:09 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')]} 15:51:09 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:51:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:51:11 INFO - Downloaded 10840145 bytes. 15:51:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:51:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:51:11 INFO - Return code: 0 15:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:11 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:51:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:51:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:51:14 INFO - Downloaded 114159654 bytes. 15:51:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:14 INFO - mkdir: /builds/slave/test/properties 15:51:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:14 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:14 INFO - Contents: 15:51:14 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:15 INFO - Running post-action listener: _resource_record_post_action 15:51:15 INFO - Running post-action listener: set_extra_try_arguments 15:51:15 INFO - ##### 15:51:15 INFO - ##### Running populate-webroot step. 15:51:15 INFO - ##### 15:51:15 INFO - Running pre-action listener: _resource_record_pre_action 15:51:15 INFO - Running main action method: populate_webroot 15:51:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:15 INFO - '--tppagecycles', 15:51:15 INFO - '7'], 15:51:15 INFO - 'tests': ['tsvgm']}}, 15:51:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['damp', 'tps']}, 15:51:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'tests': ['damp', 'tps']}, 15:51:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['dromaeo_dom']}, 15:51:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['a11yr', 15:51:15 INFO - 'ts_paint', 15:51:15 INFO - 'tpaint', 15:51:15 INFO - 'sessionrestore', 15:51:15 INFO - 'sessionrestore_no_auto_restore', 15:51:15 INFO - 'tabpaint']}, 15:51:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['tsvgx', 15:51:15 INFO - 'tsvgr_opacity', 15:51:15 INFO - 'tart', 15:51:15 INFO - 'tscrollx', 15:51:15 INFO - 'cart']}, 15:51:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:15 INFO - 'tsvgr_opacity', 15:51:15 INFO - 'tart', 15:51:15 INFO - 'tscrollx', 15:51:15 INFO - 'cart']}, 15:51:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'talos_options': ['--disable-e10s'], 15:51:15 INFO - 'tests': ['tp5o']}, 15:51:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'tests': ['tp5o']}, 15:51:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'talos_options': ['--disable-e10s', 15:51:15 INFO - '--xperf_path', 15:51:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:15 INFO - 'tests': ['tp5n']}, 15:51:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:15 INFO - 'talos_options': ['--xperf_path', 15:51:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:15 INFO - 'tests': ['tp5n']}}, 15:51:15 INFO - 'talos.zip': {'path': '', 15:51:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:15 INFO - Running post-action listener: _resource_record_post_action 15:51:15 INFO - ##### 15:51:15 INFO - ##### Running create-virtualenv step. 15:51:15 INFO - ##### 15:51:15 INFO - Running pre-action listener: _resource_record_pre_action 15:51:15 INFO - Running main action method: create_virtualenv 15:51:15 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:15 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 15:51:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:15 INFO - Using real prefix '/tools/python27' 15:51:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:16 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:19 INFO - Installing pip.................done. 15:51:19 INFO - Return code: 0 15:51:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:51:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:19 INFO - 'HOME': '/Users/cltbld', 15:51:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:19 INFO - 'LOGNAME': 'cltbld', 15:51:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:19 INFO - 'MOZ_NO_REMOTE': '1', 15:51:19 INFO - 'NO_EM_RESTART': '1', 15:51:19 INFO - 'PAGER': '/bin/cat', 15:51:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:19 INFO - 'PWD': '/builds/slave/test', 15:51:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:19 INFO - 'SHELL': '/bin/bash', 15:51:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:19 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:19 INFO - 'USER': 'cltbld', 15:51:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:19 INFO - 'XPC_FLAGS': '0x0', 15:51:19 INFO - 'XPC_SERVICE_NAME': '0', 15:51:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:20 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 15:51:20 INFO - Cleaning up... 15:51:20 INFO - Return code: 0 15:51:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:20 INFO - 'HOME': '/Users/cltbld', 15:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:20 INFO - 'LOGNAME': 'cltbld', 15:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:20 INFO - 'MOZ_NO_REMOTE': '1', 15:51:20 INFO - 'NO_EM_RESTART': '1', 15:51:20 INFO - 'PAGER': '/bin/cat', 15:51:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:20 INFO - 'PWD': '/builds/slave/test', 15:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:20 INFO - 'SHELL': '/bin/bash', 15:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:20 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:20 INFO - 'USER': 'cltbld', 15:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:20 INFO - 'XPC_FLAGS': '0x0', 15:51:20 INFO - 'XPC_SERVICE_NAME': '0', 15:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:20 INFO - Downloading/unpacking psutil>=0.7.1 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:22 INFO - Installing collected packages: psutil 15:51:22 INFO - Running setup.py install for psutil 15:51:22 INFO - building 'psutil._psutil_osx' extension 15:51:22 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 15:51:23 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 15:51:23 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 15:51:23 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 15:51:23 INFO - building 'psutil._psutil_posix' extension 15:51:23 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 15:51:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:51:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:51:23 INFO - ^ 15:51:23 INFO - 1 warning generated. 15:51:23 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 15:51:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:23 INFO - Successfully installed psutil 15:51:23 INFO - Cleaning up... 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:24 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 15:51:24 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 15:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:24 INFO - 'HOME': '/Users/cltbld', 15:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:24 INFO - 'LOGNAME': 'cltbld', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'PAGER': '/bin/cat', 15:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:24 INFO - 'PWD': '/builds/slave/test', 15:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:24 INFO - 'SHELL': '/bin/bash', 15:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:24 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:24 INFO - 'USER': 'cltbld', 15:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:24 INFO - 'XPC_FLAGS': '0x0', 15:51:24 INFO - 'XPC_SERVICE_NAME': '0', 15:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:51:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:51:26 INFO - Installing collected packages: mozsystemmonitor 15:51:26 INFO - Running setup.py install for mozsystemmonitor 15:51:26 INFO - Successfully installed mozsystemmonitor 15:51:26 INFO - Cleaning up... 15:51:26 INFO - Return code: 0 15:51:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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', '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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:51: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 blobuploader==1.2.4 15:51:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:26 INFO - 'HOME': '/Users/cltbld', 15:51:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PAGER': '/bin/cat', 15:51:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:26 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - 'XPC_FLAGS': '0x0', 15:51:26 INFO - 'XPC_SERVICE_NAME': '0', 15:51:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:27 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:29 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:30 INFO - Downloading docopt-0.6.1.tar.gz 15:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:30 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:30 INFO - Running setup.py install for blobuploader 15:51:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:31 INFO - Running setup.py install for requests 15:51:31 INFO - Running setup.py install for docopt 15:51:31 INFO - Successfully installed blobuploader requests docopt 15:51:31 INFO - Cleaning up... 15:51:31 INFO - Return code: 0 15:51:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:31 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')]} 15:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 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')]} 15:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:31 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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:31 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 15:51:31 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 15:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:31 INFO - 'HOME': '/Users/cltbld', 15:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:31 INFO - 'LOGNAME': 'cltbld', 15:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:31 INFO - 'MOZ_NO_REMOTE': '1', 15:51:31 INFO - 'NO_EM_RESTART': '1', 15:51:31 INFO - 'PAGER': '/bin/cat', 15:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:31 INFO - 'PWD': '/builds/slave/test', 15:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:31 INFO - 'SHELL': '/bin/bash', 15:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:31 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:31 INFO - 'USER': 'cltbld', 15:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:31 INFO - 'XPC_FLAGS': '0x0', 15:51:31 INFO - 'XPC_SERVICE_NAME': '0', 15:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-gXC_mL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-eSLyFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-hlk8xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-d1xRwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-amqMB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Z_qYWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-U4nmSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-zHgEjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-MrvmiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:32 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-dqSPLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-xA5O2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-DJYxbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-eSTQS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1DsecQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-G0JrNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-EGevMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:33 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-8kO5Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:33 INFO - Running setup.py install for manifestparser 15:51:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:33 INFO - Running setup.py install for mozcrash 15:51:34 INFO - Running setup.py install for mozdebug 15:51:34 INFO - Running setup.py install for mozdevice 15:51:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Running setup.py install for mozfile 15:51:34 INFO - Running setup.py install for mozhttpd 15:51:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Running setup.py install for mozinfo 15:51:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Running setup.py install for mozInstall 15:51:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:34 INFO - Running setup.py install for mozleak 15:51:35 INFO - Running setup.py install for mozlog 15:51:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Running setup.py install for moznetwork 15:51:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Running setup.py install for mozprocess 15:51:35 INFO - Running setup.py install for mozprofile 15:51:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Running setup.py install for mozrunner 15:51:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:35 INFO - Running setup.py install for mozscreenshot 15:51:36 INFO - Running setup.py install for moztest 15:51:36 INFO - Running setup.py install for mozversion 15:51:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:36 INFO - Cleaning up... 15:51:36 INFO - Return code: 0 15:51:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:36 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 15:51:36 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 15:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:36 INFO - 'HOME': '/Users/cltbld', 15:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:36 INFO - 'LOGNAME': 'cltbld', 15:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:36 INFO - 'MOZ_NO_REMOTE': '1', 15:51:36 INFO - 'NO_EM_RESTART': '1', 15:51:36 INFO - 'PAGER': '/bin/cat', 15:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:36 INFO - 'PWD': '/builds/slave/test', 15:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:36 INFO - 'SHELL': '/bin/bash', 15:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:36 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:36 INFO - 'USER': 'cltbld', 15:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:36 INFO - 'XPC_FLAGS': '0x0', 15:51:36 INFO - 'XPC_SERVICE_NAME': '0', 15:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:36 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-e3PbL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:36 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)) 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:36 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-YxLYQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:36 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)) 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:36 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-9rZ6Yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:36 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)) 15:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:36 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-QtrcrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-YyRUL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-UfsZ3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-VNRXdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-n8o6Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-_yCu4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-NGcovC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-RGQhfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-demgmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:37 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)) 15:51:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:37 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1EoSu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-oJsFbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-8AREQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-NUeoxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:38 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)) 15:51:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-BelWxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:38 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)) 15:51:38 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)) 15:51:38 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)) 15:51:38 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)) 15:51:38 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)) 15:51:38 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)) 15:51:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:38 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 15:51:38 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 15:51:38 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 15:51:38 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 15:51:38 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 15:51:38 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 15:51:40 INFO - Downloading blessings-1.6.tar.gz 15:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:40 INFO - Installing collected packages: blessings 15:51:40 INFO - Running setup.py install for blessings 15:51:41 INFO - Successfully installed blessings 15:51:41 INFO - Cleaning up... 15:51:41 INFO - Return code: 0 15:51:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:41 INFO - Reading from file tmpfile_stdout 15:51:41 INFO - Current package versions: 15:51:41 INFO - blessings == 1.6 15:51:41 INFO - blobuploader == 1.2.4 15:51:41 INFO - docopt == 0.6.1 15:51:41 INFO - manifestparser == 1.1 15:51:41 INFO - mozInstall == 1.12 15:51:41 INFO - mozcrash == 0.17 15:51:41 INFO - mozdebug == 0.1 15:51:41 INFO - mozdevice == 0.48 15:51:41 INFO - mozfile == 1.2 15:51:41 INFO - mozhttpd == 0.7 15:51:41 INFO - mozinfo == 0.9 15:51:41 INFO - mozleak == 0.1 15:51:41 INFO - mozlog == 3.1 15:51:41 INFO - moznetwork == 0.27 15:51:41 INFO - mozprocess == 0.22 15:51:41 INFO - mozprofile == 0.28 15:51:41 INFO - mozrunner == 6.11 15:51:41 INFO - mozscreenshot == 0.1 15:51:41 INFO - mozsystemmonitor == 0.0 15:51:41 INFO - moztest == 0.7 15:51:41 INFO - mozversion == 1.4 15:51:41 INFO - psutil == 3.1.1 15:51:41 INFO - requests == 1.2.3 15:51:41 INFO - wsgiref == 0.1.2 15:51:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:41 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 15:51:41 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 15:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:41 INFO - 'HOME': '/Users/cltbld', 15:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:41 INFO - 'LOGNAME': 'cltbld', 15:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:41 INFO - 'MOZ_NO_REMOTE': '1', 15:51:41 INFO - 'NO_EM_RESTART': '1', 15:51:41 INFO - 'PAGER': '/bin/cat', 15:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:41 INFO - 'PWD': '/builds/slave/test', 15:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:41 INFO - 'SHELL': '/bin/bash', 15:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:41 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:41 INFO - 'USER': 'cltbld', 15:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:41 INFO - 'XPC_FLAGS': '0x0', 15:51:41 INFO - 'XPC_SERVICE_NAME': '0', 15:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 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)) 15:51:41 INFO - Cleaning up... 15:51:41 INFO - Return code: 0 15:51:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 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 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51:41 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 15:51:41 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 15:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:41 INFO - 'HOME': '/Users/cltbld', 15:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:41 INFO - 'LOGNAME': 'cltbld', 15:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:41 INFO - 'MOZ_NO_REMOTE': '1', 15:51:41 INFO - 'NO_EM_RESTART': '1', 15:51:41 INFO - 'PAGER': '/bin/cat', 15:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:41 INFO - 'PWD': '/builds/slave/test', 15:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:41 INFO - 'SHELL': '/bin/bash', 15:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:41 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:41 INFO - 'USER': 'cltbld', 15:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:41 INFO - 'XPC_FLAGS': '0x0', 15:51:41 INFO - 'XPC_SERVICE_NAME': '0', 15:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:41 INFO - Downloading/unpacking jsonschema 15:51:41 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 15:51:41 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 15:51:41 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 15:51:41 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 15:51:41 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 15:51:41 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 15:51:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:44 INFO - Downloading/unpacking functools32 (from jsonschema) 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 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 15:51:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:44 INFO - warning: no files found matching '*.txt' 15:51:44 INFO - no previously-included directories found matching 'build' 15:51:44 INFO - no previously-included directories found matching 'dist' 15:51:44 INFO - no previously-included directories found matching '.git*' 15:51:44 INFO - Installing collected packages: jsonschema, functools32 15:51:45 INFO - Running setup.py install for functools32 15:51:45 INFO - warning: no files found matching '*.txt' 15:51:45 INFO - no previously-included directories found matching 'build' 15:51:45 INFO - no previously-included directories found matching 'dist' 15:51:45 INFO - no previously-included directories found matching '.git*' 15:51:45 INFO - Successfully installed jsonschema functools32 15:51:45 INFO - Cleaning up... 15:51:45 INFO - Return code: 0 15:51:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1030c6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030d6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8533cafdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:51: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', 'flake8'] in /builds/slave/test/build 15:51: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 flake8 15:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:45 INFO - 'HOME': '/Users/cltbld', 15:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:45 INFO - 'LOGNAME': 'cltbld', 15:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:45 INFO - 'MOZ_NO_REMOTE': '1', 15:51:45 INFO - 'NO_EM_RESTART': '1', 15:51:45 INFO - 'PAGER': '/bin/cat', 15:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:45 INFO - 'PWD': '/builds/slave/test', 15:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:45 INFO - 'SHELL': '/bin/bash', 15:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:51:45 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:45 INFO - 'USER': 'cltbld', 15:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:45 INFO - 'XPC_FLAGS': '0x0', 15:51:45 INFO - 'XPC_SERVICE_NAME': '0', 15:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:45 INFO - Downloading/unpacking flake8 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:51:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:47 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 15:51:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:51:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51: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 15:51:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:51:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:49 INFO - no previously-included directories found matching 'docs/_build' 15:51:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:51:49 INFO - Running setup.py install for pep8 15:51:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:51:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:51:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:51:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:51:49 INFO - no previously-included directories found matching 'docs/_build' 15:51:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:51:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:51:49 INFO - Cleaning up... 15:51:49 INFO - Return code: 0 15:51:49 INFO - Running post-action listener: _resource_record_post_action 15:51:49 INFO - Running post-action listener: _start_resource_monitoring 15:51:49 INFO - Starting resource monitoring. 15:51:49 INFO - ##### 15:51:49 INFO - ##### Running install step. 15:51:49 INFO - ##### 15:51:49 INFO - Running pre-action listener: _resource_record_pre_action 15:51:49 INFO - Running main action method: install 15:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:49 INFO - Reading from file tmpfile_stdout 15:51:49 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:49 INFO - Reading from file tmpfile_stdout 15:51:49 INFO - Output received: 15:51:49 INFO - Usage: mozinstall [options] installer 15:51:49 INFO - Options: 15:51:49 INFO - -h, --help show this help message and exit 15:51:49 INFO - -d DEST, --destination=DEST 15:51:49 INFO - Directory to install application into. [default: 15:51:49 INFO - "/builds/slave/test"] 15:51:49 INFO - --app=APP Application being installed. [default: firefox] 15:51:49 INFO - mkdir: /builds/slave/test/build/application 15:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:52:21 INFO - Reading from file tmpfile_stdout 15:52:21 INFO - Output received: 15:52:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:52:21 INFO - Running post-action listener: _resource_record_post_action 15:52:21 INFO - ##### 15:52:21 INFO - ##### Running run-tests step. 15:52:21 INFO - ##### 15:52:21 INFO - Running pre-action listener: _resource_record_pre_action 15:52:21 INFO - Running main action method: run_tests 15:52:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:21 INFO - 2016-05-30 15:52:21.902 screenresolution[1660:7937] starting screenresolution argv=screenresolution get 15:52:21 INFO - 2016-05-30 15:52:21.905 screenresolution[1660:7937] Display 0: 1600x1200x32@60 15:52:21 INFO - 2016-05-30 15:52:21.911 screenresolution[1661:7940] starting screenresolution argv=screenresolution list 15:52:21 INFO - Available Modes on Display 0 15:52:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:22 INFO - Intel Iris: 15:52:22 INFO - Chipset Model: Intel Iris 15:52:22 INFO - Type: GPU 15:52:22 INFO - Bus: Built-In 15:52:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:22 INFO - Vendor: Intel (0x8086) 15:52:22 INFO - Device ID: 0x0a2e 15:52:22 INFO - Revision ID: 0x0009 15:52:22 INFO - Displays: 15:52:22 INFO - Display: 15:52:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:22 INFO - Display Serial Number: 1600x1200 60 15:52:22 INFO - Main Display: Yes 15:52:22 INFO - Mirror: Off 15:52:22 INFO - Online: Yes 15:52:22 INFO - Rotation: Supported 15:52:22 INFO - Return code: 0 15:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:22 INFO - Python 2.7.3 15:52:22 INFO - Return code: 0 15:52:22 INFO - grabbing minidump binary from tooltool 15:52: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')]} 15:52:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f65e00>, '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 0x102dcedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f60b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:52:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:52:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:22 INFO - Return code: 0 15:52:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:52:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:52:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:52:23 INFO - Return code: 0 15:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 15:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0363 --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 15:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 15:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:23 INFO - 'HOME': '/Users/cltbld', 15:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:23 INFO - 'LOGNAME': 'cltbld', 15:52:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:23 INFO - 'MOZ_NO_REMOTE': '1', 15:52:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:23 INFO - 'NO_EM_RESTART': '1', 15:52:23 INFO - 'PAGER': '/bin/cat', 15:52:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:23 INFO - 'PWD': '/builds/slave/test', 15:52:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:23 INFO - 'SHELL': '/bin/bash', 15:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 15:52:23 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:23 INFO - 'USER': 'cltbld', 15:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:23 INFO - 'XPC_FLAGS': '0x0', 15:52:23 INFO - 'XPC_SERVICE_NAME': '0', 15:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 15:52:23 INFO - mozversion application_buildid: 20160530120549 15:52:23 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:52:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:52:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:23 INFO - mozversion application_name: Firefox 15:52:23 INFO - mozversion application_remotingname: firefox-dev 15:52:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:52:23 INFO - mozversion application_vendor: Mozilla 15:52:23 INFO - mozversion application_version: 48.0a2 15:52:23 INFO - mozversion platform_buildid: 20160530120549 15:52:23 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:52:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:52:23 INFO - mozversion platform_version: 48.0a2 15:52:23 INFO - using testdate: 1464648743 15:52:23 INFO - actual date: 1464648743 15:52:23 INFO - starting webserver on 'localhost:49275' 15:52:23 INFO - SUITE-START | Running 2 tests 15:52:23 INFO - TEST-START | dromaeo_css 15:52:23 INFO - operating with platform_type : mac_ 15:52:23 INFO - Initialising browser for dromaeo_css test... 15:52:23 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpE_4yaN/profile http://localhost:49275/getInfo.html) 15:52:25 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 15:52:25 INFO - PROCESS | 1679 | colorDepth:24 15:52:25 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 15:52:25 INFO - PROCESS | 1679 | __metrics 15:52:25 INFO - PROCESS | 1679 | 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" 15:52:26 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:52:26 INFO - TEST-INFO | 1679: exit 0 15:52:26 INFO - Browser initialized. 15:52:26 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:26 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpE_4yaN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:38 INFO - PROCESS | 1681 | RSS: Main: 211415040 15:52:38 INFO - PROCESS | 1681 | 15:53:30 INFO - PROCESS | 1681 | 1464648810108 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] 15:55:40 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:55:42 INFO - PROCESS | 1681 | RSS: Main: 380878848 15:55:42 INFO - PROCESS | 1681 | 15:58:45 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:58:45 INFO - PROCESS | 1681 | RSS: Main: 357511168 15:58:45 INFO - PROCESS | 1681 | 16:01:48 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:01:48 INFO - PROCESS | 1681 | RSS: Main: 359100416 16:01:48 INFO - PROCESS | 1681 | 16:04:50 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:04:51 INFO - PROCESS | 1681 | RSS: Main: 357179392 16:04:51 INFO - PROCESS | 1681 | 16:07:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:07:55 INFO - PROCESS | 1681 | RSS: Main: 328540160 16:07:55 INFO - PROCESS | 1681 | 16:10:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:10:58 INFO - PROCESS | 1681 | RSS: Main: 364240896 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | __start_tp_report 16:10:58 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 16:10:58 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 16:10:58 INFO - PROCESS | 1681 | |i|pagename|runs| 16:10:58 INFO - PROCESS | 1681 | |0;dojo.html;3378.594568651762;3419.9316013679713;3441.0537102296857;3467.2718729066896;3492.8822776711463;5593.300837395325;5595.300587426572;5631.211630371751;5634.633748806325;5641.407652196517;5612.635178713381;5655.519280861132;5664.971675141629;5677.148427735841;5682.886342273162;5083.339165908426;5132.204508301219;5179.715115668637;5233.790648374061;5251.553617942461;5224.921626175591;5269.235960785683;5275.155975043994;5279.128943724281;5280.234366016925;5163.870903227412;5197.454267301936;5221.869453263661;5227;5228.7124208168725;5256.526912577867;5289.471052894718;5311.123664595337;5335.466453354634;5360.758765855546;5048.29323894655;5071.974640126813;5099.184130539133;5223.660462069991;5242.423333433319;5488.067028605107;5522.806701765419;5541.085720856054;5550.972245138789;5585.636933599344;6181.474574661114;6220.533459990519;6229.408206220402;6229.563930524865;6244.469220116295;6125.021800333839;6170.753169873245;6255.06174073888;6255.718492667841;6306.621602703853;5718.485336319668;5729.684867332254;5730.111832665943;5749.568782341341;5778.508826749731;6332.936670633235;6351.491880649537;6354.459870910932;6354.809355719336;6369.0446433034485;5971;6039.577229593974;6060.757569697251;6065.969670151621;6068.605540639845;6148.231471066117;6186.783462578788;6196.597221180609;6308.589941653778;6314.684265786692;6138.232720909887;6143.157052884117;6145.647957449354;6154.261488621394;6168.228971378578;6131.172291740647;6148.538859585549;6161.537884658579;6165.475934545524;6165.876682466415;4813.15769740294;4896.902061958812;4899.0691768564575;4900.289458028566;4936.333594964705;4397.1205758848355;4420.80106395213;4447.510773814878;4449.466064072332;4453.087117140978;5598.832035038955;5658.74535645897;5674.716264186774;5686.118651609007;5703.258576385043;6211.81364559064;6286.842828929313;6287.49700023997;6295.496360291166;6314.305426403092;5522.696251706195;5629.183768353648;5648.717564121777;5649.293838270216;5660.688662123623;5187.455317191716;5318.97340513295;5333.7066461345;5364.061289274361;5380.569554435636;6319.747210111544;6325.525585581007;6336.7148478319505;6348.492120630339;6355.650439225887;6118.082287656887;6248.968755156377;6291;6305.054241863573;6305.653189074645;6275.623462592259;6324.778632747832;6327.873442531216;6333.524985626004;6349.746010159542;6154.538409619386;6187.597806142587;6202.255729312511;6209;6219.689015549295;6076.210092687922;6266.185395898504;6288.622682639057;6293.874122517615;6296.307406185224;5027.4218464877;5992.191054207626;6072.453479186895;6104.69476526163;6117.449429551361;6079.088136779518;6099.176611157437;6121.84695382619;6130.111133885473;6168.013117901114;5742.827715168552;5920.674362910081;5967.164596956516;5973.790917318047;5981.551383646331;5939.554533409925;5966.224390829338;5971.402859714168;5976.252968378953;5996.2504686914135;5844.970775146097;5860.94139058621;5862.706864656665;5868.031774757288;5869.002269614261 16:10:58 INFO - PROCESS | 1681 | |1;ext.html;490.3551829344412;493.2576472409024;507.29739311054186;596.4900010491032;619.8946179149544;31134.53298200526;37695;39016;39096.80451597745;39156.60843391565;42574.57425425745;42919.57080429195;42970.570294297046;43034.35448468276;43217.56782432175;8390.286825619829;9390.436573805577;9601.47191904445;9611.375260608072;9621.903780962206;8499.61751721174;11440.970312671858;11653.766924661502;11666.650000499998;11684;11373.147013973943;11443.656690299264;11446.599369022086;11466.71333216672;11529;8525.104863989316;8683.348748843862;8683.56582170889;11096.223264371498;11167.888321116807;8464;11394.430278486125;11655.358955257458;11669.066474682006;11669.241499302518;39088.60911390878;39511.60488395108;39595;39615.801920990496;39622.405663915066;3225.145336485833;3251.5935508061493;3298.10951043217;3328.21786880083;3329.900102996914;27868.25124641935;28332;28614.14157575276;28777.56833647497;29157.271068223254;2748.4228312054493;2761.0198379575363;2776.583512473125;2824.463351963141;2857.8713957871946;458578;460196;462535.374646256;462839;463333;63756.68121659362;63863;64471.677641611495;64578.677106615105;64592;68934;70219.64890175516;70277.2972270281;70426.64786676034;70443.64778176077;17616.383426580007;17797.932124072337;17798.77515310586;17834.46496605078;17837.732434013502;12598.622041338775;12806.67983300425;13392.464301428028;13611.523596674268;13759;19446.07428770319;20217.88801615896;20234.392968210977;20252.18991240363;20321.390358289274;8182.199916018467;8239.299659528888;8249.752507424786;8254.793630159174;8331.791705207419;982.4203719805349;1033.338663255517;1061.6125114333213;1109.97327472088;1138.9487473063732;29210.707892921222;30042.54936175959;30143.69856301453;30630.69369306323;30645.540316895265;1120.3278033180027;1156.8958793708607;1160.785254727878;1164.8485696859523;1189.2151180221185;15208.847911520743;15425.537233883002;15435.459758908384;15765;15791.605209869846;631603;632338;634882;636331;637946;61578.38421615727;62021.68989155025;62154;62179.756404872554;62380.376196238365;1383.9100458470168;1392.449982257023;1484.4136566056563;1506.0361368724025;1526.4275896538797;28774.705138269248;28927;29188;29931.85034074816;29944.401111978073;47720.2841957364;47739.522604773505;48084.27873581829;48114;48168.5183148164;258703.41296587547;303270;306518;307999;308356;23992.88003560041;24376.268711938672;24530.877345613873;27432.588511172748;27476.45047099087;41596.58403415927;42664;44054.55945440505;44162;44234.33648495211;33385.666143338254;35297;35992;36723.265534688624;36770.816145919096;35940.46089308715;36099.097522562144;37092;37408;38027.85916422512;30262.394752104396;36797.81601091977;36915;37286.6271337294;38017.429738553394;32326.676733232365;32396;32692.67307326896;32950.176245594055;32953.67046329506;24763.009479620614;24792.380190495383;25123.87438062871;25242.24273271805;25360.74639253584 16:10:58 INFO - PROCESS | 1681 | |2;jquery.html;342.8839128636292;400.5133762478588;400.69346949583564;405.7484359696986;417.6366561091851;13536.864631353683;15546.611334716637;20239.291624793135;20743.066562004708;20862.687059694115;15440.910602970744;16021;16187.514374568753;20398.796012039875;20730.792692073075;5426.186072089188;5863.912041319383;5868.618539794899;5871.031279838843;5873.911891321633;4127.731697439672;5733.885322293551;5885.529157667398;5885.646861188342;6202.100695399165;5372.7313634318325;6461.386168314026;6463.903041454382;6468.417842394184;6475.5467117301805;6201.3798620138305;6449.290578036413;6461.7738379156735;6477.935220647781;6491.383318584727;5656.745446454919;5674.829755107354;5676.631018983794;5676.772929082832;5683.971580142114;24790.876045619836;25573.616395754078;25898.22305330843;25919.74080259192;25938.610920836203;2400.243923164198;2606.9702467525412;2920.5911172435876;2971.227480854972;2985.1641540368732;13947.157167289153;14669.853301466956;14745.926270368687;14784.408623655148;14911.70176596462;1687.6709041736867;1730;1751.9474415767547;1757.0072908806612;1803.1435068342564;109783;112943;114920;115592;116474;10739.409332486788;11061.834072488919;11286.54853805855;11303.86961303863;11516.366599837089;14292.857071429153;14362.994590378783;14369.99410041287;14379.352929118215;14401.567952961428;7810.726624568113;8135.3491720662205;8370.455920365279;8696.826063478695;8805.911940880509;9050.86423703645;9076.591553380114;9090.454572725657;9157.77105572366;9173.266138708887;5770.942290577041;6592.604808523614;6798.66006699663;6807.128039788999;6815.522913396015;1554.9766753498707;1852.8610354223488;1887.0941947864826;1922.1926790747918;1939.3309308288617;965.9951700241454;991.9553620087113;1028.598846449885;1035.2245898284477;1114.8272017837248;19873.801261987483;20991.895040524698;21053.578928421346;21165.94170291479;21421.143154273654;897.434616191795;984.1831280037552;1032.1175395037285;1075.1882328841675;1082.9566817327218;8668.78328041804;10432.582696692047;10694.572217111385;10739.463026848627;10880.455977201109;613893;625662;636991;638319;651067;73121;86563;129138;135783.32108339394;136951;880.8062226310329;1055.1242468750918;1073.3345325897992;1074.564801255477;1074.822654262038;17606.943583385037;17626.29494820244;17787.46637600874;17922.92462452257;18152.636947261246;52294.95410091853;52879.94240115099;54503.72748136234;55484.72257638686;55628;54329;75454.62272688601;75979.62010190135;76318.61840690984;78574.607126964;7980.580785722083;8114.5942702865805;8136.891228909924;8246.628901699318;8249.505029698237;8873.201411872962;11407.486663100015;12426.502939882303;12661.113722039368;12708.872911271139;10696.946515267375;10766.838497422388;10814.729631759268;10983.286086404358;11002.174836887105;10206.897931020594;10564.366138031743;10640.095591874777;10730.463476826284;10942.069924056545;10031.247656425652;10163.796724065625;10436.060754532129;10557.894421055691;11005.339679619248;7965.601719914097;8027.5584842834205;8089.393295502979;8247.463914845515;8356.456830306253;9035.141661542142;9508.762280943032;9620.71137865865;9677.22582193437;9889.208863290776 16:10:58 INFO - PROCESS | 1681 | |3;mootools.html;1410.668492904168;1538.9230538473078;1568.4353632692232;1791.8745687801859;1945.9124339404725;8054.315383192428;8735.694250701219;8905.821883562325;8916.643334266637;8918.82162356754;8633.525156116411;8901.154390332924;8908.10918908109;8918;8923.063078376757;2470.1354525915967;2494.9251522454356;2496.276079936816;2496.737842526527;2497.1384872219055;2052.3945436096387;2439.7560243975636;2492.314613481289;2494.3514686181543;2501.8999240030375;2461.360046387943;2482.056818409002;2488.601823708207;2504.561701702204;2505.0355613089;2440.3289095498703;2473.75262473753;2481.0944005437977;2487.614419764939;2495.089292408276;2436.8172387070863;2475.962860557093;2488.8506689598503;2489.514544663791;2492.9376765580823;8567.314614830799;8883.644654213824;8934.64261429542;8948.194662480408;8957.14907083826;5289.153735402318;5441.319835020622;5492.423295553989;5493.615446918718;5497.39528651848;2911.621489206411;3372.140104273419;3416.6241713411505;3490.7556471047037;3490.965090349089;1043.1275859831621;1058.978820423595;1063.7979083635441;1078.4607696151925;1081.0054749630358;10165.288429809916;12101;12162.756744865053;12178.451969661386;12265.202761820543;3957.168994511185;4478.57453541913;4704.929426058612;4718.834840780556;4721.740304283228;9436;9495.810083798286;9543;9548.90451095494;9563.904360956301;5192.5586325162785;5281.20781882713;5357.544608708304;5373.838784836461;5440.755166017539;3828.48315477407;4275.102228532043;4418.983633764252;4707.8587642370785;4929.802807887716;3818.579956204815;3897.922041559153;3916;3964.484616999772;4010.839566417369;1214.1440284599337;1222.3888055972013;1223.7368965672424;1232.617888454582;1237.3442075699893;620.5656040771478;826.7561069484517;872.9170728780756;902.0933961368913;981.8330883749779;5851.21008663825;5975.790847320323;6011.819645410645;6098;6128.690507126102;974.2498276327331;994.4381424495137;1003.598560575761;1014.7209517382779;1083.47992963378;3874.5737968823405;4000.7599544027453;4021.034951611592;4071.6131967463043;4108.4864391951005;10500.369977801356;10693.946530267454;10830.187735919837;10870.945645271722;10990.945045274722;10716.410597417216;10836.187285953585;10838.891611083789;10839;10852.511636976196;7799.610019499002;8261.13258107902;8278.627461764236;8325.750227493185;8472.322214222848;5257.8159764408065;5827.59206855533;5854.443827836349;5940.990031694536;6220.377962203744;9271.67549135777;9279.072092790668;9496.097870702271;9656;9665;9654.662086826927;9811.55847986828;9815.705528834147;9825.606975720892;9930.751731206488;7153.320434558708;7579.848403032019;7581.355584775135;7584.203658615766;7588.089429268523;8177.141400153137;8361.372897032625;8486.448380855225;8495.065542790411;8518.48889066658;6328.557001009885;7311.744088956861;7495.287947644965;7514;7539.962300188683;7303.379212766974;7488.9625551871895;7508.136564295176;7602.961985190039;7654;7050.647467626495;7085.893711594227;7116.928830711834;7121.78929581962;7127.358537731629;4876.804927802848;4946.777395017161;4971.179755340473;5069.011542749276;5095;5024.673396229234;5051.040302342543;5112.565431938327;5123.462036486116;5305.257263983122 16:10:58 INFO - PROCESS | 1681 | |4;prototype.html;320.64728798321846;323.61328212785713;329.3922712595262;329.5978905735004;333.5896846878341;5870.88258234835;6466.7413303467865;6469.353064693529;6509.772157974472;6522;6509.707063182156;6511.934880651192;6518.837029074263;6529.216494020712;6529.967350163254;3805.5052843130356;4619.74591397473;4703.811847526094;4811.35046768687;4825.975870120645;4683.578477936985;4800.375951126366;4831.541003604652;4832.3717916670785;4847.733374664392;4005.479287692596;4648.186567350717;4782.13921494131;4786.114568804782;4817.03659268146;4591.219492686251;4656.254999200146;4778.235482322812;4799.160146974265;4805.591524720403;4097.057676734368;4107.958920410817;4121.29937910553;4124.13393187431;4125.566815484361;6147.877042459126;6150.446459818593;6176.660283684396;6235.532335074842;6260.843478913018;5652.0391533439415;5674.148877668342;5681.488666020078;5682.289713785777;5690.687012214326;6740.056392105109;6980.825479363006;6992.273013524729;6994.607615306572;7000.684969176399;4668.043051174501;4668.883277918046;4672.836450724209;4676.69601475903;4678.95321046792;7290.489665723401;7337.19290878003;7369.7052117915755;7370.189279179286;7376.225496322916;4736.076465089274;4744.881377965509;4749.2876068589985;4755.167845627071;4755.548222918817;4812.807487700523;4813.518648135158;4820.807167713252;4826.493218212107;4829.830955916526;5675.432456754292;5680;5683;5686.857828554319;5698.145278208219;6743.763968261087;7008.159020917523;7037.824054398681;7052.435805135577;7074.893876591856;1655.8078183707712;1680.487451327357;1685.005846550541;1685.4438035448272;1687.333503266203;891.714651311574;1013.6148263659762;1027.8561001459802;1059.4755595979884;1087.260662749338;6388.4569811566535;6537.477001839841;6557.540972131905;6573.408393244631;6585.83535411609;6287.591306565058;7279.854402911912;7291.416686665054;7292.781216563511;7298.671559779823;6502.674866256668;6513.869722605521;6514.120593719882;6516.413522782877;6532.41208291256;3781.7541859779576;3829.0810205551024;3835.5780864104927;3837.366834472281;3839;6428.935710642833;7283.70865165398;7354;7490.4382171337365;7616;6342.809715708536;7474.140473845578;7488.213737557587;7507.774766757006;7559.924400756032;7679.0785105786545;7693.576853273012;7701.88447173304;7709.498882572615;7710.691572337156;7010.50926435165;7338.486305958745;7349.77950661481;7359.37445317154;7399.3340599344165;7174.713011479483;7199.532030418006;7225;7232.385247254042;7278.781636550912;7685.4620176587105;7688.192739762245;7691.15397306307;7691.730789422343;7699.422543309162;4551.476580193188;4624.375709279204;4626.930596040995;4628.930566041579;4634.420697412824;6997.195322537973;7313.524620899624;7316.341529262392;7318.890216646861;7324.267573242633;7045.859082818418;7061.576305421691;7066.222715501188;7069.540479868998;7101.147862256562;7036.929630703832;7046.4715146363205;7049.39172506176;7051.1186101737285;7071.71713131469;6879.89680154788;6881.346272104142;6881.41507971828;6900.3444672756;6940.826479337855;6756.8648627026205;7002.964985175246;7042.683079261548;7053.823654408681;7069.858602828018;6663.400293973566;6785.287544807921;6816.931830681828;6897.3447522485285;6908.205556360882 16:10:58 INFO - PROCESS | 1681 | |5;yui.html;691.737139886843;694.978381778785;709.6522703875099;712.0956385390552;765.2462324610254;14958.401663933444;15060.774088388684;15127.84872151278;15155.54533363998;15156.393744250217;13641.113327633697;13655.522056728016;13673.794893076612;13717.176969381819;13723.313834308294;717.1394326807825;738.5531753289263;900.4372267332917;902.7968707040912;928.6053427293407;920.6501529418845;920.8158368326328;921.1341339141219;925.5418567808911;930.0838673906197;975.1905918088005;978.4031740638205;978.5254151736402;981.7300242433316;983.1986930651497;761.8552475029754;968.6900191938582;969.8254314223437;975.2782940623923;988.6836212412031;731.3710209220067;731.846312274423;733.581858340746;734.4858598980735;744.3449764207517;14491.275436228147;14564.49024284145;14568.344424500923;14578.70842583153;14588.70822583553;12556.058295627774;12641.2415255085;12745.61763147107;12872.16330938486;12939.417726202344;10016.89983100167;10048.899511004869;10145.746356341077;10228.284020118595;10349.948250258776;3483.4600636901323;3506;3507.824608769551;3517.4196257617464;3576.5529308836394;58480.70759646175;76464;76588;79804.60097699532;80179.19820801775;9417.623295068257;9533.142017218484;9609.135177833889;9714.319997600242;10130.138938190188;22084.668729969064;22246.11015559392;22268.220612278492;22283;22289.331320060424;7464.776056718307;7469.066366704162;7564.319211271012;7589.392848572101;7833.138354780969;4021.4571032910767;4127.8968025799595;4146.937795933003;4150.522689890641;4251.80866860992;3572.1248294167576;3587.838547265327;3628.637136286403;3632.0919770057485;3640.162762564625;614.1064750787593;615.2463232540102;621.2389822467442;623.0716232813166;628.6542401679059;352.66144501278546;605.9454649081604;615.8029430582171;629.4618101523223;637.7066549387241;7868;8245.13426090264;8282.254597086292;8299.045609754834;8345.059954414897;483.3957553058677;596.5406636889571;600.1058422949774;602.2682440834408;605.449041372349;3820.7898565578603;3833.4824798651825;3864.7681139131178;3977.3636218204947;4077.55146933837;84281;86460;86897;87675.56162219148;87708;74117;74194;74494.62752686202;75653;75841;63155.368446315864;63566.682166589795;64553.70892582216;64875.351246486935;66392;7641.2740789624895;7712.460127791225;7885.684572617031;7977.282044616012;8168.693009118512;45892.8526786833;46907;46940.765296173304;48094.519054809;48650.51349486601;54580.18129727978;55116.72441637766;57418;60353.69823150856;61053;10814;10993.285436446606;11094.167937404565;11306.604268850551;11688.532458701557;11694.122940779305;12068.974137198085;12185.207961482476;12240.265584064984;12357.814632780335;9673;10526.368417894952;10764.138868890313;11695.824562631396;11811.645650630495;11181.664550063511;11422.714432139262;11497.425128743696;11541.019013383957;11726.765464690488;8554.871676924726;9208.447493150432;9442.622295108118;9660.565274562801;9667.661631842851;7873.37013038944;9324.300677449082;9504.809903802023;9531.237500999765;9918;9548.570314335733;9549.331546791658;9570.760730981781;9655.517224138624;9719.708408747749 16:10:58 INFO - PROCESS | 1681 | __end_tp_report 16:10:58 INFO - PROCESS | 1681 | __start_cc_report 16:10:58 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1643 16:10:58 INFO - PROCESS | 1681 | __end_cc_report 16:10:58 INFO - PROCESS | 1681 | __startTimestamp1464649858047__endTimestamp 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | ------- Summary: start ------- 16:10:58 INFO - PROCESS | 1681 | Number of tests: 6 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#0] dojo.html Cycles:165 Average:5719.86 Median:5920.67 stddev:631.05 (10.7%) stddev-sans-first:605.67 16:10:58 INFO - PROCESS | 1681 | Values: 3378.6 3419.9 3441.1 3467.3 3492.9 5593.3 5595.3 5631.2 5634.6 5641.4 5612.6 5655.5 5665.0 5677.1 5682.9 5083.3 5132.2 5179.7 5233.8 5251.6 5224.9 5269.2 5275.2 5279.1 5280.2 5163.9 5197.5 5221.9 5227.0 5228.7 5256.5 5289.5 5311.1 5335.5 5360.8 5048.3 5072.0 5099.2 5223.7 5242.4 5488.1 5522.8 5541.1 5551.0 5585.6 6181.5 6220.5 6229.4 6229.6 6244.5 6125.0 6170.8 6255.1 6255.7 6306.6 5718.5 5729.7 5730.1 5749.6 5778.5 6332.9 6351.5 6354.5 6354.8 6369.0 5971.0 6039.6 6060.8 6066.0 6068.6 6148.2 6186.8 6196.6 6308.6 6314.7 6138.2 6143.2 6145.6 6154.3 6168.2 6131.2 6148.5 6161.5 6165.5 6165.9 4813.2 4896.9 4899.1 4900.3 4936.3 4397.1 4420.8 4447.5 4449.5 4453.1 5598.8 5658.7 5674.7 5686.1 5703.3 6211.8 6286.8 6287.5 6295.5 6314.3 5522.7 5629.2 5648.7 5649.3 5660.7 5187.5 5319.0 5333.7 5364.1 5380.6 6319.7 6325.5 6336.7 6348.5 6355.7 6118.1 6249.0 6291.0 6305.1 6305.7 6275.6 6324.8 6327.9 6333.5 6349.7 6154.5 6187.6 6202.3 6209.0 6219.7 6076.2 6266.2 6288.6 6293.9 6296.3 5027.4 5992.2 6072.5 6104.7 6117.4 6079.1 6099.2 6121.8 6130.1 6168.0 5742.8 5920.7 5967.2 5973.8 5981.6 5939.6 5966.2 5971.4 5976.3 5996.3 5845.0 5860.9 5862.7 5868.0 5869.0 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#1] ext.html Cycles:180 Average:61602.58 Median:32821.42 stddev:129480.24 (394.5%) stddev-sans-first:129762.16 16:10:58 INFO - PROCESS | 1681 | Values: 490.4 493.3 507.3 596.5 619.9 31134.5 37695.0 39016.0 39096.8 39156.6 42574.6 42919.6 42970.6 43034.4 43217.6 8390.3 9390.4 9601.5 9611.4 9621.9 8499.6 11441.0 11653.8 11666.7 11684.0 11373.1 11443.7 11446.6 11466.7 11529.0 8525.1 8683.3 8683.6 11096.2 11167.9 8464.0 11394.4 11655.4 11669.1 11669.2 39088.6 39511.6 39595.0 39615.8 39622.4 3225.1 3251.6 3298.1 3328.2 3329.9 27868.3 28332.0 28614.1 28777.6 29157.3 2748.4 2761.0 2776.6 2824.5 2857.9 458578.0 460196.0 462535.4 462839.0 463333.0 63756.7 63863.0 64471.7 64578.7 64592.0 68934.0 70219.6 70277.3 70426.6 70443.6 17616.4 17797.9 17798.8 17834.5 17837.7 12598.6 12806.7 13392.5 13611.5 13759.0 19446.1 20217.9 20234.4 20252.2 20321.4 8182.2 8239.3 8249.8 8254.8 8331.8 982.4 1033.3 1061.6 1110.0 1138.9 29210.7 30042.5 30143.7 30630.7 30645.5 1120.3 1156.9 1160.8 1164.8 1189.2 15208.8 15425.5 15435.5 15765.0 15791.6 631603.0 632338.0 634882.0 636331.0 637946.0 61578.4 62021.7 62154.0 62179.8 62380.4 1383.9 1392.4 1484.4 1506.0 1526.4 28774.7 28927.0 29188.0 29931.9 29944.4 47720.3 47739.5 48084.3 48114.0 48168.5 258703.4 303270.0 306518.0 307999.0 308356.0 23992.9 24376.3 24530.9 27432.6 27476.5 41596.6 42664.0 44054.6 44162.0 44234.3 33385.7 35297.0 35992.0 36723.3 36770.8 35940.5 36099.1 37092.0 37408.0 38027.9 30262.4 36797.8 36915.0 37286.6 38017.4 32326.7 32396.0 32692.7 32950.2 32953.7 24763.0 24792.4 25123.9 25242.2 25360.7 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#2] jquery.html Cycles:180 Average:35259.58 Median:25929.18 stddev:104999.37 (404.9%) stddev-sans-first:105261.19 16:10:58 INFO - PROCESS | 1681 | Values: 342.9 400.5 400.7 405.7 417.6 13536.9 15546.6 20239.3 20743.1 20862.7 15440.9 16021.0 16187.5 20398.8 20730.8 5426.2 5863.9 5868.6 5871.0 5873.9 4127.7 5733.9 5885.5 5885.6 6202.1 5372.7 6461.4 6463.9 6468.4 6475.5 6201.4 6449.3 6461.8 6477.9 6491.4 5656.7 5674.8 5676.6 5676.8 5684.0 24790.9 25573.6 25898.2 25919.7 25938.6 2400.2 2607.0 2920.6 2971.2 2985.2 13947.2 14669.9 14745.9 14784.4 14911.7 1687.7 1730.0 1751.9 1757.0 1803.1 109783.0 112943.0 114920.0 115592.0 116474.0 10739.4 11061.8 11286.5 11303.9 11516.4 14292.9 14363.0 14370.0 14379.4 14401.6 7810.7 8135.3 8370.5 8696.8 8805.9 9050.9 9076.6 9090.5 9157.8 9173.3 5770.9 6592.6 6798.7 6807.1 6815.5 1555.0 1852.9 1887.1 1922.2 1939.3 966.0 992.0 1028.6 1035.2 1114.8 19873.8 20991.9 21053.6 21165.9 21421.1 897.4 984.2 1032.1 1075.2 1083.0 8668.8 10432.6 10694.6 10739.5 10880.5 613893.0 625662.0 636991.0 638319.0 651067.0 73121.0 86563.0 129138.0 135783.3 136951.0 880.8 1055.1 1073.3 1074.6 1074.8 17606.9 17626.3 17787.5 17922.9 18152.6 52295.0 52879.9 54503.7 55484.7 55628.0 54329.0 75454.6 75979.6 76318.6 78574.6 7980.6 8114.6 8136.9 8246.6 8249.5 8873.2 11407.5 12426.5 12661.1 12708.9 10696.9 10766.8 10814.7 10983.3 11002.2 10206.9 10564.4 10640.1 10730.5 10942.1 10031.2 10163.8 10436.1 10557.9 11005.3 7965.6 8027.6 8089.4 8247.5 8356.5 9035.1 9508.8 9620.7 9677.2 9889.2 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#3] mootools.html Cycles:180 Average:5712.17 Median:5118.01 stddev:3176.80 (62.1%) stddev-sans-first:3169.27 16:10:58 INFO - PROCESS | 1681 | Values: 1410.7 1538.9 1568.4 1791.9 1945.9 8054.3 8735.7 8905.8 8916.6 8918.8 8633.5 8901.2 8908.1 8918.0 8923.1 2470.1 2494.9 2496.3 2496.7 2497.1 2052.4 2439.8 2492.3 2494.4 2501.9 2461.4 2482.1 2488.6 2504.6 2505.0 2440.3 2473.8 2481.1 2487.6 2495.1 2436.8 2476.0 2488.9 2489.5 2492.9 8567.3 8883.6 8934.6 8948.2 8957.1 5289.2 5441.3 5492.4 5493.6 5497.4 2911.6 3372.1 3416.6 3490.8 3491.0 1043.1 1059.0 1063.8 1078.5 1081.0 10165.3 12101.0 12162.8 12178.5 12265.2 3957.2 4478.6 4704.9 4718.8 4721.7 9436.0 9495.8 9543.0 9548.9 9563.9 5192.6 5281.2 5357.5 5373.8 5440.8 3828.5 4275.1 4419.0 4707.9 4929.8 3818.6 3897.9 3916.0 3964.5 4010.8 1214.1 1222.4 1223.7 1232.6 1237.3 620.6 826.8 872.9 902.1 981.8 5851.2 5975.8 6011.8 6098.0 6128.7 974.2 994.4 1003.6 1014.7 1083.5 3874.6 4000.8 4021.0 4071.6 4108.5 10500.4 10693.9 10830.2 10870.9 10990.9 10716.4 10836.2 10838.9 10839.0 10852.5 7799.6 8261.1 8278.6 8325.8 8472.3 5257.8 5827.6 5854.4 5941.0 6220.4 9271.7 9279.1 9496.1 9656.0 9665.0 9654.7 9811.6 9815.7 9825.6 9930.8 7153.3 7579.8 7581.4 7584.2 7588.1 8177.1 8361.4 8486.4 8495.1 8518.5 6328.6 7311.7 7495.3 7514.0 7540.0 7303.4 7489.0 7508.1 7603.0 7654.0 7050.6 7085.9 7116.9 7121.8 7127.4 4876.8 4946.8 4971.2 5069.0 5095.0 5024.7 5051.0 5112.6 5123.5 5305.3 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#4] prototype.html Cycles:180 Average:5745.49 Median:6514.00 stddev:1836.31 (28.2%) stddev-sans-first:1795.76 16:10:58 INFO - PROCESS | 1681 | Values: 320.6 323.6 329.4 329.6 333.6 5870.9 6466.7 6469.4 6509.8 6522.0 6509.7 6511.9 6518.8 6529.2 6530.0 3805.5 4619.7 4703.8 4811.4 4826.0 4683.6 4800.4 4831.5 4832.4 4847.7 4005.5 4648.2 4782.1 4786.1 4817.0 4591.2 4656.3 4778.2 4799.2 4805.6 4097.1 4108.0 4121.3 4124.1 4125.6 6147.9 6150.4 6176.7 6235.5 6260.8 5652.0 5674.1 5681.5 5682.3 5690.7 6740.1 6980.8 6992.3 6994.6 7000.7 4668.0 4668.9 4672.8 4676.7 4679.0 7290.5 7337.2 7369.7 7370.2 7376.2 4736.1 4744.9 4749.3 4755.2 4755.5 4812.8 4813.5 4820.8 4826.5 4829.8 5675.4 5680.0 5683.0 5686.9 5698.1 6743.8 7008.2 7037.8 7052.4 7074.9 1655.8 1680.5 1685.0 1685.4 1687.3 891.7 1013.6 1027.9 1059.5 1087.3 6388.5 6537.5 6557.5 6573.4 6585.8 6287.6 7279.9 7291.4 7292.8 7298.7 6502.7 6513.9 6514.1 6516.4 6532.4 3781.8 3829.1 3835.6 3837.4 3839.0 6428.9 7283.7 7354.0 7490.4 7616.0 6342.8 7474.1 7488.2 7507.8 7559.9 7679.1 7693.6 7701.9 7709.5 7710.7 7010.5 7338.5 7349.8 7359.4 7399.3 7174.7 7199.5 7225.0 7232.4 7278.8 7685.5 7688.2 7691.2 7691.7 7699.4 4551.5 4624.4 4626.9 4628.9 4634.4 6997.2 7313.5 7316.3 7318.9 7324.3 7045.9 7061.6 7066.2 7069.5 7101.1 7036.9 7046.5 7049.4 7051.1 7071.7 6879.9 6881.3 6881.4 6900.3 6940.8 6756.9 7003.0 7042.7 7053.8 7069.9 6663.4 6785.3 6816.9 6897.3 6908.2 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - PROCESS | 1681 | [#5] yui.html Cycles:180 Average:17305.01 Median:618.52 stddev:23735.01 (3837.4%) stddev-sans-first:23768.81 16:10:58 INFO - PROCESS | 1681 | Values: 691.7 695.0 709.7 712.1 765.2 14958.4 15060.8 15127.8 15155.5 15156.4 13641.1 13655.5 13673.8 13717.2 13723.3 717.1 738.6 900.4 902.8 928.6 920.7 920.8 921.1 925.5 930.1 975.2 978.4 978.5 981.7 983.2 761.9 968.7 969.8 975.3 988.7 731.4 731.8 733.6 734.5 744.3 14491.3 14564.5 14568.3 14578.7 14588.7 12556.1 12641.2 12745.6 12872.2 12939.4 10016.9 10048.9 10145.7 10228.3 10349.9 3483.5 3506.0 3507.8 3517.4 3576.6 58480.7 76464.0 76588.0 79804.6 80179.2 9417.6 9533.1 9609.1 9714.3 10130.1 22084.7 22246.1 22268.2 22283.0 22289.3 7464.8 7469.1 7564.3 7589.4 7833.1 4021.5 4127.9 4146.9 4150.5 4251.8 3572.1 3587.8 3628.6 3632.1 3640.2 614.1 615.2 621.2 623.1 628.7 352.7 605.9 615.8 629.5 637.7 7868.0 8245.1 8282.3 8299.0 8345.1 483.4 596.5 600.1 602.3 605.4 3820.8 3833.5 3864.8 3977.4 4077.6 84281.0 86460.0 86897.0 87675.6 87708.0 74117.0 74194.0 74494.6 75653.0 75841.0 63155.4 63566.7 64553.7 64875.4 66392.0 7641.3 7712.5 7885.7 7977.3 8168.7 45892.9 46907.0 46940.8 48094.5 48650.5 54580.2 55116.7 57418.0 60353.7 61053.0 10814.0 10993.3 11094.2 11306.6 11688.5 11694.1 12069.0 12185.2 12240.3 12357.8 9673.0 10526.4 10764.1 11695.8 11811.6 11181.7 11422.7 11497.4 11541.0 11726.8 8554.9 9208.4 9442.6 9660.6 9667.7 7873.4 9324.3 9504.8 9531.2 9918.0 9548.6 9549.3 9570.8 9655.5 9719.7 16:10:58 INFO - PROCESS | 1681 | -------- Summary: end -------- 16:10:58 INFO - PROCESS | 1681 | 16:10:58 INFO - TEST-INFO | 1681: exit 0 16:10:58 INFO - TEST-OK | dromaeo_css | took 1115098ms 16:10:58 INFO - TEST-START | kraken 16:10:58 INFO - operating with platform_type : mac_ 16:10:58 INFO - Initialising browser for kraken test... 16:10:58 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpB7tuxn/profile http://localhost:49275/getInfo.html) 16:11:00 INFO - PROCESS | 1688 | __metrics Screen width/height:1600/1200 16:11:00 INFO - PROCESS | 1688 | colorDepth:24 16:11:00 INFO - PROCESS | 1688 | Browser inner width/height: 1024/689 16:11:00 INFO - PROCESS | 1688 | __metrics 16:11:00 INFO - PROCESS | 1688 | 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:11:00 ERROR - PROCESS | 1688 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:11:01 INFO - TEST-INFO | 1688: exit 0 16:11:01 INFO - Browser initialized. 16:11:01 INFO - Running cycle 1/1 for kraken test... 16:11:01 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpB7tuxn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:13 INFO - PROCESS | 1690 | RSS: Main: 211427328 16:11:13 INFO - PROCESS | 1690 | 16:11:15 INFO - PROCESS | 1690 | 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:11:16 INFO - PROCESS | 1690 | RSS: Main: 271765504 16:11:16 INFO - PROCESS | 1690 | 16:11:19 INFO - PROCESS | 1690 | 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:11:20 INFO - PROCESS | 1690 | RSS: Main: 286388224 16:11:20 INFO - PROCESS | 1690 | 16:11:23 INFO - PROCESS | 1690 | 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:11:24 INFO - PROCESS | 1690 | RSS: Main: 375025664 16:11:24 INFO - PROCESS | 1690 | 16:11:26 INFO - PROCESS | 1690 | 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:11:26 INFO - PROCESS | 1690 | RSS: Main: 279429120 16:11:26 INFO - PROCESS | 1690 | 16:11:29 INFO - PROCESS | 1690 | 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:11:29 INFO - PROCESS | 1690 | RSS: Main: 280109056 16:11:29 INFO - PROCESS | 1690 | 16:11:32 INFO - PROCESS | 1690 | 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:11:33 INFO - PROCESS | 1690 | RSS: Main: 316071936 16:11:33 INFO - PROCESS | 1690 | 16:11:35 INFO - PROCESS | 1690 | 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:11:36 INFO - PROCESS | 1690 | RSS: Main: 317878272 16:11:36 INFO - PROCESS | 1690 | 16:11:39 INFO - PROCESS | 1690 | 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:11:39 INFO - PROCESS | 1690 | RSS: Main: 319234048 16:11:39 INFO - PROCESS | 1690 | 16:11:41 INFO - PROCESS | 1690 | 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:11:42 INFO - PROCESS | 1690 | RSS: Main: 309391360 16:11:42 INFO - PROCESS | 1690 | 16:11:44 INFO - PROCESS | 1690 | 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:11:44 INFO - PROCESS | 1690 | RSS: Main: 316850176 16:11:44 INFO - PROCESS | 1690 | 16:11:47 INFO - PROCESS | 1690 | 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:11:47 INFO - PROCESS | 1690 | RSS: Main: 319246336 16:11:47 INFO - PROCESS | 1690 | 16:11:50 INFO - PROCESS | 1690 | 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:11:51 INFO - PROCESS | 1690 | RSS: Main: 323821568 16:11:51 INFO - PROCESS | 1690 | 16:11:53 INFO - PROCESS | 1690 | 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:11:54 INFO - PROCESS | 1690 | RSS: Main: 321900544 16:11:54 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | 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:11:56 INFO - PROCESS | 1690 | RSS: Main: 386195456 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | __start_tp_report 16:11:56 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 16:11:56 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 16:11:56 INFO - PROCESS | 1690 | |i|pagename|runs| 16:11:56 INFO - PROCESS | 1690 | |0;ai-astar;113;114;120;113;114;112;112;113;115;137 16:11:56 INFO - PROCESS | 1690 | |1;audio-beat-detection;156;123;140;183;153;136;120;173;196;147 16:11:56 INFO - PROCESS | 1690 | |2;audio-dft;185;186;188;185;185;184;182;182;186;185 16:11:56 INFO - PROCESS | 1690 | |3;audio-fft;73;104;109;194;55;55;56;59;133;57 16:11:56 INFO - PROCESS | 1690 | |4;audio-oscillator;92;92;154;108;117;110;123;79;77;124 16:11:56 INFO - PROCESS | 1690 | |5;imaging-gaussian-blur;88;85;88;85;87;85;88;86;88;84 16:11:56 INFO - PROCESS | 1690 | |6;imaging-darkroom;94;94;93;93;98;93;93;98;110;94 16:11:56 INFO - PROCESS | 1690 | |7;imaging-desaturate;84;83;83;85;84;83;85;84;86;93 16:11:56 INFO - PROCESS | 1690 | |8;json-parse-financial;61;64;64;62;66;65;66;85;59;57 16:11:56 INFO - PROCESS | 1690 | |9;json-stringify-tinderbox;63;69;64;89;108;106;97;75;69;67 16:11:56 INFO - PROCESS | 1690 | |10;stanford-crypto-aes;82;84;94;104;104;104;79;81;82;94 16:11:56 INFO - PROCESS | 1690 | |11;stanford-crypto-ccm;125;126;117;123;127;167;129;131;153;163 16:11:56 INFO - PROCESS | 1690 | |12;stanford-crypto-pbkdf2;168;167;166;167;165;154;163;165;164;163 16:11:56 INFO - PROCESS | 1690 | |13;stanford-crypto-sha256-iterative;69;70;68;68;67;69;67;69;67;67 16:11:56 INFO - PROCESS | 1690 | __end_tp_report 16:11:56 INFO - PROCESS | 1690 | __start_cc_report 16:11:56 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1975 16:11:56 INFO - PROCESS | 1690 | __end_cc_report 16:11:56 INFO - PROCESS | 1690 | __startTimestamp1464649916585__endTimestamp 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | ------- Summary: start ------- 16:11:56 INFO - PROCESS | 1690 | Number of tests: 14 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:7.63 (6.7%) stddev-sans-first:8.00 16:11:56 INFO - PROCESS | 1690 | Values: 113.0 114.0 120.0 113.0 114.0 112.0 112.0 113.0 115.0 137.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:154.50 stddev:25.03 (16.2%) stddev-sans-first:26.52 16:11:56 INFO - PROCESS | 1690 | Values: 156.0 123.0 140.0 183.0 153.0 136.0 120.0 173.0 196.0 147.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.81 (1.0%) stddev-sans-first:1.92 16:11:56 INFO - PROCESS | 1690 | Values: 185.0 186.0 188.0 185.0 185.0 184.0 182.0 182.0 186.0 185.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#3] audio-fft Cycles:10 Average:89.50 Median:55.50 stddev:46.15 (83.1%) stddev-sans-first:48.56 16:11:56 INFO - PROCESS | 1690 | Values: 73.0 104.0 109.0 194.0 55.0 55.0 56.0 59.0 133.0 57.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#4] audio-oscillator Cycles:10 Average:107.60 Median:115.50 stddev:23.56 (20.4%) stddev-sans-first:24.30 16:11:56 INFO - PROCESS | 1690 | Values: 92.0 92.0 154.0 108.0 117.0 110.0 123.0 79.0 77.0 124.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.50 stddev:1.58 (1.8%) stddev-sans-first:1.56 16:11:56 INFO - PROCESS | 1690 | Values: 88.0 85.0 88.0 85.0 87.0 85.0 88.0 86.0 88.0 84.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:94.00 stddev:5.29 (5.6%) stddev-sans-first:5.56 16:11:56 INFO - PROCESS | 1690 | Values: 94.0 94.0 93.0 93.0 98.0 93.0 93.0 98.0 110.0 94.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:84.50 stddev:2.98 (3.5%) stddev-sans-first:3.14 16:11:56 INFO - PROCESS | 1690 | Values: 84.0 83.0 83.0 85.0 84.0 83.0 85.0 84.0 86.0 93.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:7.67 (11.9%) stddev-sans-first:8.00 16:11:56 INFO - PROCESS | 1690 | Values: 61.0 64.0 64.0 62.0 66.0 65.0 66.0 85.0 59.0 57.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:80.70 Median:69.00 stddev:17.66 (25.6%) stddev-sans-first:17.53 16:11:56 INFO - PROCESS | 1690 | Values: 63.0 69.0 64.0 89.0 108.0 106.0 97.0 75.0 69.0 67.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:82.00 stddev:10.43 (12.7%) stddev-sans-first:10.57 16:11:56 INFO - PROCESS | 1690 | Values: 82.0 84.0 94.0 104.0 104.0 104.0 79.0 81.0 82.0 94.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:130.00 stddev:17.90 (13.8%) stddev-sans-first:18.53 16:11:56 INFO - PROCESS | 1690 | Values: 125.0 126.0 117.0 123.0 127.0 167.0 129.0 131.0 153.0 163.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.50 stddev:3.97 (2.4%) stddev-sans-first:3.96 16:11:56 INFO - PROCESS | 1690 | Values: 168.0 167.0 166.0 167.0 165.0 154.0 163.0 165.0 164.0 163.0 16:11:56 INFO - PROCESS | 1690 | 16:11:56 INFO - PROCESS | 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.10 (1.6%) stddev-sans-first:1.12 16:11:56 INFO - PROCESS | 1690 | Values: 69.0 70.0 68.0 68.0 67.0 69.0 67.0 69.0 67.0 67.0 16:11:56 INFO - PROCESS | 1690 | -------- Summary: end -------- 16:11:56 INFO - PROCESS | 1690 | 16:11:57 INFO - TEST-INFO | 1690: exit 0 16:11:57 INFO - TEST-OK | kraken | took 58760ms 16:11:57 INFO - SUITE-END | took 1173s 16:11:57 INFO - Completed test suite (00:19:34) 16:11:57 INFO - javascript benchmark 16:11:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.594568651762, 3419.9316013679713, 3441.0537102296857, 3467.2718729066896, 3492.8822776711463, 5593.300837395325, 5595.300587426572, 5631.211630371751, 5634.633748806325, 5641.407652196517, 5612.635178713381, 5655.519280861132, 5664.971675141629, 5677.148427735841, 5682.886342273162, 5083.339165908426, 5132.204508301219, 5179.715115668637, 5233.790648374061, 5251.553617942461, 5224.921626175591, 5269.235960785683, 5275.155975043994, 5279.128943724281, 5280.234366016925, 5163.870903227412, 5197.454267301936, 5221.869453263661, 5227.0, 5228.7124208168725, 5256.526912577867, 5289.471052894718, 5311.123664595337, 5335.466453354634, 5360.758765855546, 5048.29323894655, 5071.974640126813, 5099.184130539133, 5223.660462069991, 5242.423333433319, 5488.067028605107, 5522.806701765419, 5541.085720856054, 5550.972245138789, 5585.636933599344, 6181.474574661114, 6220.533459990519, 6229.408206220402, 6229.563930524865, 6244.469220116295, 6125.021800333839, 6170.753169873245, 6255.06174073888, 6255.718492667841, 6306.621602703853, 5718.485336319668, 5729.684867332254, 5730.111832665943, 5749.568782341341, 5778.508826749731, 6332.936670633235, 6351.491880649537, 6354.459870910932, 6354.809355719336, 6369.0446433034485, 5971.0, 6039.577229593974, 6060.757569697251, 6065.969670151621, 6068.605540639845, 6148.231471066117, 6186.783462578788, 6196.597221180609, 6308.589941653778, 6314.684265786692, 6138.232720909887, 6143.157052884117, 6145.647957449354, 6154.261488621394, 6168.228971378578, 6131.172291740647, 6148.538859585549, 6161.537884658579, 6165.475934545524, 6165.876682466415, 4813.15769740294, 4896.902061958812, 4899.0691768564575, 4900.289458028566, 4936.333594964705, 4397.1205758848355, 4420.80106395213, 4447.510773814878, 4449.466064072332, 4453.087117140978, 5598.832035038955, 5658.74535645897, 5674.716264186774, 5686.118651609007, 5703.258576385043, 6211.81364559064, 6286.842828929313, 6287.49700023997, 6295.496360291166, 6314.305426403092, 5522.696251706195, 5629.183768353648, 5648.717564121777, 5649.293838270216, 5660.688662123623, 5187.455317191716, 5318.97340513295, 5333.7066461345, 5364.061289274361, 5380.569554435636, 6319.747210111544, 6325.525585581007, 6336.7148478319505, 6348.492120630339, 6355.650439225887, 6118.082287656887, 6248.968755156377, 6291.0, 6305.054241863573, 6305.653189074645, 6275.623462592259, 6324.778632747832, 6327.873442531216, 6333.524985626004, 6349.746010159542, 6154.538409619386, 6187.597806142587, 6202.255729312511, 6209.0, 6219.689015549295, 6076.210092687922, 6266.185395898504, 6288.622682639057, 6293.874122517615, 6296.307406185224, 5027.4218464877, 5992.191054207626, 6072.453479186895, 6104.69476526163, 6117.449429551361, 6079.088136779518, 6099.176611157437, 6121.84695382619, 6130.111133885473, 6168.013117901114, 5742.827715168552, 5920.674362910081, 5967.164596956516, 5973.790917318047, 5981.551383646331, 5939.554533409925, 5966.224390829338, 5971.402859714168, 5976.252968378953, 5996.2504686914135, 5844.970775146097, 5860.94139058621, 5862.706864656665, 5868.031774757288, 5869.002269614261], "value": 5680.091268115016, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.3551829344412, 493.2576472409024, 507.29739311054186, 596.4900010491032, 619.8946179149544, 31134.53298200526, 37695.0, 39016.0, 39096.80451597745, 39156.60843391565, 42574.57425425745, 42919.57080429195, 42970.570294297046, 43034.35448468276, 43217.56782432175, 8390.286825619829, 9390.436573805577, 9601.47191904445, 9611.375260608072, 9621.903780962206, 8499.61751721174, 11440.970312671858, 11653.766924661502, 11666.650000499998, 11684.0, 11373.147013973943, 11443.656690299264, 11446.599369022086, 11466.71333216672, 11529.0, 8525.104863989316, 8683.348748843862, 8683.56582170889, 11096.223264371498, 11167.888321116807, 8464.0, 11394.430278486125, 11655.358955257458, 11669.066474682006, 11669.241499302518, 39088.60911390878, 39511.60488395108, 39595.0, 39615.801920990496, 39622.405663915066, 3225.145336485833, 3251.5935508061493, 3298.10951043217, 3328.21786880083, 3329.900102996914, 27868.25124641935, 28332.0, 28614.14157575276, 28777.56833647497, 29157.271068223254, 2748.4228312054493, 2761.0198379575363, 2776.583512473125, 2824.463351963141, 2857.8713957871946, 458578.0, 460196.0, 462535.374646256, 462839.0, 463333.0, 63756.68121659362, 63863.0, 64471.677641611495, 64578.677106615105, 64592.0, 68934.0, 70219.64890175516, 70277.2972270281, 70426.64786676034, 70443.64778176077, 17616.383426580007, 17797.932124072337, 17798.77515310586, 17834.46496605078, 17837.732434013502, 12598.622041338775, 12806.67983300425, 13392.464301428028, 13611.523596674268, 13759.0, 19446.07428770319, 20217.88801615896, 20234.392968210977, 20252.18991240363, 20321.390358289274, 8182.199916018467, 8239.299659528888, 8249.752507424786, 8254.793630159174, 8331.791705207419, 982.4203719805349, 1033.338663255517, 1061.6125114333213, 1109.97327472088, 1138.9487473063732, 29210.707892921222, 30042.54936175959, 30143.69856301453, 30630.69369306323, 30645.540316895265, 1120.3278033180027, 1156.8958793708607, 1160.785254727878, 1164.8485696859523, 1189.2151180221185, 15208.847911520743, 15425.537233883002, 15435.459758908384, 15765.0, 15791.605209869846, 631603.0, 632338.0, 634882.0, 636331.0, 637946.0, 61578.38421615727, 62021.68989155025, 62154.0, 62179.756404872554, 62380.376196238365, 1383.9100458470168, 1392.449982257023, 1484.4136566056563, 1506.0361368724025, 1526.4275896538797, 28774.705138269248, 28927.0, 29188.0, 29931.85034074816, 29944.401111978073, 47720.2841957364, 47739.522604773505, 48084.27873581829, 48114.0, 48168.5183148164, 258703.41296587547, 303270.0, 306518.0, 307999.0, 308356.0, 23992.88003560041, 24376.268711938672, 24530.877345613873, 27432.588511172748, 27476.45047099087, 41596.58403415927, 42664.0, 44054.55945440505, 44162.0, 44234.33648495211, 33385.666143338254, 35297.0, 35992.0, 36723.265534688624, 36770.816145919096, 35940.46089308715, 36099.097522562144, 37092.0, 37408.0, 38027.85916422512, 30262.394752104396, 36797.81601091977, 36915.0, 37286.6271337294, 38017.429738553394, 32326.676733232365, 32396.0, 32692.67307326896, 32950.176245594055, 32953.67046329506, 24763.009479620614, 24792.380190495383, 25123.87438062871, 25242.24273271805, 25360.74639253584], "value": 19862.541682770425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.8839128636292, 400.5133762478588, 400.69346949583564, 405.7484359696986, 417.6366561091851, 13536.864631353683, 15546.611334716637, 20239.291624793135, 20743.066562004708, 20862.687059694115, 15440.910602970744, 16021.0, 16187.514374568753, 20398.796012039875, 20730.792692073075, 5426.186072089188, 5863.912041319383, 5868.618539794899, 5871.031279838843, 5873.911891321633, 4127.731697439672, 5733.885322293551, 5885.529157667398, 5885.646861188342, 6202.100695399165, 5372.7313634318325, 6461.386168314026, 6463.903041454382, 6468.417842394184, 6475.5467117301805, 6201.3798620138305, 6449.290578036413, 6461.7738379156735, 6477.935220647781, 6491.383318584727, 5656.745446454919, 5674.829755107354, 5676.631018983794, 5676.772929082832, 5683.971580142114, 24790.876045619836, 25573.616395754078, 25898.22305330843, 25919.74080259192, 25938.610920836203, 2400.243923164198, 2606.9702467525412, 2920.5911172435876, 2971.227480854972, 2985.1641540368732, 13947.157167289153, 14669.853301466956, 14745.926270368687, 14784.408623655148, 14911.70176596462, 1687.6709041736867, 1730.0, 1751.9474415767547, 1757.0072908806612, 1803.1435068342564, 109783.0, 112943.0, 114920.0, 115592.0, 116474.0, 10739.409332486788, 11061.834072488919, 11286.54853805855, 11303.86961303863, 11516.366599837089, 14292.857071429153, 14362.994590378783, 14369.99410041287, 14379.352929118215, 14401.567952961428, 7810.726624568113, 8135.3491720662205, 8370.455920365279, 8696.826063478695, 8805.911940880509, 9050.86423703645, 9076.591553380114, 9090.454572725657, 9157.77105572366, 9173.266138708887, 5770.942290577041, 6592.604808523614, 6798.66006699663, 6807.128039788999, 6815.522913396015, 1554.9766753498707, 1852.8610354223488, 1887.0941947864826, 1922.1926790747918, 1939.3309308288617, 965.9951700241454, 991.9553620087113, 1028.598846449885, 1035.2245898284477, 1114.8272017837248, 19873.801261987483, 20991.895040524698, 21053.578928421346, 21165.94170291479, 21421.143154273654, 897.434616191795, 984.1831280037552, 1032.1175395037285, 1075.1882328841675, 1082.9566817327218, 8668.78328041804, 10432.582696692047, 10694.572217111385, 10739.463026848627, 10880.455977201109, 613893.0, 625662.0, 636991.0, 638319.0, 651067.0, 73121.0, 86563.0, 129138.0, 135783.32108339394, 136951.0, 880.8062226310329, 1055.1242468750918, 1073.3345325897992, 1074.564801255477, 1074.822654262038, 17606.943583385037, 17626.29494820244, 17787.46637600874, 17922.92462452257, 18152.636947261246, 52294.95410091853, 52879.94240115099, 54503.72748136234, 55484.72257638686, 55628.0, 54329.0, 75454.62272688601, 75979.62010190135, 76318.61840690984, 78574.607126964, 7980.580785722083, 8114.5942702865805, 8136.891228909924, 8246.628901699318, 8249.505029698237, 8873.201411872962, 11407.486663100015, 12426.502939882303, 12661.113722039368, 12708.872911271139, 10696.946515267375, 10766.838497422388, 10814.729631759268, 10983.286086404358, 11002.174836887105, 10206.897931020594, 10564.366138031743, 10640.095591874777, 10730.463476826284, 10942.069924056545, 10031.247656425652, 10163.796724065625, 10436.060754532129, 10557.894421055691, 11005.339679619248, 7965.601719914097, 8027.5584842834205, 8089.393295502979, 8247.463914845515, 8356.456830306253, 9035.141661542142, 9508.762280943032, 9620.71137865865, 9677.22582193437, 9889.208863290776], "value": 9659.656035413534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.668492904168, 1538.9230538473078, 1568.4353632692232, 1791.8745687801859, 1945.9124339404725, 8054.315383192428, 8735.694250701219, 8905.821883562325, 8916.643334266637, 8918.82162356754, 8633.525156116411, 8901.154390332924, 8908.10918908109, 8918.0, 8923.063078376757, 2470.1354525915967, 2494.9251522454356, 2496.276079936816, 2496.737842526527, 2497.1384872219055, 2052.3945436096387, 2439.7560243975636, 2492.314613481289, 2494.3514686181543, 2501.8999240030375, 2461.360046387943, 2482.056818409002, 2488.601823708207, 2504.561701702204, 2505.0355613089, 2440.3289095498703, 2473.75262473753, 2481.0944005437977, 2487.614419764939, 2495.089292408276, 2436.8172387070863, 2475.962860557093, 2488.8506689598503, 2489.514544663791, 2492.9376765580823, 8567.314614830799, 8883.644654213824, 8934.64261429542, 8948.194662480408, 8957.14907083826, 5289.153735402318, 5441.319835020622, 5492.423295553989, 5493.615446918718, 5497.39528651848, 2911.621489206411, 3372.140104273419, 3416.6241713411505, 3490.7556471047037, 3490.965090349089, 1043.1275859831621, 1058.978820423595, 1063.7979083635441, 1078.4607696151925, 1081.0054749630358, 10165.288429809916, 12101.0, 12162.756744865053, 12178.451969661386, 12265.202761820543, 3957.168994511185, 4478.57453541913, 4704.929426058612, 4718.834840780556, 4721.740304283228, 9436.0, 9495.810083798286, 9543.0, 9548.90451095494, 9563.904360956301, 5192.5586325162785, 5281.20781882713, 5357.544608708304, 5373.838784836461, 5440.755166017539, 3828.48315477407, 4275.102228532043, 4418.983633764252, 4707.8587642370785, 4929.802807887716, 3818.579956204815, 3897.922041559153, 3916.0, 3964.484616999772, 4010.839566417369, 1214.1440284599337, 1222.3888055972013, 1223.7368965672424, 1232.617888454582, 1237.3442075699893, 620.5656040771478, 826.7561069484517, 872.9170728780756, 902.0933961368913, 981.8330883749779, 5851.21008663825, 5975.790847320323, 6011.819645410645, 6098.0, 6128.690507126102, 974.2498276327331, 994.4381424495137, 1003.598560575761, 1014.7209517382779, 1083.47992963378, 3874.5737968823405, 4000.7599544027453, 4021.034951611592, 4071.6131967463043, 4108.4864391951005, 10500.369977801356, 10693.946530267454, 10830.187735919837, 10870.945645271722, 10990.945045274722, 10716.410597417216, 10836.187285953585, 10838.891611083789, 10839.0, 10852.511636976196, 7799.610019499002, 8261.13258107902, 8278.627461764236, 8325.750227493185, 8472.322214222848, 5257.8159764408065, 5827.59206855533, 5854.443827836349, 5940.990031694536, 6220.377962203744, 9271.67549135777, 9279.072092790668, 9496.097870702271, 9656.0, 9665.0, 9654.662086826927, 9811.55847986828, 9815.705528834147, 9825.606975720892, 9930.751731206488, 7153.320434558708, 7579.848403032019, 7581.355584775135, 7584.203658615766, 7588.089429268523, 8177.141400153137, 8361.372897032625, 8486.448380855225, 8495.065542790411, 8518.48889066658, 6328.557001009885, 7311.744088956861, 7495.287947644965, 7514.0, 7539.962300188683, 7303.379212766974, 7488.9625551871895, 7508.136564295176, 7602.961985190039, 7654.0, 7050.647467626495, 7085.893711594227, 7116.928830711834, 7121.78929581962, 7127.358537731629, 4876.804927802848, 4946.777395017161, 4971.179755340473, 5069.011542749276, 5095.0, 5024.673396229234, 5051.040302342543, 5112.565431938327, 5123.462036486116, 5305.257263983122], "value": 4606.486255645485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.64728798321846, 323.61328212785713, 329.3922712595262, 329.5978905735004, 333.5896846878341, 5870.88258234835, 6466.7413303467865, 6469.353064693529, 6509.772157974472, 6522.0, 6509.707063182156, 6511.934880651192, 6518.837029074263, 6529.216494020712, 6529.967350163254, 3805.5052843130356, 4619.74591397473, 4703.811847526094, 4811.35046768687, 4825.975870120645, 4683.578477936985, 4800.375951126366, 4831.541003604652, 4832.3717916670785, 4847.733374664392, 4005.479287692596, 4648.186567350717, 4782.13921494131, 4786.114568804782, 4817.03659268146, 4591.219492686251, 4656.254999200146, 4778.235482322812, 4799.160146974265, 4805.591524720403, 4097.057676734368, 4107.958920410817, 4121.29937910553, 4124.13393187431, 4125.566815484361, 6147.877042459126, 6150.446459818593, 6176.660283684396, 6235.532335074842, 6260.843478913018, 5652.0391533439415, 5674.148877668342, 5681.488666020078, 5682.289713785777, 5690.687012214326, 6740.056392105109, 6980.825479363006, 6992.273013524729, 6994.607615306572, 7000.684969176399, 4668.043051174501, 4668.883277918046, 4672.836450724209, 4676.69601475903, 4678.95321046792, 7290.489665723401, 7337.19290878003, 7369.7052117915755, 7370.189279179286, 7376.225496322916, 4736.076465089274, 4744.881377965509, 4749.2876068589985, 4755.167845627071, 4755.548222918817, 4812.807487700523, 4813.518648135158, 4820.807167713252, 4826.493218212107, 4829.830955916526, 5675.432456754292, 5680.0, 5683.0, 5686.857828554319, 5698.145278208219, 6743.763968261087, 7008.159020917523, 7037.824054398681, 7052.435805135577, 7074.893876591856, 1655.8078183707712, 1680.487451327357, 1685.005846550541, 1685.4438035448272, 1687.333503266203, 891.714651311574, 1013.6148263659762, 1027.8561001459802, 1059.4755595979884, 1087.260662749338, 6388.4569811566535, 6537.477001839841, 6557.540972131905, 6573.408393244631, 6585.83535411609, 6287.591306565058, 7279.854402911912, 7291.416686665054, 7292.781216563511, 7298.671559779823, 6502.674866256668, 6513.869722605521, 6514.120593719882, 6516.413522782877, 6532.41208291256, 3781.7541859779576, 3829.0810205551024, 3835.5780864104927, 3837.366834472281, 3839.0, 6428.935710642833, 7283.70865165398, 7354.0, 7490.4382171337365, 7616.0, 6342.809715708536, 7474.140473845578, 7488.213737557587, 7507.774766757006, 7559.924400756032, 7679.0785105786545, 7693.576853273012, 7701.88447173304, 7709.498882572615, 7710.691572337156, 7010.50926435165, 7338.486305958745, 7349.77950661481, 7359.37445317154, 7399.3340599344165, 7174.713011479483, 7199.532030418006, 7225.0, 7232.385247254042, 7278.781636550912, 7685.4620176587105, 7688.192739762245, 7691.15397306307, 7691.730789422343, 7699.422543309162, 4551.476580193188, 4624.375709279204, 4626.930596040995, 4628.930566041579, 4634.420697412824, 6997.195322537973, 7313.524620899624, 7316.341529262392, 7318.890216646861, 7324.267573242633, 7045.859082818418, 7061.576305421691, 7066.222715501188, 7069.540479868998, 7101.147862256562, 7036.929630703832, 7046.4715146363205, 7049.39172506176, 7051.1186101737285, 7071.71713131469, 6879.89680154788, 6881.346272104142, 6881.41507971828, 6900.3444672756, 6940.826479337855, 6756.8648627026205, 7002.964985175246, 7042.683079261548, 7053.823654408681, 7069.858602828018, 6663.400293973566, 6785.287544807921, 6816.931830681828, 6897.3447522485285, 6908.205556360882], "value": 5127.214043918238, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.737139886843, 694.978381778785, 709.6522703875099, 712.0956385390552, 765.2462324610254, 14958.401663933444, 15060.774088388684, 15127.84872151278, 15155.54533363998, 15156.393744250217, 13641.113327633697, 13655.522056728016, 13673.794893076612, 13717.176969381819, 13723.313834308294, 717.1394326807825, 738.5531753289263, 900.4372267332917, 902.7968707040912, 928.6053427293407, 920.6501529418845, 920.8158368326328, 921.1341339141219, 925.5418567808911, 930.0838673906197, 975.1905918088005, 978.4031740638205, 978.5254151736402, 981.7300242433316, 983.1986930651497, 761.8552475029754, 968.6900191938582, 969.8254314223437, 975.2782940623923, 988.6836212412031, 731.3710209220067, 731.846312274423, 733.581858340746, 734.4858598980735, 744.3449764207517, 14491.275436228147, 14564.49024284145, 14568.344424500923, 14578.70842583153, 14588.70822583553, 12556.058295627774, 12641.2415255085, 12745.61763147107, 12872.16330938486, 12939.417726202344, 10016.89983100167, 10048.899511004869, 10145.746356341077, 10228.284020118595, 10349.948250258776, 3483.4600636901323, 3506.0, 3507.824608769551, 3517.4196257617464, 3576.5529308836394, 58480.70759646175, 76464.0, 76588.0, 79804.60097699532, 80179.19820801775, 9417.623295068257, 9533.142017218484, 9609.135177833889, 9714.319997600242, 10130.138938190188, 22084.668729969064, 22246.11015559392, 22268.220612278492, 22283.0, 22289.331320060424, 7464.776056718307, 7469.066366704162, 7564.319211271012, 7589.392848572101, 7833.138354780969, 4021.4571032910767, 4127.8968025799595, 4146.937795933003, 4150.522689890641, 4251.80866860992, 3572.1248294167576, 3587.838547265327, 3628.637136286403, 3632.0919770057485, 3640.162762564625, 614.1064750787593, 615.2463232540102, 621.2389822467442, 623.0716232813166, 628.6542401679059, 352.66144501278546, 605.9454649081604, 615.8029430582171, 629.4618101523223, 637.7066549387241, 7868.0, 8245.13426090264, 8282.254597086292, 8299.045609754834, 8345.059954414897, 483.3957553058677, 596.5406636889571, 600.1058422949774, 602.2682440834408, 605.449041372349, 3820.7898565578603, 3833.4824798651825, 3864.7681139131178, 3977.3636218204947, 4077.55146933837, 84281.0, 86460.0, 86897.0, 87675.56162219148, 87708.0, 74117.0, 74194.0, 74494.62752686202, 75653.0, 75841.0, 63155.368446315864, 63566.682166589795, 64553.70892582216, 64875.351246486935, 66392.0, 7641.2740789624895, 7712.460127791225, 7885.684572617031, 7977.282044616012, 8168.693009118512, 45892.8526786833, 46907.0, 46940.765296173304, 48094.519054809, 48650.51349486601, 54580.18129727978, 55116.72441637766, 57418.0, 60353.69823150856, 61053.0, 10814.0, 10993.285436446606, 11094.167937404565, 11306.604268850551, 11688.532458701557, 11694.122940779305, 12068.974137198085, 12185.207961482476, 12240.265584064984, 12357.814632780335, 9673.0, 10526.368417894952, 10764.138868890313, 11695.824562631396, 11811.645650630495, 11181.664550063511, 11422.714432139262, 11497.425128743696, 11541.019013383957, 11726.765464690488, 8554.871676924726, 9208.447493150432, 9442.622295108118, 9660.565274562801, 9667.661631842851, 7873.37013038944, 9324.300677449082, 9504.809903802023, 9531.237500999765, 9918.0, 9548.570314335733, 9549.331546791658, 9570.760730981781, 9655.517224138624, 9719.708408747749], "value": 6762.570938331159, "unit": "score"}], "name": "dromaeo_css", "value": 7472.3769044376895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 120.0, 113.0, 114.0, 112.0, 112.0, 113.0, 115.0, 137.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 140.0, 183.0, 153.0, 136.0, 120.0, 173.0, 196.0, 147.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 188.0, 185.0, 185.0, 184.0, 182.0, 182.0, 186.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 104.0, 109.0, 194.0, 55.0, 55.0, 56.0, 59.0, 133.0, 57.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 154.0, 108.0, 117.0, 110.0, 123.0, 79.0, 77.0, 124.0], "value": 107.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 85.0, 88.0, 86.0, 88.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 98.0, 93.0, 93.0, 98.0, 110.0, 94.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 85.0, 84.0, 83.0, 85.0, 84.0, 86.0, 93.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 62.0, 66.0, 65.0, 66.0, 85.0, 59.0, 57.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 64.0, 89.0, 108.0, 106.0, 97.0, 75.0, 69.0, 67.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 94.0, 104.0, 104.0, 104.0, 79.0, 81.0, 82.0, 94.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 126.0, 117.0, 123.0, 127.0, 167.0, 129.0, 131.0, 153.0, 163.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 166.0, 167.0, 165.0, 154.0, 163.0, 165.0, 164.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 68.0, 67.0, 69.0, 67.0, 69.0, 67.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1523.0999999999997, "alertThreshold": 2.0}]} 16:11:57 INFO - Return code: 0 16:11:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:11:57 ERROR - # TBPL SUCCESS # 16:11:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:57 INFO - 2016-05-30 16:11:57.897 screenresolution[1694:10124] starting screenresolution argv=screenresolution get 16:11:57 INFO - 2016-05-30 16:11:57.899 screenresolution[1694:10124] Display 0: 1600x1200x32@60 16:11:57 INFO - 2016-05-30 16:11:57.905 screenresolution[1695:10127] starting screenresolution argv=screenresolution list 16:11:57 INFO - Available Modes on Display 0 16:11:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:58 INFO - Intel Iris: 16:11:58 INFO - Chipset Model: Intel Iris 16:11:58 INFO - Type: GPU 16:11:58 INFO - Bus: Built-In 16:11:58 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:58 INFO - Vendor: Intel (0x8086) 16:11:58 INFO - Device ID: 0x0a2e 16:11:58 INFO - Revision ID: 0x0009 16:11:58 INFO - Displays: 16:11:58 INFO - Display: 16:11:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:58 INFO - Display Serial Number: 1600x1200 60 16:11:58 INFO - Main Display: Yes 16:11:58 INFO - Mirror: Off 16:11:58 INFO - Online: Yes 16:11:58 INFO - Rotation: Supported 16:11:58 INFO - Return code: 0 16:11:58 INFO - Running post-action listener: _resource_record_post_action 16:11:58 INFO - Running post-run listener: _resource_record_post_run 16:11:59 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 36771328; Write bytes: 653704192; Read time: 235; Write time: 1902 16:11:59 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 356598272; Write bytes: 347088384; Read time: 27289; Write time: 781 16:11:59 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 29666816; Write bytes: 295822848; Read time: 179; Write time: 1085 16:11:59 INFO - Running post-run listener: _upload_blobber_files 16:11:59 INFO - Blob upload gear active. 16:11:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:11:59 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:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:11:59 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:11:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:12:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:00 INFO - (blobuploader) - INFO - Done attempting. 16:12:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:12:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:12:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:01 INFO - (blobuploader) - INFO - Done attempting. 16:12:01 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:01 INFO - Return code: 0 16:12:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ac10fe41a7b157f68ac6e9f0adf5af4a83f750680d3594f055fad4d1e6dd71f573c4f6797de4c8abbfc5bafe1df0314d26e9e7adf6217eb2ac8ff474768950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de9fe56bf16d0c00d5f4c06f2efaf353a40a0b443d65fd1a4cf86dce4a93ec04f12f8dcc1457b5cedc3b3fbe7fc739bb3dea7e2b6d4b5d7ee3c5024a195a4473"} 16:12:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:01 INFO - Contents: 16:12:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ac10fe41a7b157f68ac6e9f0adf5af4a83f750680d3594f055fad4d1e6dd71f573c4f6797de4c8abbfc5bafe1df0314d26e9e7adf6217eb2ac8ff474768950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de9fe56bf16d0c00d5f4c06f2efaf353a40a0b443d65fd1a4cf86dce4a93ec04f12f8dcc1457b5cedc3b3fbe7fc739bb3dea7e2b6d4b5d7ee3c5024a195a4473"} 16:12:01 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:01 INFO - Copying logs to upload dir... 16:12:01 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.314186 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-30 16:12:01.736842) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:01.740168) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ac10fe41a7b157f68ac6e9f0adf5af4a83f750680d3594f055fad4d1e6dd71f573c4f6797de4c8abbfc5bafe1df0314d26e9e7adf6217eb2ac8ff474768950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de9fe56bf16d0c00d5f4c06f2efaf353a40a0b443d65fd1a4cf86dce4a93ec04f12f8dcc1457b5cedc3b3fbe7fc739bb3dea7e2b6d4b5d7ee3c5024a195a4473"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010982 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38ac10fe41a7b157f68ac6e9f0adf5af4a83f750680d3594f055fad4d1e6dd71f573c4f6797de4c8abbfc5bafe1df0314d26e9e7adf6217eb2ac8ff474768950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de9fe56bf16d0c00d5f4c06f2efaf353a40a0b443d65fd1a4cf86dce4a93ec04f12f8dcc1457b5cedc3b3fbe7fc739bb3dea7e2b6d4b5d7ee3c5024a195a4473"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:01.774261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:01.774869) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.005251 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:01.827817) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:01.828543) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:01.829260) ========= ========= Total master_lag: 0.19 =========