Ticket #561 (in_testing defect)
Call status indication sometimes doesn't report a released call (aka does CLCC confuse Calypso? -- most probably not)
| Reported by: | PaulFertser | Owned by: | mickey |
|---|---|---|---|
| Priority: | major | Milestone: | |
| Component: | cornucopia/fsogsm | Version: | milestone5.5 |
| Keywords: | Cc: |
Description
I had a phone conversation on FR, then the other party released the call but i never got the signal, the logs indeed show that there was no %CPI or any other clue for the fsogsmd. "FsoGsmGenericAtCallHandler? <>: Synchronizing call status" continued forever until i killed fsogsmd.
2010-05-13T16:45:07.045521Z [DEBUG] FsoGsmGenericAtCallHandler <>: 1 calls known in the system 2010-05-13T16:45:10.012085Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:10.020156Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-05-13T16:45:10.033962Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 9 bytes 2010-05-13T16:45:10.040750Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 62 bytes 2010-05-13T16:45:10.044377Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 11 bytes 2010-05-13T16:45:10.049014Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "+CLCC: 1,1,0,0,0,"+79629034288",145,"004D006F0 074006800650072"", "OK" ] 2010-05-13T16:45:10.050329Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-05-13T16:45:10.051211Z [DEBUG] FsoGsmGenericAtCallHandler <>: 1 calls known in the system 2010-05-13T16:45:13.008344Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:13.014310Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-05-13T16:45:16.023406Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:19.008297Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:22.009203Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:25.008293Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:28.008888Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:31.008279Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:34.012997Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:37.009843Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:40.012604Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:43.008426Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:46.020229Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:49.008318Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:52.008318Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:53.536018Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 24 bytes 2010-05-13T16:45:53.537445Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 8, 99, 0" ] 2010-05-13T16:45:53.538334Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 8, 99, 0' 2010-05-13T16:45:53.539363Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-05-13T16:45:55.003503Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:45:58.010493Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:01.008314Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:04.010919Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:07.008317Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:09.854292Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 17 bytes 2010-05-13T16:46:09.869130Z [DEBUG] libfsotransport <Serial /dev/ttySAC0@115200 (fd 30)>: read 8 bytes 2010-05-13T16:46:09.871935Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 11, 99, 1" ] 2010-05-13T16:46:09.872648Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 11, 99, 1' 2010-05-13T16:46:09.873910Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-05-13T16:46:10.003319Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:13.010862Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:16.025089Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:19.009895Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-05-13T16:46:22.013569Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status
Attachments
-
fsogsmd.core.3793.bz2
(208.9 KB) -
added by PaulFertser 20 months ago.
-
fsogsmd
(14.4 KB) -
added by PaulFertser 20 months ago.
-
fsogsmd.clcc-gone-wild.log
(278.7 KB) -
added by PaulFertser 19 months ago.
Change History
comment:1 Changed 20 months ago by PaulFertser
- Summary changed from Call status indication sometimes doesn't report a released call (aka does CLCC confuse Calypso?) to Call status indication sometimes doesn't report a released call (aka does CLCC confuse Calypso? -- most probably not)
comment:2 Changed 20 months ago by PaulFertser
I think i have an impression i got that when i tried to hangup the call at the ~same time the other party hanged up (and i got some CME error reported via dbus).
comment:3 Changed 20 months ago by PaulFertser
Got this bug again after trying to Release() a call. I confirm that it doesn't respond to dbus anymore.
(gdb) bt full #0 0x40653464 in poll () from /lib/libc.so.6 No symbol table info available. #1 0x4050c8f0 in g_poll () from /lib/libglib-2.0.so.0 No symbol table info available. #2 0x404ff5d0 in ?? () from /lib/libglib-2.0.so.0 No symbol table info available. Cannot access memory at address 0xbb2 #3 0x404ff5d0 in ?? () from /lib/libglib-2.0.so.0 No symbol table info available. Cannot access memory at address 0xbb2 Backtrace stopped: previous frame identical to this frame (corrupt stack?)
cornucopia version 3dc9d7ebde32d8c29c901b52f7d3549b774b068b.
comment:4 Changed 19 months ago by PaulFertser
The Reality strikes back. CLCC gone wild. Everything misbehaves. Does this log show there should be a per-channel at parser? Does this log show status updater should be reworked somehow? I have no idea :/
2010-07-13T17:03:35.037733Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:03:35.038624Z [DEBUG] FsoGsmGenericAtCallHandler <>: 1 calls known in the system 2010-07-13T17:03:38.011568Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:38.016197Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:03:38.043531Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "+CLCC: 1,1,0,0,0,"+79035945921",145,"004600610074006800650072"", "OK" ] 2010-07-13T17:03:38.044869Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:03:38.045976Z [DEBUG] FsoGsmGenericAtCallHandler <>: 1 calls known in the system 2010-07-13T17:03:41.007634Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:41.013645Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:03:41.042176Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "+CLCC: 1,1,0,0,0,"+79035945921",145,"004600610074006800650072"", "OK" ] 2010-07-13T17:03:41.043507Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:03:41.044395Z [DEBUG] FsoGsmGenericAtCallHandler <>: 1 calls known in the system 2010-07-13T17:03:44.009952Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:44.018000Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:03:47.006523Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:50.010001Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:53.006255Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:56.008382Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:03:59.007762Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:02.010046Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:05.007338Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:08.010023Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:11.007023Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:12.844980Z [INFO] libfsotransport <0710:3>: URC: [ "%CPI: 1,1,0,1,1,0,"+79035945921",145,"004600610074006800650072",16,0" ] 2010-07-13T17:04:12.845997Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CPI', '1,1,0,1,1,0,"+79035945921",145,"004600610074006800650072",16,0' 2010-07-13T17:04:13.020278Z [INFO] libfsotransport <0710:3>: URC: [ "%CPI: 1,7,0,0,,,,,,,0" ] 2010-07-13T17:04:13.021468Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CPI', '1,7,0,0,,,,,,,0' 2010-07-13T17:04:14.006260Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:14.538890Z [INFO] libfsotransport <0710:3>: URC: [ "+CGREG: 1,"8CA3","287A"" ] 2010-07-13T17:04:14.541329Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CGREG', '1,"8CA3","287A"' 2010-07-13T17:04:14.552364Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","287A"" ] 2010-07-13T17:04:14.553072Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","287A"' 2010-07-13T17:04:16.722400Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 18, 99, 2" ] 2010-07-13T17:04:16.724895Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 18, 99, 2' 2010-07-13T17:04:16.726113Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:04:17.001997Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:20.010157Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:23.006670Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:26.008414Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:29.006245Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:29.437490Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 15, 99, 1" ] 2010-07-13T17:04:29.441586Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 15, 99, 1' 2010-07-13T17:04:29.442595Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:04:32.009442Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:35.006374Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:35.532869Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","9EB8"" ] 2010-07-13T17:04:35.533601Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","9EB8"' 2010-07-13T17:04:38.008456Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:41.007785Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:44.008424Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:47.006235Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:50.009997Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:53.006249Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:56.008425Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:04:59.006384Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:02.009935Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:05.006206Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:08.011652Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:11.007221Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:14.010151Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:17.006246Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:19.354100Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 18, 99, 2" ] 2010-07-13T17:05:19.354931Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 18, 99, 2' 2010-07-13T17:05:19.357695Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:05:20.006254Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:23.009007Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:23.587467Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 15, 99, 1" ] 2010-07-13T17:05:23.589773Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 15, 99, 1' 2010-07-13T17:05:23.592544Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:05:26.007305Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:29.006234Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:32.008447Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:35.006202Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:38.010018Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:38.098247Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","17C5"" ] 2010-07-13T17:05:38.098956Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","17C5"' 2010-07-13T17:05:41.006362Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:43.579158Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 21, 99, 2" ] 2010-07-13T17:05:43.579991Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 21, 99, 2' 2010-07-13T17:05:43.581169Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:05:44.006468Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:47.006226Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:50.011531Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:53.006261Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:53.492287Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","373E"" ] 2010-07-13T17:05:53.492980Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","373E"' 2010-07-13T17:05:56.011787Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:05:56.138827Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 13, 99, 1" ] 2010-07-13T17:05:56.139538Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 13, 99, 1' 2010-07-13T17:05:56.140515Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:05:59.006234Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:02.010225Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:05.006243Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:06.342496Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","17C1"" ] 2010-07-13T17:06:06.343313Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","17C1"' 2010-07-13T17:06:08.006260Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:10.403280Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 28, 99, 3" ] 2010-07-13T17:06:10.404200Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 28, 99, 3' 2010-07-13T17:06:10.405181Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:06:11.006256Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:14.007834Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:17.007349Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:20.008430Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:23.007544Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:26.009945Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:29.006254Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:32.008407Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:35.006212Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:35.822552Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 25, 99, 2" ] 2010-07-13T17:06:35.823264Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 25, 99, 2' 2010-07-13T17:06:35.824245Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:06:38.006812Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:41.006444Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:44.008579Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:47.009261Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:50.008438Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:53.006202Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:56.009992Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:06:59.008408Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:01.249058Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 16, 99, 1" ] 2010-07-13T17:07:01.249865Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 16, 99, 1' 2010-07-13T17:07:01.252664Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:07:02.006275Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:05.007814Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:06.602234Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","17C3"" ] 2010-07-13T17:07:06.604605Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","17C3"' 2010-07-13T17:07:08.007123Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:08.308523Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 25, 99, 2" ] 2010-07-13T17:07:08.309246Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 25, 99, 2' 2010-07-13T17:07:08.310221Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:07:11.007808Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:14.008408Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:17.008987Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:20.008743Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:23.006204Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:26.009618Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:29.007811Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:32.009981Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:35.007805Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:38.008416Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:41.009089Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:44.008394Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:47.006249Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:50.009981Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:53.006331Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:56.011629Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:07:59.006254Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:02.011631Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:05.006253Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:08.008584Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:11.007783Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:14.008684Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:17.006214Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:20.008618Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:23.006215Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:26.009960Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:29.007813Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:32.008440Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:35.008762Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:38.008408Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:41.006235Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:44.010571Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:47.006249Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:50.011934Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:53.006609Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:56.009693Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:08:59.006220Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:02.009630Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:05.006238Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:08.010019Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:11.007812Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:14.008387Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:17.006249Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:20.010140Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:23.006252Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:26.011776Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:29.007809Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:32.009696Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:35.006207Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:38.009978Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:41.006880Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:44.008685Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:47.009365Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:49.317372Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 16, 99, 1" ] 2010-07-13T17:09:49.321408Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 16, 99, 1' 2010-07-13T17:09:49.322446Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:09:50.006627Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:53.006248Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:56.011684Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:09:59.006248Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:02.007861Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:05.007405Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:08.008580Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:11.006208Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:14.008709Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:17.006241Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:20.011937Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:23.007573Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:26.009962Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:29.006242Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:30.985996Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA2","28DD"" ] 2010-07-13T17:10:30.986798Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA2","28DD"' 2010-07-13T17:10:31.497917Z [INFO] libfsotransport <0710:3>: URC: [ "+CGREG: 1,"8CA2","28DD"" ] 2010-07-13T17:10:31.498728Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CGREG', '1,"8CA2","28DD"' 2010-07-13T17:10:32.009324Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:35.006656Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:38.009719Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:41.006220Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:44.010611Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:47.006981Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:50.008671Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:53.006218Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:54.913333Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA2","DEB3"" ] 2010-07-13T17:10:54.914164Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA2","DEB3"' 2010-07-13T17:10:56.006306Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:10:57.548458Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 23, 99, 2" ] 2010-07-13T17:10:57.549279Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 23, 99, 2' 2010-07-13T17:10:57.550255Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:10:59.008798Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:01.783734Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 26, 99, 3" ] 2010-07-13T17:11:01.784577Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 26, 99, 3' 2010-07-13T17:11:01.785540Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:11:02.001210Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:05.007492Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:08.011629Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:11.007814Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:14.010321Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:17.006236Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:20.011685Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:22.974133Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 24, 99, 2" ] 2010-07-13T17:11:22.975055Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 24, 99, 2' 2010-07-13T17:11:22.976268Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:11:23.007634Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:26.009358Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:28.809024Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA2","DEB5"" ] 2010-07-13T17:11:28.809976Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA2","DEB5"' 2010-07-13T17:11:29.006089Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:32.009940Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:32.857441Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 29, 99, 3" ] 2010-07-13T17:11:32.858151Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 29, 99, 3' 2010-07-13T17:11:32.862519Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:11:35.009247Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:38.008412Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:41.007565Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:44.011440Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:45.567418Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 25, 99, 2" ] 2010-07-13T17:11:45.568234Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 25, 99, 2' 2010-07-13T17:11:45.569215Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:11:47.006590Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:50.010304Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:51.407443Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA2","DEB4"" ] 2010-07-13T17:11:51.408262Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA2","DEB4"' 2010-07-13T17:11:53.006221Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:56.011007Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:11:59.006428Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:02.008414Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:05.007781Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:08.011646Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:11.007404Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:12.402437Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 15, 99, 1" ] 2010-07-13T17:12:12.403265Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 15, 99, 1' 2010-07-13T17:12:12.404246Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:12:14.006249Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:17.006246Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:18.077854Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA2","A5F3"" ] 2010-07-13T17:12:18.078673Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA2","A5F3"' 2010-07-13T17:12:20.008884Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:22.127919Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 21, 99, 2" ] 2010-07-13T17:12:22.128833Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 21, 99, 2' 2010-07-13T17:12:22.129829Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:12:23.006236Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:26.008436Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:29.009310Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:32.008403Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:35.006240Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:38.011528Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:41.006247Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:44.010291Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:47.006256Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:50.008425Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:53.006212Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:56.007643Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:12:59.006253Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:02.011755Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:04.500572Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 15, 99, 1" ] 2010-07-13T17:13:04.501647Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 15, 99, 1' 2010-07-13T17:13:04.506839Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:13:05.007252Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:08.008443Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:11.007767Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:14.008957Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:17.006226Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:20.011484Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:23.006227Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:26.009023Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:29.006762Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:32.011651Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:35.006229Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:38.010542Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:41.006240Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:44.008404Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:47.006247Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:50.027904Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:53.006216Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:56.010001Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:58.873319Z [INFO] libfsotransport <0710:3>: URC: [ "+CREG: 1,"8CA3","A8A3"" ] 2010-07-13T17:13:58.874313Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CREG', '1,"8CA3","A8A3"' 2010-07-13T17:13:59.001245Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:13:59.378798Z [INFO] libfsotransport <0710:3>: URC: [ "+CGREG: 1,"8CA3","A8A3"" ] 2010-07-13T17:13:59.379505Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '+CGREG', '1,"8CA3","A8A3"' 2010-07-13T17:14:02.008408Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:04.071023Z [INFO] libfsotransport <0710:3>: URC: [ "%CSQ: 27, 99, 3" ] 2010-07-13T17:14:04.071924Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited '%CSQ', ' 27, 99, 3' 2010-07-13T17:14:04.072909Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for TiCalypsoPercentCSQ 2010-07-13T17:14:05.006235Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:08.007826Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:11.006204Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:14.010254Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:17.007668Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:20.009930Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:23.006226Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:26.009459Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:29.006207Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:32.010025Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:35.007436Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:38.009982Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:41.006239Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:44.010635Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:47.006256Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:50.008657Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:53.007007Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:56.011649Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:14:59.006442Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:02.010026Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:05.006240Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:08.011133Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:11.006251Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:14.008426Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:17.009108Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:20.008408Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:23.006238Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:26.011703Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:29.006251Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:32.011600Z [DEBUG] FsoGsmGenericAtCallHandler <>: Synchronizing call status 2010-07-13T17:15:33.590418Z [DEBUG] DBusServiceResource <GSM>: Suspending resource DBusServiceResource... 2010-07-13T17:15:33.603590Z [DEBUG] DBusServiceResource <GSM>: Suspending GSM resource... 2010-07-13T17:15:33.608062Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:true>: onModemStatusChange FSO_GSM_MODEM_STATUS_ALIVE_REGISTERED -> FSO_GSM_MODEM_STATUS_SUSPENDING 2010-07-13T17:15:33.610152Z [INFO] TiCalypsoModem <4C>: Modem Status changed to FSO_GSM_MODEM_STATUS_SUSPENDING 2010-07-13T17:15:33.881173Z [DEBUG] libfsotransport <0710:3>: Wrote '+CREG=0'. Waiting for answer... 2010-07-13T17:15:33.892363Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:33.893403Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:33.894260Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:33.896657Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:33.903099Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:33.906944Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "NO CARRIER" ] 2010-07-13T17:15:33.909316Z [DEBUG] TiCalypsoModem <4C>: Did not receive OK (instead 'NO CARRIER') for FsoGsmPlusCLCC 2010-07-13T17:15:33.911588Z [ERROR] FsoGsmGenericAtCallHandler <>: Can't synchronize call status: NO CARRIER 2010-07-13T17:15:33.913142Z [INFO] libfsotransport <0710:3>: SRC: "+CREG=0" -> [ "OK" ] 2010-07-13T17:15:33.921195Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:33.926386Z [DEBUG] libfsotransport <0710:3>: Wrote '+CGEREP=0,0'. Waiting for answer... 2010-07-13T17:15:33.929888Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:33.930866Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:33.931736Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:33.932299Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:33.948929Z [INFO] libfsotransport <0710:2>: URC: [ "OK" ] 2010-07-13T17:15:33.949575Z [DEBUG] TiCalypsoUnsolicitedResponseHandler : Dispatching AT unsolicited 'OK', '' 2010-07-13T17:15:33.950164Z [WARN] TiCalypsoModem <4C>: No handler for URC OK w/ rhs , please report to Mickey <smartphones-userland@linuxtogo.org> 2010-07-13T17:15:33.954101Z [INFO] libfsotransport <0710:3>: SRC: "+CGEREP=0,0" -> [ "OK" ] 2010-07-13T17:15:33.959997Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:33.967615Z [DEBUG] libfsotransport <0710:3>: Wrote '+CGREG=0'. Waiting for answer... 2010-07-13T17:15:33.978489Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:33.979289Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:33.980137Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:33.980878Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:33.989082Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:33.993352Z [INFO] libfsotransport <0710:3>: SRC: "+CGREG=0" -> [ "OK" ] 2010-07-13T17:15:33.999119Z [DEBUG] libfsotransport <0710:3>: Wrote '+CTZU=0'. Waiting for answer... 2010-07-13T17:15:34.003823Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.004721Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.007417Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.007960Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.018616Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.022938Z [INFO] libfsotransport <0710:3>: SRC: "+CTZU=0" -> [ "OK" ] 2010-07-13T17:15:34.029667Z [DEBUG] libfsotransport <0710:3>: Wrote '+CTZR=0'. Waiting for answer... 2010-07-13T17:15:34.035994Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.036770Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.037624Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.038218Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.047815Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.051518Z [INFO] libfsotransport <0710:3>: SRC: "+CTZR=0" -> [ "OK" ] 2010-07-13T17:15:34.057564Z [DEBUG] libfsotransport <0710:3>: Wrote '%CBHZ=0'. Waiting for answer... 2010-07-13T17:15:34.062560Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.064977Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.066030Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.066624Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.074861Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.077144Z [INFO] libfsotransport <0710:3>: SRC: "%CBHZ=0" -> [ "OK" ] 2010-07-13T17:15:34.084615Z [DEBUG] libfsotransport <0710:3>: Wrote '%CPI=0'. Waiting for answer... 2010-07-13T17:15:34.090015Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.091027Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.091881Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.092438Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.101240Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.104767Z [INFO] libfsotransport <0710:3>: SRC: "%CPI=0" -> [ "OK" ] 2010-07-13T17:15:34.111509Z [DEBUG] libfsotransport <0710:3>: Wrote '%CPRI=0'. Waiting for answer... 2010-07-13T17:15:34.118868Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.119667Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.120521Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.121345Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.129820Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.133585Z [INFO] libfsotransport <0710:3>: SRC: "%CPRI=0" -> [ "OK" ] 2010-07-13T17:15:34.140552Z [DEBUG] libfsotransport <0710:3>: Wrote '%CSQ=0'. Waiting for answer... 2010-07-13T17:15:34.145625Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.148294Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.149156Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.149723Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.159412Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:34.163515Z [INFO] libfsotransport <0710:3>: SRC: "%CSQ=0" -> [ "%CSQ: 0 ", "OK" ] 2010-07-13T17:15:34.169473Z [DEBUG] libfsotransport <0710:3>: Wrote '%CNIV=0'. Waiting for answer... 2010-07-13T17:15:34.173878Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:34.174667Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:34.177288Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:34.177840Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:34.187294Z [DEBUG] libfsotransport <0710:2>: Wrote '+CSQ'. Waiting for answer... 2010-07-13T17:15:34.189791Z [INFO] libfsotransport <0710:3>: SRC: "%CNIV=0" -> [ "OK" ] 2010-07-13T17:15:34.209237Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:true>: onModemStatusChange FSO_GSM_MODEM_STATUS_SUSPENDING -> FSO_GSM_MODEM_STATUS_SUSPENDED 2010-07-13T17:15:34.213241Z [INFO] TiCalypsoModem <4C>: Modem Status changed to FSO_GSM_MODEM_STATUS_SUSPENDED 2010-07-13T17:15:34.215896Z [INFO] DBusServiceDevice <>: Modem suspended successfully 2010-07-13T17:15:34.219066Z [INFO] libfsotransport <0710:2>: SRC: "+CSQ" -> [ "+CSQ: 27,99 ", "OK" ] 2010-07-13T17:15:34.257184Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCSQ 2010-07-13T17:15:34.264940Z [DEBUG] libfsotransport <0710:2>: Wrote '+CSQ'. Waiting for answer... 2010-07-13T17:15:36.410514Z [DEBUG] DBusServiceResource <GSM>: Resuming resource DBusServiceResource... 2010-07-13T17:15:36.412308Z [DEBUG] DBusServiceResource <GSM>: Resuming GSM resource... 2010-07-13T17:15:36.414841Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:true>: onModemStatusChange FSO_GSM_MODEM_STATUS_SUSPENDED -> FSO_GSM_MODEM_STATUS_RESUMING 2010-07-13T17:15:36.453680Z [INFO] TiCalypsoModem <4C>: Modem Status changed to FSO_GSM_MODEM_STATUS_RESUMING 2010-07-13T17:15:36.492771Z [DEBUG] libfsotransport <0710:3>: Wrote '+CREG=2'. Waiting for answer... 2010-07-13T17:15:37.338863Z [INFO] libfsotransport <0710:2>: SRC: "+CSQ" -> [ "+CSQ: 27,99 ", "OK" ] 2010-07-13T17:15:37.340032Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCSQ 2010-07-13T17:15:37.349081Z [DEBUG] libfsotransport <0710:2>: Wrote '+CHLD=11'. Waiting for answer... 2010-07-13T17:15:37.351260Z [INFO] libfsotransport <0710:3>: SRC: "+CREG=2" -> [ "OK" ] 2010-07-13T17:15:37.399915Z [DEBUG] libfsotransport <0710:3>: Wrote '+CGEREP=2,1'. Waiting for answer... 2010-07-13T17:15:37.402162Z [INFO] libfsotransport <0710:2>: SRC: "+CHLD=11" -> [ "+CME ERROR: 3" ] 2010-07-13T17:15:37.434882Z [DEBUG] TiCalypsoModem <4C>: Did not receive OK (instead '+CME ERROR: 3') for FsoGsmPlusCHLD 2010-07-13T17:15:37.458740Z [INFO] libfsotransport <0710:3>: SRC: "+CGEREP=2,1" -> [ "OK" ] 2010-07-13T17:15:37.488681Z [DEBUG] libfsotransport <0710:3>: Wrote '+CGREG=2'. Waiting for answer... 2010-07-13T17:15:37.495265Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:37.517941Z [INFO] libfsotransport <0710:3>: SRC: "+CGREG=2" -> [ "OK" ] 2010-07-13T17:15:37.522921Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:37.523729Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:37.524581Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:37.525149Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:37.604163Z [DEBUG] libfsotransport <0710:3>: Wrote '+CTZU=1'. Waiting for answer... 2010-07-13T17:15:37.613620Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:37.622327Z [INFO] libfsotransport <0710:3>: SRC: "+CTZU=1" -> [ "OK" ] 2010-07-13T17:15:37.630162Z [DEBUG] libfsotransport <0710:3>: Wrote '+CTZR=1'. Waiting for answer... 2010-07-13T17:15:37.639653Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:37.640443Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:37.681663Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:37.682212Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:37.736117Z [INFO] libfsotransport <0710:3>: SRC: "+CTZR=1" -> [ "OK" ] 2010-07-13T17:15:37.740113Z [DEBUG] libfsotransport <0710:3>: Wrote '%CPI=3'. Waiting for answer... 2010-07-13T17:15:37.786930Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:37.788817Z [INFO] libfsotransport <0710:3>: SRC: "%CPI=3" -> [ "OK" ] 2010-07-13T17:15:37.814623Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:37.815397Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:37.833230Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:37.833828Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:37.865322Z [DEBUG] libfsotransport <0710:3>: Wrote '%CPRI=0'. Waiting for answer... 2010-07-13T17:15:37.885319Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:37.899216Z [INFO] libfsotransport <0710:3>: SRC: "%CPRI=0" -> [ "OK" ] 2010-07-13T17:15:37.903689Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:37.904478Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:37.911459Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:37.912069Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:37.972668Z [DEBUG] libfsotransport <0710:3>: Wrote '%CNIV=1'. Waiting for answer... 2010-07-13T17:15:37.999603Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:38.038954Z [INFO] libfsotransport <0710:3>: SRC: "%CNIV=1" -> [ "OK" ] 2010-07-13T17:15:38.040634Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:38.091966Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:38.092901Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:38.093459Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:38.383828Z [DEBUG] libfsotransport <0710:3>: Wrote '%CSQ=1'. Waiting for answer... 2010-07-13T17:15:38.415076Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:38.472544Z [INFO] libfsotransport <0710:3>: SRC: "%CSQ=1" -> [ "%CSQ: 1 ", "OK" ] 2010-07-13T17:15:38.531834Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:38.532818Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:38.587170Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:38.587723Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:38.630406Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:true>: onModemStatusChange FSO_GSM_MODEM_STATUS_RESUMING -> FSO_GSM_MODEM_STATUS_ALIVE_REGISTERED 2010-07-13T17:15:38.684160Z [INFO] TiCalypsoModem <4C>: Modem Status changed to FSO_GSM_MODEM_STATUS_ALIVE_REGISTERED 2010-07-13T17:15:38.685188Z [INFO] DBusServiceDevice <>: Modem resumed successfully 2010-07-13T17:15:38.761307Z [DEBUG] libfsotransport <0710:3>: Wrote '+CNMI=2,2,2,1,1'. Waiting for answer... 2010-07-13T17:15:38.764538Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:38.792033Z [INFO] libfsotransport <0710:3>: SRC: "+CNMI=2,2,2,1,1" -> [ "OK" ] 2010-07-13T17:15:38.802459Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:38.803252Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:38.804098Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:38.877171Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:38.908451Z [DEBUG] libfsotransport <0710:3>: Wrote '+CCWA=1,1'. Waiting for answer... 2010-07-13T17:15:38.933517Z [DEBUG] libfsotransport <0710:2>: Wrote '+CSQ'. Waiting for answer... 2010-07-13T17:15:38.967953Z [INFO] libfsotransport <0710:2>: SRC: "+CSQ" -> [ "+CSQ: 27,99 ", "OK" ] 2010-07-13T17:15:39.025585Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCSQ 2010-07-13T17:15:39.073644Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.093131Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.093921Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.096624Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.097270Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.128366Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.152290Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.154700Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.155556Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.200395Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.234656Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.258925Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.261500Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.262348Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.262898Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.271208Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.292339Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.293124Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.293951Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.294518Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.313187Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.329035Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.329831Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.330866Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.331499Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.390215Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.407516Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.408301Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.409143Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.409687Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.438194Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.457324Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.458116Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.459577Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.460165Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.468336Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.487292Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.488080Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.488890Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.489457Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.497783Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.517298Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.518086Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.518911Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.522932Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.527786Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.547289Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.548073Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.552374Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.552926Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.557687Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.577320Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.581454Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.582299Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.582841Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.591065Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.612289Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.613077Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.613918Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.614476Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.622739Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.642293Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.643083Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.643906Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.644476Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.652733Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.672317Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.673108Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.673934Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.674491Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.682678Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.709351Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.710137Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.711280Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.711862Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.720467Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.742293Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.746418Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.747273Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.747832Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.755955Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.775542Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.776567Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.777411Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.777984Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.786492Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.805490Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.806480Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.807336Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.807886Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.816687Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.837310Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.838101Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.838926Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.842869Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.847767Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.873287Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.874077Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.874905Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.875474Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.883697Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.908997Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.911630Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.912479Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.913040Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.921423Z [DEBUG] libfsotransport <0710:2>: Wrote '+CSQ'. Waiting for answer... 2010-07-13T17:15:39.942840Z [INFO] libfsotransport <0710:2>: SRC: "+CSQ" -> [ "+CSQ: 27,99 ", "OK" ] 2010-07-13T17:15:39.943892Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCSQ 2010-07-13T17:15:39.951951Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:39.970128Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:39.971114Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:39.971967Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:39.972527Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:39.982176Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.002381Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.003277Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.009915Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.012345Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.020538Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.050168Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.051163Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.052021Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.054226Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.061701Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.078126Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.078919Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.079748Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.080318Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.089708Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.107370Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.108157Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.108980Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.109551Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.118295Z [DEBUG] libfsotransport <0710:2>: Wrote '+CSQ'. Waiting for answer... 2010-07-13T17:15:40.147307Z [INFO] libfsotransport <0710:2>: SRC: "+CSQ" -> [ "+CSQ: 27,99 ", "OK" ] 2010-07-13T17:15:40.148419Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCSQ 2010-07-13T17:15:40.171849Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.191016Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.191793Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.194293Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.194837Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.223574Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.248703Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.249490Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.250307Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.253129Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.259571Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.284349Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.287013Z [DEBUG] TiCalypsoModem <4C>: Did receive a valid response for FsoGsmPlusCLCC 2010-07-13T17:15:40.287850Z [DEBUG] FsoGsmGenericAtCallHandler <>: 0 calls known in the system 2010-07-13T17:15:40.288420Z [DEBUG] FsoGsmGenericAtCallHandler <>: call status idle -> stopping updater 2010-07-13T17:15:40.297507Z [DEBUG] libfsotransport <0710:2>: Wrote '+CLCC'. Waiting for answer... 2010-07-13T17:15:40.312342Z [INFO] libfsotransport <0710:2>: SRC: "+CLCC" -> [ "OK" ] 2010-07-13T17:15:40.313131Z [DEBUG] TiCalypsoModem <4C
comment:5 Changed 19 months ago by mickey
- Status changed from new to in_testing
Thanks to our call synchronization timeout of 3 seconds, an unanswered +CLCC will keep adding +CLCC forever. This at least is supposed to be fixed now by
commit 7107e46e0a05c99109ac41c782ed3674440ddf71 Author: Michael 'Mickey' Lauer <mickey@…> Date: Thu Jul 15 17:49:33 2010 +0200
fsogsmd: atcall: don't reenter call synchronization method
Note: See
TracTickets for help on using
tickets.
Q-Master on irc reports that after that happened for him, he could no longer reach fsogsmd via dbus, all mdbus2 invocations timed out.
After fsogsmd restart everything was back to normal