builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1463543947.91 results: success (0) buildid: 20160517192138 builduid: a38a80082be24e8cb70be9dda4a25a6d revision: 8751a7511341585167961854d72d66a538f03555 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:07.912498) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:07.912876) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:07.913156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:07.934577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:07.934867) ========= 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.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 20:59:07-- 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% 15.8M=0.001s 2016-05-17 20:59:08 (15.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.567849 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:08.520945) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:08.524302) ========= 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.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.027553 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:08.572896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 20:59:08.576273) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8751a7511341585167961854d72d66a538f03555 --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 8751a7511341585167961854d72d66a538f03555 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 20:59:08,657 truncating revision to first 12 chars 2016-05-17 20:59:08,658 Setting DEBUG logging. 2016-05-17 20:59:08,658 attempt 1/10 2016-05-17 20:59:08,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8751a7511341?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 20:59:10,420 unpacking tar archive at: fx-team-8751a7511341/testing/mozharness/ program finished with exit code 0 elapsedTime=2.027011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-17 20:59:10.623398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:10.623843) ========= script_repo_revision: 8751a7511341585167961854d72d66a538f03555 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:10.624409) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:10.624820) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 20:59:10.643536) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-17 20:59:10.643954) ========= /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.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:10 INFO - MultiFileLogger online at 20160517 20:59:10 in /builds/slave/test 20:59:10 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 20:59:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:59:10 INFO - {'append_to_log': False, 20:59:10 INFO - 'base_work_dir': '/builds/slave/test', 20:59:10 INFO - 'blob_upload_branch': 'Fx-Team', 20:59:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:59:10 INFO - 'branch': 'Fx-Team', 20:59:10 INFO - 'buildbot_json_path': 'buildprops.json', 20:59:10 INFO - 'config_files': ('talos/mac_config.py',), 20:59:10 INFO - 'default_actions': ('clobber', 20:59:10 INFO - 'read-buildbot-config', 20:59:10 INFO - 'download-and-extract', 20:59:10 INFO - 'populate-webroot', 20:59:10 INFO - 'create-virtualenv', 20:59:10 INFO - 'install', 20:59:10 INFO - 'run-tests'), 20:59:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:59:10 INFO - 'download_minidump_stackwalk': True, 20:59:10 INFO - 'download_symbols': 'ondemand', 20:59:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:59:10 INFO - 'tooltool.py': '/tools/tooltool.py', 20:59:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:59:10 INFO - '/tools/misc-python/virtualenv.py')}, 20:59:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:59:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:59:10 INFO - 'installer_path': 'installer.exe', 20:59:10 INFO - 'log_level': 'info', 20:59:10 INFO - 'log_name': 'talos', 20:59:10 INFO - 'log_to_console': True, 20:59:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:59:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:59:10 INFO - 'opt_config_files': (), 20:59:10 INFO - 'pip_index': False, 20:59:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:10 INFO - 'cmd': ('bash', 20:59:10 INFO - '-c', 20:59:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:10 INFO - 'enabled': True, 20:59:10 INFO - 'halt_on_failure': False, 20:59:10 INFO - 'name': 'check_screen_resolution'},), 20:59:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:10 INFO - 'cmd': ('bash', 20:59:10 INFO - '-c', 20:59:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:10 INFO - 'enabled': True, 20:59:10 INFO - 'halt_on_failure': False, 20:59:10 INFO - 'name': 'check_screen_resolution'},), 20:59:10 INFO - 'run_cmd_checks_enabled': True, 20:59:10 INFO - 'sps_profile': False, 20:59:10 INFO - 'sps_profile_interval': 0, 20:59:10 INFO - 'suite': 'dromaeojs', 20:59:10 INFO - 'system_bits': '32', 20:59:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:59:10 INFO - 'title': 't-yosemite-r7-0034', 20:59:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:59:10 INFO - 'use_talos_json': True, 20:59:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:59:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:59:10 INFO - 'work_dir': 'build'} 20:59:10 INFO - ##### 20:59:10 INFO - ##### Running clobber step. 20:59:10 INFO - ##### 20:59:10 INFO - Running pre-action listener: _resource_record_pre_action 20:59:10 INFO - Running main action method: clobber 20:59:10 INFO - rmtree: /builds/slave/test/build 20:59:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:59:12 INFO - Running post-action listener: _resource_record_post_action 20:59:12 INFO - ##### 20:59:12 INFO - ##### Finished clobber step (success) 20:59:12 INFO - ##### 20:59:12 INFO - ##### 20:59:12 INFO - ##### Running read-buildbot-config step. 20:59:12 INFO - ##### 20:59:12 INFO - Running pre-action listener: _resource_record_pre_action 20:59:12 INFO - Running main action method: read_buildbot_config 20:59:12 INFO - Using buildbot properties: 20:59:12 INFO - { 20:59:12 INFO - "project": "", 20:59:12 INFO - "product": "firefox", 20:59:12 INFO - "script_repo_revision": "production", 20:59:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:59:12 INFO - "repository": "", 20:59:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:59:12 INFO - "buildid": "20160517192138", 20:59:12 INFO - "pgo_build": "False", 20:59:12 INFO - "basedir": "/builds/slave/test", 20:59:12 INFO - "buildnumber": 15, 20:59:12 INFO - "slavename": "t-yosemite-r7-0034", 20:59:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:59:12 INFO - "platform": "yosemite_r7", 20:59:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:59:12 INFO - "branch": "fx-team", 20:59:12 INFO - "repo_path": "integration/fx-team", 20:59:12 INFO - "revision": "8751a7511341585167961854d72d66a538f03555", 20:59:12 INFO - "stage_platform": "macosx64", 20:59:12 INFO - "builduid": "a38a80082be24e8cb70be9dda4a25a6d", 20:59:12 INFO - "slavebuilddir": "test" 20:59:12 INFO - } 20:59:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:59:12 INFO - Running post-action listener: _resource_record_post_action 20:59:12 INFO - ##### 20:59:12 INFO - ##### Finished read-buildbot-config step (success) 20:59:12 INFO - ##### 20:59:12 INFO - ##### 20:59:12 INFO - ##### Running download-and-extract step. 20:59:12 INFO - ##### 20:59:12 INFO - Running pre-action listener: _resource_record_pre_action 20:59:12 INFO - Running main action method: download_and_extract 20:59:12 INFO - mkdir: /builds/slave/test/build/tests 20:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:12 INFO - https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:59:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:15 INFO - Downloaded 1199 bytes. 20:59:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:59:15 INFO - Using the following test package requirements: 20:59:15 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:59:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:59:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:59:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'jsshell-mac.zip'], 20:59:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:59:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:59:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:59:15 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:59:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:59:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:59:15 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:59:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:59:15 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')]} 20:59:15 INFO - https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:59:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:17 INFO - Downloaded 18306038 bytes. 20:59:17 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'] 20:59:17 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 20:59:20 INFO - Return code: 0 20:59:20 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 20:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:20 INFO - https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:59:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:59:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:22 INFO - Downloaded 18306038 bytes. 20:59:22 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'] 20:59:22 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 20:59:26 INFO - Return code: 0 20:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:26 INFO - https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:59:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:59:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:59:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:28 INFO - Downloaded 10840194 bytes. 20:59:28 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'] 20:59:28 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 20:59:28 INFO - Return code: 0 20:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:28 INFO - https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:59:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:59:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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 20:59:35 INFO - Downloaded 110182641 bytes. 20:59:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:35 INFO - mkdir: /builds/slave/test/properties 20:59:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:59:35 INFO - Writing to file /builds/slave/test/properties/build_url 20:59:35 INFO - Contents: 20:59:35 INFO - build_url:https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:59:36 INFO - Running post-action listener: _resource_record_post_action 20:59:36 INFO - Running post-action listener: set_extra_try_arguments 20:59:36 INFO - ##### 20:59:36 INFO - ##### Finished download-and-extract step (success) 20:59:36 INFO - ##### 20:59:36 INFO - ##### 20:59:36 INFO - ##### Running populate-webroot step. 20:59:36 INFO - ##### 20:59:36 INFO - Running pre-action listener: _resource_record_pre_action 20:59:36 INFO - Running main action method: populate_webroot 20:59:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:59:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:59:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:59:36 INFO - '--tppagecycles', 20:59:36 INFO - '7'], 20:59:36 INFO - 'tests': ['tsvgm']}}, 20:59:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:59:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:59:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:59:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:59:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['damp', 'tps']}, 20:59:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'tests': ['damp', 'tps']}, 20:59:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['dromaeo_dom']}, 20:59:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:59:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'other-e10s': {'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['a11yr', 20:59:36 INFO - 'ts_paint', 20:59:36 INFO - 'tpaint', 20:59:36 INFO - 'sessionrestore', 20:59:36 INFO - 'sessionrestore_no_auto_restore', 20:59:36 INFO - 'tabpaint']}, 20:59:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['tsvgx', 20:59:36 INFO - 'tsvgr_opacity', 20:59:36 INFO - 'tart', 20:59:36 INFO - 'tscrollx', 20:59:36 INFO - 'cart']}, 20:59:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:59:36 INFO - 'tsvgr_opacity', 20:59:36 INFO - 'tart', 20:59:36 INFO - 'tscrollx', 20:59:36 INFO - 'cart']}, 20:59:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'talos_options': ['--disable-e10s'], 20:59:36 INFO - 'tests': ['tp5o']}, 20:59:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'tests': ['tp5o']}, 20:59:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'talos_options': ['--disable-e10s', 20:59:36 INFO - '--xperf_path', 20:59:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:36 INFO - 'tests': ['tp5n']}, 20:59:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:59:36 INFO - 'talos_options': ['--xperf_path', 20:59:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:36 INFO - 'tests': ['tp5n']}}, 20:59:36 INFO - 'talos.zip': {'path': '', 20:59:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:59:36 INFO - Running post-action listener: _resource_record_post_action 20:59:36 INFO - ##### 20:59:36 INFO - ##### Finished populate-webroot step (success) 20:59:36 INFO - ##### 20:59:36 INFO - ##### 20:59:36 INFO - ##### Running create-virtualenv step. 20:59:36 INFO - ##### 20:59:36 INFO - Running pre-action listener: _resource_record_pre_action 20:59:36 INFO - Running main action method: create_virtualenv 20:59:36 INFO - Creating virtualenv /builds/slave/test/build/venv 20:59:36 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 20:59:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:59:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:59:37 INFO - Using real prefix '/tools/python27' 20:59:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:59:37 INFO - Installing distribute.............................................................................................................................................................................................done. 20:59:40 INFO - Installing pip.................done. 20:59:40 INFO - Return code: 0 20:59:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:40 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:40 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 20:59:40 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 20:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:40 INFO - 'HOME': '/Users/cltbld', 20:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:40 INFO - 'LOGNAME': 'cltbld', 20:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:40 INFO - 'MOZ_NO_REMOTE': '1', 20:59:40 INFO - 'NO_EM_RESTART': '1', 20:59:40 INFO - 'PAGER': '/bin/cat', 20:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:40 INFO - 'PWD': '/builds/slave/test', 20:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:40 INFO - 'SHELL': '/bin/bash', 20:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:40 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:40 INFO - 'USER': 'cltbld', 20:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:40 INFO - 'XPC_FLAGS': '0x0', 20:59:40 INFO - 'XPC_SERVICE_NAME': '0', 20:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:41 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 20:59:41 INFO - Cleaning up... 20:59:41 INFO - Return code: 0 20:59:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:41 INFO - 'HOME': '/Users/cltbld', 20:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:41 INFO - 'LOGNAME': 'cltbld', 20:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:41 INFO - 'MOZ_NO_REMOTE': '1', 20:59:41 INFO - 'NO_EM_RESTART': '1', 20:59:41 INFO - 'PAGER': '/bin/cat', 20:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:41 INFO - 'PWD': '/builds/slave/test', 20:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:41 INFO - 'SHELL': '/bin/bash', 20:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:41 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:41 INFO - 'USER': 'cltbld', 20:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:41 INFO - 'XPC_FLAGS': '0x0', 20:59:41 INFO - 'XPC_SERVICE_NAME': '0', 20:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:41 INFO - Downloading/unpacking psutil>=3.1.1 20:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:59:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:44 INFO - Installing collected packages: psutil 20:59:44 INFO - Running setup.py install for psutil 20:59:44 INFO - building 'psutil._psutil_osx' extension 20:59:44 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 20:59:45 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 20:59:45 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 20:59:45 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 20:59:45 INFO - building 'psutil._psutil_posix' extension 20:59:45 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 20:59:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:59:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:59:45 INFO - ^ 20:59:45 INFO - 1 warning generated. 20:59:45 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 20:59:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:46 INFO - Successfully installed psutil 20:59:46 INFO - Cleaning up... 20:59:46 INFO - Return code: 0 20:59:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:46 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:46 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.3'] in /builds/slave/test/build 20:59:46 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.3 20:59:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:46 INFO - 'HOME': '/Users/cltbld', 20:59:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:46 INFO - 'LOGNAME': 'cltbld', 20:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:46 INFO - 'MOZ_NO_REMOTE': '1', 20:59:46 INFO - 'NO_EM_RESTART': '1', 20:59:46 INFO - 'PAGER': '/bin/cat', 20:59:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:46 INFO - 'PWD': '/builds/slave/test', 20:59:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:46 INFO - 'SHELL': '/bin/bash', 20:59:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:46 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:46 INFO - 'USER': 'cltbld', 20:59:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:46 INFO - 'XPC_FLAGS': '0x0', 20:59:46 INFO - 'XPC_SERVICE_NAME': '0', 20:59:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:59:46 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 20:59:46 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 20:59:46 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 20:59:46 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 20:59:46 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 20:59:46 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 20:59:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:59:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:59:49 INFO - Installing collected packages: mozsystemmonitor 20:59:49 INFO - Running setup.py install for mozsystemmonitor 20:59:49 INFO - Successfully installed mozsystemmonitor 20:59:49 INFO - Cleaning up... 20:59:49 INFO - Return code: 0 20:59:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:49 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:49 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 20:59:49 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 20:59:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:49 INFO - 'HOME': '/Users/cltbld', 20:59:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:49 INFO - 'LOGNAME': 'cltbld', 20:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:49 INFO - 'MOZ_NO_REMOTE': '1', 20:59:49 INFO - 'NO_EM_RESTART': '1', 20:59:49 INFO - 'PAGER': '/bin/cat', 20:59:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:49 INFO - 'PWD': '/builds/slave/test', 20:59:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:49 INFO - 'SHELL': '/bin/bash', 20:59:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:49 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:49 INFO - 'USER': 'cltbld', 20:59:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:49 INFO - 'XPC_FLAGS': '0x0', 20:59:49 INFO - 'XPC_SERVICE_NAME': '0', 20:59:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:49 INFO - Downloading/unpacking blobuploader==1.2.4 20:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:51 INFO - Downloading blobuploader-1.2.4.tar.gz 20:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:59:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:59:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:53 INFO - Downloading docopt-0.6.1.tar.gz 20:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:59:53 INFO - Installing collected packages: blobuploader, requests, docopt 20:59:53 INFO - Running setup.py install for blobuploader 20:59:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:59:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:59:53 INFO - Running setup.py install for requests 20:59:54 INFO - Running setup.py install for docopt 20:59:54 INFO - Successfully installed blobuploader requests docopt 20:59:54 INFO - Cleaning up... 20:59:54 INFO - Return code: 0 20:59:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:54 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:54 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 20:59:54 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 20:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:54 INFO - 'HOME': '/Users/cltbld', 20:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:54 INFO - 'LOGNAME': 'cltbld', 20:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:54 INFO - 'MOZ_NO_REMOTE': '1', 20:59:54 INFO - 'NO_EM_RESTART': '1', 20:59:54 INFO - 'PAGER': '/bin/cat', 20:59:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:54 INFO - 'PWD': '/builds/slave/test', 20:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:54 INFO - 'SHELL': '/bin/bash', 20:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:54 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:54 INFO - 'USER': 'cltbld', 20:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:54 INFO - 'XPC_FLAGS': '0x0', 20:59:54 INFO - 'XPC_SERVICE_NAME': '0', 20:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RGglj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tNHgOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-S8BXSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8hVjAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:54 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-PG1id6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-IGaGvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Kf2M9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-U97YVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AyPrlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0Qkf1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-FPQiVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-BTbRJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6oJ8ZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:59:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-A7zHGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-SZt4Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:59:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-68I4Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:59:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-CiraPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:59:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:59:56 INFO - Running setup.py install for manifestparser 20:59:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:59:56 INFO - Running setup.py install for mozcrash 20:59:56 INFO - Running setup.py install for mozdebug 20:59:56 INFO - Running setup.py install for mozdevice 20:59:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:59:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Running setup.py install for mozfile 20:59:57 INFO - Running setup.py install for mozhttpd 20:59:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Running setup.py install for mozinfo 20:59:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Running setup.py install for mozInstall 20:59:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Running setup.py install for mozleak 20:59:57 INFO - Running setup.py install for mozlog 20:59:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:59:57 INFO - Running setup.py install for moznetwork 20:59:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:59:58 INFO - Running setup.py install for mozprocess 20:59:58 INFO - Running setup.py install for mozprofile 20:59:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:59:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:59:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:59:58 INFO - Running setup.py install for mozrunner 20:59:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:59:58 INFO - Running setup.py install for mozscreenshot 20:59:58 INFO - Running setup.py install for moztest 20:59:58 INFO - Running setup.py install for mozversion 20:59:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:59:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:59:59 INFO - Cleaning up... 20:59:59 INFO - Return code: 0 20:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:59 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 20:59:59 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 20:59:59 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 20:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 20:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:59 INFO - 'HOME': '/Users/cltbld', 20:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:59 INFO - 'LOGNAME': 'cltbld', 20:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:59 INFO - 'MOZ_NO_REMOTE': '1', 20:59:59 INFO - 'NO_EM_RESTART': '1', 20:59:59 INFO - 'PAGER': '/bin/cat', 20:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:59 INFO - 'PWD': '/builds/slave/test', 20:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:59 INFO - 'SHELL': '/bin/bash', 20:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 20:59:59 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 20:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:59 INFO - 'USER': 'cltbld', 20:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:59 INFO - 'XPC_FLAGS': '0x0', 20:59:59 INFO - 'XPC_SERVICE_NAME': '0', 20:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ckzGOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:59 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)) 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2MOkFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:59 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)) 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-tnBlSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:59 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)) 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MwnRpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:59 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)) 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-m5MdzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:59 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)) 20:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:59 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-PsY8qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-jEOtYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-iQ6mDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Xby8tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-G7lnFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-KeNUNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-H0Txee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-jQ6yrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-X0mdaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:00:00 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)) 21:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:00:00 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yL5rFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:00:01 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)) 21:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:00:01 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2kOmoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:00:01 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)) 21:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:00:01 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-rEoEx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:00:01 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)) 21:00:01 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)) 21:00:01 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)) 21:00:01 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)) 21:00:01 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)) 21:00:01 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)) 21:00:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00:03 INFO - Downloading blessings-1.6.tar.gz 21:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:00:03 INFO - Installing collected packages: blessings 21:00:03 INFO - Running setup.py install for blessings 21:00:04 INFO - Successfully installed blessings 21:00:04 INFO - Cleaning up... 21:00:04 INFO - Return code: 0 21:00:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:00:04 INFO - Reading from file tmpfile_stdout 21:00:04 INFO - Current package versions: 21:00:04 INFO - blessings == 1.6 21:00:04 INFO - blobuploader == 1.2.4 21:00:04 INFO - docopt == 0.6.1 21:00:04 INFO - manifestparser == 1.1 21:00:04 INFO - mozInstall == 1.12 21:00:04 INFO - mozcrash == 0.17 21:00:04 INFO - mozdebug == 0.1 21:00:04 INFO - mozdevice == 0.48 21:00:04 INFO - mozfile == 1.2 21:00:04 INFO - mozhttpd == 0.7 21:00:04 INFO - mozinfo == 0.9 21:00:04 INFO - mozleak == 0.1 21:00:04 INFO - mozlog == 3.2 21:00:04 INFO - moznetwork == 0.27 21:00:04 INFO - mozprocess == 0.22 21:00:04 INFO - mozprofile == 0.28 21:00:04 INFO - mozrunner == 6.11 21:00:04 INFO - mozscreenshot == 0.1 21:00:04 INFO - mozsystemmonitor == 0.3 21:00:04 INFO - moztest == 0.7 21:00:04 INFO - mozversion == 1.4 21:00:04 INFO - psutil == 3.1.1 21:00:04 INFO - requests == 1.2.3 21:00:04 INFO - wsgiref == 0.1.2 21:00:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:00: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')]} 21:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00: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')]} 21:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00:04 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 21:00:04 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 21:00:04 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 21:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 21:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:04 INFO - 'HOME': '/Users/cltbld', 21:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:04 INFO - 'LOGNAME': 'cltbld', 21:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:04 INFO - 'MOZ_NO_REMOTE': '1', 21:00:04 INFO - 'NO_EM_RESTART': '1', 21:00:04 INFO - 'PAGER': '/bin/cat', 21:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:04 INFO - 'PWD': '/builds/slave/test', 21:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:04 INFO - 'SHELL': '/bin/bash', 21:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 21:00:04 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 21:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:04 INFO - 'USER': 'cltbld', 21:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:04 INFO - 'XPC_FLAGS': '0x0', 21:00:04 INFO - 'XPC_SERVICE_NAME': '0', 21:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 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)) 21:00:04 INFO - Cleaning up... 21:00:04 INFO - Return code: 0 21:00:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:00: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')]} 21:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00: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')]} 21:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 21:00: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', 'jsonschema'] in /builds/slave/test/build 21:00: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 jsonschema 21:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 21:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:04 INFO - 'HOME': '/Users/cltbld', 21:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:04 INFO - 'LOGNAME': 'cltbld', 21:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:04 INFO - 'MOZ_NO_REMOTE': '1', 21:00:04 INFO - 'NO_EM_RESTART': '1', 21:00:04 INFO - 'PAGER': '/bin/cat', 21:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:04 INFO - 'PWD': '/builds/slave/test', 21:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:04 INFO - 'SHELL': '/bin/bash', 21:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 21:00:04 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 21:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:04 INFO - 'USER': 'cltbld', 21:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:04 INFO - 'XPC_FLAGS': '0x0', 21:00:04 INFO - 'XPC_SERVICE_NAME': '0', 21:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:05 INFO - Downloading/unpacking jsonschema 21:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:00:07 INFO - Downloading/unpacking functools32 (from jsonschema) 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00:08 INFO - Downloading functools32-3.2.3-2.tar.gz 21:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:00:08 INFO - warning: no files found matching '*.txt' 21:00:08 INFO - no previously-included directories found matching 'build' 21:00:08 INFO - no previously-included directories found matching 'dist' 21:00:08 INFO - no previously-included directories found matching '.git*' 21:00:08 INFO - Installing collected packages: jsonschema, functools32 21:00:08 INFO - Running setup.py install for functools32 21:00:08 INFO - warning: no files found matching '*.txt' 21:00:08 INFO - no previously-included directories found matching 'build' 21:00:08 INFO - no previously-included directories found matching 'dist' 21:00:08 INFO - no previously-included directories found matching '.git*' 21:00:08 INFO - Successfully installed jsonschema functools32 21:00:08 INFO - Cleaning up... 21:00:08 INFO - Return code: 0 21:00:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:00:08 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')]} 21:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00:08 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')]} 21:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:00:08 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 0x103aeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b956da80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, '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.EKippMjEfp/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.JAlJuaKPY7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 21:00:08 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 21:00:08 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 21:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 21:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:08 INFO - 'HOME': '/Users/cltbld', 21:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:08 INFO - 'LOGNAME': 'cltbld', 21:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:08 INFO - 'MOZ_NO_REMOTE': '1', 21:00:08 INFO - 'NO_EM_RESTART': '1', 21:00:08 INFO - 'PAGER': '/bin/cat', 21:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:08 INFO - 'PWD': '/builds/slave/test', 21:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:08 INFO - 'SHELL': '/bin/bash', 21:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 21:00:08 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 21:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:08 INFO - 'USER': 'cltbld', 21:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:08 INFO - 'XPC_FLAGS': '0x0', 21:00:08 INFO - 'XPC_SERVICE_NAME': '0', 21:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:08 INFO - Downloading/unpacking flake8 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00: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 21:00:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:00:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:00:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:11 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 21:00:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:00:12 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 21:00:12 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 21:00:12 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 21:00:12 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 21:00:12 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 21:00:12 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 21:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:00:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:00:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:00:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:00:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:00:12 INFO - no previously-included directories found matching 'docs/_build' 21:00:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:00:12 INFO - Running setup.py install for pep8 21:00:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:00:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:00:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:00:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:00:12 INFO - no previously-included directories found matching 'docs/_build' 21:00:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:00:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:00:12 INFO - Cleaning up... 21:00:13 INFO - Return code: 0 21:00:13 INFO - Running post-action listener: _resource_record_post_action 21:00:13 INFO - Running post-action listener: _start_resource_monitoring 21:00:13 INFO - Starting resource monitoring. 21:00:13 INFO - ##### 21:00:13 INFO - ##### Finished create-virtualenv step (success) 21:00:13 INFO - ##### 21:00:13 INFO - ##### 21:00:13 INFO - ##### Running install step. 21:00:13 INFO - ##### 21:00:13 INFO - Running pre-action listener: _resource_record_pre_action 21:00:13 INFO - Running main action method: install 21:00:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:00:13 INFO - Reading from file tmpfile_stdout 21:00:13 INFO - Detecting whether we're running mozinstall >=1.0... 21:00:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:00:13 INFO - Reading from file tmpfile_stdout 21:00:13 INFO - Output received: 21:00:13 INFO - Usage: mozinstall [options] installer 21:00:13 INFO - Options: 21:00:13 INFO - -h, --help show this help message and exit 21:00:13 INFO - -d DEST, --destination=DEST 21:00:13 INFO - Directory to install application into. [default: 21:00:13 INFO - "/builds/slave/test"] 21:00:13 INFO - --app=APP Application being installed. [default: firefox] 21:00:13 INFO - mkdir: /builds/slave/test/build/application 21:00:13 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'] 21:00:13 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 21:00:48 INFO - Reading from file tmpfile_stdout 21:00:48 INFO - Output received: 21:00:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:00:48 INFO - Running post-action listener: _resource_record_post_action 21:00:48 INFO - ##### 21:00:48 INFO - ##### Finished install step (success) 21:00:48 INFO - ##### 21:00:48 INFO - ##### 21:00:48 INFO - ##### Running run-tests step. 21:00:48 INFO - ##### 21:00:48 INFO - Running pre-action listener: _resource_record_pre_action 21:00:48 INFO - Running main action method: run_tests 21:00:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:00:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:00:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:00:48 INFO - 2016-05-17 21:00:48.183 screenresolution[1649:7934] starting screenresolution argv=screenresolution get 21:00:48 INFO - 2016-05-17 21:00:48.186 screenresolution[1649:7934] Display 0: 1600x1200x32@60 21:00:48 INFO - 2016-05-17 21:00:48.192 screenresolution[1650:7936] starting screenresolution argv=screenresolution list 21:00:48 INFO - Available Modes on Display 0 21:00:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:00:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:00:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:00:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:00:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:00:48 INFO - Intel Iris: 21:00:48 INFO - Chipset Model: Intel Iris 21:00:48 INFO - Type: GPU 21:00:48 INFO - Bus: Built-In 21:00:48 INFO - VRAM (Dynamic, Max): 1536 MB 21:00:48 INFO - Vendor: Intel (0x8086) 21:00:48 INFO - Device ID: 0x0a2e 21:00:48 INFO - Revision ID: 0x0009 21:00:48 INFO - Displays: 21:00:48 INFO - Display: 21:00:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:00:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:00:48 INFO - Display Serial Number: 1600x1200 60 21:00:48 INFO - Main Display: Yes 21:00:48 INFO - Mirror: Off 21:00:48 INFO - Online: Yes 21:00:48 INFO - Rotation: Supported 21:00:48 INFO - Return code: 0 21:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:00:48 INFO - Python 2.7.3 21:00:48 INFO - Return code: 0 21:00:48 INFO - grabbing minidump binary from tooltool 21:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:00:48 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10398fd30>, '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 0x1037b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103986b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:00:48 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 21:00:48 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 21:00:48 INFO - Calling ['/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'] with output_timeout 600 21:00:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:00:48 INFO - Return code: 0 21:00:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:00:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:00:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:00:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:00:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:00:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:00:49 INFO - Return code: 0 21:00:49 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/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 21:00:49 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/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 21:00:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKippMjEfp/Render', 21:00:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:49 INFO - 'HOME': '/Users/cltbld', 21:00:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:49 INFO - 'LOGNAME': 'cltbld', 21:00:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:00:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:49 INFO - 'MOZ_NO_REMOTE': '1', 21:00:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:00:49 INFO - 'NO_EM_RESTART': '1', 21:00:49 INFO - 'PAGER': '/bin/cat', 21:00:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:49 INFO - 'PWD': '/builds/slave/test', 21:00:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:00:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:49 INFO - 'SHELL': '/bin/bash', 21:00:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JAlJuaKPY7/Listeners', 21:00:49 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 21:00:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:49 INFO - 'USER': 'cltbld', 21:00:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:49 INFO - 'XPC_FLAGS': '0x0', 21:00:49 INFO - 'XPC_SERVICE_NAME': '0', 21:00:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:49 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/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 21:00:49 INFO - mozversion application_buildid: 20160517192138 21:00:49 INFO - mozversion application_changeset: 8751a7511341585167961854d72d66a538f03555 21:00:49 INFO - mozversion application_display_name: Nightly 21:00:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:00:49 INFO - mozversion application_name: Firefox 21:00:49 INFO - mozversion application_remotingname: firefox 21:00:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:00:49 INFO - mozversion application_vendor: Mozilla 21:00:49 INFO - mozversion application_version: 49.0a1 21:00:49 INFO - mozversion platform_buildid: 20160517192138 21:00:49 INFO - mozversion platform_changeset: 8751a7511341585167961854d72d66a538f03555 21:00:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:00:49 INFO - mozversion platform_version: 49.0a1 21:00:49 INFO - using testdate: 1463544049 21:00:49 INFO - actual date: 1463544049 21:00:49 INFO - starting webserver on 'localhost:49278' 21:00:49 INFO - SUITE-START | Running 2 tests 21:00:49 INFO - TEST-START | dromaeo_css 21:00:49 INFO - operating with platform_type : mac_ 21:00:49 INFO - Initialising browser for dromaeo_css test... 21:00:49 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp_cPcuh/profile http://localhost:49278/getInfo.html) 21:00:52 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 21:00:52 INFO - PROCESS | 1668 | colorDepth:24 21:00:52 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 21:00:52 INFO - PROCESS | 1668 | __metrics 21:00:52 INFO - PROCESS | 1668 | 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" 21:00:52 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:00:53 INFO - TEST-INFO | 1668: exit 0 21:00:53 INFO - Browser initialized. 21:00:53 INFO - Running cycle 1/1 for dromaeo_css test... 21:00:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp_cPcuh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:01:05 INFO - PROCESS | 1671 | RSS: Main: 238538752 21:01:05 INFO - PROCESS | 1671 | 21:01:57 INFO - PROCESS | 1671 | 1463544117016 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] 21:04:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 21:04:06 INFO - PROCESS | 1671 | RSS: Main: 313921536 21:04:06 INFO - PROCESS | 1671 | 21:07:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 21:07:10 INFO - PROCESS | 1671 | RSS: Main: 365928448 21:07:10 INFO - PROCESS | 1671 | 21:10:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 21:10:13 INFO - PROCESS | 1671 | RSS: Main: 365395968 21:10:13 INFO - PROCESS | 1671 | 21:13:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 21:13:16 INFO - PROCESS | 1671 | RSS: Main: 366800896 21:13:16 INFO - PROCESS | 1671 | 21:16:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 21:16:20 INFO - PROCESS | 1671 | RSS: Main: 327860224 21:16:20 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 21:19:23 INFO - PROCESS | 1671 | RSS: Main: 375398400 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | __start_tp_report 21:19:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 21:19:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 21:19:23 INFO - PROCESS | 1671 | |i|pagename|runs| 21:19:23 INFO - PROCESS | 1671 | |0;dojo.html;2889.378783561534;2944.661363943147;2948.91153265402;2965.347623522824;3003.63956325241;4657.90684186317;4737.028909073636;4747.62019038477;4877.561019508244;4909.484504127065;4804.90390192196;4830.492798256177;4837;4884.169691152504;4889.951100488994;4364.956350436495;4401.735895846244;4526.366308716782;4529.728216307032;4539.909201815962;4447.710898791568;4527.750973696446;4534.002519445717;4546.704464209833;4577.29051996941;4397.252467080604;4556.248219043854;4586;4586.105709386671;4591.989762252316;4309.159713855799;4312.5903039211225;4394.011347446831;4423.225935461282;4521.977390113028;4334.436523251957;4340.934885976713;4366.519682834885;4378.5183629800695;4378.5183629800695;4712.505186955389;4723.338732577442;4758.95241047592;4770.928436073462;4786.44955830077;5019.824306149268;5147.3051138096625;5236.031334203185;5284.260203571503;5301.443348448396;5274.129768588178;5290.39160496544;5291.1534154535475;5325.760340784635;5345.251664766935;4979.950200497985;5001.274815151783;5034.874128146753;5044.924326135111;5049.444561098277;5216.26972223889;5282.81510147143;5333.173358129496;5422.40353561108;5459.508644222039;5201.063808514428;5219.4780521947505;5246.029484545371;5258.39528454225;5265.368155821326;5349.759260833272;5385.919211211835;5404.189371594213;5443.8094666686475;5446.373667028263;4295.613394794484;5072.492750724898;5165.199394093846;5246;5266.131088370453;5308.946910530923;5310.336207974004;5322.467753224724;5325.147976323847;5382.838514844561;4297.09760950204;4313.503947046067;4324.329728892027;4367.759773212504;4384.473863136444;3719.3119272934055;3773.5471743390967;3802.6387493188104;3817.331966905836;3864.381698928158;4713.128071306751;4719.882002949885;4738.1708201064675;4746.833860814855;4766.880827979328;5299.5760339172775;5332.0402327581405;5332.653377530448;5353.9196912046355;5373.811916582901;4610.377599024089;4630.467496237909;4681.391419115494;4746.95253047472;4751.83368582098;4648.116857797076;4681.250999840009;4706.10583989041;4707.599854012447;4737.313089601988;5536.612437129443;5550.250716153349;5579.525740312119;5584.497395234448;5600.635958662674;5175.275461435477;5226.764795584132;5238.4237733850005;5319.441458646941;5341.065313570188;4580.88547786308;5123;5285.286486324297;5512.476314750093;5517.337919449692;5239.240310155083;5255.395629502656;5256.579473642023;5259.21111833228;5271.077561426811;5318.4681531848055;5320.06898792702;5324.520793128637;5353.625246232727;5370.865728356823;4882.145624515767;4981.562721722;5082.491750824888;5123.692578445305;5179.19722443022;5183.870403239949;5195.454477279831;5200.5059519345605;5208.374995000624;5237.0835103857435;4987.40151181846;5072.061668591323;5073.3911930568565;5100;5106.234064890147;5006.9986002800315;5141.33162688863;5142.04872098663;5211.0880595894805;5252.290940722954;5134.178531434953;5135.820246291362;5151.948480515147;5152.9742351288005;5169.095408303607 21:19:23 INFO - PROCESS | 1671 | |1;ext.html;454.7226192022865;469.6501106675527;490.44579625023715;490.7104808163183;499.9500049995;29761.702382976164;31636;31855.044348669548;31866.68133318666;36840.447393289054;36929.076773080684;42623.360649590206;42711.78644106783;42806.57193428065;42900.57099429005;9269.536523173849;9477.526123693822;9477.668281610146;9485.193758530533;9526.523673816317;7841.176676448977;8367.497950123012;8561.700340488083;9339.673111441101;9415.387999779992;9913.603455861792;9976.850347244797;11251.718707032349;11475.540978360897;11590.884091159107;11509.136814738928;11545.307281563091;11560.364179970098;11567.305961642329;11605.129615278804;8300.750977470685;11095.83356249657;11258.605948791754;11384.373859437728;11385.601503947404;39175.60824391748;39506.20987580261;39506.20987580261;39657.60342396568;39685.40471892895;3203.1511649412923;3264.167637252509;3292.8682852685865;3370.703022436688;3650;27719;28566.143015709564;28663;28700.282492937637;28756.856215718995;2644.1010056580853;2648.23201271631;2654.721254268313;2759.9172024839286;2886;513509;520297;524984;657510;663736.9999999952;60908.69545652332;61280.69359653263;61664;61760.69119654373;61908.69045654743;65858.67070664711;65991.34008659948;66103.66948165228;66137.33862661407;66251.66874165599;16317.34730610786;16570.254338554794;16617.833821661872;16777.664446710998;16819.411320603966;12642.178258413174;12644;12683.365831708377;12686.302253376152;12687.365631718561;14331.498397556035;15980.76028859568;16097.022050920617;16577.668446631;18621.44135675932;6733;6736.360045795642;7683.462157649023;7947.205279472122;7969.282764551102;815.5188438821124;822.8642274024719;909.3949179697838;948.9656274660653;1016.506994107857;26327.605085923726;28372.14883553497;28472;28946.27634309159;29335.7066429333;1005.8088963096989;1006.4967516241879;1019.250850624805;1052.7524883013652;1110.4669758515959;12374.938125309316;14602.269886505632;14608.85391146075;14780.778288325684;15068.849311506963;672444.1178088074;682411;689215;690585;696633;50306;58368.8326233473;58668.41331586715;58773.412265876796;58895.41104588985;1254.4919307680404;1303.2050449226153;1303.8956883449112;1403.6701375176826;1438.0724432740824;29063.709362906102;29171.41657166802;30030.69969300279;30194.396112078073;30935.535966960062;52506;53322;53416;53489.465105350006;53675.463245368606;575023;579941;580087;580097;580329;33004.33991320208;33356.166095847795;38881.6111838878;41080;41081.97295067528;35154.47268291029;39031.609683902796;43484.78257608692;43568.34647480354;45040;30787.230319241302;35470.64529354673;35682.82158589191;35821.46267806037;36801.26397471982;35056.47415288722;35145.47281790724;35415.46876796902;35681.28637427185;35757.82121089378;35107.771228006895;35469.29061418809;35542.289154216254;35822.641773581934;36360.45459318059;29902.102936911928;30343;32457.35085298328;32464.188395290308;32562.185945351557;23544;24080.87959560191;24276.71211160434;24374.390640234134;24401.51196976015 21:19:23 INFO - PROCESS | 1671 | |2;jquery.html;356.61485595556803;360.6231488094941;365.161953317137;378.98105094745256;387.17145309038665;18939.526511837194;20225.49436264094;20875.37373878786;21288.787112128874;21299.68050479242;9831.967643397447;11819.40902954851;12067.034637229022;12069.396530173479;12070.51717931284;5078.289039534459;5294.470552944713;5344.412114667383;5349.09065458871;5352.866178345553;3757.1546402059453;4043.777592232426;5594.804181853636;5945.524358051345;5963.910541341883;5872.207252020965;5958.821235362946;5965.761369545213;5978.103284507337;5980;5703.5152012079025;5879.235699359098;5906.114082887559;5936.465718085371;5944.583879128462;3625.800580968046;5226.817061402832;5643.492085712305;5661.179129026309;5705.372409035002;13346.865516431115;13494.932525337408;13564.846988006031;13671.794923076162;13732.65668358299;2053;2079.688046792978;2104.7474303083577;2180.4766855954617;2562.192909233604;9775.51122443875;9813.705588832347;9819.754506137404;10111.140553053;10115.443650599214;1682.5204816627324;1731.6796392667397;1756.5608597850537;1781.6436712657392;1800.3698705453119;120506;120509;124018;128037.35981320127;130198;11019.889801102046;11441.199116061962;11485.827712584318;11598.362090084958;11896.286222826659;14737;14797.260136993107;14820.407183712747;14825.925870370795;14856.331465084097;8578.485290882567;8602.827943441096;8635.956820215859;8669.566521674018;8744.300455963508;7161.498695091398;8550.700725474579;8602.698905538276;8677.262432693165;8722.781930451789;4505.07645932583;4584.679072464895;4591.403117594691;4614.377059097046;4655.11552804966;1472.8968972172058;1788.9799224839965;1811.1707175752585;1909.961800763977;1977.4463150317933;735.7351353512731;758.4652819762055;825.8761185822175;841.3521588376916;864.3690106222493;19630.920299383386;20138.798612013987;20320.796792032186;20353.287634932705;20594.794052059588;833.6272722304689;846.4794151596847;849.7396924181647;860.9741707748777;861.8707193920836;9004.954975225082;10690.572377104983;10771.78456430867;10794.568217271379;10801.78396432067;556401;566189;568941;580821;586199;118101;125745.37127314304;128514;128690;134718.3264083693;950.8811398575316;962.4417837654144;986.225812736998;1003.8544411060356;1007.0131271353968;17542.298308068046;17581.91209043998;17906.731399029286;18268.15392196436;18521.166547505127;51812.740936296585;51878.48121518736;53085.40743777693;53359.466405337;53524.464755351946;74773.626131869;76911.53898074488;79062;79236;79406;7677.539347639159;7811.336036436966;7905;7977.680892764457;8161.265486106279;9071.818563628822;9826.852597211191;12418.875811242133;12596.307203103917;12872.742545149233;5980.132880732356;10113.342632728849;10353;10720;10721.35671859691;7485.588292643957;8832.470051796912;10436;10495.947520262349;10630.58202961087;11048.779024419306;11167.553297868319;11201.327920324806;11248.212625116163;11426.885731142915;7828.138904720362;7858.057033155829;7868.25251601097;8059.879101813361;8080.71717489885;10259.948700256451;10891.18316126278;11059.889401106208;11121.388323642277;11283.323000619988 21:19:23 INFO - PROCESS | 1671 | |3;mootools.html;1364.2701154882127;1504.9014219619678;1516.4540765324484;1730.6971280025991;1897.3264491105665;7831.921680783191;8347.540885251308;8350.749477515668;8355.707550235744;8359.164083591639;8429.91570084299;8753.518556479392;8782.385233033674;8880.689175878846;8880.822383552326;2344.495933374324;2416.830821842472;2424.8181386396004;2426.3085020769086;2427.927162185137;2100.8004239597212;2120.4698825293676;2348.518553696488;2388.6178211486167;2401.2436082633913;2369.0168580039262;2394.3415560720855;2405.338531903723;2410.517896420714;2412.107520217522;2034.6744520876662;2335.054303007285;2342.7422983471806;2384.332386931662;2385.1651921827397;2370.4429459077105;2375.9287221383215;2406.121765555577;2407.8555286682854;2412;8645.481271123752;8818.162274583905;8821.51481668508;8829.779255518599;8837.248833849131;4677.532246775295;5177.326947496839;5234.136367499358;5407.486288802557;5426.023315803154;2863.871125799344;3532.2405682778067;3565.7682250653625;3595;3596.2088340565033;994.955227014786;1009.6617633092923;1021.4892553723139;1035.7669524357034;1042.4943902207424;10383.273170877967;11909.821352679717;11988;12003.699907502294;12037.398130093461;4494.16857881293;4615.838445654386;4690.53094690535;4699.78850951708;4704.03567268716;9205.585748641328;9324.953375233215;9331.860022099674;9331.90668093324;9350.90649093514;5298.761555730001;5304.4960728731485;5306.469353064663;5306.52241298285;5313.362396512443;4227.682923780729;4404.405405270311;4412.646988240933;4923.458419573844;4940.086084074458;3221.5650887130405;3821.2548553031893;3852.7110466715108;3876.980615096963;3921.686265098785;1170.1867202294325;1179.8525184351956;1182.9763404731857;1193.40926241511;1199.4062938845327;588.4330867518559;714.7927101140715;781.4725060584083;835.2733122183739;877.2587163846539;5618.543588106462;5716.314042314949;5956.255468066492;5963.7912673056235;6013.4888534474185;864.8356812205786;866.6793286484042;882.580774132282;909.60432211988;909.713440266314;3543.36219480496;3624.5831729350934;3653.6529029742132;3680.926381472409;3713.517242758424;9647.1317581418;10708.518116684769;11031.724206894893;11080.279781814157;11119.555217791198;9854.802903941882;10564;10840.024397804236;10898.727531811768;11095.83356249657;6930.341617546325;8131.14622964592;8204.056533498604;8204.425690201748;8208.507489550526;5137.537621614073;5693.174489699053;5707.885842283048;5755.935151996894;5794;9951.900480995097;10245.590176392861;10280.331778434378;10294.330868493524;10300.381977081399;9918.752031199278;10007.999200079934;10445.791084178425;10458.320209186379;10509.737256568647;7022.192447868356;7093.751718689821;7187.892181617175;7188.209296977224;7214.386777123793;8188.959055204686;8336.499810011419;8337.83324333522;8345.95827020861;8408.747737567883;6655.334466553306;6915.412189963909;7381;7544.8491030180185;7627.0847498298335;7233.3851622612665;7400.222976587596;7426.74006409773;7494.5503269803985;7663.693452261847;6892.620905850025;7000.334968178015;7094.184168820445;7095.751648692271;7100.680469378994;4713.057388522241;4835.782389792397;4866.245731911559;4875.68308059975;4876.975615121902;4767.332573439652;4865.635077369282;4904.141775189399;4943.184374578154;5164.561012313995 21:19:23 INFO - PROCESS | 1671 | |4;prototype.html;254.3006731488407;262.7676198628434;283.98722057507416;297.52693217783724;298.79681816364865;3667.174885650734;3687.8340474678635;4120.670346372291;4137.586241375865;4154.27300222461;3877.515310586177;4057.2291264659766;4058.107216412392;4062.918741625173;4064.2277967186287;2899.4925887969625;2959.0235222376673;3204.1669166016886;3204.2309845637114;3233.5634689316935;3033.5752994580776;3184.1243657994005;3228.3059142284405;3233.1917020744813;3246.3345014272018;2400.4958958618713;2722.1897438203573;3125.95311070333;3146.7954582952148;3151.290959534098;3180.918487714165;3180.9681903181136;3197.2486465680545;3219.710226079664;3264.7877887937325;2251.5159240763314;2785.8049936504453;2827.0246764866088;2836.801423900328;2858.1711303721895;3301.735861131104;3794;3801.600831912637;3821.159344944108;3831.923361532754;3418.8974330770116;3708.8331025103666;3711.3505136601257;3724.646158614927;3726.757760745543;4083.020075181965;4165.479315085614;4386.451693538308;4398.516163222043;4430.822767089313;2787;3039.1794215561895;3174.3333899881054;3183.997040932122;3187.8087314761187;3840.8847734568008;4218.936715949294;4576.679632425731;4632.698874573142;4634.490206077328;3162.4623813951684;3182.188541921795;3209.21374263307;3235.7573182011442;3236.6925142111463;2797.6011994002997;3257.1857035741064;3282.7373810095137;3282.901512954615;3286.7041966223155;3532.5054492371087;3549.982250088733;3708.332500149999;3713.2573485303046;3763.6800871925643;4055.1281474483126;4477.888052798706;4488.685791994529;4489.528599497071;4492.562380038422;1105.7401510644993;1107.4739498738036;1128.5667202652749;1131.2533727739653;1132.64887884756;582.825152454262;702.5889854435212;712.7113519024804;755.8488302339554;778.6807408962298;3408.7102596279096;3890.7628113834658;3892.5328960524917;3898.9610103899167;3919.451276821247;4428.269335559597;4435.489918659331;4444.910996805803;4447.288433850633;4447.7998490067375;3339.3655205510877;3406.9148271293416;3765.3222419964122;3902.434147048662;3903.765774053566;2175.6192666283337;2311.8959646815933;2327.3832434404894;2353.9293821185393;2372.7745864143226;4033.576474470197;4685.390899183084;4711.929321060187;4738.93856172566;4775.307580400822;4116.608922152391;4648.767561621973;4663.670853806649;4691.46048204454;4733;3389.084947064328;3831.214601006787;4686.320483529801;4710.29345598163;4769.284607308931;3580.427131658922;3939.586343433898;4155.631290164698;4355.776725223375;4566.4748553916725;3595.838187281631;4549.727016379028;4558.954410455853;4561.954380456153;4623.884402889955;4117.5882411758585;4566.771661416982;4740.928886066643;4761.738104404291;4782.450018247946;3263.918402039968;3270.8201048942537;3298.7690861639453;3302.7522935779434;3303.4053870302614;3739.0652336915773;4249.490061192677;4406.369643232025;4512.571305725951;4524.977375113103;3489.476578513243;3803.4484999674337;3842.269968705937;4348.978255108705;4400.647948164204;4197.307444271661;4344.739315641072;4367.410399596014;4378.036831896978;4384.342348647729;3877.8284160870003;3898.6686131679126;4090.9772556860785;4294.291441912174;4308.159908817751;3615.313090512794;4180.26845302077;4290.764007979466;4429.867103986886;4454.354118652842;3680.8343624536424;3959.980200098981;4061.882982179924;4260.573942605714;4286.185624731291 21:19:23 INFO - PROCESS | 1671 | |5;yui.html;627.4196368359268;653.111767995526;700.2927043685876;726.614894106124;742.4468770765781;14859.479918202866;15115.92442037791;15170.317335719892;15181.54455366338;15197.012194207382;13932.930335348334;13948.302584870766;13960.860391396083;14000;14032.71934561308;695.4366962760155;716.0655344775066;718.1705130574195;723.2297603052758;724.474755802042;864.3258258558303;884.2395539835736;888.1606881496988;892.2193081054078;894.0433735902587;933.383966582056;938.6526985015554;940.8118376324728;941.7174847545743;941.8163458125669;926.9490178040239;927.0358826820099;929.2426672262152;931.9394239374419;934.1545900959575;677.6442367756918;689.1454596300601;689.5001124184976;690.0546251635242;694.121935751275;11568.421578921105;14464.566063018126;14501;14529.491467798576;14531.418743250257;11314.886851131465;11823.172377933548;12011;12337.50649974;12475.875241247653;9434.952825235898;9801.117899389017;9844.26168037393;10049.748756281151;10141.695749127464;3003.969960300391;3365.326934613063;3408;3431.5024321473497;3541.769784963969;82475.17524824735;82673;84912.57543712242;85299.29401411937;85385.57307213487;8915;9221;9225.769355766159;9318.254539636813;9424.670136545325;21457;21460.892695536735;21467.463313416978;21472.033758480906;21497.92510374475;7195.460340474486;7255.492115551966;7282.12614486265;7414.962925185339;7444.143923448764;3792.3173828710546;3863.613638636114;3885.3681453789477;3897.4738410314812;4119.9176016479505;3443.1564266754804;3576.3741345264475;3577.767445116059;3579.4272916333784;3580.337637537064;551.6414330685087;634.1280738983897;642.2164958750317;649.3798422506495;654.1104098426136;441.7437886026161;498.6639382530929;521.0568870344688;542.4169018305338;564.8627660468975;7842.607869606497;7848.018997625297;8044.919550804534;8083.272505474535;8115.188481151956;474.2625217786375;550.7081246939129;552.3316786688152;557.6977756937515;574.4252034009924;3555.897671721723;3799.1262009737916;3813.1992281620614;3833.3674943634546;3879.1078052047624;98337.50831245798;98600;98639;101931.49034254781;102160;78439;83804;84237.57881210677;85551.5722421384;86842.13157868467;62094.68952655208;73683;76548.08629784189;76752.23247767563;76989.07527311862;7546.3962882968135;7704.152543220129;7755.612219389121;7915.9208407915185;8569.700060497853;45196.77401612971;45481.77259113683;45517.31724024208;45771.77114114408;46787.53212467832;45769.313460298785;59668.70165649144;61485.385146147964;62859;64675.35324646693;10412.32319899204;10849.349039057683;10884.346939183675;10960.671179864617;11111.944440277746;11363.295655563257;11561.537538498702;11566.884331156582;11651.41742912868;12154.75690486203;10799.13606911461;11120.944395277971;11155.442227888736;11211.733487336767;11404.854910714073;10945;11044.668659940215;11062.919731372089;11133.443327833415;11389.601363952223;10728.409937453514;10775.6228532001;11022.559097636005;11218.775624487627;11243.988041076342;7214.531055481377;9071;9125.954370228372;9373.343865929592;9469.895516650931;8948.597313121056;9034.412763170372;9437.952810235905;9614.615415383307;9851.802963940538 21:19:23 INFO - PROCESS | 1671 | __end_tp_report 21:19:23 INFO - PROCESS | 1671 | __start_cc_report 21:19:23 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1226 21:19:23 INFO - PROCESS | 1671 | __end_cc_report 21:19:23 INFO - PROCESS | 1671 | __startTimestamp1463545163821__endTimestamp 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | ------- Summary: start ------- 21:19:23 INFO - PROCESS | 1671 | Number of tests: 6 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:4888.51 Median:5072.06 stddev:532.63 (10.5%) stddev-sans-first:510.65 21:19:23 INFO - PROCESS | 1671 | Values: 2889.4 2944.7 2948.9 2965.3 3003.6 4657.9 4737.0 4747.6 4877.6 4909.5 4804.9 4830.5 4837.0 4884.2 4890.0 4365.0 4401.7 4526.4 4529.7 4539.9 4447.7 4527.8 4534.0 4546.7 4577.3 4397.3 4556.2 4586.0 4586.1 4592.0 4309.2 4312.6 4394.0 4423.2 4522.0 4334.4 4340.9 4366.5 4378.5 4378.5 4712.5 4723.3 4759.0 4770.9 4786.4 5019.8 5147.3 5236.0 5284.3 5301.4 5274.1 5290.4 5291.2 5325.8 5345.3 4980.0 5001.3 5034.9 5044.9 5049.4 5216.3 5282.8 5333.2 5422.4 5459.5 5201.1 5219.5 5246.0 5258.4 5265.4 5349.8 5385.9 5404.2 5443.8 5446.4 4295.6 5072.5 5165.2 5246.0 5266.1 5308.9 5310.3 5322.5 5325.1 5382.8 4297.1 4313.5 4324.3 4367.8 4384.5 3719.3 3773.5 3802.6 3817.3 3864.4 4713.1 4719.9 4738.2 4746.8 4766.9 5299.6 5332.0 5332.7 5353.9 5373.8 4610.4 4630.5 4681.4 4747.0 4751.8 4648.1 4681.3 4706.1 4707.6 4737.3 5536.6 5550.3 5579.5 5584.5 5600.6 5175.3 5226.8 5238.4 5319.4 5341.1 4580.9 5123.0 5285.3 5512.5 5517.3 5239.2 5255.4 5256.6 5259.2 5271.1 5318.5 5320.1 5324.5 5353.6 5370.9 4882.1 4981.6 5082.5 5123.7 5179.2 5183.9 5195.5 5200.5 5208.4 5237.1 4987.4 5072.1 5073.4 5100.0 5106.2 5007.0 5141.3 5142.0 5211.1 5252.3 5134.2 5135.8 5151.9 5153.0 5169.1 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:73559.72 Median:35506.47 stddev:165387.49 (465.8%) stddev-sans-first:165760.36 21:19:23 INFO - PROCESS | 1671 | Values: 454.7 469.7 490.4 490.7 500.0 29761.7 31636.0 31855.0 31866.7 36840.4 36929.1 42623.4 42711.8 42806.6 42900.6 9269.5 9477.5 9477.7 9485.2 9526.5 7841.2 8367.5 8561.7 9339.7 9415.4 9913.6 9976.9 11251.7 11475.5 11590.9 11509.1 11545.3 11560.4 11567.3 11605.1 8300.8 11095.8 11258.6 11384.4 11385.6 39175.6 39506.2 39506.2 39657.6 39685.4 3203.2 3264.2 3292.9 3370.7 3650.0 27719.0 28566.1 28663.0 28700.3 28756.9 2644.1 2648.2 2654.7 2759.9 2886.0 513509.0 520297.0 524984.0 657510.0 663737.0 60908.7 61280.7 61664.0 61760.7 61908.7 65858.7 65991.3 66103.7 66137.3 66251.7 16317.3 16570.3 16617.8 16777.7 16819.4 12642.2 12644.0 12683.4 12686.3 12687.4 14331.5 15980.8 16097.0 16577.7 18621.4 6733.0 6736.4 7683.5 7947.2 7969.3 815.5 822.9 909.4 949.0 1016.5 26327.6 28372.1 28472.0 28946.3 29335.7 1005.8 1006.5 1019.3 1052.8 1110.5 12374.9 14602.3 14608.9 14780.8 15068.8 672444.1 682411.0 689215.0 690585.0 696633.0 50306.0 58368.8 58668.4 58773.4 58895.4 1254.5 1303.2 1303.9 1403.7 1438.1 29063.7 29171.4 30030.7 30194.4 30935.5 52506.0 53322.0 53416.0 53489.5 53675.5 575023.0 579941.0 580087.0 580097.0 580329.0 33004.3 33356.2 38881.6 41080.0 41082.0 35154.5 39031.6 43484.8 43568.3 45040.0 30787.2 35470.6 35682.8 35821.5 36801.3 35056.5 35145.5 35415.5 35681.3 35757.8 35107.8 35469.3 35542.3 35822.6 36360.5 29902.1 30343.0 32457.4 32464.2 32562.2 23544.0 24080.9 24276.7 24374.4 24401.5 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:33632.24 Median:2061.16 stddev:95994.97 (4657.3%) stddev-sans-first:96231.74 21:19:23 INFO - PROCESS | 1671 | Values: 356.6 360.6 365.2 379.0 387.2 18939.5 20225.5 20875.4 21288.8 21299.7 9832.0 11819.4 12067.0 12069.4 12070.5 5078.3 5294.5 5344.4 5349.1 5352.9 3757.2 4043.8 5594.8 5945.5 5963.9 5872.2 5958.8 5965.8 5978.1 5980.0 5703.5 5879.2 5906.1 5936.5 5944.6 3625.8 5226.8 5643.5 5661.2 5705.4 13346.9 13494.9 13564.8 13671.8 13732.7 2053.0 2079.7 2104.7 2180.5 2562.2 9775.5 9813.7 9819.8 10111.1 10115.4 1682.5 1731.7 1756.6 1781.6 1800.4 120506.0 120509.0 124018.0 128037.4 130198.0 11019.9 11441.2 11485.8 11598.4 11896.3 14737.0 14797.3 14820.4 14825.9 14856.3 8578.5 8602.8 8636.0 8669.6 8744.3 7161.5 8550.7 8602.7 8677.3 8722.8 4505.1 4584.7 4591.4 4614.4 4655.1 1472.9 1789.0 1811.2 1910.0 1977.4 735.7 758.5 825.9 841.4 864.4 19630.9 20138.8 20320.8 20353.3 20594.8 833.6 846.5 849.7 861.0 861.9 9005.0 10690.6 10771.8 10794.6 10801.8 556401.0 566189.0 568941.0 580821.0 586199.0 118101.0 125745.4 128514.0 128690.0 134718.3 950.9 962.4 986.2 1003.9 1007.0 17542.3 17581.9 17906.7 18268.2 18521.2 51812.7 51878.5 53085.4 53359.5 53524.5 74773.6 76911.5 79062.0 79236.0 79406.0 7677.5 7811.3 7905.0 7977.7 8161.3 9071.8 9826.9 12418.9 12596.3 12872.7 5980.1 10113.3 10353.0 10720.0 10721.4 7485.6 8832.5 10436.0 10495.9 10630.6 11048.8 11167.6 11201.3 11248.2 11426.9 7828.1 7858.1 7868.3 8059.9 8080.7 10259.9 10891.2 11059.9 11121.4 11283.3 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5630.51 Median:4890.56 stddev:3205.43 (65.5%) stddev-sans-first:3198.38 21:19:23 INFO - PROCESS | 1671 | Values: 1364.3 1504.9 1516.5 1730.7 1897.3 7831.9 8347.5 8350.7 8355.7 8359.2 8429.9 8753.5 8782.4 8880.7 8880.8 2344.5 2416.8 2424.8 2426.3 2427.9 2100.8 2120.5 2348.5 2388.6 2401.2 2369.0 2394.3 2405.3 2410.5 2412.1 2034.7 2335.1 2342.7 2384.3 2385.2 2370.4 2375.9 2406.1 2407.9 2412.0 8645.5 8818.2 8821.5 8829.8 8837.2 4677.5 5177.3 5234.1 5407.5 5426.0 2863.9 3532.2 3565.8 3595.0 3596.2 995.0 1009.7 1021.5 1035.8 1042.5 10383.3 11909.8 11988.0 12003.7 12037.4 4494.2 4615.8 4690.5 4699.8 4704.0 9205.6 9325.0 9331.9 9331.9 9350.9 5298.8 5304.5 5306.5 5306.5 5313.4 4227.7 4404.4 4412.6 4923.5 4940.1 3221.6 3821.3 3852.7 3877.0 3921.7 1170.2 1179.9 1183.0 1193.4 1199.4 588.4 714.8 781.5 835.3 877.3 5618.5 5716.3 5956.3 5963.8 6013.5 864.8 866.7 882.6 909.6 909.7 3543.4 3624.6 3653.7 3680.9 3713.5 9647.1 10708.5 11031.7 11080.3 11119.6 9854.8 10564.0 10840.0 10898.7 11095.8 6930.3 8131.1 8204.1 8204.4 8208.5 5137.5 5693.2 5707.9 5755.9 5794.0 9951.9 10245.6 10280.3 10294.3 10300.4 9918.8 10008.0 10445.8 10458.3 10509.7 7022.2 7093.8 7187.9 7188.2 7214.4 8189.0 8336.5 8337.8 8346.0 8408.7 6655.3 6915.4 7381.0 7544.8 7627.1 7233.4 7400.2 7426.7 7494.6 7663.7 6892.6 7000.3 7094.2 7095.8 7100.7 4713.1 4835.8 4866.2 4875.7 4877.0 4767.3 4865.6 4904.1 4943.2 5164.6 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:3547.89 Median:3841.58 stddev:1062.77 (27.7%) stddev-sans-first:1036.60 21:19:23 INFO - PROCESS | 1671 | Values: 254.3 262.8 284.0 297.5 298.8 3667.2 3687.8 4120.7 4137.6 4154.3 3877.5 4057.2 4058.1 4062.9 4064.2 2899.5 2959.0 3204.2 3204.2 3233.6 3033.6 3184.1 3228.3 3233.2 3246.3 2400.5 2722.2 3126.0 3146.8 3151.3 3180.9 3181.0 3197.2 3219.7 3264.8 2251.5 2785.8 2827.0 2836.8 2858.2 3301.7 3794.0 3801.6 3821.2 3831.9 3418.9 3708.8 3711.4 3724.6 3726.8 4083.0 4165.5 4386.5 4398.5 4430.8 2787.0 3039.2 3174.3 3184.0 3187.8 3840.9 4218.9 4576.7 4632.7 4634.5 3162.5 3182.2 3209.2 3235.8 3236.7 2797.6 3257.2 3282.7 3282.9 3286.7 3532.5 3550.0 3708.3 3713.3 3763.7 4055.1 4477.9 4488.7 4489.5 4492.6 1105.7 1107.5 1128.6 1131.3 1132.6 582.8 702.6 712.7 755.8 778.7 3408.7 3890.8 3892.5 3899.0 3919.5 4428.3 4435.5 4444.9 4447.3 4447.8 3339.4 3406.9 3765.3 3902.4 3903.8 2175.6 2311.9 2327.4 2353.9 2372.8 4033.6 4685.4 4711.9 4738.9 4775.3 4116.6 4648.8 4663.7 4691.5 4733.0 3389.1 3831.2 4686.3 4710.3 4769.3 3580.4 3939.6 4155.6 4355.8 4566.5 3595.8 4549.7 4559.0 4562.0 4623.9 4117.6 4566.8 4740.9 4761.7 4782.5 3263.9 3270.8 3298.8 3302.8 3303.4 3739.1 4249.5 4406.4 4512.6 4525.0 3489.5 3803.4 3842.3 4349.0 4400.6 4197.3 4344.7 4367.4 4378.0 4384.3 3877.8 3898.7 4091.0 4294.3 4308.2 3615.3 4180.3 4290.8 4429.9 4454.4 3680.8 3960.0 4061.9 4260.6 4286.2 21:19:23 INFO - PROCESS | 1671 | 21:19:23 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:18321.02 Median:30121.56 stddev:26661.90 (88.5%) stddev-sans-first:26703.60 21:19:23 INFO - PROCESS | 1671 | Values: 627.4 653.1 700.3 726.6 742.4 14859.5 15115.9 15170.3 15181.5 15197.0 13932.9 13948.3 13960.9 14000.0 14032.7 695.4 716.1 718.2 723.2 724.5 864.3 884.2 888.2 892.2 894.0 933.4 938.7 940.8 941.7 941.8 926.9 927.0 929.2 931.9 934.2 677.6 689.1 689.5 690.1 694.1 11568.4 14464.6 14501.0 14529.5 14531.4 11314.9 11823.2 12011.0 12337.5 12475.9 9435.0 9801.1 9844.3 10049.7 10141.7 3004.0 3365.3 3408.0 3431.5 3541.8 82475.2 82673.0 84912.6 85299.3 85385.6 8915.0 9221.0 9225.8 9318.3 9424.7 21457.0 21460.9 21467.5 21472.0 21497.9 7195.5 7255.5 7282.1 7415.0 7444.1 3792.3 3863.6 3885.4 3897.5 4119.9 3443.2 3576.4 3577.8 3579.4 3580.3 551.6 634.1 642.2 649.4 654.1 441.7 498.7 521.1 542.4 564.9 7842.6 7848.0 8044.9 8083.3 8115.2 474.3 550.7 552.3 557.7 574.4 3555.9 3799.1 3813.2 3833.4 3879.1 98337.5 98600.0 98639.0 101931.5 102160.0 78439.0 83804.0 84237.6 85551.6 86842.1 62094.7 73683.0 76548.1 76752.2 76989.1 7546.4 7704.2 7755.6 7915.9 8569.7 45196.8 45481.8 45517.3 45771.8 46787.5 45769.3 59668.7 61485.4 62859.0 64675.4 10412.3 10849.3 10884.3 10960.7 11111.9 11363.3 11561.5 11566.9 11651.4 12154.8 10799.1 11120.9 11155.4 11211.7 11404.9 10945.0 11044.7 11062.9 11133.4 11389.6 10728.4 10775.6 11022.6 11218.8 11244.0 7214.5 9071.0 9126.0 9373.3 9469.9 8948.6 9034.4 9438.0 9614.6 9851.8 21:19:23 INFO - PROCESS | 1671 | -------- Summary: end -------- 21:19:23 INFO - PROCESS | 1671 | 21:19:24 INFO - TEST-INFO | 1671: exit 0 21:19:24 INFO - TEST-OK | dromaeo_css | took 1114481ms 21:19:24 INFO - TEST-START | kraken 21:19:24 INFO - operating with platform_type : mac_ 21:19:24 INFO - Initialising browser for kraken test... 21:19:24 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpCeNCji/profile http://localhost:49278/getInfo.html) 21:19:26 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 21:19:26 INFO - PROCESS | 1677 | colorDepth:24 21:19:26 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 21:19:26 INFO - PROCESS | 1677 | __metrics 21:19:26 INFO - PROCESS | 1677 | 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" 21:19:26 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:19:27 INFO - TEST-INFO | 1677: exit 0 21:19:27 INFO - Browser initialized. 21:19:27 INFO - Running cycle 1/1 for kraken test... 21:19:27 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpCeNCji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:39 INFO - PROCESS | 1679 | RSS: Main: 238886912 21:19:39 INFO - PROCESS | 1679 | 21:19:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 21:19:43 INFO - PROCESS | 1679 | RSS: Main: 287391744 21:19:43 INFO - PROCESS | 1679 | 21:19:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 21:19:47 INFO - PROCESS | 1679 | RSS: Main: 295829504 21:19:47 INFO - PROCESS | 1679 | 21:19:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 21:19:51 INFO - PROCESS | 1679 | RSS: Main: 383488000 21:19:51 INFO - PROCESS | 1679 | 21:19:53 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 21:19:54 INFO - PROCESS | 1679 | RSS: Main: 283865088 21:19:54 INFO - PROCESS | 1679 | 21:19:56 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:19:57 INFO - PROCESS | 1679 | RSS: Main: 287395840 21:19:57 INFO - PROCESS | 1679 | 21:20:00 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 21:20:01 INFO - PROCESS | 1679 | RSS: Main: 326811648 21:20:01 INFO - PROCESS | 1679 | 21:20:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 21:20:04 INFO - PROCESS | 1679 | RSS: Main: 327438336 21:20:04 INFO - PROCESS | 1679 | 21:20:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 21:20:09 INFO - PROCESS | 1679 | RSS: Main: 326168576 21:20:09 INFO - PROCESS | 1679 | 21:20:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:20:11 INFO - PROCESS | 1679 | RSS: Main: 316100608 21:20:11 INFO - PROCESS | 1679 | 21:20:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:20:14 INFO - PROCESS | 1679 | RSS: Main: 322351104 21:20:14 INFO - PROCESS | 1679 | 21:20:16 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:20:17 INFO - PROCESS | 1679 | RSS: Main: 325836800 21:20:17 INFO - PROCESS | 1679 | 21:20:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:20:20 INFO - PROCESS | 1679 | RSS: Main: 330551296 21:20:20 INFO - PROCESS | 1679 | 21:20:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:20:24 INFO - PROCESS | 1679 | RSS: Main: 328273920 21:20:24 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 21:20:26 INFO - PROCESS | 1679 | RSS: Main: 375205888 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | __start_tp_report 21:20:26 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 21:20:26 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 21:20:26 INFO - PROCESS | 1679 | |i|pagename|runs| 21:20:26 INFO - PROCESS | 1679 | |0;ai-astar;113;110;120;163;110;151;166;157;115;114 21:20:26 INFO - PROCESS | 1679 | |1;audio-beat-detection;162;123;148;202;123;162;142;124;170;125 21:20:26 INFO - PROCESS | 1679 | |2;audio-dft;211;215;213;214;211;216;212;209;214;214 21:20:26 INFO - PROCESS | 1679 | |3;audio-fft;82;78;58;129;58;63;59;63;114;57 21:20:26 INFO - PROCESS | 1679 | |4;audio-oscillator;94;104;137;84;110;110;136;84;83;104 21:20:26 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;93;90;94;90;93;90;93;91;94;89 21:20:26 INFO - PROCESS | 1679 | |6;imaging-darkroom;98;100;99;99;98;98;98;98;98;98 21:20:26 INFO - PROCESS | 1679 | |7;imaging-desaturate;107;91;146;85;154;157;119;104;106;99 21:20:26 INFO - PROCESS | 1679 | |8;json-parse-financial;60;61;61;58;57;58;58;121;59;57 21:20:26 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;68;75;75;76;77;75;75;76;80;75 21:20:26 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;90;90;89;87;88;92;90;88;89;89 21:20:26 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;145;145;156;139;141;144;145;142;228;146 21:20:26 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;227;180;172;172;172;170;171;172;188;217 21:20:26 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;69;69;69;68;69;78;84;84;85;74 21:20:26 INFO - PROCESS | 1679 | __end_tp_report 21:20:26 INFO - PROCESS | 1679 | __start_cc_report 21:20:26 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2316 21:20:26 INFO - PROCESS | 1679 | __end_cc_report 21:20:26 INFO - PROCESS | 1679 | __startTimestamp1463545226718__endTimestamp 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | ------- Summary: start ------- 21:20:26 INFO - PROCESS | 1679 | Number of tests: 14 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:131.90 Median:135.50 stddev:24.01 (17.7%) stddev-sans-first:24.47 21:20:26 INFO - PROCESS | 1679 | Values: 113.0 110.0 120.0 163.0 110.0 151.0 166.0 157.0 115.0 114.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:155.00 stddev:26.21 (16.9%) stddev-sans-first:27.31 21:20:26 INFO - PROCESS | 1679 | Values: 162.0 123.0 148.0 202.0 123.0 162.0 142.0 124.0 170.0 125.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:212.90 Median:214.00 stddev:2.13 (1.0%) stddev-sans-first:2.15 21:20:26 INFO - PROCESS | 1679 | Values: 211.0 215.0 213.0 214.0 211.0 216.0 212.0 209.0 214.0 214.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:76.10 Median:61.00 stddev:25.67 (42.1%) stddev-sans-first:27.13 21:20:26 INFO - PROCESS | 1679 | Values: 82.0 78.0 58.0 129.0 58.0 63.0 59.0 63.0 114.0 57.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:110.00 stddev:19.78 (18.0%) stddev-sans-first:20.61 21:20:26 INFO - PROCESS | 1679 | Values: 94.0 104.0 137.0 84.0 110.0 110.0 136.0 84.0 83.0 104.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:93.00 stddev:1.89 (2.0%) stddev-sans-first:1.94 21:20:26 INFO - PROCESS | 1679 | Values: 93.0 90.0 94.0 90.0 93.0 90.0 93.0 91.0 94.0 89.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:98.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 21:20:26 INFO - PROCESS | 1679 | Values: 98.0 100.0 99.0 99.0 98.0 98.0 98.0 98.0 98.0 98.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:116.80 Median:155.50 stddev:26.31 (16.9%) stddev-sans-first:27.66 21:20:26 INFO - PROCESS | 1679 | Values: 107.0 91.0 146.0 85.0 154.0 157.0 119.0 104.0 106.0 99.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:58.50 stddev:19.73 (33.7%) stddev-sans-first:20.85 21:20:26 INFO - PROCESS | 1679 | Values: 60.0 61.0 61.0 58.0 57.0 58.0 58.0 121.0 59.0 57.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:75.50 stddev:2.97 (3.9%) stddev-sans-first:1.66 21:20:26 INFO - PROCESS | 1679 | Values: 68.0 75.0 75.0 76.0 77.0 75.0 75.0 76.0 80.0 75.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.50 stddev:1.40 (1.6%) stddev-sans-first:1.45 21:20:26 INFO - PROCESS | 1679 | Values: 90.0 90.0 89.0 87.0 88.0 92.0 90.0 88.0 89.0 89.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:145.00 stddev:26.70 (18.4%) stddev-sans-first:28.16 21:20:26 INFO - PROCESS | 1679 | Values: 145.0 145.0 156.0 139.0 141.0 144.0 145.0 142.0 228.0 146.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:176.00 stddev:20.85 (11.8%) stddev-sans-first:15.27 21:20:26 INFO - PROCESS | 1679 | Values: 227.0 180.0 172.0 172.0 172.0 170.0 171.0 172.0 188.0 217.0 21:20:26 INFO - PROCESS | 1679 | 21:20:26 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:76.00 stddev:7.19 (9.5%) stddev-sans-first:7.30 21:20:26 INFO - PROCESS | 1679 | Values: 69.0 69.0 69.0 68.0 69.0 78.0 84.0 84.0 85.0 74.0 21:20:26 INFO - PROCESS | 1679 | -------- Summary: end -------- 21:20:26 INFO - PROCESS | 1679 | 21:20:27 INFO - TEST-INFO | 1679: exit 0 21:20:27 INFO - TEST-OK | kraken | took 63173ms 21:20:27 INFO - SUITE-END | took 1177s 21:20:27 INFO - Completed test suite (00:19:37) 21:20:27 INFO - javascript benchmark 21:20:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2889.378783561534, 2944.661363943147, 2948.91153265402, 2965.347623522824, 3003.63956325241, 4657.90684186317, 4737.028909073636, 4747.62019038477, 4877.561019508244, 4909.484504127065, 4804.90390192196, 4830.492798256177, 4837.0, 4884.169691152504, 4889.951100488994, 4364.956350436495, 4401.735895846244, 4526.366308716782, 4529.728216307032, 4539.909201815962, 4447.710898791568, 4527.750973696446, 4534.002519445717, 4546.704464209833, 4577.29051996941, 4397.252467080604, 4556.248219043854, 4586.0, 4586.105709386671, 4591.989762252316, 4309.159713855799, 4312.5903039211225, 4394.011347446831, 4423.225935461282, 4521.977390113028, 4334.436523251957, 4340.934885976713, 4366.519682834885, 4378.5183629800695, 4378.5183629800695, 4712.505186955389, 4723.338732577442, 4758.95241047592, 4770.928436073462, 4786.44955830077, 5019.824306149268, 5147.3051138096625, 5236.031334203185, 5284.260203571503, 5301.443348448396, 5274.129768588178, 5290.39160496544, 5291.1534154535475, 5325.760340784635, 5345.251664766935, 4979.950200497985, 5001.274815151783, 5034.874128146753, 5044.924326135111, 5049.444561098277, 5216.26972223889, 5282.81510147143, 5333.173358129496, 5422.40353561108, 5459.508644222039, 5201.063808514428, 5219.4780521947505, 5246.029484545371, 5258.39528454225, 5265.368155821326, 5349.759260833272, 5385.919211211835, 5404.189371594213, 5443.8094666686475, 5446.373667028263, 4295.613394794484, 5072.492750724898, 5165.199394093846, 5246.0, 5266.131088370453, 5308.946910530923, 5310.336207974004, 5322.467753224724, 5325.147976323847, 5382.838514844561, 4297.09760950204, 4313.503947046067, 4324.329728892027, 4367.759773212504, 4384.473863136444, 3719.3119272934055, 3773.5471743390967, 3802.6387493188104, 3817.331966905836, 3864.381698928158, 4713.128071306751, 4719.882002949885, 4738.1708201064675, 4746.833860814855, 4766.880827979328, 5299.5760339172775, 5332.0402327581405, 5332.653377530448, 5353.9196912046355, 5373.811916582901, 4610.377599024089, 4630.467496237909, 4681.391419115494, 4746.95253047472, 4751.83368582098, 4648.116857797076, 4681.250999840009, 4706.10583989041, 4707.599854012447, 4737.313089601988, 5536.612437129443, 5550.250716153349, 5579.525740312119, 5584.497395234448, 5600.635958662674, 5175.275461435477, 5226.764795584132, 5238.4237733850005, 5319.441458646941, 5341.065313570188, 4580.88547786308, 5123.0, 5285.286486324297, 5512.476314750093, 5517.337919449692, 5239.240310155083, 5255.395629502656, 5256.579473642023, 5259.21111833228, 5271.077561426811, 5318.4681531848055, 5320.06898792702, 5324.520793128637, 5353.625246232727, 5370.865728356823, 4882.145624515767, 4981.562721722, 5082.491750824888, 5123.692578445305, 5179.19722443022, 5183.870403239949, 5195.454477279831, 5200.5059519345605, 5208.374995000624, 5237.0835103857435, 4987.40151181846, 5072.061668591323, 5073.3911930568565, 5100.0, 5106.234064890147, 5006.9986002800315, 5141.33162688863, 5142.04872098663, 5211.0880595894805, 5252.290940722954, 5134.178531434953, 5135.820246291362, 5151.948480515147, 5152.9742351288005, 5169.095408303607], "value": 4855.923077254435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.7226192022865, 469.6501106675527, 490.44579625023715, 490.7104808163183, 499.9500049995, 29761.702382976164, 31636.0, 31855.044348669548, 31866.68133318666, 36840.447393289054, 36929.076773080684, 42623.360649590206, 42711.78644106783, 42806.57193428065, 42900.57099429005, 9269.536523173849, 9477.526123693822, 9477.668281610146, 9485.193758530533, 9526.523673816317, 7841.176676448977, 8367.497950123012, 8561.700340488083, 9339.673111441101, 9415.387999779992, 9913.603455861792, 9976.850347244797, 11251.718707032349, 11475.540978360897, 11590.884091159107, 11509.136814738928, 11545.307281563091, 11560.364179970098, 11567.305961642329, 11605.129615278804, 8300.750977470685, 11095.83356249657, 11258.605948791754, 11384.373859437728, 11385.601503947404, 39175.60824391748, 39506.20987580261, 39506.20987580261, 39657.60342396568, 39685.40471892895, 3203.1511649412923, 3264.167637252509, 3292.8682852685865, 3370.703022436688, 3650.0, 27719.0, 28566.143015709564, 28663.0, 28700.282492937637, 28756.856215718995, 2644.1010056580853, 2648.23201271631, 2654.721254268313, 2759.9172024839286, 2886.0, 513509.0, 520297.0, 524984.0, 657510.0, 663736.9999999952, 60908.69545652332, 61280.69359653263, 61664.0, 61760.69119654373, 61908.69045654743, 65858.67070664711, 65991.34008659948, 66103.66948165228, 66137.33862661407, 66251.66874165599, 16317.34730610786, 16570.254338554794, 16617.833821661872, 16777.664446710998, 16819.411320603966, 12642.178258413174, 12644.0, 12683.365831708377, 12686.302253376152, 12687.365631718561, 14331.498397556035, 15980.76028859568, 16097.022050920617, 16577.668446631, 18621.44135675932, 6733.0, 6736.360045795642, 7683.462157649023, 7947.205279472122, 7969.282764551102, 815.5188438821124, 822.8642274024719, 909.3949179697838, 948.9656274660653, 1016.506994107857, 26327.605085923726, 28372.14883553497, 28472.0, 28946.27634309159, 29335.7066429333, 1005.8088963096989, 1006.4967516241879, 1019.250850624805, 1052.7524883013652, 1110.4669758515959, 12374.938125309316, 14602.269886505632, 14608.85391146075, 14780.778288325684, 15068.849311506963, 672444.1178088074, 682411.0, 689215.0, 690585.0, 696633.0, 50306.0, 58368.8326233473, 58668.41331586715, 58773.412265876796, 58895.41104588985, 1254.4919307680404, 1303.2050449226153, 1303.8956883449112, 1403.6701375176826, 1438.0724432740824, 29063.709362906102, 29171.41657166802, 30030.69969300279, 30194.396112078073, 30935.535966960062, 52506.0, 53322.0, 53416.0, 53489.465105350006, 53675.463245368606, 575023.0, 579941.0, 580087.0, 580097.0, 580329.0, 33004.33991320208, 33356.166095847795, 38881.6111838878, 41080.0, 41081.97295067528, 35154.47268291029, 39031.609683902796, 43484.78257608692, 43568.34647480354, 45040.0, 30787.230319241302, 35470.64529354673, 35682.82158589191, 35821.46267806037, 36801.26397471982, 35056.47415288722, 35145.47281790724, 35415.46876796902, 35681.28637427185, 35757.82121089378, 35107.771228006895, 35469.29061418809, 35542.289154216254, 35822.641773581934, 36360.45459318059, 29902.102936911928, 30343.0, 32457.35085298328, 32464.188395290308, 32562.185945351557, 23544.0, 24080.87959560191, 24276.71211160434, 24374.390640234134, 24401.51196976015], "value": 19725.445140557877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.61485595556803, 360.6231488094941, 365.161953317137, 378.98105094745256, 387.17145309038665, 18939.526511837194, 20225.49436264094, 20875.37373878786, 21288.787112128874, 21299.68050479242, 9831.967643397447, 11819.40902954851, 12067.034637229022, 12069.396530173479, 12070.51717931284, 5078.289039534459, 5294.470552944713, 5344.412114667383, 5349.09065458871, 5352.866178345553, 3757.1546402059453, 4043.777592232426, 5594.804181853636, 5945.524358051345, 5963.910541341883, 5872.207252020965, 5958.821235362946, 5965.761369545213, 5978.103284507337, 5980.0, 5703.5152012079025, 5879.235699359098, 5906.114082887559, 5936.465718085371, 5944.583879128462, 3625.800580968046, 5226.817061402832, 5643.492085712305, 5661.179129026309, 5705.372409035002, 13346.865516431115, 13494.932525337408, 13564.846988006031, 13671.794923076162, 13732.65668358299, 2053.0, 2079.688046792978, 2104.7474303083577, 2180.4766855954617, 2562.192909233604, 9775.51122443875, 9813.705588832347, 9819.754506137404, 10111.140553053, 10115.443650599214, 1682.5204816627324, 1731.6796392667397, 1756.5608597850537, 1781.6436712657392, 1800.3698705453119, 120506.0, 120509.0, 124018.0, 128037.35981320127, 130198.0, 11019.889801102046, 11441.199116061962, 11485.827712584318, 11598.362090084958, 11896.286222826659, 14737.0, 14797.260136993107, 14820.407183712747, 14825.925870370795, 14856.331465084097, 8578.485290882567, 8602.827943441096, 8635.956820215859, 8669.566521674018, 8744.300455963508, 7161.498695091398, 8550.700725474579, 8602.698905538276, 8677.262432693165, 8722.781930451789, 4505.07645932583, 4584.679072464895, 4591.403117594691, 4614.377059097046, 4655.11552804966, 1472.8968972172058, 1788.9799224839965, 1811.1707175752585, 1909.961800763977, 1977.4463150317933, 735.7351353512731, 758.4652819762055, 825.8761185822175, 841.3521588376916, 864.3690106222493, 19630.920299383386, 20138.798612013987, 20320.796792032186, 20353.287634932705, 20594.794052059588, 833.6272722304689, 846.4794151596847, 849.7396924181647, 860.9741707748777, 861.8707193920836, 9004.954975225082, 10690.572377104983, 10771.78456430867, 10794.568217271379, 10801.78396432067, 556401.0, 566189.0, 568941.0, 580821.0, 586199.0, 118101.0, 125745.37127314304, 128514.0, 128690.0, 134718.3264083693, 950.8811398575316, 962.4417837654144, 986.225812736998, 1003.8544411060356, 1007.0131271353968, 17542.298308068046, 17581.91209043998, 17906.731399029286, 18268.15392196436, 18521.166547505127, 51812.740936296585, 51878.48121518736, 53085.40743777693, 53359.466405337, 53524.464755351946, 74773.626131869, 76911.53898074488, 79062.0, 79236.0, 79406.0, 7677.539347639159, 7811.336036436966, 7905.0, 7977.680892764457, 8161.265486106279, 9071.818563628822, 9826.852597211191, 12418.875811242133, 12596.307203103917, 12872.742545149233, 5980.132880732356, 10113.342632728849, 10353.0, 10720.0, 10721.35671859691, 7485.588292643957, 8832.470051796912, 10436.0, 10495.947520262349, 10630.58202961087, 11048.779024419306, 11167.553297868319, 11201.327920324806, 11248.212625116163, 11426.885731142915, 7828.138904720362, 7858.057033155829, 7868.25251601097, 8059.879101813361, 8080.71717489885, 10259.948700256451, 10891.18316126278, 11059.889401106208, 11121.388323642277, 11283.323000619988], "value": 8928.768660968433, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.2701154882127, 1504.9014219619678, 1516.4540765324484, 1730.6971280025991, 1897.3264491105665, 7831.921680783191, 8347.540885251308, 8350.749477515668, 8355.707550235744, 8359.164083591639, 8429.91570084299, 8753.518556479392, 8782.385233033674, 8880.689175878846, 8880.822383552326, 2344.495933374324, 2416.830821842472, 2424.8181386396004, 2426.3085020769086, 2427.927162185137, 2100.8004239597212, 2120.4698825293676, 2348.518553696488, 2388.6178211486167, 2401.2436082633913, 2369.0168580039262, 2394.3415560720855, 2405.338531903723, 2410.517896420714, 2412.107520217522, 2034.6744520876662, 2335.054303007285, 2342.7422983471806, 2384.332386931662, 2385.1651921827397, 2370.4429459077105, 2375.9287221383215, 2406.121765555577, 2407.8555286682854, 2412.0, 8645.481271123752, 8818.162274583905, 8821.51481668508, 8829.779255518599, 8837.248833849131, 4677.532246775295, 5177.326947496839, 5234.136367499358, 5407.486288802557, 5426.023315803154, 2863.871125799344, 3532.2405682778067, 3565.7682250653625, 3595.0, 3596.2088340565033, 994.955227014786, 1009.6617633092923, 1021.4892553723139, 1035.7669524357034, 1042.4943902207424, 10383.273170877967, 11909.821352679717, 11988.0, 12003.699907502294, 12037.398130093461, 4494.16857881293, 4615.838445654386, 4690.53094690535, 4699.78850951708, 4704.03567268716, 9205.585748641328, 9324.953375233215, 9331.860022099674, 9331.90668093324, 9350.90649093514, 5298.761555730001, 5304.4960728731485, 5306.469353064663, 5306.52241298285, 5313.362396512443, 4227.682923780729, 4404.405405270311, 4412.646988240933, 4923.458419573844, 4940.086084074458, 3221.5650887130405, 3821.2548553031893, 3852.7110466715108, 3876.980615096963, 3921.686265098785, 1170.1867202294325, 1179.8525184351956, 1182.9763404731857, 1193.40926241511, 1199.4062938845327, 588.4330867518559, 714.7927101140715, 781.4725060584083, 835.2733122183739, 877.2587163846539, 5618.543588106462, 5716.314042314949, 5956.255468066492, 5963.7912673056235, 6013.4888534474185, 864.8356812205786, 866.6793286484042, 882.580774132282, 909.60432211988, 909.713440266314, 3543.36219480496, 3624.5831729350934, 3653.6529029742132, 3680.926381472409, 3713.517242758424, 9647.1317581418, 10708.518116684769, 11031.724206894893, 11080.279781814157, 11119.555217791198, 9854.802903941882, 10564.0, 10840.024397804236, 10898.727531811768, 11095.83356249657, 6930.341617546325, 8131.14622964592, 8204.056533498604, 8204.425690201748, 8208.507489550526, 5137.537621614073, 5693.174489699053, 5707.885842283048, 5755.935151996894, 5794.0, 9951.900480995097, 10245.590176392861, 10280.331778434378, 10294.330868493524, 10300.381977081399, 9918.752031199278, 10007.999200079934, 10445.791084178425, 10458.320209186379, 10509.737256568647, 7022.192447868356, 7093.751718689821, 7187.892181617175, 7188.209296977224, 7214.386777123793, 8188.959055204686, 8336.499810011419, 8337.83324333522, 8345.95827020861, 8408.747737567883, 6655.334466553306, 6915.412189963909, 7381.0, 7544.8491030180185, 7627.0847498298335, 7233.3851622612665, 7400.222976587596, 7426.74006409773, 7494.5503269803985, 7663.693452261847, 6892.620905850025, 7000.334968178015, 7094.184168820445, 7095.751648692271, 7100.680469378994, 4713.057388522241, 4835.782389792397, 4866.245731911559, 4875.68308059975, 4876.975615121902, 4767.332573439652, 4865.635077369282, 4904.141775189399, 4943.184374578154, 5164.561012313995], "value": 4488.01122878355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.3006731488407, 262.7676198628434, 283.98722057507416, 297.52693217783724, 298.79681816364865, 3667.174885650734, 3687.8340474678635, 4120.670346372291, 4137.586241375865, 4154.27300222461, 3877.515310586177, 4057.2291264659766, 4058.107216412392, 4062.918741625173, 4064.2277967186287, 2899.4925887969625, 2959.0235222376673, 3204.1669166016886, 3204.2309845637114, 3233.5634689316935, 3033.5752994580776, 3184.1243657994005, 3228.3059142284405, 3233.1917020744813, 3246.3345014272018, 2400.4958958618713, 2722.1897438203573, 3125.95311070333, 3146.7954582952148, 3151.290959534098, 3180.918487714165, 3180.9681903181136, 3197.2486465680545, 3219.710226079664, 3264.7877887937325, 2251.5159240763314, 2785.8049936504453, 2827.0246764866088, 2836.801423900328, 2858.1711303721895, 3301.735861131104, 3794.0, 3801.600831912637, 3821.159344944108, 3831.923361532754, 3418.8974330770116, 3708.8331025103666, 3711.3505136601257, 3724.646158614927, 3726.757760745543, 4083.020075181965, 4165.479315085614, 4386.451693538308, 4398.516163222043, 4430.822767089313, 2787.0, 3039.1794215561895, 3174.3333899881054, 3183.997040932122, 3187.8087314761187, 3840.8847734568008, 4218.936715949294, 4576.679632425731, 4632.698874573142, 4634.490206077328, 3162.4623813951684, 3182.188541921795, 3209.21374263307, 3235.7573182011442, 3236.6925142111463, 2797.6011994002997, 3257.1857035741064, 3282.7373810095137, 3282.901512954615, 3286.7041966223155, 3532.5054492371087, 3549.982250088733, 3708.332500149999, 3713.2573485303046, 3763.6800871925643, 4055.1281474483126, 4477.888052798706, 4488.685791994529, 4489.528599497071, 4492.562380038422, 1105.7401510644993, 1107.4739498738036, 1128.5667202652749, 1131.2533727739653, 1132.64887884756, 582.825152454262, 702.5889854435212, 712.7113519024804, 755.8488302339554, 778.6807408962298, 3408.7102596279096, 3890.7628113834658, 3892.5328960524917, 3898.9610103899167, 3919.451276821247, 4428.269335559597, 4435.489918659331, 4444.910996805803, 4447.288433850633, 4447.7998490067375, 3339.3655205510877, 3406.9148271293416, 3765.3222419964122, 3902.434147048662, 3903.765774053566, 2175.6192666283337, 2311.8959646815933, 2327.3832434404894, 2353.9293821185393, 2372.7745864143226, 4033.576474470197, 4685.390899183084, 4711.929321060187, 4738.93856172566, 4775.307580400822, 4116.608922152391, 4648.767561621973, 4663.670853806649, 4691.46048204454, 4733.0, 3389.084947064328, 3831.214601006787, 4686.320483529801, 4710.29345598163, 4769.284607308931, 3580.427131658922, 3939.586343433898, 4155.631290164698, 4355.776725223375, 4566.4748553916725, 3595.838187281631, 4549.727016379028, 4558.954410455853, 4561.954380456153, 4623.884402889955, 4117.5882411758585, 4566.771661416982, 4740.928886066643, 4761.738104404291, 4782.450018247946, 3263.918402039968, 3270.8201048942537, 3298.7690861639453, 3302.7522935779434, 3303.4053870302614, 3739.0652336915773, 4249.490061192677, 4406.369643232025, 4512.571305725951, 4524.977375113103, 3489.476578513243, 3803.4484999674337, 3842.269968705937, 4348.978255108705, 4400.647948164204, 4197.307444271661, 4344.739315641072, 4367.410399596014, 4378.036831896978, 4384.342348647729, 3877.8284160870003, 3898.6686131679126, 4090.9772556860785, 4294.291441912174, 4308.159908817751, 3615.313090512794, 4180.26845302077, 4290.764007979466, 4429.867103986886, 4454.354118652842, 3680.8343624536424, 3959.980200098981, 4061.882982179924, 4260.573942605714, 4286.185624731291], "value": 3225.454707181298, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.4196368359268, 653.111767995526, 700.2927043685876, 726.614894106124, 742.4468770765781, 14859.479918202866, 15115.92442037791, 15170.317335719892, 15181.54455366338, 15197.012194207382, 13932.930335348334, 13948.302584870766, 13960.860391396083, 14000.0, 14032.71934561308, 695.4366962760155, 716.0655344775066, 718.1705130574195, 723.2297603052758, 724.474755802042, 864.3258258558303, 884.2395539835736, 888.1606881496988, 892.2193081054078, 894.0433735902587, 933.383966582056, 938.6526985015554, 940.8118376324728, 941.7174847545743, 941.8163458125669, 926.9490178040239, 927.0358826820099, 929.2426672262152, 931.9394239374419, 934.1545900959575, 677.6442367756918, 689.1454596300601, 689.5001124184976, 690.0546251635242, 694.121935751275, 11568.421578921105, 14464.566063018126, 14501.0, 14529.491467798576, 14531.418743250257, 11314.886851131465, 11823.172377933548, 12011.0, 12337.50649974, 12475.875241247653, 9434.952825235898, 9801.117899389017, 9844.26168037393, 10049.748756281151, 10141.695749127464, 3003.969960300391, 3365.326934613063, 3408.0, 3431.5024321473497, 3541.769784963969, 82475.17524824735, 82673.0, 84912.57543712242, 85299.29401411937, 85385.57307213487, 8915.0, 9221.0, 9225.769355766159, 9318.254539636813, 9424.670136545325, 21457.0, 21460.892695536735, 21467.463313416978, 21472.033758480906, 21497.92510374475, 7195.460340474486, 7255.492115551966, 7282.12614486265, 7414.962925185339, 7444.143923448764, 3792.3173828710546, 3863.613638636114, 3885.3681453789477, 3897.4738410314812, 4119.9176016479505, 3443.1564266754804, 3576.3741345264475, 3577.767445116059, 3579.4272916333784, 3580.337637537064, 551.6414330685087, 634.1280738983897, 642.2164958750317, 649.3798422506495, 654.1104098426136, 441.7437886026161, 498.6639382530929, 521.0568870344688, 542.4169018305338, 564.8627660468975, 7842.607869606497, 7848.018997625297, 8044.919550804534, 8083.272505474535, 8115.188481151956, 474.2625217786375, 550.7081246939129, 552.3316786688152, 557.6977756937515, 574.4252034009924, 3555.897671721723, 3799.1262009737916, 3813.1992281620614, 3833.3674943634546, 3879.1078052047624, 98337.50831245798, 98600.0, 98639.0, 101931.49034254781, 102160.0, 78439.0, 83804.0, 84237.57881210677, 85551.5722421384, 86842.13157868467, 62094.68952655208, 73683.0, 76548.08629784189, 76752.23247767563, 76989.07527311862, 7546.3962882968135, 7704.152543220129, 7755.612219389121, 7915.9208407915185, 8569.700060497853, 45196.77401612971, 45481.77259113683, 45517.31724024208, 45771.77114114408, 46787.53212467832, 45769.313460298785, 59668.70165649144, 61485.385146147964, 62859.0, 64675.35324646693, 10412.32319899204, 10849.349039057683, 10884.346939183675, 10960.671179864617, 11111.944440277746, 11363.295655563257, 11561.537538498702, 11566.884331156582, 11651.41742912868, 12154.75690486203, 10799.13606911461, 11120.944395277971, 11155.442227888736, 11211.733487336767, 11404.854910714073, 10945.0, 11044.668659940215, 11062.919731372089, 11133.443327833415, 11389.601363952223, 10728.409937453514, 10775.6228532001, 11022.559097636005, 11218.775624487627, 11243.988041076342, 7214.531055481377, 9071.0, 9125.954370228372, 9373.343865929592, 9469.895516650931, 8948.597313121056, 9034.412763170372, 9437.952810235905, 9614.615415383307, 9851.802963940538], "value": 6687.425481023957, "unit": "score"}], "name": "dromaeo_css", "value": 6602.006679729076, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 120.0, 163.0, 110.0, 151.0, 166.0, 157.0, 115.0, 114.0], "value": 131.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 123.0, 148.0, 202.0, 123.0, 162.0, 142.0, 124.0, 170.0, 125.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 215.0, 213.0, 214.0, 211.0, 216.0, 212.0, 209.0, 214.0, 214.0], "value": 212.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 58.0, 129.0, 58.0, 63.0, 59.0, 63.0, 114.0, 57.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 104.0, 137.0, 84.0, 110.0, 110.0, 136.0, 84.0, 83.0, 104.0], "value": 104.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 94.0, 90.0, 93.0, 90.0, 93.0, 91.0, 94.0, 89.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 99.0, 99.0, 98.0, 98.0, 98.0, 98.0, 98.0, 98.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 91.0, 146.0, 85.0, 154.0, 157.0, 119.0, 104.0, 106.0, 99.0], "value": 116.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 58.0, 57.0, 58.0, 58.0, 121.0, 59.0, 57.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 75.0, 76.0, 77.0, 75.0, 75.0, 76.0, 80.0, 75.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 87.0, 88.0, 92.0, 90.0, 88.0, 89.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 156.0, 139.0, 141.0, 144.0, 145.0, 142.0, 228.0, 146.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 180.0, 172.0, 172.0, 172.0, 170.0, 171.0, 172.0, 188.0, 217.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 68.0, 69.0, 78.0, 84.0, 84.0, 85.0, 74.0], "value": 74.9, "unit": "score"}], "name": "kraken", "value": 1622.0, "alertThreshold": 2.0}]} 21:20:27 INFO - Return code: 0 21:20:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:20:28 ERROR - # TBPL SUCCESS # 21:20:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:20:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:20:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:20:28 INFO - 2016-05-17 21:20:28.044 screenresolution[1683:10036] starting screenresolution argv=screenresolution get 21:20:28 INFO - 2016-05-17 21:20:28.047 screenresolution[1683:10036] Display 0: 1600x1200x32@60 21:20:28 INFO - 2016-05-17 21:20:28.053 screenresolution[1684:10039] starting screenresolution argv=screenresolution list 21:20:28 INFO - Available Modes on Display 0 21:20:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:20:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:20:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:20:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:20:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:20:28 INFO - Intel Iris: 21:20:28 INFO - Chipset Model: Intel Iris 21:20:28 INFO - Type: GPU 21:20:28 INFO - Bus: Built-In 21:20:28 INFO - VRAM (Dynamic, Max): 1536 MB 21:20:28 INFO - Vendor: Intel (0x8086) 21:20:28 INFO - Device ID: 0x0a2e 21:20:28 INFO - Revision ID: 0x0009 21:20:28 INFO - Displays: 21:20:28 INFO - Display: 21:20:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:20:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:20:28 INFO - Display Serial Number: 1600x1200 60 21:20:28 INFO - Main Display: Yes 21:20:28 INFO - Mirror: Off 21:20:28 INFO - Online: Yes 21:20:28 INFO - Rotation: Supported 21:20:28 INFO - Return code: 0 21:20:28 INFO - Running post-action listener: _resource_record_post_action 21:20:28 INFO - ##### 21:20:28 INFO - ##### Finished run-tests step (success) 21:20:28 INFO - ##### 21:20:28 INFO - Running post-run listener: _resource_record_post_run 21:20:28 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 31792640; Write bytes: 695308288; Read time: 321; Write time: 1978 21:20:28 INFO - TinderboxPrint: CPU usage
53.9% 21:20:28 INFO - TinderboxPrint: I/O read bytes / time
31,792,640 / 321 21:20:28 INFO - TinderboxPrint: I/O write bytes / time
695,308,288 / 1,978 21:20:28 INFO - TinderboxPrint: CPU idle
2,238.5 (46.1%) 21:20:28 INFO - TinderboxPrint: CPU system
975.4 (20.1%) 21:20:28 INFO - TinderboxPrint: CPU user
1,643.8 (33.8%) 21:20:28 INFO - TinderboxPrint: Swap in / out
423,153,664 / 0 21:20:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396253696; Write bytes: 398026240; Read time: 30737; Write time: 795 21:20:28 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30006784; Write bytes: 296434176; Read time: 302; Write time: 1182 21:20:28 INFO - Running post-run listener: _upload_blobber_files 21:20:28 INFO - Blob upload gear active. 21:20:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:20:28 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 21:20:28 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'] 21:20:28 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 21:20:29 INFO - (blobuploader) - INFO - Open directory for files ... 21:20:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:20:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:30 INFO - (blobuploader) - INFO - Done attempting. 21:20:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:20:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:20:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:31 INFO - (blobuploader) - INFO - Done attempting. 21:20:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:20:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:20:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:34 INFO - (blobuploader) - INFO - Done attempting. 21:20:34 INFO - (blobuploader) - INFO - Iteration through files over. 21:20:34 INFO - Return code: 0 21:20:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:20:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:20:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37581e48180bb98e64babc04473df3e4f89e94d38f1b2249b750ce1fa627f49deb46f90b298c3961aaceb14c1ec7d74070e122e043d41345206fdabeb56c69bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdff7bf0fefd32c6c400cef695472a5d09e362be5def372dfcc42f9b19ce1a43002247d3352d2a82b1aa0b48fc03a7c13c5a4dec209d1652e8afe7f1c18c6989", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c44bba91351c04a87866fbe153f9978a23147ef27ae680d87d294faf7330028410ec796819a0275c97fc5574c675ab53a732c09bf06ff8ca09e1a758a574923"} 21:20:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:20:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:20:34 INFO - Contents: 21:20:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37581e48180bb98e64babc04473df3e4f89e94d38f1b2249b750ce1fa627f49deb46f90b298c3961aaceb14c1ec7d74070e122e043d41345206fdabeb56c69bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdff7bf0fefd32c6c400cef695472a5d09e362be5def372dfcc42f9b19ce1a43002247d3352d2a82b1aa0b48fc03a7c13c5a4dec209d1652e8afe7f1c18c6989", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c44bba91351c04a87866fbe153f9978a23147ef27ae680d87d294faf7330028410ec796819a0275c97fc5574c675ab53a732c09bf06ff8ca09e1a758a574923"} 21:20:34 INFO - Running post-run listener: copy_logs_to_upload_dir 21:20:34 INFO - Copying logs to upload dir... 21:20:34 INFO - mkdir: /builds/slave/test/build/upload/logs 21:20:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.412846 ========= master_lag: 0.52 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-05-17 21:20:34.572238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 21:20:34.575521) ========= 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.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/37581e48180bb98e64babc04473df3e4f89e94d38f1b2249b750ce1fa627f49deb46f90b298c3961aaceb14c1ec7d74070e122e043d41345206fdabeb56c69bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdff7bf0fefd32c6c400cef695472a5d09e362be5def372dfcc42f9b19ce1a43002247d3352d2a82b1aa0b48fc03a7c13c5a4dec209d1652e8afe7f1c18c6989", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c44bba91351c04a87866fbe153f9978a23147ef27ae680d87d294faf7330028410ec796819a0275c97fc5574c675ab53a732c09bf06ff8ca09e1a758a574923"} build_url:https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011590 build_url: 'https://queue.taskcluster.net/v1/task/HSSwv-0_TX2fDR7Vw71_kg/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/37581e48180bb98e64babc04473df3e4f89e94d38f1b2249b750ce1fa627f49deb46f90b298c3961aaceb14c1ec7d74070e122e043d41345206fdabeb56c69bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdff7bf0fefd32c6c400cef695472a5d09e362be5def372dfcc42f9b19ce1a43002247d3352d2a82b1aa0b48fc03a7c13c5a4dec209d1652e8afe7f1c18c6989", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c44bba91351c04a87866fbe153f9978a23147ef27ae680d87d294faf7330028410ec796819a0275c97fc5574c675ab53a732c09bf06ff8ca09e1a758a574923"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 21:20:34.643250) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 21:20:34.643582) ========= 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.EKippMjEfp/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.JAlJuaKPY7/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.004785 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 21:20:34.664005) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 21:20:34.664289) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 21:20:34.664581) ========= ========= Total master_lag: 0.65 =========