[Pidgin] #14679: 100% cpu usage

Pidgin trac at pidgin.im
Mon Oct 17 22:26:19 EDT 2011


#14679: 100% cpu usage
----------------------+-----------------------------------------------------
 Reporter:  zyounker  |        Owner:  rekkanoryo  
     Type:  defect    |       Status:  new         
Milestone:            |    Component:  unclassified
  Version:  2.8.0     |   Resolution:              
 Keywords:            |  
----------------------+-----------------------------------------------------
Description changed by QuLogic:

Old description:

> I've been experiencing an issue with pidgin on ubuntu 11.04 for a while
> now.. I decided to download and compile my a newer version. I just did
> this with 2.8.0. I setup a single AOL account and am seeing the same
> issue.
>
> Debug does not say anything.. the cpu usage starts a while after the
> "dns[18825]: nobody needs me" line.
>
> Debug:
>
> (14:12:01) certificate/x509/tls_cached: Starting verify for
> bos.oscar.aol.com
> (14:12:01) certificate/x509/tls_cached: Checking for cached cert...
> (14:12:01) certificate/x509/tls_cached: ...Found cached cert
> (14:12:01) nss/x509: Loading certificate from
> /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
> (14:12:01) certificate/x509/tls_cached: Peer cert matched cached
> (14:12:01) nss/x509: Exporting certificate to
> /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
> (14:12:01) util: Writing file
> /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
> (14:12:01) certificate: Successfully verified certificate for
> bos.oscar.aol.com
> (14:12:01) oscar: connected to FLAP server of type 0x000d
> (14:12:01) oscar: FLAP connection of type 0x0010 is now fully connected
> (14:12:01) oscar: no more icons to request
> (14:12:01) oscar: FLAP connection of type 0x000d is now fully connected
> (14:12:01) oscar: chat info: Max Concurrent Rooms: 17, Exchange List (15
> total): 2, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 20,
> (14:12:02) util: Writing file prefs.xml to directory
> /home/zyounker/.purple
> (14:12:02) util: Writing file /home/zyounker/.purple/prefs.xml
> (14:12:02) util: Writing file accounts.xml to directory
> /home/zyounker/.purple
> (14:12:02) util: Writing file /home/zyounker/.purple/accounts.xml
> (14:12:03) util: Writing file blist.xml to directory
> /home/zyounker/.purple
> (14:12:03) util: Writing file /home/zyounker/.purple/blist.xml
> (14:12:08) oscar: rate limit cleared (param ID 0x0003): curavg = 3245,
> maxavg = 4500, alert at 2500, clear warning at 3100, limit at 2000,
> disconnect at 1500, delta is 7380, dropping is 0 (window size = 20)
> dns[18825]: nobody needs me... =(
> ^C(14:13:04) sighandler: Caught signal 2
>

> strace of what is happening when pidgin starts using 100% cpu. After that
> the same EAGAIN message repeats continuously:
>
> fstat64(9, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
> fcntl64(9, F_GETFL)                     = 0x802 (flags O_RDWR|O_NONBLOCK)
> send(9, "\27\3\1\0
> E\351)\221\20\303y^\236\3\307~\261RE\200\35\266\243x)u9X\221\345\30"...,
> 37, 0) = 37
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> time(NULL)                              = 1318885980
> read(7, 0x97a0a70, 4096)                = -1 EAGAIN (Resource temporarily
> unavailable)
> clock_gettime(CLOCK_MONOTONIC, {169424, 440677600}) = 0
> poll([{fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=10,
> events=POLLIN}, {fd=17, events=POLLIN}, {fd=18, events=POLLIN}, {fd=16,
> events=POLLIN}, {fd=8, events=POLLIN}, {fd=20, events=POLLIN}, {fd=23,
> events=POLLIN}, {fd=21, events=POLLIN}, {fd=9, events=POLLIN}, {fd=4,
> events=POLLIN}, {fd=23, events=POLLOUT}, {fd=21, events=POLLOUT}, {fd=20,
> events=POLLOUT}, {fd=9, events=POLLOUT}], 16, 27991) = 4 ([{fd=23,
> revents=POLLOUT}, {fd=21, revents=POLLOUT}, {fd=20, revents=POLLOUT},
> {fd=9, revents=POLLOUT}])
> clock_gettime(CLOCK_MONOTONIC, {169424, 441100838}) = 0
> recv(20, 0x9f6ff57, 10746, 0)           = -1 EAGAIN (Resource temporarily
> unavailable)
> read(7, 0x97a0a70, 4096)                = -1 EAGAIN (Resource temporarily
> unavailable)
>

> Let me know if there is any other info i can provide.
>
> Thanks,
>   -Zach

New description:

 I've been experiencing an issue with pidgin on ubuntu 11.04 for a while
 now.. I decided to download and compile my a newer version. I just did
 this with 2.8.0. I setup a single AOL account and am seeing the same
 issue.

 Debug does not say anything.. the cpu usage starts a while after the
 "dns[18825]: nobody needs me" line.

 Debug:
 {{{
 (14:12:01) certificate/x509/tls_cached: Starting verify for
 bos.oscar.aol.com
 (14:12:01) certificate/x509/tls_cached: Checking for cached cert...
 (14:12:01) certificate/x509/tls_cached: ...Found cached cert
 (14:12:01) nss/x509: Loading certificate from
 /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
 (14:12:01) certificate/x509/tls_cached: Peer cert matched cached
 (14:12:01) nss/x509: Exporting certificate to
 /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
 (14:12:01) util: Writing file
 /home/zyounker/.purple/certificates/x509/tls_peers/bos.oscar.aol.com
 (14:12:01) certificate: Successfully verified certificate for
 bos.oscar.aol.com
 (14:12:01) oscar: connected to FLAP server of type 0x000d
 (14:12:01) oscar: FLAP connection of type 0x0010 is now fully connected
 (14:12:01) oscar: no more icons to request
 (14:12:01) oscar: FLAP connection of type 0x000d is now fully connected
 (14:12:01) oscar: chat info: Max Concurrent Rooms: 17, Exchange List (15
 total): 2, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 20,
 (14:12:02) util: Writing file prefs.xml to directory
 /home/zyounker/.purple
 (14:12:02) util: Writing file /home/zyounker/.purple/prefs.xml
 (14:12:02) util: Writing file accounts.xml to directory
 /home/zyounker/.purple
 (14:12:02) util: Writing file /home/zyounker/.purple/accounts.xml
 (14:12:03) util: Writing file blist.xml to directory
 /home/zyounker/.purple
 (14:12:03) util: Writing file /home/zyounker/.purple/blist.xml
 (14:12:08) oscar: rate limit cleared (param ID 0x0003): curavg = 3245,
 maxavg = 4500, alert at 2500, clear warning at 3100, limit at 2000,
 disconnect at 1500, delta is 7380, dropping is 0 (window size = 20)
 dns[18825]: nobody needs me... =(
 ^C(14:13:04) sighandler: Caught signal 2
 }}}

 strace of what is happening when pidgin starts using 100% cpu. After that
 the same EAGAIN message repeats continuously:
 {{{
 fstat64(9, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
 fcntl64(9, F_GETFL)                     = 0x802 (flags O_RDWR|O_NONBLOCK)
 send(9, "\27\3\1\0
 E\351)\221\20\303y^\236\3\307~\261RE\200\35\266\243x)u9X\221\345\30"...,
 37, 0) = 37
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 time(NULL)                              = 1318885980
 read(7, 0x97a0a70, 4096)                = -1 EAGAIN (Resource temporarily
 unavailable)
 clock_gettime(CLOCK_MONOTONIC, {169424, 440677600}) = 0
 poll([{fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=10,
 events=POLLIN}, {fd=17, events=POLLIN}, {fd=18, events=POLLIN}, {fd=16,
 events=POLLIN}, {fd=8, events=POLLIN}, {fd=20, events=POLLIN}, {fd=23,
 events=POLLIN}, {fd=21, events=POLLIN}, {fd=9, events=POLLIN}, {fd=4,
 events=POLLIN}, {fd=23, events=POLLOUT}, {fd=21, events=POLLOUT}, {fd=20,
 events=POLLOUT}, {fd=9, events=POLLOUT}], 16, 27991) = 4 ([{fd=23,
 revents=POLLOUT}, {fd=21, revents=POLLOUT}, {fd=20, revents=POLLOUT},
 {fd=9, revents=POLLOUT}])
 clock_gettime(CLOCK_MONOTONIC, {169424, 441100838}) = 0
 recv(20, 0x9f6ff57, 10746, 0)           = -1 EAGAIN (Resource temporarily
 unavailable)
 read(7, 0x97a0a70, 4096)                = -1 EAGAIN (Resource temporarily
 unavailable)
 }}}

 Let me know if there is any other info i can provide.

 Thanks,
   -Zach

--

-- 
Ticket URL: <http://developer.pidgin.im/ticket/14679#comment:1>
Pidgin <http://pidgin.im>
Pidgin


More information about the Tracker mailing list