builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0178 starttime: 1461890430.0 results: success (0) buildid: 20160428153650 builduid: 7b10f8fbcfb94042b0b2fdffb2f4c283 revision: f7bb3675bb71c81789faabc5e46528e054b8aa90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.004958) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.008362) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.008634) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.058340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.058855) ========= 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.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-28 17:40:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.2M=0.001s 2016-04-28 17:40:30 (10.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104989 ========= master_lag: 0.42 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:30.583864) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:40:30.584190) ========= 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.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.027463 ========= master_lag: 0.97 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:40:31.584634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:40:31.585031) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f7bb3675bb71c81789faabc5e46528e054b8aa90 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f7bb3675bb71c81789faabc5e46528e054b8aa90 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-28 17:40:31,710 truncating revision to first 12 chars 2016-04-28 17:40:31,710 Setting DEBUG logging. 2016-04-28 17:40:31,710 attempt 1/10 2016-04-28 17:40:31,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f7bb3675bb71?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 17:40:32,431 unpacking tar archive at: fx-team-f7bb3675bb71/testing/mozharness/ program finished with exit code 0 elapsedTime=0.951531 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 17:40:32.603169) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:32.603483) ========= script_repo_revision: f7bb3675bb71c81789faabc5e46528e054b8aa90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:32.603867) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:32.604139) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 17:40:32.640550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-04-28 17:40:32.640819) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:40:32 INFO - MultiFileLogger online at 20160428 17:40:32 in /builds/slave/test 17:40:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:40:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:40:32 INFO - {'append_to_log': False, 17:40:32 INFO - 'base_work_dir': '/builds/slave/test', 17:40:32 INFO - 'blob_upload_branch': 'Fx-Team', 17:40:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:40:32 INFO - 'branch': 'Fx-Team', 17:40:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:40:32 INFO - 'config_files': ('talos/mac_config.py',), 17:40:32 INFO - 'default_actions': ('clobber', 17:40:32 INFO - 'read-buildbot-config', 17:40:32 INFO - 'download-and-extract', 17:40:32 INFO - 'populate-webroot', 17:40:32 INFO - 'create-virtualenv', 17:40:32 INFO - 'install', 17:40:32 INFO - 'run-tests'), 17:40:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:40:32 INFO - 'download_minidump_stackwalk': True, 17:40:32 INFO - 'download_symbols': 'ondemand', 17:40:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:40:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:40:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:40:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:40:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:40:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:40:32 INFO - 'installer_path': 'installer.exe', 17:40:32 INFO - 'log_level': 'info', 17:40:32 INFO - 'log_name': 'talos', 17:40:32 INFO - 'log_to_console': True, 17:40:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:40:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:40:32 INFO - 'opt_config_files': (), 17:40:32 INFO - 'pip_index': False, 17:40:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:40:32 INFO - 'cmd': ('bash', 17:40:32 INFO - '-c', 17:40:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:40:32 INFO - 'enabled': True, 17:40:32 INFO - 'halt_on_failure': False, 17:40:32 INFO - 'name': 'check_screen_resolution'},), 17:40:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:40:32 INFO - 'cmd': ('bash', 17:40:32 INFO - '-c', 17:40:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:40:32 INFO - 'enabled': True, 17:40:32 INFO - 'halt_on_failure': False, 17:40:32 INFO - 'name': 'check_screen_resolution'},), 17:40:32 INFO - 'run_cmd_checks_enabled': True, 17:40:32 INFO - 'sps_profile': False, 17:40:32 INFO - 'sps_profile_interval': 0, 17:40:32 INFO - 'suite': 'dromaeojs', 17:40:32 INFO - 'system_bits': '32', 17:40:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:40:32 INFO - 'title': 't-yosemite-r7-0178', 17:40:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:40:32 INFO - 'use_talos_json': True, 17:40:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:40:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:40:32 INFO - 'work_dir': 'build'} 17:40:32 INFO - ##### 17:40:32 INFO - ##### Running clobber step. 17:40:32 INFO - ##### 17:40:32 INFO - Running pre-action listener: _resource_record_pre_action 17:40:32 INFO - Running main action method: clobber 17:40:32 INFO - rmtree: /builds/slave/test/build 17:40:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:40:34 INFO - Running post-action listener: _resource_record_post_action 17:40:34 INFO - ##### 17:40:34 INFO - ##### Running read-buildbot-config step. 17:40:34 INFO - ##### 17:40:34 INFO - Running pre-action listener: _resource_record_pre_action 17:40:34 INFO - Running main action method: read_buildbot_config 17:40:34 INFO - Using buildbot properties: 17:40:34 INFO - { 17:40:34 INFO - "project": "", 17:40:34 INFO - "product": "firefox", 17:40:34 INFO - "script_repo_revision": "production", 17:40:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:40:34 INFO - "repository": "", 17:40:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:40:34 INFO - "buildid": "20160428153650", 17:40:34 INFO - "pgo_build": "False", 17:40:34 INFO - "basedir": "/builds/slave/test", 17:40:34 INFO - "buildnumber": 789, 17:40:34 INFO - "slavename": "t-yosemite-r7-0178", 17:40:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:40:34 INFO - "platform": "yosemite_r7", 17:40:34 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:40:34 INFO - "branch": "fx-team", 17:40:34 INFO - "repo_path": "integration/fx-team", 17:40:34 INFO - "revision": "f7bb3675bb71c81789faabc5e46528e054b8aa90", 17:40:34 INFO - "stage_platform": "macosx64", 17:40:34 INFO - "builduid": "7b10f8fbcfb94042b0b2fdffb2f4c283", 17:40:34 INFO - "slavebuilddir": "test" 17:40:34 INFO - } 17:40:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:40:34 INFO - Running post-action listener: _resource_record_post_action 17:40:34 INFO - ##### 17:40:34 INFO - ##### Running download-and-extract step. 17:40:34 INFO - ##### 17:40:34 INFO - Running pre-action listener: _resource_record_pre_action 17:40:34 INFO - Running main action method: download_and_extract 17:40:34 INFO - mkdir: /builds/slave/test/build/tests 17:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:34 INFO - https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:40:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:40:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:40:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 17:40:37 INFO - Downloaded 1199 bytes. 17:40:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:40:37 INFO - Using the following test package requirements: 17:40:37 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:40:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:40:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:40:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'jsshell-mac.zip'], 17:40:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:40:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:40:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:40:37 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:40:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:40:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:40:37 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:40:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:37 INFO - https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:40:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:40:38 INFO - Downloaded 18124395 bytes. 17:40:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:40:41 INFO - Return code: 0 17:40:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:40: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')]} 17:40:41 INFO - https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:40:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:40:43 INFO - Downloaded 18124395 bytes. 17:40:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:40:46 INFO - Return code: 0 17:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:46 INFO - https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:40:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:40:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:40:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:40:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:40:47 INFO - Downloaded 10840145 bytes. 17:40:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:40:47 INFO - Return code: 0 17:40:47 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')]} 17:40:47 INFO - https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:40:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:40:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:40:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:40:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 17:40:51 INFO - Downloaded 110274317 bytes. 17:40:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:40:51 INFO - mkdir: /builds/slave/test/properties 17:40:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:40:51 INFO - Writing to file /builds/slave/test/properties/build_url 17:40:51 INFO - Contents: 17:40:51 INFO - build_url:https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:40:53 INFO - Running post-action listener: _resource_record_post_action 17:40:53 INFO - Running post-action listener: set_extra_try_arguments 17:40:53 INFO - ##### 17:40:53 INFO - ##### Running populate-webroot step. 17:40:53 INFO - ##### 17:40:53 INFO - Running pre-action listener: _resource_record_pre_action 17:40:53 INFO - Running main action method: populate_webroot 17:40:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:40:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:40:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:40:53 INFO - '--tppagecycles', 17:40:53 INFO - '7'], 17:40:53 INFO - 'tests': ['tsvgm']}}, 17:40:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:40:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:40:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:40:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:40:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['damp', 'tps']}, 17:40:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'tests': ['damp', 'tps']}, 17:40:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['dromaeo_dom']}, 17:40:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:40:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'other-e10s': {'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['a11yr', 17:40:53 INFO - 'ts_paint', 17:40:53 INFO - 'tpaint', 17:40:53 INFO - 'sessionrestore', 17:40:53 INFO - 'sessionrestore_no_auto_restore', 17:40:53 INFO - 'tabpaint']}, 17:40:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['tsvgx', 17:40:53 INFO - 'tsvgr_opacity', 17:40:53 INFO - 'tart', 17:40:53 INFO - 'tscrollx', 17:40:53 INFO - 'cart']}, 17:40:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:40:53 INFO - 'tsvgr_opacity', 17:40:53 INFO - 'tart', 17:40:53 INFO - 'tscrollx', 17:40:53 INFO - 'cart']}, 17:40:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'talos_options': ['--disable-e10s'], 17:40:53 INFO - 'tests': ['tp5o']}, 17:40:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'tests': ['tp5o']}, 17:40:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'talos_options': ['--disable-e10s', 17:40:53 INFO - '--xperf_path', 17:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:53 INFO - 'tests': ['tp5n']}, 17:40:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:53 INFO - 'talos_options': ['--xperf_path', 17:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:53 INFO - 'tests': ['tp5n']}}, 17:40:53 INFO - 'talos.zip': {'path': '', 17:40:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:40:53 INFO - Running post-action listener: _resource_record_post_action 17:40:53 INFO - ##### 17:40:53 INFO - ##### Running create-virtualenv step. 17:40:53 INFO - ##### 17:40:53 INFO - Running pre-action listener: _resource_record_pre_action 17:40:53 INFO - Running main action method: create_virtualenv 17:40:53 INFO - Creating virtualenv /builds/slave/test/build/venv 17:40:53 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 17:40:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:40:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:40:53 INFO - Using real prefix '/tools/python27' 17:40:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:40:54 INFO - Installing distribute.............................................................................................................................................................................................done. 17:40:56 INFO - Installing pip.................done. 17:40:56 INFO - Return code: 0 17:40:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:40:56 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')]} 17:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:56 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')]} 17:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:56 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:40:56 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 17:40:56 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 17:40:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:40:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:56 INFO - 'HOME': '/Users/cltbld', 17:40:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:56 INFO - 'LOGNAME': 'cltbld', 17:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:56 INFO - 'MOZ_NO_REMOTE': '1', 17:40:56 INFO - 'NO_EM_RESTART': '1', 17:40:56 INFO - 'PAGER': '/bin/cat', 17:40:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:56 INFO - 'PWD': '/builds/slave/test', 17:40:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:56 INFO - 'SHELL': '/bin/bash', 17:40:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:40:56 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:40:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:56 INFO - 'USER': 'cltbld', 17:40:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:56 INFO - 'XPC_FLAGS': '0x0', 17:40:56 INFO - 'XPC_SERVICE_NAME': '0', 17:40:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:57 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 17:40:57 INFO - Cleaning up... 17:40:57 INFO - Return code: 0 17:40:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:57 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:40:57 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 17:40:57 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 17:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:57 INFO - 'HOME': '/Users/cltbld', 17:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:57 INFO - 'LOGNAME': 'cltbld', 17:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:57 INFO - 'MOZ_NO_REMOTE': '1', 17:40:57 INFO - 'NO_EM_RESTART': '1', 17:40:57 INFO - 'PAGER': '/bin/cat', 17:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:57 INFO - 'PWD': '/builds/slave/test', 17:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:57 INFO - 'SHELL': '/bin/bash', 17:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:40:57 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:57 INFO - 'USER': 'cltbld', 17:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:57 INFO - 'XPC_FLAGS': '0x0', 17:40:57 INFO - 'XPC_SERVICE_NAME': '0', 17:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:57 INFO - Downloading/unpacking psutil>=0.7.1 17:40:57 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 17:40:57 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 17:40:57 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 17:40:57 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 17:40:57 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 17:40:57 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 17:41:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:41:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:41:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:41:00 INFO - Installing collected packages: psutil 17:41:00 INFO - Running setup.py install for psutil 17:41:00 INFO - building 'psutil._psutil_osx' extension 17:41:00 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 17:41:00 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 17:41:01 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 17:41:01 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 17:41:01 INFO - building 'psutil._psutil_posix' extension 17:41:01 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 17:41:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:41:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:41:01 INFO - ^ 17:41:01 INFO - 1 warning generated. 17:41:01 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 17:41:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:41:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:41:01 INFO - Successfully installed psutil 17:41:01 INFO - Cleaning up... 17:41:01 INFO - Return code: 0 17:41:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:41:01 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')]} 17:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:01 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')]} 17:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:01 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:01 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 17:41:01 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 17:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:01 INFO - 'HOME': '/Users/cltbld', 17:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:01 INFO - 'LOGNAME': 'cltbld', 17:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:01 INFO - 'MOZ_NO_REMOTE': '1', 17:41:01 INFO - 'NO_EM_RESTART': '1', 17:41:01 INFO - 'PAGER': '/bin/cat', 17:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:01 INFO - 'PWD': '/builds/slave/test', 17:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:01 INFO - 'SHELL': '/bin/bash', 17:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:01 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:01 INFO - 'USER': 'cltbld', 17:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:01 INFO - 'XPC_FLAGS': '0x0', 17:41:01 INFO - 'XPC_SERVICE_NAME': '0', 17:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:41:01 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 17:41:01 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 17:41:01 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 17:41:01 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 17:41:01 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 17:41:01 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 17:41:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:41:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:41:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:41:04 INFO - Installing collected packages: mozsystemmonitor 17:41:04 INFO - Running setup.py install for mozsystemmonitor 17:41:04 INFO - Successfully installed mozsystemmonitor 17:41:04 INFO - Cleaning up... 17:41:04 INFO - Return code: 0 17:41:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:04 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:04 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 17:41:04 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 17:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:04 INFO - 'HOME': '/Users/cltbld', 17:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:04 INFO - 'LOGNAME': 'cltbld', 17:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:04 INFO - 'MOZ_NO_REMOTE': '1', 17:41:04 INFO - 'NO_EM_RESTART': '1', 17:41:04 INFO - 'PAGER': '/bin/cat', 17:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:04 INFO - 'PWD': '/builds/slave/test', 17:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:04 INFO - 'SHELL': '/bin/bash', 17:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:04 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:04 INFO - 'USER': 'cltbld', 17:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:04 INFO - 'XPC_FLAGS': '0x0', 17:41:04 INFO - 'XPC_SERVICE_NAME': '0', 17:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:04 INFO - Downloading/unpacking blobuploader==1.2.4 17:41:04 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 17:41:04 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 17:41:04 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 17:41:04 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 17:41:04 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 17:41:04 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 17:41:07 INFO - Downloading blobuploader-1.2.4.tar.gz 17:41:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:41:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:41:07 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 17:41:07 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 17:41:07 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 17:41:07 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 17:41:07 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 17:41:07 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 17:41:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:41:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:41:08 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 17:41:08 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 17:41:08 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 17:41:08 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 17:41:08 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 17:41:08 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 17:41:08 INFO - Downloading docopt-0.6.1.tar.gz 17:41:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:41:08 INFO - Installing collected packages: blobuploader, requests, docopt 17:41:08 INFO - Running setup.py install for blobuploader 17:41:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:41:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:41:08 INFO - Running setup.py install for requests 17:41:09 INFO - Running setup.py install for docopt 17:41:09 INFO - Successfully installed blobuploader requests docopt 17:41:09 INFO - Cleaning up... 17:41:09 INFO - Return code: 0 17:41:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:09 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:09 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 17:41:09 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 17:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:09 INFO - 'HOME': '/Users/cltbld', 17:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:09 INFO - 'LOGNAME': 'cltbld', 17:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:09 INFO - 'MOZ_NO_REMOTE': '1', 17:41:09 INFO - 'NO_EM_RESTART': '1', 17:41:09 INFO - 'PAGER': '/bin/cat', 17:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:09 INFO - 'PWD': '/builds/slave/test', 17:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:09 INFO - 'SHELL': '/bin/bash', 17:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:09 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:09 INFO - 'USER': 'cltbld', 17:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:09 INFO - 'XPC_FLAGS': '0x0', 17:41:09 INFO - 'XPC_SERVICE_NAME': '0', 17:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-9Vwtvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-u52ocn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-2YEGOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-3QlYji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-67ir5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nTgi5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-YU52jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:41:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-iJyq0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-3phc0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-VHIqO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6SELFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-LxCowy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-8lho9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-H3SIkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-wdG9HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-5yLTm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:41:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-fvgZGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:41:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:41:11 INFO - Running setup.py install for manifestparser 17:41:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:41:12 INFO - Running setup.py install for mozcrash 17:41:12 INFO - Running setup.py install for mozdebug 17:41:12 INFO - Running setup.py install for mozdevice 17:41:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:41:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:41:12 INFO - Running setup.py install for mozfile 17:41:12 INFO - Running setup.py install for mozhttpd 17:41:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:41:12 INFO - Running setup.py install for mozinfo 17:41:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:41:12 INFO - Running setup.py install for mozInstall 17:41:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Running setup.py install for mozleak 17:41:13 INFO - Running setup.py install for mozlog 17:41:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Running setup.py install for moznetwork 17:41:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Running setup.py install for mozprocess 17:41:13 INFO - Running setup.py install for mozprofile 17:41:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:41:13 INFO - Running setup.py install for mozrunner 17:41:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:41:14 INFO - Running setup.py install for mozscreenshot 17:41:14 INFO - Running setup.py install for moztest 17:41:14 INFO - Running setup.py install for mozversion 17:41:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:41:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:41:14 INFO - Cleaning up... 17:41:14 INFO - Return code: 0 17:41:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:14 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:14 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 17:41:14 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 17:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:14 INFO - 'HOME': '/Users/cltbld', 17:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:14 INFO - 'LOGNAME': 'cltbld', 17:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:14 INFO - 'MOZ_NO_REMOTE': '1', 17:41:14 INFO - 'NO_EM_RESTART': '1', 17:41:14 INFO - 'PAGER': '/bin/cat', 17:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:14 INFO - 'PWD': '/builds/slave/test', 17:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:14 INFO - 'SHELL': '/bin/bash', 17:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:14 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:14 INFO - 'USER': 'cltbld', 17:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:14 INFO - 'XPC_FLAGS': '0x0', 17:41:14 INFO - 'XPC_SERVICE_NAME': '0', 17:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:41:14 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-GK1lG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:41:14 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)) 17:41:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:41:14 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-_X_uL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-FqE48q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6FgQ6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-TsANNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-AuavEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-4GnUWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Xj2ePG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-kjWg63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-qZOBZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-wSSOYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:41:15 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)) 17:41:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:41:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nS5n0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:41:16 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)) 17:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:41:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-MrOkt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:41:16 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)) 17:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:41:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-P8D702-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:41:16 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)) 17:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:41:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-j3RtiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:41:16 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)) 17:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:41:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Qkw1oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:41:16 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)) 17:41:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:41:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-o1q2FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:41:16 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)) 17:41:16 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)) 17:41:16 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)) 17:41:16 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)) 17:41:16 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)) 17:41:16 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)) 17:41:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:19 INFO - Downloading blessings-1.6.tar.gz 17:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:41:19 INFO - Installing collected packages: blessings 17:41:19 INFO - Running setup.py install for blessings 17:41:19 INFO - Successfully installed blessings 17:41:19 INFO - Cleaning up... 17:41:19 INFO - Return code: 0 17:41:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:41:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:41:20 INFO - Reading from file tmpfile_stdout 17:41:20 INFO - Current package versions: 17:41:20 INFO - blessings == 1.6 17:41:20 INFO - blobuploader == 1.2.4 17:41:20 INFO - docopt == 0.6.1 17:41:20 INFO - manifestparser == 1.1 17:41:20 INFO - mozInstall == 1.12 17:41:20 INFO - mozcrash == 0.17 17:41:20 INFO - mozdebug == 0.1 17:41:20 INFO - mozdevice == 0.48 17:41:20 INFO - mozfile == 1.2 17:41:20 INFO - mozhttpd == 0.7 17:41:20 INFO - mozinfo == 0.9 17:41:20 INFO - mozleak == 0.1 17:41:20 INFO - mozlog == 3.1 17:41:20 INFO - moznetwork == 0.27 17:41:20 INFO - mozprocess == 0.22 17:41:20 INFO - mozprofile == 0.28 17:41:20 INFO - mozrunner == 6.11 17:41:20 INFO - mozscreenshot == 0.1 17:41:20 INFO - mozsystemmonitor == 0.0 17:41:20 INFO - moztest == 0.7 17:41:20 INFO - mozversion == 1.4 17:41:20 INFO - psutil == 3.1.1 17:41:20 INFO - requests == 1.2.3 17:41:20 INFO - wsgiref == 0.1.2 17:41:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:20 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:20 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 17:41:20 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 17:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:20 INFO - 'HOME': '/Users/cltbld', 17:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:20 INFO - 'LOGNAME': 'cltbld', 17:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:20 INFO - 'MOZ_NO_REMOTE': '1', 17:41:20 INFO - 'NO_EM_RESTART': '1', 17:41:20 INFO - 'PAGER': '/bin/cat', 17:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:20 INFO - 'PWD': '/builds/slave/test', 17:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:20 INFO - 'SHELL': '/bin/bash', 17:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:20 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:20 INFO - 'USER': 'cltbld', 17:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:20 INFO - 'XPC_FLAGS': '0x0', 17:41:20 INFO - 'XPC_SERVICE_NAME': '0', 17:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 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)) 17:41:20 INFO - Cleaning up... 17:41:20 INFO - Return code: 0 17:41:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41: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', 'jsonschema'] in /builds/slave/test/build 17:41: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 jsonschema 17:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:20 INFO - 'HOME': '/Users/cltbld', 17:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:20 INFO - 'LOGNAME': 'cltbld', 17:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:20 INFO - 'MOZ_NO_REMOTE': '1', 17:41:20 INFO - 'NO_EM_RESTART': '1', 17:41:20 INFO - 'PAGER': '/bin/cat', 17:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:20 INFO - 'PWD': '/builds/slave/test', 17:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:20 INFO - 'SHELL': '/bin/bash', 17:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:20 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:20 INFO - 'USER': 'cltbld', 17:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:20 INFO - 'XPC_FLAGS': '0x0', 17:41:20 INFO - 'XPC_SERVICE_NAME': '0', 17:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:20 INFO - Downloading/unpacking jsonschema 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:41:22 INFO - Downloading/unpacking functools32 (from jsonschema) 17:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:23 INFO - Downloading functools32-3.2.3-2.tar.gz 17:41:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:41:23 INFO - warning: no files found matching '*.txt' 17:41:23 INFO - no previously-included directories found matching 'build' 17:41:23 INFO - no previously-included directories found matching 'dist' 17:41:23 INFO - no previously-included directories found matching '.git*' 17:41:23 INFO - Installing collected packages: jsonschema, functools32 17:41:23 INFO - Running setup.py install for functools32 17:41:23 INFO - warning: no files found matching '*.txt' 17:41:23 INFO - no previously-included directories found matching 'build' 17:41:23 INFO - no previously-included directories found matching 'dist' 17:41:23 INFO - no previously-included directories found matching '.git*' 17:41:23 INFO - Successfully installed jsonschema functools32 17:41:23 INFO - Cleaning up... 17:41:23 INFO - Return code: 0 17:41:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:41:23 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')]} 17:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:23 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')]} 17:41:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:23 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 0x109256a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109266420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba22466de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, '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.Q6bhQInUT0/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.SVspSFRrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 17:41:23 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 17:41:23 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 17:41:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:41:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:23 INFO - 'HOME': '/Users/cltbld', 17:41:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:23 INFO - 'LOGNAME': 'cltbld', 17:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:23 INFO - 'MOZ_NO_REMOTE': '1', 17:41:23 INFO - 'NO_EM_RESTART': '1', 17:41:23 INFO - 'PAGER': '/bin/cat', 17:41:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:23 INFO - 'PWD': '/builds/slave/test', 17:41:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:23 INFO - 'SHELL': '/bin/bash', 17:41:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:41:23 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:41:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:23 INFO - 'USER': 'cltbld', 17:41:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:23 INFO - 'XPC_FLAGS': '0x0', 17:41:23 INFO - 'XPC_SERVICE_NAME': '0', 17:41:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:23 INFO - Downloading/unpacking flake8 17:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:41:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:41:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:41:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:41:27 INFO - no previously-included directories found matching 'docs/_build' 17:41:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:41:27 INFO - Running setup.py install for pep8 17:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:41:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:41:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:41:27 INFO - no previously-included directories found matching 'docs/_build' 17:41:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:41:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:41:27 INFO - Cleaning up... 17:41:27 INFO - Return code: 0 17:41:27 INFO - Running post-action listener: _resource_record_post_action 17:41:27 INFO - Running post-action listener: _start_resource_monitoring 17:41:28 INFO - Starting resource monitoring. 17:41:28 INFO - ##### 17:41:28 INFO - ##### Running install step. 17:41:28 INFO - ##### 17:41:28 INFO - Running pre-action listener: _resource_record_pre_action 17:41:28 INFO - Running main action method: install 17:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:41:28 INFO - Reading from file tmpfile_stdout 17:41:28 INFO - Detecting whether we're running mozinstall >=1.0... 17:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:41:28 INFO - Reading from file tmpfile_stdout 17:41:28 INFO - Output received: 17:41:28 INFO - Usage: mozinstall [options] installer 17:41:28 INFO - Options: 17:41:28 INFO - -h, --help show this help message and exit 17:41:28 INFO - -d DEST, --destination=DEST 17:41:28 INFO - Directory to install application into. [default: 17:41:28 INFO - "/builds/slave/test"] 17:41:28 INFO - --app=APP Application being installed. [default: firefox] 17:41:28 INFO - mkdir: /builds/slave/test/build/application 17:41:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:41:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:42:02 INFO - Reading from file tmpfile_stdout 17:42:02 INFO - Output received: 17:42:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:42:02 INFO - Running post-action listener: _resource_record_post_action 17:42:02 INFO - ##### 17:42:02 INFO - ##### Running run-tests step. 17:42:02 INFO - ##### 17:42:02 INFO - Running pre-action listener: _resource_record_pre_action 17:42:02 INFO - Running main action method: run_tests 17:42:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:42:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:42:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:42:02 INFO - 2016-04-28 17:42:02.596 screenresolution[1643:7648] starting screenresolution argv=screenresolution get 17:42:02 INFO - 2016-04-28 17:42:02.599 screenresolution[1643:7648] Display 0: 1600x1200x32@60 17:42:02 INFO - 2016-04-28 17:42:02.604 screenresolution[1644:7650] starting screenresolution argv=screenresolution list 17:42:02 INFO - Available Modes on Display 0 17:42:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:42:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:42:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:42:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:42:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:42:02 INFO - Intel Iris: 17:42:02 INFO - Chipset Model: Intel Iris 17:42:02 INFO - Type: GPU 17:42:02 INFO - Bus: Built-In 17:42:02 INFO - VRAM (Dynamic, Max): 1536 MB 17:42:02 INFO - Vendor: Intel (0x8086) 17:42:02 INFO - Device ID: 0x0a2e 17:42:02 INFO - Revision ID: 0x0009 17:42:02 INFO - Displays: 17:42:02 INFO - Display: 17:42:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:42:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:42:02 INFO - Display Serial Number: 1600x1200 60 17:42:02 INFO - Main Display: Yes 17:42:02 INFO - Mirror: Off 17:42:02 INFO - Online: Yes 17:42:02 INFO - Rotation: Supported 17:42:02 INFO - Return code: 0 17:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:42:02 INFO - Python 2.7.3 17:42:02 INFO - Return code: 0 17:42:02 INFO - grabbing minidump binary from tooltool 17:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:02 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 0x1090f5e00>, '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 0x108f1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:42:02 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 17:42:02 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 17:42:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:42:02 INFO - Return code: 0 17:42:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:42:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:42:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:42:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:42:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:42:04 INFO - Return code: 0 17:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 17:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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 17:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q6bhQInUT0/Render', 17:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:42:04 INFO - 'HOME': '/Users/cltbld', 17:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:42:04 INFO - 'LOGNAME': 'cltbld', 17:42:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:42:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:04 INFO - 'MOZ_NO_REMOTE': '1', 17:42:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:42:04 INFO - 'NO_EM_RESTART': '1', 17:42:04 INFO - 'PAGER': '/bin/cat', 17:42:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:04 INFO - 'PWD': '/builds/slave/test', 17:42:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:42:04 INFO - 'SHELL': '/bin/bash', 17:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SVspSFRrpm/Listeners', 17:42:04 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 17:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:42:04 INFO - 'USER': 'cltbld', 17:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:04 INFO - 'XPC_FLAGS': '0x0', 17:42:04 INFO - 'XPC_SERVICE_NAME': '0', 17:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:42:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 17:42:04 INFO - mozversion application_buildid: 20160428153650 17:42:04 INFO - mozversion application_changeset: f7bb3675bb71c81789faabc5e46528e054b8aa90 17:42:04 INFO - mozversion application_display_name: Nightly 17:42:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:42:04 INFO - mozversion application_name: Firefox 17:42:04 INFO - mozversion application_remotingname: firefox 17:42:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:42:04 INFO - mozversion application_vendor: Mozilla 17:42:04 INFO - mozversion application_version: 49.0a1 17:42:04 INFO - mozversion platform_buildid: 20160428153650 17:42:04 INFO - mozversion platform_changeset: f7bb3675bb71c81789faabc5e46528e054b8aa90 17:42:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:42:04 INFO - mozversion platform_version: 49.0a1 17:42:04 INFO - using testdate: 1461890524 17:42:04 INFO - actual date: 1461890524 17:42:04 INFO - starting webserver on 'localhost:49270' 17:42:04 INFO - SUITE-START | Running 2 tests 17:42:04 INFO - TEST-START | dromaeo_css 17:42:04 INFO - operating with platform_type : mac_ 17:42:04 INFO - Initialising browser for dromaeo_css test... 17:42:04 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmp93rR4V/profile http://localhost:49270/getInfo.html) 17:42:06 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 17:42:06 INFO - PROCESS | 1662 | colorDepth:24 17:42:06 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 17:42:06 INFO - PROCESS | 1662 | __metrics 17:42:06 INFO - PROCESS | 1662 | 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" 17:42:06 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:42:07 INFO - TEST-INFO | 1662: exit 0 17:42:07 INFO - Browser initialized. 17:42:07 INFO - Running cycle 1/1 for dromaeo_css test... 17:42:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmp93rR4V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:42:19 INFO - PROCESS | 1664 | RSS: Main: 239448064 17:42:19 INFO - PROCESS | 1664 | 17:43:10 INFO - PROCESS | 1664 | 1461890590972 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] 17:45:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 17:45:23 INFO - PROCESS | 1664 | RSS: Main: 428126208 17:45:23 INFO - PROCESS | 1664 | 17:48:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 17:48:26 INFO - PROCESS | 1664 | RSS: Main: 376233984 17:48:26 INFO - PROCESS | 1664 | 17:51:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 17:51:29 INFO - PROCESS | 1664 | RSS: Main: 370343936 17:51:29 INFO - PROCESS | 1664 | 17:54:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 17:54:32 INFO - PROCESS | 1664 | RSS: Main: 368627712 17:54:32 INFO - PROCESS | 1664 | 17:57:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 17:57:36 INFO - PROCESS | 1664 | RSS: Main: 338141184 17:57:36 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 18:00:39 INFO - PROCESS | 1664 | RSS: Main: 407343104 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | __start_tp_report 18:00:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 18:00:39 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 18:00:39 INFO - PROCESS | 1664 | |i|pagename|runs| 18:00:39 INFO - PROCESS | 1664 | |0;dojo.html;3320.236345640503;3375.7130643895266;3388.5594872666547;3397.5073614325906;3449.9482507762386;5468.179773034037;5547.278853749017;5550.500454959053;5613.494785469307;5627.2403225564585;5554.166874968757;5602.551795856332;5618.803341883035;5737.655740655564;5741;5016.44819069902;5075.593952483811;5086.465921078289;5097.439281679012;5143.254228136937;5252.8424147275455;5263.499967503081;5283.101872681634;5308.814191503298;5335.546478549328;5036.445990940993;5070.898582028376;5117.232415137741;5197.636165468419;5234.816781412632;5112.437631860493;5122.692638441706;5133.922991155136;5147.124988751883;5165.793368265265;5094.133997220481;5120.641555091145;5159.7678104485385;5182.559482443997;5198.064348417283;5380.757865896044;5436.483534064258;5477.589180811416;5509.146082357241;5543.279373681436;6097.817065488042;6122.051082082285;6125.448709616156;6137.846553836145;6153.4154255345675;6156.076588511714;6194.690265486753;6208.534359922979;6209.254889413254;6224.751009959641;5575.776968921279;5649.745761440745;5709.8287051388525;5748.223989761413;5751.1085781703905;6238.968805156036;6271.122042913996;6273.905891411632;6280.309165991737;6291.748330066837;5875.618084824473;6063.090536419484;6068.423499767515;6071.119687645267;6083.178770865877;6071.210742603434;6125.724342404603;6144.893919094606;6166.229221347332;6169;5068.974655126774;6043.7280322385595;6091.096936483645;6130.856783744145;6163.630182189083;5977.432143946405;6121.081837724377;6123.724432400552;6142.754289828446;6147.231596050494;4783.425988881357;4837.927431088537;4859.076775412731;4914.557689807934;4914.557689807934;3645.234500754871;4377.409049778303;4389.561043895585;4393.275109606943;4421.646268298528;5410.594205434608;5614.494695477427;5624.184493248456;5627.324721033502;5653.038983372837;6068.089786532056;6122.184701511125;6124.594542075386;6168.845778855564;6182.505399568024;5604.747786349624;5614.326280846243;5624.85937851557;5626.043572592669;5657.8019769307875;5326.387465441447;5330.760115794798;5349.786008559614;5354.785808567692;5381.811636592779;6142.959235178526;6167.475764560063;6249.281332646713;6262;6266.404691554295;6139.1098290746695;6179.598326108789;6186.226721659793;6189.288231853215;6202.782902598387;6215.81352559424;6248.4376406123665;6257.311695713559;6261.624302541862;6267.780627678009;5891.499222565958;5941.257342832146;5987.101934709828;5991.430814072656;6006.819795406145;6002.489788368038;6120.7857724981195;6137.202163718688;6144.907826382519;6159.29168145669;6007.918574656604;6060.78787242462;6061.969690151521;6070.119832624333;6092.512598992159;5950.613210141327;5983.55123365758;5989.251343582052;6000.609960352563;6017.037274036134;4518.502964673949;5712.37163911961;5845.532357411482;5851.882962340815;5877.676727780014;5946.851328716817;5976.760929562769;6059.545534084873;6066.211392519121;6076.058210977306;5743.0811070228565;5801.3038435388025;5836.503897168788;5840;5847.356790753099 18:00:39 INFO - PROCESS | 1664 | |1;ext.html;506.47832732285735;516.7338820507438;532.7442827442829;572.602041581101;602.7257597793005;29887.701122988765;39452.81641550754;39586.60413395865;39619.40570891432;39708.24230486578;40548.39177412334;41087.178256434854;41220.793896030555;41269.587304127024;41338;8402.579871006456;8598.269147122503;9336.953315233415;9592;9598.952005239964;10922.344659320426;11210.383428911407;11218.644084742215;11219.15856310776;11226.989570938615;11147.498362573662;11291.322520648746;11297.322160670386;11301.378424186667;11331.093512518979;8304.584770761438;11012.669619911416;11270.718232044183;11270.985611294982;11407.714807129805;11202.159838012181;11347.829782553268;11349.602763903305;11349.829752553718;11350.14873884462;37627.8118609408;38318.80840595807;38322.23355532902;38349.424758628644;38389.35301146386;3161.8893338733033;3219.9678003220138;3441.9311613767836;3587.910302242439;3938.8030598469963;24674.0130394784;24914.875425622755;25172.874135629205;25203.62194567083;25494.87252563744;2659.135780871205;2684.5973104034356;2715.1311580294314;2730.24918147509;2793.9522678995377;470756;475013;477157.9999999965;477232;477285;33882.830585847405;60104.797904041676;60312.396876031555;60483.69758151181;60733;35479.4678079829;35631.64368356335;35642.64357356445;35737.46393804095;35745;14986.550403487912;15249.008814427027;15253.08481491114;15260.465883694018;15261.694766104838;11402.94298528502;11650.88349116515;11652.883471165349;11656.88343116558;12905.483780648856;7644.617769111522;7978.960105199437;8147.5111493309405;8154.592270386457;8170.305524030524;3005.3989202159655;3339.398908196548;3400.4219282721997;3434.1929646533044;3445.3109378124477;999.6155324874956;1025.6512785652915;1029.9588016479406;1055.4195192644015;1139.1797905508022;28762.568561471595;28926.98755543546;29390;29454.558181627293;29798.55302170469;1150.246588484544;1159.8550181227347;1202.296656455983;1217.896478799312;1233.864274929757;15346.386144554315;15369.769453458208;15441.613959651098;15663.216839157996;15743.44897928567;547538;548677;548872;549714;647482;60369.698151508965;61316;61564.38435615676;61598.07602885961;61717.38282617206;1378.2281922123443;1385.681293302526;1499.265359973605;1502.684436268374;1516.7194069097252;24194.153204637754;24446.633300500864;26546.070887518843;28968.7103128966;29501.557476637438;54538.909221814545;55353.892922142135;55578;55587.72206138943;55743.44256557382;588067;591939;592132;592504;594333;26717.7328226723;26932.46135077327;26969.865150674905;27483.17550473489;27532.58701119525;34324;34492.65507344995;40087;41094.38358424566;42367;23627.40931476727;34862.12844678802;36320.36558354828;36330.091747706516;36957.26085478222;34388.312233755685;35149.29701406009;35507.46738798972;35586.46620300646;36349.45475817812;35477.75827846013;35542.289154216254;35849.46225806667;35877.92366229017;36010.819945900104;30294;31238.843805780827;31352.37295254128;31376;31411.84294078515;23760;24051.518969620858;24318.27045188647;24782.876085620177;24961.625575616745 18:00:39 INFO - PROCESS | 1664 | |2;jquery.html;392.860534510249;396.635095711945;397.49716608490263;397.72358211043326;408.78743053612124;13538;16676.58308542287;19685.803141968594;20446.89776551117;20486.18055277789;16295.59261018475;18467.261309547637;19840.801591984076;20047.69928451071;20529.97350132495;4956.7769450374635;5960.135780311853;5979.0134627786365;6017.638941663514;6019.367966363535;5575.637583557056;5897.203877476529;5985.34161242263;6001.21984142061;6032.487238584726;3868.071662800922;4088.2641124597562;5823.941760582382;5892.234009578749;6130.877382452349;6034.879302413927;6088.66512341821;6106.450419462225;6108.8472788180425;6130.877382452349;5518.255035570227;5725.942740572583;5759.769609215627;5762.222100016528;5764.942350576483;13559.661008474768;13735.313234338244;13838.031337806358;13957.023008389417;14033.78949315761;1934.419674097765;2055.075216152722;2087.864288821237;2130.4358611793277;2232.1852523828848;9815.754606134833;10001.39101404925;10052.597896084148;10294.764628244659;10387.376757394504;1665.8266003263802;1722.9741553876702;1734.5663584103975;1747.921343539531;1758.78015248094;117656.41171794172;118497.40751296275;121118.39440802828;121130.3943480277;121740;9590.808183836423;11578.421078946187;11732.061435085172;11751.823722644167;11778.76442471163;15054.548363549111;15149.697006059818;15170.772438413433;15180.316885740169;15222.086674799326;8796.736097917073;8830.86753698695;8881.42270752399;8885.155910188521;8981.595828187747;8764.649414023495;8767.736967890973;8790.648374065093;8868.423552469068;8901.866472002925;4670.369500117508;4684.414448193976;4687.320338550891;4709.128811169944;4728.1252968201;1897.3074827687794;1941.155901893207;2001.5696625225646;2002.6895831146196;2016.9899150504154;749.5240522268399;847.4788005376649;921.4747593871429;954.6372378496127;1023.3092662452815;16865.747013794804;19694.8030519693;19845.60308793816;20010.69983950242;20231.393058208276;943.3962264150949;946.7065209784988;955.3121752338305;1014.7919676466307;1038.190211634926;10012.499375031219;10613.790932304533;10808.351498910091;11005.449727513593;11732.472038758277;572958;582360;598559;611608.9419552963;621864;109746.90253097526;133239.33521329713;133762.3311883454;138053;141673;1040.7242080848582;1052.7999680060916;1070.8126077936174;1085.582050910405;1086.0388556127725;17394.565135871704;17675.027873467083;17679.204435800162;17686.20412081434;17982.280708772025;48747;50686.746566266935;51161.744191278805;51662;52839.47160528499;71306.57386852338;74307;74932;74949.6252518734;75241.74274771765;7731.961340193263;7983.960080199562;8063.556504392314;8230.259276665129;8417.663293468193;10160.64437744705;11935.940320298343;12042.036637069188;12187.87812121903;12392.62822115338;10162.39025658463;10338.483075846027;10353.844692329772;10641.840372394576;10683.57265709363;10249.282550221413;10332.070113689804;10432.895671043192;10556.419396933243;10616.097631701392;10312.587496500057;10362.5336859843;10614.628488002883;10911.727206819893;10986.560537578727;7859.253370929981;7885.132635410215;8136.83726325482;8148.755537333889;8172.305354044973;9231.58457869411;10118.544665489924;10150.547898892235;10230.437325947145;10505.474726263503 18:00:39 INFO - PROCESS | 1664 | |3;mootools.html;1423.4590855474921;1441.4738620403557;1604.8395160483954;1821.5992481654039;1985.6128055029258;7821.726239581616;8177.427580069398;8563.443376180552;8826.338024648145;8826.558672066387;8509.064002959669;8570.185832345916;8655.17775811297;8664.74005779826;8677.609507572159;2438.5366780311774;2467.568175569277;2468.6297055441737;2471.122751423246;2471.233917485576;2409.5301416223842;2464.1745015419765;2464.223769512607;2465.0263146057296;2466;2420.818438617102;2437.3784684905413;2455.1161581830524;2459.6310553417043;2462.9507409851794;2415.6738840256603;2430.428849220432;2447.2535876557645;2450.803935685141;2473.171487551663;1995.820376166152;2319.3389883883183;2389.462370966536;2420.1287536486852;2423.8182136339847;8682.913170868274;8755.737327880173;8764;8767.693130740394;8785;4709.175894218532;5023.287424790608;5389.622726409153;5420.83737487876;5424.864378390533;3522.630123836986;3581.928361432783;3661.8535258589623;3663.3405986922344;3683;1035.4667346316653;1051.3218973761957;1053.2048303530785;1056.6935588679273;1056.889026652198;11217.046551043171;11335.14986376025;11341.773164536746;11368.204225704205;11565.49648545692;4559.475660299042;4574.977125114353;4689.226277664216;4703.341532185496;4721.881952951204;9137.680181193626;9149.77125571866;9171.816563668823;9177.403468774508;9178.173964343241;5204.089284375219;5268.236105764643;5269.973650131725;5272.182811664198;5290.312259406252;4163.7293575917465;4390.604845563851;4580.1068791585985;4865.854024379274;4882.95117048825;3945.782981936021;4112.527059388209;4112.588741125924;4121.340585506305;4153.584641535822;1349.966250843717;1381.1229869033036;1389.9861001389856;1394.1610191386455;1399.7410479061407;777.6889244302212;846.6876341670509;872.2237208884098;921.313621352097;923.2014307623857;5617.915731264035;5767.8558036049435;5937.851553711192;6033.064874944391;6039.78860739872;886.2776836877924;895.562621991694;941.2987324443196;966.4781018250067;970.1317320997667;3898.3957486589056;3949.822257998397;3983.9840840585466;4023.376376661622;4044.676425885922;9928.553215105338;10645;10687.732806679895;10696.197785166143;10807.297525660806;10014.098731114089;10593.788124237471;10610.310329828537;10742.03321701035;10744.838827417596;7781.338586220121;8032.1164671886045;8094.069182044141;8106.432549721463;8138.674453021813;5783.94216057834;5922.2597175353085;5964.105384192406;6014;6044;9455.858162127435;9543.568464730279;9748.658796942073;9831;9865.654702085392;8199.754007379788;9623.422594644344;9725.513724313898;9920;10052.296339256181;6818.28408017151;7090.326418990187;7099.219085900443;7101.928980710333;7115.039469671735;7680.423968202295;8025.678972841254;8274.793130171794;8307.418480706534;8316.916830831857;5854.590178687451;7262.709491620276;7350.19147893742;7405.407567394704;7461.477696561188;7167.426605871622;7221.53060051095;7227.566346019255;7332.376747976482;7372.557646541225;5942.168096466585;6840.48696347766;6874.587524748331;6962.617056061965;6968.95465680152;4683.805629564439;4815.6147508199965;4866.7079975201605;4955.72743499111;5057.115004874058;4225.936610950776;4652.232381657124;4813.15769740301;4892.68197567157;4937.21004639256 18:00:39 INFO - PROCESS | 1664 | |4;prototype.html;300.15356694122585;312.64514775729543;319.60847961247475;319.7953309881676;322.7611567440092;5141.665791723535;5156.8397110650085;5208.921866172011;5216.8174113906025;5225.634205605609;4924.581410580096;4998.225275082359;5079.466656001113;5128.487151284879;5139;3301.6863397977213;3384.8307584620793;3983.780892050936;3987.3819557968563;4030.858919937803;3266.5100234964707;3932.6067393260732;3945.782981935992;4002.959970400288;4014;3732.701383889282;3777.471154038437;3804;3881.844726210962;3941.192055628589;3224.532442795793;3725.683316918052;3952.486176796998;3963.8810835674976;3990.7206495545324;3029.621297337833;3473.513708080871;3511.525943997579;3516.191276006533;3523.2953409317984;4298.505671847746;4854.635902307341;4980.9252861207115;5003.774830132653;5023.522765337287;4509.571590698878;4540.455145382575;4557.680962332638;4565.52062033485;4566.748828814414;5332.973335133338;5353.464653534655;5384.40771515133;5387.19192121181;5400.488772720703;3023.51847594677;3870.883873483772;3876.046432638721;3885.7668539887413;3886.8833934981994;5616.213730077792;5623;5623.690697011662;5678.26182596264;5725;3784.2053168834855;3793.2982398256413;3969.4442778011103;3987.325323364194;3996.780177090288;3789.242151569697;3987.960120398759;4026.6778657707314;4026.8187931543152;4031.395290706359;3491.5984661764223;3886.630770076838;4386.780660966939;4456.665750068758;4540.954590454053;4331;4866.805327786919;5409.702466364309;5438.374586922476;5455.454454554513;1157.571698471571;1439.604108870069;1448.8551144885428;1464.1581090872792;1465;919.7884486567993;938.0010289042149;965.3387429610739;984.1388784813288;986.850319377931;4548.545145485425;4633.837815676435;5481.75332110056;5509.988854068236;5532.861678458071;5320.17537281722;5865.912011319833;5894.6757928314355;6116.296625887991;6168.599041062406;4633.4671512775785;5111.335526381621;5476.561875049986;5522.779088836482;5526.364468086141;2885.3697660821567;2952.601398811176;3141.5601815745813;3152.952705709416;3181.9522707159413;4955.578775804025;5568.638038527482;5599.356074051536;6146.4468197861515;6251.281102673251;5011.774470148852;5603.142397377285;5792.391798861143;5795.536357091422;5800.651960882361;4797.856064318006;5808.361080281165;5813.04084825999;5843.561732870052;5854.941450585439;4676.906461870812;5186.170212765939;5322.54758345545;5578.470045345685;5637.267155269788;5549.972250138723;5586.86032849182;5589.832305030855;5641.561401842504;5659.349174844946;4640.535946405333;4888.4537380236725;5707.972564938351;5869.413058694096;5916.763329466773;3500.2474467988486;3812.980935095307;3826.4069069294305;3828.1386687994977;3842.2507611016704;4913.361263035782;5626.718664066862;5647.632903861236;5686;5726.341470730919;5506.311711036121;5515.227868098389;5515.751791169488;5540.944590554204;5564.527015203591;5120.667156634807;5237.816676416307;5551.333839939233;5555.583331250254;5580.497755202051;4509.909801804011;4623.8671525476475;5072.923906141337;5194.636375453682;5398.2442458056685;5520.392756796829;5535.39110697831;5552.028395030773;5576.247206627054;5576.5817563683695;5391.973040134774;5438.374586922555;5439.782408703766;5445.428230035947;5506.311711036121 18:00:39 INFO - PROCESS | 1664 | |5;yui.html;552.2186106659077;696.9059177011105;708.174976152782;712.202333386607;715.5742333311678;14648.853511464895;14707.264636768146;15154.77267840982;15165.772513412308;15376;13995.580132596013;14049.157050576947;14160.221187834664;14165.079269847452;14171.503997360094;729.9854002919939;762.3063012658482;918.9586468608928;933.8225737109927;935.9391639543442;911.3483859040784;944.6032666280179;945.3666043750669;947.8862536495595;950.0309684122191;986.4475893499628;995.7560397702573;998.0418797953973;999.20063948841;999.4503023337172;722.4906440959164;728.8870225115115;938.6902322233647;985.3447457440793;988.5946761827616;722.7325889420895;726.1286456252466;726.5386479585449;727.3599232675265;727.7889412070546;14910.850891491164;14925.402983880631;14980.176090315028;14985.325660345201;14987.850121498754;12165.817512737316;12345.38273086342;12421.1926224796;12623.87376126236;12744.426500807485;10976.506057227443;11156.051735602483;11166.944165279121;11250;11615.070794336432;4097.938530922039;4116.5060192777055;4128.6284234419045;4236.279832428495;4244.766537840417;82375;83879;84094.73857892136;84637.15362846416;85599.28801424;9331.533423328807;9513.904860951441;9541.379810312308;9554.713358599252;9764.121229089416;17756.289748410178;21258.893705531682;22181.334559963227;22188.445288867584;22197.667034994487;7293.817654558678;7314.232005639437;7340.155882073523;7473.925260747432;7488.4009279257525;3941.901452463654;3981.8606348778235;4036.7779772112817;4057.2899742544937;4338.522762496123;3474.2356681530023;3549.254656522159;3585.4263317869018;3594.5866225384416;3598.280343931172;557.5511713071016;635.2091645900883;645.6481217736344;647.6729251727847;656.1404560026381;513.2609042978099;566.9518090962232;571.6027360984159;604.78530121807;614.9914140809077;8064.153263907206;8091.716789912325;8154.75535733929;8253.215944485264;8347.791305217419;539.5710410223882;583.8073435766187;592.2005292854611;595.6068994463633;598.7694737526084;3665.486831843544;3822.21781785753;3839.2897313997;3945.2109578084496;4042.171354872244;95885;96613.03386966181;97408;99644;101718;65462.67268663721;68802.65598671975;73034.63482682552;78169.65491035278;84393;74491.13772155631;74629.6268518654;75149;75959.24040759522;77469.61265193638;8363.692543844474;8435.283000944988;8532.70135545253;8568.228859402683;8772.914421371926;44443.555564443945;45161.322580161985;45909.85225369393;45934;46038;64465.67767161134;66033;66379.33620663731;67001.32998669951;67687;10943.562257509611;10962.835557466804;10963.451827408438;11158.386288754196;11435.599754008903;7907.920920790949;8745;11814.881851181723;11951;13012.479500819862;9729;10734.677959661223;11144.721381965515;11214.719632008939;11927.045836333245;11371.715707107389;11510;11783.88216117862;11808.70478238051;11998.760024799281;10905.96994071986;11160.944195278971;11179.105671546095;11179.608713695307;11549.538018479167;9527.28545359087;9630.807383852423;9729.756756081155;9930.93950220536;10000.149987251165;8884.822303554021;9154.588043537924;9245.72262832116;9917.950410247902;9939.403635781877 18:00:39 INFO - PROCESS | 1664 | __end_tp_report 18:00:39 INFO - PROCESS | 1664 | __start_cc_report 18:00:39 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2177 18:00:39 INFO - PROCESS | 1664 | __end_cc_report 18:00:39 INFO - PROCESS | 1664 | __startTimestamp1461891639622__endTimestamp 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | ------- Summary: start ------- 18:00:39 INFO - PROCESS | 1664 | Number of tests: 6 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5644.99 Median:5845.53 stddev:634.94 (10.9%) stddev-sans-first:610.13 18:00:39 INFO - PROCESS | 1664 | Values: 3320.2 3375.7 3388.6 3397.5 3449.9 5468.2 5547.3 5550.5 5613.5 5627.2 5554.2 5602.6 5618.8 5737.7 5741.0 5016.4 5075.6 5086.5 5097.4 5143.3 5252.8 5263.5 5283.1 5308.8 5335.5 5036.4 5070.9 5117.2 5197.6 5234.8 5112.4 5122.7 5133.9 5147.1 5165.8 5094.1 5120.6 5159.8 5182.6 5198.1 5380.8 5436.5 5477.6 5509.1 5543.3 6097.8 6122.1 6125.4 6137.8 6153.4 6156.1 6194.7 6208.5 6209.3 6224.8 5575.8 5649.7 5709.8 5748.2 5751.1 6239.0 6271.1 6273.9 6280.3 6291.7 5875.6 6063.1 6068.4 6071.1 6083.2 6071.2 6125.7 6144.9 6166.2 6169.0 5069.0 6043.7 6091.1 6130.9 6163.6 5977.4 6121.1 6123.7 6142.8 6147.2 4783.4 4837.9 4859.1 4914.6 4914.6 3645.2 4377.4 4389.6 4393.3 4421.6 5410.6 5614.5 5624.2 5627.3 5653.0 6068.1 6122.2 6124.6 6168.8 6182.5 5604.7 5614.3 5624.9 5626.0 5657.8 5326.4 5330.8 5349.8 5354.8 5381.8 6143.0 6167.5 6249.3 6262.0 6266.4 6139.1 6179.6 6186.2 6189.3 6202.8 6215.8 6248.4 6257.3 6261.6 6267.8 5891.5 5941.3 5987.1 5991.4 6006.8 6002.5 6120.8 6137.2 6144.9 6159.3 6007.9 6060.8 6062.0 6070.1 6092.5 5950.6 5983.6 5989.3 6000.6 6017.0 4518.5 5712.4 5845.5 5851.9 5877.7 5946.9 5976.8 6059.5 6066.2 6076.1 5743.1 5801.3 5836.5 5840.0 5847.4 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:66248.17 Median:18862.21 stddev:146641.02 (777.4%) stddev-sans-first:146969.32 18:00:39 INFO - PROCESS | 1664 | Values: 506.5 516.7 532.7 572.6 602.7 29887.7 39452.8 39586.6 39619.4 39708.2 40548.4 41087.2 41220.8 41269.6 41338.0 8402.6 8598.3 9337.0 9592.0 9599.0 10922.3 11210.4 11218.6 11219.2 11227.0 11147.5 11291.3 11297.3 11301.4 11331.1 8304.6 11012.7 11270.7 11271.0 11407.7 11202.2 11347.8 11349.6 11349.8 11350.1 37627.8 38318.8 38322.2 38349.4 38389.4 3161.9 3220.0 3441.9 3587.9 3938.8 24674.0 24914.9 25172.9 25203.6 25494.9 2659.1 2684.6 2715.1 2730.2 2794.0 470756.0 475013.0 477158.0 477232.0 477285.0 33882.8 60104.8 60312.4 60483.7 60733.0 35479.5 35631.6 35642.6 35737.5 35745.0 14986.6 15249.0 15253.1 15260.5 15261.7 11402.9 11650.9 11652.9 11656.9 12905.5 7644.6 7979.0 8147.5 8154.6 8170.3 3005.4 3339.4 3400.4 3434.2 3445.3 999.6 1025.7 1030.0 1055.4 1139.2 28762.6 28927.0 29390.0 29454.6 29798.6 1150.2 1159.9 1202.3 1217.9 1233.9 15346.4 15369.8 15441.6 15663.2 15743.4 547538.0 548677.0 548872.0 549714.0 647482.0 60369.7 61316.0 61564.4 61598.1 61717.4 1378.2 1385.7 1499.3 1502.7 1516.7 24194.2 24446.6 26546.1 28968.7 29501.6 54538.9 55353.9 55578.0 55587.7 55743.4 588067.0 591939.0 592132.0 592504.0 594333.0 26717.7 26932.5 26969.9 27483.2 27532.6 34324.0 34492.7 40087.0 41094.4 42367.0 23627.4 34862.1 36320.4 36330.1 36957.3 34388.3 35149.3 35507.5 35586.5 36349.5 35477.8 35542.3 35849.5 35877.9 36010.8 30294.0 31238.8 31352.4 31376.0 31411.8 23760.0 24051.5 24318.3 24782.9 24961.6 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34416.17 Median:11032.34 stddev:99965.22 (906.1%) stddev-sans-first:100213.01 18:00:39 INFO - PROCESS | 1664 | Values: 392.9 396.6 397.5 397.7 408.8 13538.0 16676.6 19685.8 20446.9 20486.2 16295.6 18467.3 19840.8 20047.7 20530.0 4956.8 5960.1 5979.0 6017.6 6019.4 5575.6 5897.2 5985.3 6001.2 6032.5 3868.1 4088.3 5823.9 5892.2 6130.9 6034.9 6088.7 6106.5 6108.8 6130.9 5518.3 5725.9 5759.8 5762.2 5764.9 13559.7 13735.3 13838.0 13957.0 14033.8 1934.4 2055.1 2087.9 2130.4 2232.2 9815.8 10001.4 10052.6 10294.8 10387.4 1665.8 1723.0 1734.6 1747.9 1758.8 117656.4 118497.4 121118.4 121130.4 121740.0 9590.8 11578.4 11732.1 11751.8 11778.8 15054.5 15149.7 15170.8 15180.3 15222.1 8796.7 8830.9 8881.4 8885.2 8981.6 8764.6 8767.7 8790.6 8868.4 8901.9 4670.4 4684.4 4687.3 4709.1 4728.1 1897.3 1941.2 2001.6 2002.7 2017.0 749.5 847.5 921.5 954.6 1023.3 16865.7 19694.8 19845.6 20010.7 20231.4 943.4 946.7 955.3 1014.8 1038.2 10012.5 10613.8 10808.4 11005.4 11732.5 572958.0 582360.0 598559.0 611608.9 621864.0 109746.9 133239.3 133762.3 138053.0 141673.0 1040.7 1052.8 1070.8 1085.6 1086.0 17394.6 17675.0 17679.2 17686.2 17982.3 48747.0 50686.7 51161.7 51662.0 52839.5 71306.6 74307.0 74932.0 74949.6 75241.7 7732.0 7984.0 8063.6 8230.3 8417.7 10160.6 11935.9 12042.0 12187.9 12392.6 10162.4 10338.5 10353.8 10641.8 10683.6 10249.3 10332.1 10432.9 10556.4 10616.1 10312.6 10362.5 10614.6 10911.7 10986.6 7859.3 7885.1 8136.8 8148.8 8172.3 9231.6 10118.5 10150.5 10230.4 10505.5 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5601.53 Median:5040.20 stddev:3070.58 (60.9%) stddev-sans-first:3063.14 18:00:39 INFO - PROCESS | 1664 | Values: 1423.5 1441.5 1604.8 1821.6 1985.6 7821.7 8177.4 8563.4 8826.3 8826.6 8509.1 8570.2 8655.2 8664.7 8677.6 2438.5 2467.6 2468.6 2471.1 2471.2 2409.5 2464.2 2464.2 2465.0 2466.0 2420.8 2437.4 2455.1 2459.6 2463.0 2415.7 2430.4 2447.3 2450.8 2473.2 1995.8 2319.3 2389.5 2420.1 2423.8 8682.9 8755.7 8764.0 8767.7 8785.0 4709.2 5023.3 5389.6 5420.8 5424.9 3522.6 3581.9 3661.9 3663.3 3683.0 1035.5 1051.3 1053.2 1056.7 1056.9 11217.0 11335.1 11341.8 11368.2 11565.5 4559.5 4575.0 4689.2 4703.3 4721.9 9137.7 9149.8 9171.8 9177.4 9178.2 5204.1 5268.2 5270.0 5272.2 5290.3 4163.7 4390.6 4580.1 4865.9 4883.0 3945.8 4112.5 4112.6 4121.3 4153.6 1350.0 1381.1 1390.0 1394.2 1399.7 777.7 846.7 872.2 921.3 923.2 5617.9 5767.9 5937.9 6033.1 6039.8 886.3 895.6 941.3 966.5 970.1 3898.4 3949.8 3984.0 4023.4 4044.7 9928.6 10645.0 10687.7 10696.2 10807.3 10014.1 10593.8 10610.3 10742.0 10744.8 7781.3 8032.1 8094.1 8106.4 8138.7 5783.9 5922.3 5964.1 6014.0 6044.0 9455.9 9543.6 9748.7 9831.0 9865.7 8199.8 9623.4 9725.5 9920.0 10052.3 6818.3 7090.3 7099.2 7101.9 7115.0 7680.4 8025.7 8274.8 8307.4 8316.9 5854.6 7262.7 7350.2 7405.4 7461.5 7167.4 7221.5 7227.6 7332.4 7372.6 5942.2 6840.5 6874.6 6962.6 6969.0 4683.8 4815.6 4866.7 4955.7 5057.1 4225.9 4652.2 4813.2 4892.7 4937.2 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4485.12 Median:5048.22 stddev:1370.81 (27.2%) stddev-sans-first:1338.19 18:00:39 INFO - PROCESS | 1664 | Values: 300.2 312.6 319.6 319.8 322.8 5141.7 5156.8 5208.9 5216.8 5225.6 4924.6 4998.2 5079.5 5128.5 5139.0 3301.7 3384.8 3983.8 3987.4 4030.9 3266.5 3932.6 3945.8 4003.0 4014.0 3732.7 3777.5 3804.0 3881.8 3941.2 3224.5 3725.7 3952.5 3963.9 3990.7 3029.6 3473.5 3511.5 3516.2 3523.3 4298.5 4854.6 4980.9 5003.8 5023.5 4509.6 4540.5 4557.7 4565.5 4566.7 5333.0 5353.5 5384.4 5387.2 5400.5 3023.5 3870.9 3876.0 3885.8 3886.9 5616.2 5623.0 5623.7 5678.3 5725.0 3784.2 3793.3 3969.4 3987.3 3996.8 3789.2 3988.0 4026.7 4026.8 4031.4 3491.6 3886.6 4386.8 4456.7 4541.0 4331.0 4866.8 5409.7 5438.4 5455.5 1157.6 1439.6 1448.9 1464.2 1465.0 919.8 938.0 965.3 984.1 986.9 4548.5 4633.8 5481.8 5510.0 5532.9 5320.2 5865.9 5894.7 6116.3 6168.6 4633.5 5111.3 5476.6 5522.8 5526.4 2885.4 2952.6 3141.6 3153.0 3182.0 4955.6 5568.6 5599.4 6146.4 6251.3 5011.8 5603.1 5792.4 5795.5 5800.7 4797.9 5808.4 5813.0 5843.6 5854.9 4676.9 5186.2 5322.5 5578.5 5637.3 5550.0 5586.9 5589.8 5641.6 5659.3 4640.5 4888.5 5708.0 5869.4 5916.8 3500.2 3813.0 3826.4 3828.1 3842.3 4913.4 5626.7 5647.6 5686.0 5726.3 5506.3 5515.2 5515.8 5540.9 5564.5 5120.7 5237.8 5551.3 5555.6 5580.5 4509.9 4623.9 5072.9 5194.6 5398.2 5520.4 5535.4 5552.0 5576.2 5576.6 5392.0 5438.4 5439.8 5445.4 5506.3 18:00:39 INFO - PROCESS | 1664 | 18:00:39 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18431.74 Median:625.10 stddev:26286.86 (4205.2%) stddev-sans-first:26326.32 18:00:39 INFO - PROCESS | 1664 | Values: 552.2 696.9 708.2 712.2 715.6 14648.9 14707.3 15154.8 15165.8 15376.0 13995.6 14049.2 14160.2 14165.1 14171.5 730.0 762.3 919.0 933.8 935.9 911.3 944.6 945.4 947.9 950.0 986.4 995.8 998.0 999.2 999.5 722.5 728.9 938.7 985.3 988.6 722.7 726.1 726.5 727.4 727.8 14910.9 14925.4 14980.2 14985.3 14987.9 12165.8 12345.4 12421.2 12623.9 12744.4 10976.5 11156.1 11166.9 11250.0 11615.1 4097.9 4116.5 4128.6 4236.3 4244.8 82375.0 83879.0 84094.7 84637.2 85599.3 9331.5 9513.9 9541.4 9554.7 9764.1 17756.3 21258.9 22181.3 22188.4 22197.7 7293.8 7314.2 7340.2 7473.9 7488.4 3941.9 3981.9 4036.8 4057.3 4338.5 3474.2 3549.3 3585.4 3594.6 3598.3 557.6 635.2 645.6 647.7 656.1 513.3 567.0 571.6 604.8 615.0 8064.2 8091.7 8154.8 8253.2 8347.8 539.6 583.8 592.2 595.6 598.8 3665.5 3822.2 3839.3 3945.2 4042.2 95885.0 96613.0 97408.0 99644.0 101718.0 65462.7 68802.7 73034.6 78169.7 84393.0 74491.1 74629.6 75149.0 75959.2 77469.6 8363.7 8435.3 8532.7 8568.2 8772.9 44443.6 45161.3 45909.9 45934.0 46038.0 64465.7 66033.0 66379.3 67001.3 67687.0 10943.6 10962.8 10963.5 11158.4 11435.6 7907.9 8745.0 11814.9 11951.0 13012.5 9729.0 10734.7 11144.7 11214.7 11927.0 11371.7 11510.0 11783.9 11808.7 11998.8 10906.0 11160.9 11179.1 11179.6 11549.5 9527.3 9630.8 9729.8 9930.9 10000.1 8884.8 9154.6 9245.7 9918.0 9939.4 18:00:39 INFO - PROCESS | 1664 | -------- Summary: end -------- 18:00:39 INFO - PROCESS | 1664 | 18:00:40 INFO - TEST-INFO | 1664: exit 0 18:00:40 INFO - TEST-OK | dromaeo_css | took 1116007ms 18:00:40 INFO - TEST-START | kraken 18:00:40 INFO - operating with platform_type : mac_ 18:00:40 INFO - Initialising browser for kraken test... 18:00:40 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmp0_iiEB/profile http://localhost:49270/getInfo.html) 18:00:42 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 18:00:42 INFO - PROCESS | 1671 | colorDepth:24 18:00:42 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 18:00:42 INFO - PROCESS | 1671 | __metrics 18:00:42 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:00:42 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:00:43 INFO - TEST-INFO | 1671: exit 0 18:00:43 INFO - Browser initialized. 18:00:43 INFO - Running cycle 1/1 for kraken test... 18:00:43 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmp0_iiEB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:00:55 INFO - PROCESS | 1673 | RSS: Main: 239702016 18:00:55 INFO - PROCESS | 1673 | 18:00:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 18:00:58 INFO - PROCESS | 1673 | RSS: Main: 286810112 18:00:58 INFO - PROCESS | 1673 | 18:01:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 18:01:01 INFO - PROCESS | 1673 | RSS: Main: 295657472 18:01:01 INFO - PROCESS | 1673 | 18:01:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 18:01:06 INFO - PROCESS | 1673 | RSS: Main: 377847808 18:01:06 INFO - PROCESS | 1673 | 18:01:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 18:01:08 INFO - PROCESS | 1673 | RSS: Main: 279650304 18:01:08 INFO - PROCESS | 1673 | 18:01:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:01:11 INFO - PROCESS | 1673 | RSS: Main: 284139520 18:01:11 INFO - PROCESS | 1673 | 18:01:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 18:01:14 INFO - PROCESS | 1673 | RSS: Main: 319459328 18:01:14 INFO - PROCESS | 1673 | 18:01:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 18:01:18 INFO - PROCESS | 1673 | RSS: Main: 321142784 18:01:18 INFO - PROCESS | 1673 | 18:01:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 18:01:21 INFO - PROCESS | 1673 | RSS: Main: 321335296 18:01:21 INFO - PROCESS | 1673 | 18:01:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:01:23 INFO - PROCESS | 1673 | RSS: Main: 312750080 18:01:23 INFO - PROCESS | 1673 | 18:01:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:01:26 INFO - PROCESS | 1673 | RSS: Main: 320655360 18:01:26 INFO - PROCESS | 1673 | 18:01:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:01:29 INFO - PROCESS | 1673 | RSS: Main: 321568768 18:01:29 INFO - PROCESS | 1673 | 18:01:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:01:33 INFO - PROCESS | 1673 | RSS: Main: 318996480 18:01:33 INFO - PROCESS | 1673 | 18:01:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:01:36 INFO - PROCESS | 1673 | RSS: Main: 319672320 18:01:36 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 18:01:38 INFO - PROCESS | 1673 | RSS: Main: 385130496 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | __start_tp_report 18:01:38 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 18:01:38 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 18:01:38 INFO - PROCESS | 1673 | |i|pagename|runs| 18:01:38 INFO - PROCESS | 1673 | |0;ai-astar;111;111;108;112;111;111;111;112;105;111 18:01:38 INFO - PROCESS | 1673 | |1;audio-beat-detection;157;141;178;197;118;146;136;119;171;116 18:01:38 INFO - PROCESS | 1673 | |2;audio-dft;185;186;184;181;184;187;186;181;181;186 18:01:38 INFO - PROCESS | 1673 | |3;audio-fft;77;72;89;90;56;73;58;84;105;80 18:01:38 INFO - PROCESS | 1673 | |4;audio-oscillator;92;98;132;77;80;80;121;80;77;123 18:01:38 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;85;88;85;86;85;89;84;88;84 18:01:38 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;95;95;95;93;94;95;95;94;99 18:01:38 INFO - PROCESS | 1673 | |7;imaging-desaturate;100;118;86;84;87;85;94;100;87;84 18:01:38 INFO - PROCESS | 1673 | |8;json-parse-financial;61;61;61;59;60;61;60;76;60;59 18:01:38 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;62;104;106;103;67;67;67;67;66 18:01:38 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;85;82;83;82;82;97;70;96;98;107 18:01:38 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;138;135;136;136;137;135;140;143;192;161 18:01:38 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;168;178;168;167;165;169;181;163;221 18:01:38 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;67;68;68;71;70;69;67;68;82 18:01:38 INFO - PROCESS | 1673 | __end_tp_report 18:01:38 INFO - PROCESS | 1673 | __start_cc_report 18:01:38 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1993 18:01:38 INFO - PROCESS | 1673 | __end_cc_report 18:01:38 INFO - PROCESS | 1673 | __startTimestamp1461891698524__endTimestamp 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | ------- Summary: start ------- 18:01:38 INFO - PROCESS | 1673 | Number of tests: 14 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.16 (1.9%) stddev-sans-first:2.28 18:01:38 INFO - PROCESS | 1673 | Values: 111.0 111.0 108.0 112.0 111.0 111.0 111.0 112.0 105.0 111.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:151.50 stddev:27.63 (18.2%) stddev-sans-first:29.11 18:01:38 INFO - PROCESS | 1673 | Values: 157.0 141.0 178.0 197.0 118.0 146.0 136.0 119.0 171.0 116.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.50 stddev:2.33 (1.3%) stddev-sans-first:2.45 18:01:38 INFO - PROCESS | 1673 | Values: 185.0 186.0 184.0 181.0 184.0 187.0 186.0 181.0 181.0 186.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:78.40 Median:78.50 stddev:14.83 (18.9%) stddev-sans-first:15.72 18:01:38 INFO - PROCESS | 1673 | Values: 77.0 72.0 89.0 90.0 56.0 73.0 58.0 84.0 105.0 80.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:80.00 stddev:21.50 (26.9%) stddev-sans-first:22.75 18:01:38 INFO - PROCESS | 1673 | Values: 92.0 98.0 132.0 77.0 80.0 80.0 121.0 80.0 77.0 123.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.00 (2.3%) stddev-sans-first:1.87 18:01:38 INFO - PROCESS | 1673 | Values: 89.0 85.0 88.0 85.0 86.0 85.0 89.0 84.0 88.0 84.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:1.69 (1.8%) stddev-sans-first:1.66 18:01:38 INFO - PROCESS | 1673 | Values: 93.0 95.0 95.0 95.0 93.0 94.0 95.0 95.0 94.0 99.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:85.50 stddev:10.90 (12.7%) stddev-sans-first:11.21 18:01:38 INFO - PROCESS | 1673 | Values: 100.0 118.0 86.0 84.0 87.0 85.0 94.0 100.0 87.0 84.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:61.00 stddev:5.05 (8.3%) stddev-sans-first:5.35 18:01:38 INFO - PROCESS | 1673 | Values: 61.0 61.0 61.0 59.0 60.0 61.0 60.0 76.0 60.0 59.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:66.50 stddev:18.82 (28.3%) stddev-sans-first:19.25 18:01:38 INFO - PROCESS | 1673 | Values: 63.0 62.0 104.0 106.0 103.0 67.0 67.0 67.0 67.0 66.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:84.00 stddev:10.91 (13.0%) stddev-sans-first:11.51 18:01:38 INFO - PROCESS | 1673 | Values: 85.0 82.0 83.0 82.0 82.0 97.0 70.0 96.0 98.0 107.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:139.00 stddev:18.16 (13.1%) stddev-sans-first:19.07 18:01:38 INFO - PROCESS | 1673 | Values: 138.0 135.0 136.0 136.0 137.0 135.0 140.0 143.0 192.0 161.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:168.50 stddev:17.21 (10.2%) stddev-sans-first:18.03 18:01:38 INFO - PROCESS | 1673 | Values: 167.0 168.0 178.0 168.0 167.0 165.0 169.0 181.0 163.0 221.0 18:01:38 INFO - PROCESS | 1673 | 18:01:38 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.50 stddev:4.47 (6.5%) stddev-sans-first:4.69 18:01:38 INFO - PROCESS | 1673 | Values: 68.0 67.0 68.0 68.0 71.0 70.0 69.0 67.0 68.0 82.0 18:01:38 INFO - PROCESS | 1673 | -------- Summary: end -------- 18:01:38 INFO - PROCESS | 1673 | 18:01:39 INFO - TEST-INFO | 1673: exit 0 18:01:39 INFO - TEST-OK | kraken | took 59172ms 18:01:39 INFO - SUITE-END | took 1175s 18:01:39 INFO - Completed test suite (00:19:35) 18:01:39 INFO - javascript benchmark 18:01:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.236345640503, 3375.7130643895266, 3388.5594872666547, 3397.5073614325906, 3449.9482507762386, 5468.179773034037, 5547.278853749017, 5550.500454959053, 5613.494785469307, 5627.2403225564585, 5554.166874968757, 5602.551795856332, 5618.803341883035, 5737.655740655564, 5741.0, 5016.44819069902, 5075.593952483811, 5086.465921078289, 5097.439281679012, 5143.254228136937, 5252.8424147275455, 5263.499967503081, 5283.101872681634, 5308.814191503298, 5335.546478549328, 5036.445990940993, 5070.898582028376, 5117.232415137741, 5197.636165468419, 5234.816781412632, 5112.437631860493, 5122.692638441706, 5133.922991155136, 5147.124988751883, 5165.793368265265, 5094.133997220481, 5120.641555091145, 5159.7678104485385, 5182.559482443997, 5198.064348417283, 5380.757865896044, 5436.483534064258, 5477.589180811416, 5509.146082357241, 5543.279373681436, 6097.817065488042, 6122.051082082285, 6125.448709616156, 6137.846553836145, 6153.4154255345675, 6156.076588511714, 6194.690265486753, 6208.534359922979, 6209.254889413254, 6224.751009959641, 5575.776968921279, 5649.745761440745, 5709.8287051388525, 5748.223989761413, 5751.1085781703905, 6238.968805156036, 6271.122042913996, 6273.905891411632, 6280.309165991737, 6291.748330066837, 5875.618084824473, 6063.090536419484, 6068.423499767515, 6071.119687645267, 6083.178770865877, 6071.210742603434, 6125.724342404603, 6144.893919094606, 6166.229221347332, 6169.0, 5068.974655126774, 6043.7280322385595, 6091.096936483645, 6130.856783744145, 6163.630182189083, 5977.432143946405, 6121.081837724377, 6123.724432400552, 6142.754289828446, 6147.231596050494, 4783.425988881357, 4837.927431088537, 4859.076775412731, 4914.557689807934, 4914.557689807934, 3645.234500754871, 4377.409049778303, 4389.561043895585, 4393.275109606943, 4421.646268298528, 5410.594205434608, 5614.494695477427, 5624.184493248456, 5627.324721033502, 5653.038983372837, 6068.089786532056, 6122.184701511125, 6124.594542075386, 6168.845778855564, 6182.505399568024, 5604.747786349624, 5614.326280846243, 5624.85937851557, 5626.043572592669, 5657.8019769307875, 5326.387465441447, 5330.760115794798, 5349.786008559614, 5354.785808567692, 5381.811636592779, 6142.959235178526, 6167.475764560063, 6249.281332646713, 6262.0, 6266.404691554295, 6139.1098290746695, 6179.598326108789, 6186.226721659793, 6189.288231853215, 6202.782902598387, 6215.81352559424, 6248.4376406123665, 6257.311695713559, 6261.624302541862, 6267.780627678009, 5891.499222565958, 5941.257342832146, 5987.101934709828, 5991.430814072656, 6006.819795406145, 6002.489788368038, 6120.7857724981195, 6137.202163718688, 6144.907826382519, 6159.29168145669, 6007.918574656604, 6060.78787242462, 6061.969690151521, 6070.119832624333, 6092.512598992159, 5950.613210141327, 5983.55123365758, 5989.251343582052, 6000.609960352563, 6017.037274036134, 4518.502964673949, 5712.37163911961, 5845.532357411482, 5851.882962340815, 5877.676727780014, 5946.851328716817, 5976.760929562769, 6059.545534084873, 6066.211392519121, 6076.058210977306, 5743.0811070228565, 5801.3038435388025, 5836.503897168788, 5840.0, 5847.356790753099], "value": 5605.076213997097, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.47832732285735, 516.7338820507438, 532.7442827442829, 572.602041581101, 602.7257597793005, 29887.701122988765, 39452.81641550754, 39586.60413395865, 39619.40570891432, 39708.24230486578, 40548.39177412334, 41087.178256434854, 41220.793896030555, 41269.587304127024, 41338.0, 8402.579871006456, 8598.269147122503, 9336.953315233415, 9592.0, 9598.952005239964, 10922.344659320426, 11210.383428911407, 11218.644084742215, 11219.15856310776, 11226.989570938615, 11147.498362573662, 11291.322520648746, 11297.322160670386, 11301.378424186667, 11331.093512518979, 8304.584770761438, 11012.669619911416, 11270.718232044183, 11270.985611294982, 11407.714807129805, 11202.159838012181, 11347.829782553268, 11349.602763903305, 11349.829752553718, 11350.14873884462, 37627.8118609408, 38318.80840595807, 38322.23355532902, 38349.424758628644, 38389.35301146386, 3161.8893338733033, 3219.9678003220138, 3441.9311613767836, 3587.910302242439, 3938.8030598469963, 24674.0130394784, 24914.875425622755, 25172.874135629205, 25203.62194567083, 25494.87252563744, 2659.135780871205, 2684.5973104034356, 2715.1311580294314, 2730.24918147509, 2793.9522678995377, 470756.0, 475013.0, 477157.9999999965, 477232.0, 477285.0, 33882.830585847405, 60104.797904041676, 60312.396876031555, 60483.69758151181, 60733.0, 35479.4678079829, 35631.64368356335, 35642.64357356445, 35737.46393804095, 35745.0, 14986.550403487912, 15249.008814427027, 15253.08481491114, 15260.465883694018, 15261.694766104838, 11402.94298528502, 11650.88349116515, 11652.883471165349, 11656.88343116558, 12905.483780648856, 7644.617769111522, 7978.960105199437, 8147.5111493309405, 8154.592270386457, 8170.305524030524, 3005.3989202159655, 3339.398908196548, 3400.4219282721997, 3434.1929646533044, 3445.3109378124477, 999.6155324874956, 1025.6512785652915, 1029.9588016479406, 1055.4195192644015, 1139.1797905508022, 28762.568561471595, 28926.98755543546, 29390.0, 29454.558181627293, 29798.55302170469, 1150.246588484544, 1159.8550181227347, 1202.296656455983, 1217.896478799312, 1233.864274929757, 15346.386144554315, 15369.769453458208, 15441.613959651098, 15663.216839157996, 15743.44897928567, 547538.0, 548677.0, 548872.0, 549714.0, 647482.0, 60369.698151508965, 61316.0, 61564.38435615676, 61598.07602885961, 61717.38282617206, 1378.2281922123443, 1385.681293302526, 1499.265359973605, 1502.684436268374, 1516.7194069097252, 24194.153204637754, 24446.633300500864, 26546.070887518843, 28968.7103128966, 29501.557476637438, 54538.909221814545, 55353.892922142135, 55578.0, 55587.72206138943, 55743.44256557382, 588067.0, 591939.0, 592132.0, 592504.0, 594333.0, 26717.7328226723, 26932.46135077327, 26969.865150674905, 27483.17550473489, 27532.58701119525, 34324.0, 34492.65507344995, 40087.0, 41094.38358424566, 42367.0, 23627.40931476727, 34862.12844678802, 36320.36558354828, 36330.091747706516, 36957.26085478222, 34388.312233755685, 35149.29701406009, 35507.46738798972, 35586.46620300646, 36349.45475817812, 35477.75827846013, 35542.289154216254, 35849.46225806667, 35877.92366229017, 36010.819945900104, 30294.0, 31238.843805780827, 31352.37295254128, 31376.0, 31411.84294078515, 23760.0, 24051.518969620858, 24318.27045188647, 24782.876085620177, 24961.625575616745], "value": 18487.57445184525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.860534510249, 396.635095711945, 397.49716608490263, 397.72358211043326, 408.78743053612124, 13538.0, 16676.58308542287, 19685.803141968594, 20446.89776551117, 20486.18055277789, 16295.59261018475, 18467.261309547637, 19840.801591984076, 20047.69928451071, 20529.97350132495, 4956.7769450374635, 5960.135780311853, 5979.0134627786365, 6017.638941663514, 6019.367966363535, 5575.637583557056, 5897.203877476529, 5985.34161242263, 6001.21984142061, 6032.487238584726, 3868.071662800922, 4088.2641124597562, 5823.941760582382, 5892.234009578749, 6130.877382452349, 6034.879302413927, 6088.66512341821, 6106.450419462225, 6108.8472788180425, 6130.877382452349, 5518.255035570227, 5725.942740572583, 5759.769609215627, 5762.222100016528, 5764.942350576483, 13559.661008474768, 13735.313234338244, 13838.031337806358, 13957.023008389417, 14033.78949315761, 1934.419674097765, 2055.075216152722, 2087.864288821237, 2130.4358611793277, 2232.1852523828848, 9815.754606134833, 10001.39101404925, 10052.597896084148, 10294.764628244659, 10387.376757394504, 1665.8266003263802, 1722.9741553876702, 1734.5663584103975, 1747.921343539531, 1758.78015248094, 117656.41171794172, 118497.40751296275, 121118.39440802828, 121130.3943480277, 121740.0, 9590.808183836423, 11578.421078946187, 11732.061435085172, 11751.823722644167, 11778.76442471163, 15054.548363549111, 15149.697006059818, 15170.772438413433, 15180.316885740169, 15222.086674799326, 8796.736097917073, 8830.86753698695, 8881.42270752399, 8885.155910188521, 8981.595828187747, 8764.649414023495, 8767.736967890973, 8790.648374065093, 8868.423552469068, 8901.866472002925, 4670.369500117508, 4684.414448193976, 4687.320338550891, 4709.128811169944, 4728.1252968201, 1897.3074827687794, 1941.155901893207, 2001.5696625225646, 2002.6895831146196, 2016.9899150504154, 749.5240522268399, 847.4788005376649, 921.4747593871429, 954.6372378496127, 1023.3092662452815, 16865.747013794804, 19694.8030519693, 19845.60308793816, 20010.69983950242, 20231.393058208276, 943.3962264150949, 946.7065209784988, 955.3121752338305, 1014.7919676466307, 1038.190211634926, 10012.499375031219, 10613.790932304533, 10808.351498910091, 11005.449727513593, 11732.472038758277, 572958.0, 582360.0, 598559.0, 611608.9419552963, 621864.0, 109746.90253097526, 133239.33521329713, 133762.3311883454, 138053.0, 141673.0, 1040.7242080848582, 1052.7999680060916, 1070.8126077936174, 1085.582050910405, 1086.0388556127725, 17394.565135871704, 17675.027873467083, 17679.204435800162, 17686.20412081434, 17982.280708772025, 48747.0, 50686.746566266935, 51161.744191278805, 51662.0, 52839.47160528499, 71306.57386852338, 74307.0, 74932.0, 74949.6252518734, 75241.74274771765, 7731.961340193263, 7983.960080199562, 8063.556504392314, 8230.259276665129, 8417.663293468193, 10160.64437744705, 11935.940320298343, 12042.036637069188, 12187.87812121903, 12392.62822115338, 10162.39025658463, 10338.483075846027, 10353.844692329772, 10641.840372394576, 10683.57265709363, 10249.282550221413, 10332.070113689804, 10432.895671043192, 10556.419396933243, 10616.097631701392, 10312.587496500057, 10362.5336859843, 10614.628488002883, 10911.727206819893, 10986.560537578727, 7859.253370929981, 7885.132635410215, 8136.83726325482, 8148.755537333889, 8172.305354044973, 9231.58457869411, 10118.544665489924, 10150.547898892235, 10230.437325947145, 10505.474726263503], "value": 9241.680741324633, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.4590855474921, 1441.4738620403557, 1604.8395160483954, 1821.5992481654039, 1985.6128055029258, 7821.726239581616, 8177.427580069398, 8563.443376180552, 8826.338024648145, 8826.558672066387, 8509.064002959669, 8570.185832345916, 8655.17775811297, 8664.74005779826, 8677.609507572159, 2438.5366780311774, 2467.568175569277, 2468.6297055441737, 2471.122751423246, 2471.233917485576, 2409.5301416223842, 2464.1745015419765, 2464.223769512607, 2465.0263146057296, 2466.0, 2420.818438617102, 2437.3784684905413, 2455.1161581830524, 2459.6310553417043, 2462.9507409851794, 2415.6738840256603, 2430.428849220432, 2447.2535876557645, 2450.803935685141, 2473.171487551663, 1995.820376166152, 2319.3389883883183, 2389.462370966536, 2420.1287536486852, 2423.8182136339847, 8682.913170868274, 8755.737327880173, 8764.0, 8767.693130740394, 8785.0, 4709.175894218532, 5023.287424790608, 5389.622726409153, 5420.83737487876, 5424.864378390533, 3522.630123836986, 3581.928361432783, 3661.8535258589623, 3663.3405986922344, 3683.0, 1035.4667346316653, 1051.3218973761957, 1053.2048303530785, 1056.6935588679273, 1056.889026652198, 11217.046551043171, 11335.14986376025, 11341.773164536746, 11368.204225704205, 11565.49648545692, 4559.475660299042, 4574.977125114353, 4689.226277664216, 4703.341532185496, 4721.881952951204, 9137.680181193626, 9149.77125571866, 9171.816563668823, 9177.403468774508, 9178.173964343241, 5204.089284375219, 5268.236105764643, 5269.973650131725, 5272.182811664198, 5290.312259406252, 4163.7293575917465, 4390.604845563851, 4580.1068791585985, 4865.854024379274, 4882.95117048825, 3945.782981936021, 4112.527059388209, 4112.588741125924, 4121.340585506305, 4153.584641535822, 1349.966250843717, 1381.1229869033036, 1389.9861001389856, 1394.1610191386455, 1399.7410479061407, 777.6889244302212, 846.6876341670509, 872.2237208884098, 921.313621352097, 923.2014307623857, 5617.915731264035, 5767.8558036049435, 5937.851553711192, 6033.064874944391, 6039.78860739872, 886.2776836877924, 895.562621991694, 941.2987324443196, 966.4781018250067, 970.1317320997667, 3898.3957486589056, 3949.822257998397, 3983.9840840585466, 4023.376376661622, 4044.676425885922, 9928.553215105338, 10645.0, 10687.732806679895, 10696.197785166143, 10807.297525660806, 10014.098731114089, 10593.788124237471, 10610.310329828537, 10742.03321701035, 10744.838827417596, 7781.338586220121, 8032.1164671886045, 8094.069182044141, 8106.432549721463, 8138.674453021813, 5783.94216057834, 5922.2597175353085, 5964.105384192406, 6014.0, 6044.0, 9455.858162127435, 9543.568464730279, 9748.658796942073, 9831.0, 9865.654702085392, 8199.754007379788, 9623.422594644344, 9725.513724313898, 9920.0, 10052.296339256181, 6818.28408017151, 7090.326418990187, 7099.219085900443, 7101.928980710333, 7115.039469671735, 7680.423968202295, 8025.678972841254, 8274.793130171794, 8307.418480706534, 8316.916830831857, 5854.590178687451, 7262.709491620276, 7350.19147893742, 7405.407567394704, 7461.477696561188, 7167.426605871622, 7221.53060051095, 7227.566346019255, 7332.376747976482, 7372.557646541225, 5942.168096466585, 6840.48696347766, 6874.587524748331, 6962.617056061965, 6968.95465680152, 4683.805629564439, 4815.6147508199965, 4866.7079975201605, 4955.72743499111, 5057.115004874058, 4225.936610950776, 4652.232381657124, 4813.15769740301, 4892.68197567157, 4937.21004639256], "value": 4548.4065279175375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.15356694122585, 312.64514775729543, 319.60847961247475, 319.7953309881676, 322.7611567440092, 5141.665791723535, 5156.8397110650085, 5208.921866172011, 5216.8174113906025, 5225.634205605609, 4924.581410580096, 4998.225275082359, 5079.466656001113, 5128.487151284879, 5139.0, 3301.6863397977213, 3384.8307584620793, 3983.780892050936, 3987.3819557968563, 4030.858919937803, 3266.5100234964707, 3932.6067393260732, 3945.782981935992, 4002.959970400288, 4014.0, 3732.701383889282, 3777.471154038437, 3804.0, 3881.844726210962, 3941.192055628589, 3224.532442795793, 3725.683316918052, 3952.486176796998, 3963.8810835674976, 3990.7206495545324, 3029.621297337833, 3473.513708080871, 3511.525943997579, 3516.191276006533, 3523.2953409317984, 4298.505671847746, 4854.635902307341, 4980.9252861207115, 5003.774830132653, 5023.522765337287, 4509.571590698878, 4540.455145382575, 4557.680962332638, 4565.52062033485, 4566.748828814414, 5332.973335133338, 5353.464653534655, 5384.40771515133, 5387.19192121181, 5400.488772720703, 3023.51847594677, 3870.883873483772, 3876.046432638721, 3885.7668539887413, 3886.8833934981994, 5616.213730077792, 5623.0, 5623.690697011662, 5678.26182596264, 5725.0, 3784.2053168834855, 3793.2982398256413, 3969.4442778011103, 3987.325323364194, 3996.780177090288, 3789.242151569697, 3987.960120398759, 4026.6778657707314, 4026.8187931543152, 4031.395290706359, 3491.5984661764223, 3886.630770076838, 4386.780660966939, 4456.665750068758, 4540.954590454053, 4331.0, 4866.805327786919, 5409.702466364309, 5438.374586922476, 5455.454454554513, 1157.571698471571, 1439.604108870069, 1448.8551144885428, 1464.1581090872792, 1465.0, 919.7884486567993, 938.0010289042149, 965.3387429610739, 984.1388784813288, 986.850319377931, 4548.545145485425, 4633.837815676435, 5481.75332110056, 5509.988854068236, 5532.861678458071, 5320.17537281722, 5865.912011319833, 5894.6757928314355, 6116.296625887991, 6168.599041062406, 4633.4671512775785, 5111.335526381621, 5476.561875049986, 5522.779088836482, 5526.364468086141, 2885.3697660821567, 2952.601398811176, 3141.5601815745813, 3152.952705709416, 3181.9522707159413, 4955.578775804025, 5568.638038527482, 5599.356074051536, 6146.4468197861515, 6251.281102673251, 5011.774470148852, 5603.142397377285, 5792.391798861143, 5795.536357091422, 5800.651960882361, 4797.856064318006, 5808.361080281165, 5813.04084825999, 5843.561732870052, 5854.941450585439, 4676.906461870812, 5186.170212765939, 5322.54758345545, 5578.470045345685, 5637.267155269788, 5549.972250138723, 5586.86032849182, 5589.832305030855, 5641.561401842504, 5659.349174844946, 4640.535946405333, 4888.4537380236725, 5707.972564938351, 5869.413058694096, 5916.763329466773, 3500.2474467988486, 3812.980935095307, 3826.4069069294305, 3828.1386687994977, 3842.2507611016704, 4913.361263035782, 5626.718664066862, 5647.632903861236, 5686.0, 5726.341470730919, 5506.311711036121, 5515.227868098389, 5515.751791169488, 5540.944590554204, 5564.527015203591, 5120.667156634807, 5237.816676416307, 5551.333839939233, 5555.583331250254, 5580.497755202051, 4509.909801804011, 4623.8671525476475, 5072.923906141337, 5194.636375453682, 5398.2442458056685, 5520.392756796829, 5535.39110697831, 5552.028395030773, 5576.247206627054, 5576.5817563683695, 5391.973040134774, 5438.374586922555, 5439.782408703766, 5445.428230035947, 5506.311711036121], "value": 4062.745472066519, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.2186106659077, 696.9059177011105, 708.174976152782, 712.202333386607, 715.5742333311678, 14648.853511464895, 14707.264636768146, 15154.77267840982, 15165.772513412308, 15376.0, 13995.580132596013, 14049.157050576947, 14160.221187834664, 14165.079269847452, 14171.503997360094, 729.9854002919939, 762.3063012658482, 918.9586468608928, 933.8225737109927, 935.9391639543442, 911.3483859040784, 944.6032666280179, 945.3666043750669, 947.8862536495595, 950.0309684122191, 986.4475893499628, 995.7560397702573, 998.0418797953973, 999.20063948841, 999.4503023337172, 722.4906440959164, 728.8870225115115, 938.6902322233647, 985.3447457440793, 988.5946761827616, 722.7325889420895, 726.1286456252466, 726.5386479585449, 727.3599232675265, 727.7889412070546, 14910.850891491164, 14925.402983880631, 14980.176090315028, 14985.325660345201, 14987.850121498754, 12165.817512737316, 12345.38273086342, 12421.1926224796, 12623.87376126236, 12744.426500807485, 10976.506057227443, 11156.051735602483, 11166.944165279121, 11250.0, 11615.070794336432, 4097.938530922039, 4116.5060192777055, 4128.6284234419045, 4236.279832428495, 4244.766537840417, 82375.0, 83879.0, 84094.73857892136, 84637.15362846416, 85599.28801424, 9331.533423328807, 9513.904860951441, 9541.379810312308, 9554.713358599252, 9764.121229089416, 17756.289748410178, 21258.893705531682, 22181.334559963227, 22188.445288867584, 22197.667034994487, 7293.817654558678, 7314.232005639437, 7340.155882073523, 7473.925260747432, 7488.4009279257525, 3941.901452463654, 3981.8606348778235, 4036.7779772112817, 4057.2899742544937, 4338.522762496123, 3474.2356681530023, 3549.254656522159, 3585.4263317869018, 3594.5866225384416, 3598.280343931172, 557.5511713071016, 635.2091645900883, 645.6481217736344, 647.6729251727847, 656.1404560026381, 513.2609042978099, 566.9518090962232, 571.6027360984159, 604.78530121807, 614.9914140809077, 8064.153263907206, 8091.716789912325, 8154.75535733929, 8253.215944485264, 8347.791305217419, 539.5710410223882, 583.8073435766187, 592.2005292854611, 595.6068994463633, 598.7694737526084, 3665.486831843544, 3822.21781785753, 3839.2897313997, 3945.2109578084496, 4042.171354872244, 95885.0, 96613.03386966181, 97408.0, 99644.0, 101718.0, 65462.67268663721, 68802.65598671975, 73034.63482682552, 78169.65491035278, 84393.0, 74491.13772155631, 74629.6268518654, 75149.0, 75959.24040759522, 77469.61265193638, 8363.692543844474, 8435.283000944988, 8532.70135545253, 8568.228859402683, 8772.914421371926, 44443.555564443945, 45161.322580161985, 45909.85225369393, 45934.0, 46038.0, 64465.67767161134, 66033.0, 66379.33620663731, 67001.32998669951, 67687.0, 10943.562257509611, 10962.835557466804, 10963.451827408438, 11158.386288754196, 11435.599754008903, 7907.920920790949, 8745.0, 11814.881851181723, 11951.0, 13012.479500819862, 9729.0, 10734.677959661223, 11144.721381965515, 11214.719632008939, 11927.045836333245, 11371.715707107389, 11510.0, 11783.88216117862, 11808.70478238051, 11998.760024799281, 10905.96994071986, 11160.944195278971, 11179.105671546095, 11179.608713695307, 11549.538018479167, 9527.28545359087, 9630.807383852423, 9729.756756081155, 9930.93950220536, 10000.149987251165, 8884.822303554021, 9154.588043537924, 9245.72262832116, 9917.950410247902, 9939.403635781877], "value": 6914.879803493061, "unit": "score"}], "name": "dromaeo_css", "value": 7046.171538371272, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 108.0, 112.0, 111.0, 111.0, 111.0, 112.0, 105.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 141.0, 178.0, 197.0, 118.0, 146.0, 136.0, 119.0, 171.0, 116.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 181.0, 184.0, 187.0, 186.0, 181.0, 181.0, 186.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 89.0, 90.0, 56.0, 73.0, 58.0, 84.0, 105.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 132.0, 77.0, 80.0, 80.0, 121.0, 80.0, 77.0, 123.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 86.0, 85.0, 89.0, 84.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 95.0, 93.0, 94.0, 95.0, 95.0, 94.0, 99.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 118.0, 86.0, 84.0, 87.0, 85.0, 94.0, 100.0, 87.0, 84.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 59.0, 60.0, 61.0, 60.0, 76.0, 60.0, 59.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 104.0, 106.0, 103.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 82.0, 82.0, 97.0, 70.0, 96.0, 98.0, 107.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 136.0, 136.0, 137.0, 135.0, 140.0, 143.0, 192.0, 161.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 178.0, 168.0, 167.0, 165.0, 169.0, 181.0, 163.0, 221.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 71.0, 70.0, 69.0, 67.0, 68.0, 82.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1507.2999999999997, "alertThreshold": 2.0}]} 18:01:39 INFO - Return code: 0 18:01:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:01:39 ERROR - # TBPL SUCCESS # 18:01:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:01:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:01:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:01:39 INFO - 2016-04-28 18:01:39.831 screenresolution[1677:9801] starting screenresolution argv=screenresolution get 18:01:39 INFO - 2016-04-28 18:01:39.834 screenresolution[1677:9801] Display 0: 1600x1200x32@60 18:01:39 INFO - 2016-04-28 18:01:39.840 screenresolution[1678:9804] starting screenresolution argv=screenresolution list 18:01:39 INFO - Available Modes on Display 0 18:01:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:01:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:01:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:01:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:01:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:01:40 INFO - Intel Iris: 18:01:40 INFO - Chipset Model: Intel Iris 18:01:40 INFO - Type: GPU 18:01:40 INFO - Bus: Built-In 18:01:40 INFO - VRAM (Dynamic, Max): 1536 MB 18:01:40 INFO - Vendor: Intel (0x8086) 18:01:40 INFO - Device ID: 0x0a2e 18:01:40 INFO - Revision ID: 0x0009 18:01:40 INFO - Displays: 18:01:40 INFO - Display: 18:01:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:01:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:01:40 INFO - Display Serial Number: 1600x1200 60 18:01:40 INFO - Main Display: Yes 18:01:40 INFO - Mirror: Off 18:01:40 INFO - Online: Yes 18:01:40 INFO - Rotation: Supported 18:01:40 INFO - Return code: 0 18:01:40 INFO - Running post-action listener: _resource_record_post_action 18:01:40 INFO - Running post-run listener: _resource_record_post_run 18:01:41 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37844480; Write bytes: 696485376; Read time: 409; Write time: 2005 18:01:41 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390715904; Write bytes: 379825152; Read time: 29747; Write time: 694 18:01:41 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30707200; Write bytes: 295795200; Read time: 321; Write time: 1270 18:01:41 INFO - Running post-run listener: _upload_blobber_files 18:01:41 INFO - Blob upload gear active. 18:01:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:01:41 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 18:01:41 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:01:41 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:01:41 INFO - (blobuploader) - INFO - Open directory for files ... 18:01:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:01:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:01:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:01:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:01:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:01:42 INFO - (blobuploader) - INFO - Done attempting. 18:01:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:01:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:01:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:01:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:01:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:01:43 INFO - (blobuploader) - INFO - Done attempting. 18:01:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:01:43 INFO - Return code: 0 18:01:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:01:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:01:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2b19db843e6f4e3598ed5df9f534d29e7e589044f95fe6eef7fe64f72e9399540a5442b0a19040f05b5ee43c0c8e8b0b8d8c82bb0b161a3772d21c69b7ec0b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6610076ea014394edcf3bf036143133f033be5cdf2017fca3fb937a8efc1acbe5881eeb3890efe16f62607f7c85f33b418c200118f1265d243de391c442f2ef"} 18:01:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:01:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:01:43 INFO - Contents: 18:01:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2b19db843e6f4e3598ed5df9f534d29e7e589044f95fe6eef7fe64f72e9399540a5442b0a19040f05b5ee43c0c8e8b0b8d8c82bb0b161a3772d21c69b7ec0b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6610076ea014394edcf3bf036143133f033be5cdf2017fca3fb937a8efc1acbe5881eeb3890efe16f62607f7c85f33b418c200118f1265d243de391c442f2ef"} 18:01:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:01:43 INFO - Copying logs to upload dir... 18:01:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:01:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.018677 ========= master_lag: 1.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-04-28 18:01:44.666468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:44.671531) ========= 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.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/Fx-Team/sha512/a2b19db843e6f4e3598ed5df9f534d29e7e589044f95fe6eef7fe64f72e9399540a5442b0a19040f05b5ee43c0c8e8b0b8d8c82bb0b161a3772d21c69b7ec0b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6610076ea014394edcf3bf036143133f033be5cdf2017fca3fb937a8efc1acbe5881eeb3890efe16f62607f7c85f33b418c200118f1265d243de391c442f2ef"} build_url:https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010619 build_url: 'https://queue.taskcluster.net/v1/task/FKKeYYcUROaLe0GEfSg7Ew/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a2b19db843e6f4e3598ed5df9f534d29e7e589044f95fe6eef7fe64f72e9399540a5442b0a19040f05b5ee43c0c8e8b0b8d8c82bb0b161a3772d21c69b7ec0b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b6610076ea014394edcf3bf036143133f033be5cdf2017fca3fb937a8efc1acbe5881eeb3890efe16f62607f7c85f33b418c200118f1265d243de391c442f2ef"}' ========= master_lag: 0.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.334632) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.334929) ========= 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.Q6bhQInUT0/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.SVspSFRrpm/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005198 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 18:01:45.408917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 18:01:45.409202) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 18:01:45.409651) ========= ========= Total master_lag: 3.19 =========