chews 100% of the cpu

Bug #181047 reported by Sam Morris
2
Affects Status Importance Assigned to Milestone
nautilus (Ubuntu)
Invalid
Medium
Unassigned

Bug Description

I noticed that nautilus was consuming 100% of the CPU on one of my systems. Strace revealed that it was repeatedly writing to a log file:

write(10, "44:35.4805 (USER): debug log dum"..., 4096) = 4096
write(10, "R): debug log dumped due to sign"..., 4096) = 4096
write(10, "umped due to signal 11\n0x8177510"..., 4096) = 4096
write(10, "gnal 11\n0x8177510 2008/01/06 16:"..., 4096) = 4096
write(10, "10 2008/01/06 16:45:00.1946 (USE"..., 4096) = 4096
write(10, "6:45:07.1718 (USER): debug log d"..., 4096) = 4096
write(10, "SER): debug log dumped due to si"..., 4096) = 4096
write(10, " dumped due to signal 11\n0x81775"..., 4096) = 4096
write(10, "signal 11\n0x8177510 2008/01/06 1"..., 4096) = 4096
write(10, "7510 2008/01/06 16:45:32.2303 (U"..., 4096) = 4096
write(10, " 16:45:38.2322 (USER): debug log"..., 4096) = 4096
write(10, "(USER): debug log dumped due to "..., 4096) = 4096
write(10, "og dumped due to signal 11\n0x817"..., 4096) = 4096
write(10, "o signal 11\n0x8177510 2008/01/06"..., 4096) = 4096
write(10, "177510 2008/01/06 16:46:02.9146 "..., 4096) = 4096
write(10, "06 16:46:08.9526 (USER): debug l"..., 4096) = 4096
write(10, "2 (USER): debug log dumped due t"..., 4096) = 4096
write(10, " log dumped due to signal 11\n0x8"..., 4096) = 4096
write(10, " to signal 11\n0x8177510 2008/01/"..., 4096) = 4096
write(10, "x8177510 2008/01/06 16:46:33.618"..., 4096) = 4096
write(10, "1/06 16:46:39.6310 (USER): debug"..., 4096) = 4096
write(10, "443 (USER): debug log dumped due"..., 4096) = 4096
write(10, "ug log dumped due to signal 11\n0"..., 4096) = 4096
write(10, "ue to signal 11\n0x8177510 2008/0"..., 4096) = 4096
write(10, "\n0x8177510 2008/01/06 16:47:04.1"..., 4096) = 4096
write(10, "/01/06 16:47:10.1970 (USER): deb"..., 4096) = 4096
write(10, ".2160 (USER): debug log dumped d"..., 4096) = 4096
write(10, "ebug log dumped due to signal 11"..., 4096) = 4096
write(10, " due to signal 11\n0x8177510 2008"..., 4096) = 4096
write(10, "11\n0x8177510 2008/01/06 16:47:34"..., 4096) = 4096
write(10, "08/01/06 16:47:40.4326 (USER): d"..., 4096) = 4096
write(10, "46.4599 (USER): debug log dumped"..., 4096) = 4096
write(10, " debug log dumped due to signal "..., 4096) = 4096
write(10, "ed due to signal 11\n0x8177510 20"..., 4096) = 4096
write(10, "l 11\n0x8177510 2008/01/06 16:48:"..., 4096) = 4096
write(10, "2008/01/06 16:48:10.6632 (USER):"..., 4096) = 4096
write(10, "8:16.6870 (USER): debug log dump"..., 4096) = 4096
write(10, "): debug log dumped due to signa"..., 4096) = 4096
write(10, "mped due to signal 11\n0x8177510 "..., 4096) = 4096
write(10, "nal 11\n0x8177510 2008/01/06 16:4"..., 4096) = 4096
write(10, "0 2008/01/06 16:48:40.8921 (USER"..., 4096) = 4096
write(10, ":48:46.8930 (USER): debug log du"..., 4096) = 4096
write(10, "ER): debug log dumped due to sig"..., 4096) = 4096
write(10, "dumped due to signal 11\n0x817751"..., 4096) = 4096
write(10, "ignal 11\n0x8177510 2008/01/06 16"..., 4096) = 4096
write(10, "510 2008/01/06 16:49:12.1367 (US"..., 4096) = 4096
write(10, "16:49:18.1799 (USER): debug log "..., 4096) = 4096
write(10, "USER): debug log dumped due to s"..., 4096) = 4096
write(10, "g dumped due to signal 11\n0x8177"..., 4096) = 4096
write(10, " signal 11\n0x8177510 2008/01/06 "..., 4096) = 4096
write(10, "77510 2008/01/06 16:49:43.0788 ("..., 4096) = 4096
write(10, "6 16:49:49.4549 (USER): debug lo"..., 4096) = 4096
write(10, " (USER): debug log dumped due to"..., 4096) = 4096
write(10, "log dumped due to signal 11\n0x81"..., 4096) = 4096
write(10, "to signal 11\n0x8177510 2008/01/0"..., 4096) = 4096
write(10, "8177510 2008/01/06 16:50:15.3372"..., 4096) = 4096
write(10, "/06 16:50:21.7911 (USER): debug "..., 4096) = 4096
write(10, "30 (USER): debug log dumped due "..., 4096) = 4096
write(10, "g log dumped due to signal 11\n0x"..., 4096) = 4096
write(10, "e to signal 11\n0x8177510 2008/01"..., 4096) = 4096
write(10, "0x8177510 2008/01/06 16:50:46.91"..., 4096) = 4096
write(10, "01/06 16:50:53.2039 (USER): debu"..., 4096) = 4096
write(10, "4312 (USER): debug log dumped du"..., 4096) = 4096
write(10, "bug log dumped due to signal 11\n"..., 4096) = 4096
write(10, "due to signal 11\n0x8177510 2008/"..., 4096) = 4096
write(10, "1\n0x8177510 2008/01/06 16:51:18."..., 4096) = 4096
write(10, "8/01/06 16:51:25.2036 (USER): de"..., 4096) = 4096
write(10, "1.2458 (USER): debug log dumped "..., 4096) = 4096
write(10, "debug log dumped due to signal 1"..., 4096) = 4096
write(10, "d due to signal 11\n0x8177510 200"..., 4096) = 4096
write(10, " 11\n0x8177510 2008/01/06 16:51:4"..., 4096) = 4096
write(10, "008/01/06 16:51:56.4142 (USER): "..., 4096) = 4096
write(10, ":02.5025 (USER): debug log dumpe"..., 4096) = 4096
write(10, ": debug log dumped due to signal"..., 4096) = 4096
write(10, "ped due to signal 11\n0x8177510 2"..., 4096) = 4096
write(10, "al 11\n0x8177510 2008/01/06 16:52"..., 4096) = 4096
write(10, " 2008/01/06 16:52:27.6386 (USER)"..., 4096) = 4096
write(10, "52:33.9347 (USER): debug log dum"..., 4096) = 4096
write(10, "R): debug log dumped due to sign"..., 4096) = 4096
write(10, "umped due to signal 11\n0x8177510"..., 4096) = 4096
write(10, "gnal 11\n0x8177510 2008/01/06 16:"..., 4096) = 4096
write(10, "10 2008/01/06 16:52:59.2141 (USE"..., 4096) = 4096
write(10, "6:53:05.4165 (USER): debug log d"..., 4096) = 4096
write(10, "SER): debug log dumped due to si"..., 4096) = 4096
write(10, " dumped due to signal 11\n0x81775"..., 4096) = 4096
write(10, "signal 11\n0x8177510 2008/01/06 1"..., 4096) = 4096
write(10, "7510 2008/01/06 16:53:30.5623 (U"..., 4096) = 4096
write(10, " 16:53:36.9068 (USER): debug log"..., 4096) = 4096
write(10, "(USER): debug log dumped due to "..., 4096) = 4096
write(10, "og dumped due to signal 11\n0x817"..., 4096) = 4096
write(10, "o signal 11\n0x8177510 2008/01/06"..., 4096) = 4096
write(10, "177510 2008/01/06 16:54:02.5576 "..., 4096) = 4096
write(10, "06 16:54:08.7886 (USER): debug l"..., 4096) = 4096
write(10, "0 (USER): debug log dumped due t"..., 4096) = 4096
write(10, " log dumped due to signal 11\n0x8"..., 4096) = 4096
write(10, " to signal 11\n0x8177510 2008/01/"..., 4096) = 4096
write(10, "x8177510 2008/01/06 16:54:34.326"..., 4096) = 4096
write(10, "1/06 16:54:40.6510 (USER): debug"..., 4096) = 4096
write(10, "632 (USER): debug log dumped due"..., 4096) = 4096
write(10, "ug log dumped due to signal 11\n0"..., 4096) = 4096
write(10, "ue to signal 11\n0x8177510 2008/0"..., 4096) = 4096
write(10, "\n0x8177510 2008/01/06 16:55:07.6"..., 4096) = 4096
write(10, "/01/06 16:55:13.9904 (USER): deb"..., 4096) = 4096
write(10, ".2826 (USER): debug log dumped d"..., 4096) = 4096
write(10, "ebug log dumped due to signal 11"..., 4096) = 4096
write(10, " due to signal 11\n0x8177510 2008"..., 4096) = 4096
write(10, "11\n0x8177510 2008/01/06 16:55:38"..., 4096) = 4096
write(10, "08/01/06 16:55:45.1515 (USER): d"..., 4096) = 4096
write(10, "51.4693 (USER): debug log dumped"..., 4096) = 4096
write(10, " debug log dumped due to signal "..., 4096) = 4096
write(10, "ed due to signal 11\n0x8177510 20"..., 4096) = 4096
write(10, "l 11\n0x8177510 2008/01/06 16:56:"..., 4096) = 4096
write(10, "2008/01/06 16:56:17.0266 (USER):"..., 4096) = 4096
write(10, "6:24.1343 (USER): debug log dump"..., 4096) = 4096
write(10, "): debug log dumped due to signa"..., 4096) = 4096
write(10, "mped due to signal 11\n0x8177510 "..., 4096) = 4096
write(10, "nal 11\n0x8177510 2008/01/06 16:5"..., 4096) = 4096
write(10, "0 2008/01/06 16:56:49.9008 (USER"..., 4096) = 4096
write(10, ":56:57.1566 (USER): debug log du"..., 4096) = 4096
write(10, "ER): debug log dumped due to sig"..., 4096) = 4096
write(10, "dumped due to signal 11\n0x817751"..., 4096) = 4096
write(10, "ignal 11\n0x8177510 2008/01/06 16"..., 4096) = 4096
write(10, "510 2008/01/06 16:57:21.9049 (US"..., 4096) = 4096
write(10, "16:57:28.0619 (USER): debug log "..., 4096) = 4096
write(10, "USER): debug log dumped due to s"..., 4096) = 4096
write(10, "g dumped due to signal 11\n0x8177"..., 4096) = 4096
write(10, " signal 11\n0x8177510 2008/01/06 "..., 4096) = 4096
write(10, "77510 2008/01/06 16:57:52.8382 ("..., 4096) = 4096
write(10, "6 16:57:59.0126 (USER): debug lo"..., 4096) = 4096
write(10, " (USER): debug log dumped due to"..., 4096) = 4096
write(10, "log dumped due to signal 11\n0x81"..., 4096) = 4096
write(10, "to signal 11\n0x8177510 2008/01/0"..., 4096) = 4096
write(10, "8177510 2008/01/06 16:58:29.3943"..., 4096) = 4096
write(10, "/06 16:58:37.5870 (USER): debug "..., 4096) = 4096
write(10, "79 (USER): debug log dumped due "..., 4096) = 4096
write(10, "g log dumped due to signal 11\n0x"..., 4096) = 4096
write(10, "e to signal 11\n0x8177510 2008/01"..., 4096) = 4096
write(10, "0x8177510 2008/01/06 16:59:41.11"..., 4096) = 4096
write(10, "01/06 17:00:04.2667 (USER): debu"..., 4096) = 4096
write(10, "2087 (USER): debug log dumped du"..., 4096) = 4096
write(10, "bug log dumped due to signal 11\n"..., 4096) = 4096
write(10, "due to signal 11\n0x8177510 2008/"..., 4096) = 4096
write(10, "1\n0x8177510 2008/01/06 17:00:53."..., 4096) = 4096
write(10, "8/01/06 17:01:00.8434 (USER): de"..., 4096) = 4096
write(10, "2.2043 (USER): debug log dumped "..., 4096) = 4096
write(10, "debug log dumped due to signal 1"..., 4096) = 4096
write(10, "d due to signal 11\n0x8177510 200"..., 4096) = 4096
write(10, " 11\n0x8177510 2008/01/06 17:01:3"..., 4096) = 4096
write(10, "008/01/06 17:01:44.6077 (USER): "..., 4096) = 4096
write(10, ":51.6250 (USER): debug log dumpe"..., 4096) = 4096
write(10, ": debug log dumped due to signal"..., 4096) = 4096
write(10, "ped due to signal 11\n0x8177510 2"..., 4096) = 4096
write(10, "al 11\n0x8177510 2008/01/06 17:02"..., 4096) = 4096
write(10, " 2008/01/06 17:02:20.2898 (USER)"..., 4096) = 4096
write(10, "02:28.6357 (USER): debug log dum"..., 4096) = 4096
write(10, "R): debug log dumped due to sign"..., 4096) = 4096
write(10, "umped due to signal 11\n0x8177510"..., 4096) = 4096
write(10, "gnal 11\n0x8177510 2008/01/06 17:"..., 4096) = 4096
write(10, "10 2008/01/06 17:02:58.4874 (USE"..., 4096) = 4096
write(10, "7:03:08.1856 (USER): debug log d"..., 4096) = 4096
write(10, "SER): debug log dumped due to si"..., 4096) = 4096
write(10, " dumped due to signal 11\n0x81775"..., 4096) = 4096

The file was:

l-wx------ 1 user user 64 2008-01-07 15:56 /proc/17263/fd/10 -> /home/user/nautilus-debug-log.txt

Killing nautilus caused the file to be deleted.

Revision history for this message
Sam Morris (yrro) wrote :

Killing nautilus seemed to cause the rest of the user's processes to disappear, so it looks like they had already logged off, and the nautilus process hung around because of this error.

Revision history for this message
Sebastien Bacher (seb128) wrote :

Thanks for the bug report. This particular bug has already been reported, but feel free to report any other bugs you find. The looping bug has been workarounded in hardy by disabling the logging code, the crash is still likely due to a real bug but there is no useful details about it there

Changed in nautilus:
importance: Undecided → Medium
status: New → Invalid
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.