rendered paste body2011-05-01T22:28:12.903567Z [DEBUG] fsogsmd : Registered 2 backtrace handler
2011-05-01T22:28:12.904115Z [INFO] fsogsmd : Binary launched successful (FsoFrameworkFileLogger created as theLogger)
2011-05-01T22:28:12.955091Z [DEBUG] fsogsmd : fsogsm.singleline fso_factory_function
2011-05-01T22:28:12.966981Z [DEBUG] fsogsmd : pdp_qmi fso_factory_function
2011-05-01T22:28:12.970426Z [DEBUG] fsogsmd : fsogsm.dbus_service fso_register_function
2011-05-01T22:28:13.024298Z [WARN] SinglelineModem <>: Invalid lowlevel_type none; vendor specifics will NOT be available
2011-05-01T22:28:13.036219Z [INFO] SinglelineModem <>: Ready. Using pdp plugin qmi to handle data connectivity
2011-05-01T22:28:13.037743Z [INFO] SinglelineModem <>: Modem Status changed to FSO_GSM_MODEM_STATUS_CLOSED
2011-05-01T22:28:13.038841Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CALA'
2011-05-01T22:28:13.039024Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CCWA'
2011-05-01T22:28:13.039115Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CGEV'
2011-05-01T22:28:13.039207Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CGREG'
2011-05-01T22:28:13.039268Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CIEV'
2011-05-01T22:28:13.039359Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CLIP'
2011-05-01T22:28:13.039420Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CMTI'
2011-05-01T22:28:13.039481Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CREG'
2011-05-01T22:28:13.039573Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CRING'
2011-05-01T22:28:13.039634Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CSSI'
2011-05-01T22:28:13.039695Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CSSU'
2011-05-01T22:28:13.039756Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CTZV'
2011-05-01T22:28:13.039817Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CUSD'
2011-05-01T22:28:13.039908Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC 'NO CARRIER'
2011-05-01T22:28:13.045457Z [DEBUG] PdpQmi <>: qmi listener thread waiting for data from QMI...
2011-05-01T22:28:13.067896Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd -1)>: Created
2011-05-01T22:28:13.070030Z [DEBUG] SinglelineModem <>: Created; configured for serial:/dev/smd0@115200
2011-05-01T22:28:13.070396Z [INFO] DBusServiceDevice <>: Ready. Configured for modem singleline
2011-05-01T22:28:13.070975Z [INFO] fsogsmd : loaded 3 plugins
2011-05-01T22:28:13.071097Z [INFO] fsogsmd : fsogsmd => mainloop
2011-05-01T22:30:04.115731Z [DEBUG] fsogsmd : Registered 2 backtrace handler
2011-05-01T22:30:04.129908Z [INFO] fsogsmd : Binary launched successful (FsoFrameworkFileLogger created as theLogger)
2011-05-01T22:30:04.185548Z [DEBUG] fsogsmd : fsogsm.singleline fso_factory_function
2011-05-01T22:30:04.213871Z [DEBUG] fsogsmd : pdp_qmi fso_factory_function
2011-05-01T22:30:04.267408Z [DEBUG] fsogsmd : fsogsm.dbus_service fso_register_function
2011-05-01T22:30:04.437652Z [WARN] SinglelineModem <>: Invalid lowlevel_type none; vendor specifics will NOT be available
2011-05-01T22:30:04.452073Z [INFO] SinglelineModem <>: Ready. Using pdp plugin qmi to handle data connectivity
2011-05-01T22:30:04.453628Z [INFO] SinglelineModem <>: Modem Status changed to FSO_GSM_MODEM_STATUS_CLOSED
2011-05-01T22:30:04.454695Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CALA'
2011-05-01T22:30:04.471036Z [DEBUG] PdpQmi <>: qmi listener thread waiting for data from QMI...
2011-05-01T22:30:04.498048Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CCWA'
2011-05-01T22:30:04.498231Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CGEV'
2011-05-01T22:30:04.498323Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CGREG'
2011-05-01T22:30:04.504237Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CIEV'
2011-05-01T22:30:04.504329Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CLIP'
2011-05-01T22:30:04.504451Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CMTI'
2011-05-01T22:30:04.504542Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CREG'
2011-05-01T22:30:04.504603Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CRING'
2011-05-01T22:30:04.504664Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CSSI'
2011-05-01T22:30:04.504725Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CSSU'
2011-05-01T22:30:04.504786Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CTZV'
2011-05-01T22:30:04.504847Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC '+CUSD'
2011-05-01T22:30:04.504908Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Registering URC 'NO CARRIER'
2011-05-01T22:30:04.727774Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd -1)>: Created
2011-05-01T22:30:04.729878Z [DEBUG] SinglelineModem <>: Created; configured for serial:/dev/smd0@115200
2011-05-01T22:30:04.730182Z [INFO] DBusServiceDevice <>: Ready. Configured for modem singleline
2011-05-01T22:30:04.730792Z [INFO] fsogsmd : loaded 3 plugins
2011-05-01T22:30:04.829329Z [INFO] fsogsmd : fsogsmd => mainloop
2011-05-01T22:30:05.012804Z [DEBUG] DBusServiceResource <GSM>: Disabling resource DBusServiceResource...
2011-05-01T22:30:05.013048Z [DEBUG] DBusServiceResource <GSM>: Disabling GSM resource...
2011-05-01T22:30:05.013323Z [INFO] DBusServiceDevice <>: Modem closed successfully
2011-05-01T22:30:05.019451Z [INFO] DBusServiceResource <GSM>: Ok. Registered with org.freesmartphone.ousaged
2011-05-01T22:30:05.029481Z [DEBUG] DBusServiceResource <GSM>: Enabling resource DBusServiceResource...
2011-05-01T22:30:05.029664Z [DEBUG] DBusServiceResource <GSM>: Enabling GSM resource...
2011-05-01T22:30:05.029817Z [DEBUG] SinglelineModem <>: Opening the modem device...
2011-05-01T22:30:05.029908Z [WARN] FsoGsmNullLowLevel : NullLowlevel::poweron() - this is probably not what you want
2011-05-01T22:30:05.030000Z [DEBUG] SinglelineModem <>: Will open 1 channels...
2011-05-01T22:30:05.031128Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Opening...
2011-05-01T22:30:05.031402Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Opened
2011-05-01T22:30:05.031859Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: URC: [ "AT-Command Interpreter ready" ]
2011-05-01T22:30:05.032012Z [DEBUG] FsoGsmAtUnsolicitedResponseHandler : Dispatching AT unsolicited 'AT-Command Interpreter ready', ''
2011-05-01T22:30:05.032103Z [WARN] SinglelineModem <>: No handler for URC AT-Command Interpreter ready w/ rhs , please report to Mickey <smartphones-userland@linuxtogo.org>
2011-05-01T22:30:05.033048Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:false>: onModemStatusChange FSO_GSM_MODEM_STATUS_CLOSED -> FSO_GSM_MODEM_STATUS_INITIALIZING
2011-05-01T22:30:05.034603Z [INFO] SinglelineModem <>: Modem Status changed to FSO_GSM_MODEM_STATUS_INITIALIZING
2011-05-01T22:30:05.034786Z [INFO] DBusServiceDevice <>: Modem opened successfully
2011-05-01T22:30:05.036006Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote 'E0Q0V1'. Waiting (5s) for answer...
2011-05-01T22:30:05.036219Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:05.036341Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:05.082347Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "E0Q0V1" -> [ "OK" ]
2011-05-01T22:30:05.083597Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CMEE=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.083780Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.083902Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.096310Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CMEE=1" -> [ "OK" ]
2011-05-01T22:30:05.108384Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CRC=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.108658Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:05.108750Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:05.118231Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CRC=1" -> [ "OK" ]
2011-05-01T22:30:05.145914Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSNS=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.146158Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.146250Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.152621Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSNS=0" -> [ "ERROR" ]
2011-05-01T22:30:05.153048Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CMGF=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.153201Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.153292Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.161097Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CMGF=0" -> [ "OK" ]
2011-05-01T22:30:05.161524Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CLIP=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.161676Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.161768Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.171097Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CLIP=0" -> [ "OK" ]
2011-05-01T22:30:05.171554Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CLIR=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.171707Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.171798Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.177682Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CLIR=0" -> [ "OK" ]
2011-05-01T22:30:05.180030Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COLP=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.180274Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.180365Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.186951Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COLP=0" -> [ "OK" ]
2011-05-01T22:30:05.187347Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CCWA=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.187500Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.187591Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.196432Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CCWA=0" -> [ "OK" ]
2011-05-01T22:30:05.196859Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSMS=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.197012Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.197103Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.203536Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSMS=1" -> [ "ERROR" ]
2011-05-01T22:30:05.203963Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote 'E0V1'. Waiting (5s) for answer...
2011-05-01T22:30:05.204115Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 8 bytes in buffer
2011-05-01T22:30:05.204268Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 8 bytes
2011-05-01T22:30:05.211097Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "E0V1" -> [ "OK" ]
2011-05-01T22:30:05.211554Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CMEE=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.211707Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.211798Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.223750Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CMEE=1" -> [ "OK" ]
2011-05-01T22:30:05.224176Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CRC=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.224329Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:05.224420Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:05.234237Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CRC=1" -> [ "OK" ]
2011-05-01T22:30:05.234664Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSCS="UCS2"'. Waiting (5s) for answer...
2011-05-01T22:30:05.234847Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 16 bytes in buffer
2011-05-01T22:30:05.234939Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 16 bytes
2011-05-01T22:30:05.243323Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSCS="UCS2"" -> [ "OK" ]
2011-05-01T22:30:05.261158Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '@BRIC=0'. Waiting (5s) for answer...
2011-05-01T22:30:05.261402Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.261493Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.271128Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "@BRIC=0" -> [ "OK" ]
2011-05-01T22:30:05.271585Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+GTKC=2'. Waiting (5s) for answer...
2011-05-01T22:30:05.271737Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.271829Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.281097Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+GTKC=2" -> [ "OK" ]
2011-05-01T22:30:05.281524Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CLIP=1'. Waiting (5s) for answer...
2011-05-01T22:30:05.281676Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:05.281768Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:05.291097Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CLIP=1" -> [ "OK" ]
2011-05-01T22:30:05.291585Z [INFO] SinglelineModem <>: Configuring modem charset...
2011-05-01T22:30:05.291890Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSCS="HEX"'. Waiting (5s) for answer...
2011-05-01T22:30:05.292073Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 15 bytes in buffer
2011-05-01T22:30:05.292164Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 15 bytes
2011-05-01T22:30:05.297103Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSCS="HEX"" -> [ "OK" ]
2011-05-01T22:30:05.297317Z [INFO] SinglelineModem <>: Channel successfully configured for charset 'HEX'
2011-05-01T22:30:05.301951Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CPIN?'. Waiting (20s) for answer...
2011-05-01T22:30:05.302225Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:05.302317Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:05.321189Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CPIN?" -> [ "+CPIN: SIM PIN", "OK" ]
2011-05-01T22:30:05.321707Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCPIN
2011-05-01T22:30:05.321829Z [INFO] SinglelineModem <>: SIM Auth status FREE_SMARTPHONE_GSM_SIM_AUTH_STATUS_PIN_REQUIRED
2011-05-01T22:30:05.350914Z [DEBUG] FsoGsmGenericWatchDog <P:|KR:false>: onModemStatusChange FSO_GSM_MODEM_STATUS_INITIALIZING -> FSO_GSM_MODEM_STATUS_ALIVE_SIM_LOCKED
2011-05-01T22:30:05.360213Z [INFO] SinglelineModem <>: Modem Status changed to FSO_GSM_MODEM_STATUS_ALIVE_SIM_LOCKED
2011-05-01T22:30:14.635731Z [DEBUG] SinglelineModem <>: Created mediator FsoGsmNetworkGetSignalStrength
2011-05-01T22:30:14.636189Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSQ'. Waiting (5s) for answer...
2011-05-01T22:30:14.636341Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 8 bytes in buffer
2011-05-01T22:30:14.636463Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 8 bytes
2011-05-01T22:30:14.638658Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSQ" -> [ "+CSQ: 99,99", "OK" ]
2011-05-01T22:30:14.639024Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCSQ
2011-05-01T22:30:14.641371Z [DEBUG] SinglelineModem <>: Created mediator FsoGsmNetworkGetStatus
2011-05-01T22:30:14.641951Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSQ'. Waiting (5s) for answer...
2011-05-01T22:30:14.642134Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 8 bytes in buffer
2011-05-01T22:30:14.642225Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 8 bytes
2011-05-01T22:30:14.660487Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSQ" -> [ "+CSQ: 99,99", "OK" ]
2011-05-01T22:30:14.660762Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCSQ
2011-05-01T22:30:14.674756Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CREG?'. Waiting (5s) for answer...
2011-05-01T22:30:14.675000Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:14.675091Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:14.691371Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CREG?" -> [ "+CREG: 0,3", "OK" ]
2011-05-01T22:30:14.691646Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCREG
2011-05-01T22:30:14.692073Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CREG=2;+CREG?;+CREG=0'. Waiting (5s) for answer...
2011-05-01T22:30:14.692225Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 26 bytes in buffer
2011-05-01T22:30:14.692317Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 26 bytes
2011-05-01T22:30:14.701219Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CREG=2;+CREG?;+CREG=0" -> [ "+CREG: 2,3", "OK" ]
2011-05-01T22:30:14.701463Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCREG
2011-05-01T22:30:14.701981Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,0;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:14.702164Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:14.702256Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:14.711463Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,0;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:14.711798Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:14.712286Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,1;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:14.712469Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:14.712560Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:14.721189Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,1;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:14.721432Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:14.721798Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,2;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:14.721951Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:14.722042Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:14.731189Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,2;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:14.731432Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:14.732012Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CGREG?'. Waiting (5s) for answer...
2011-05-01T22:30:14.732195Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:14.732286Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:14.748841Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CGREG?" -> [ "+CGREG: 0,3", "OK" ]
2011-05-01T22:30:14.749085Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCGREG
2011-05-01T22:30:14.749512Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CGREG=2;+CGREG?;+CGREG=0'. Waiting (5s) for answer...
2011-05-01T22:30:14.749664Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 29 bytes in buffer
2011-05-01T22:30:14.749756Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 29 bytes
2011-05-01T22:30:14.761250Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CGREG=2;+CGREG?;+CGREG=0" -> [ "+CGREG: 2,3", "OK" ]
2011-05-01T22:30:14.761493Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCGREG
2011-05-01T22:30:20.469690Z [DEBUG] SinglelineModem <>: Created mediator FsoGsmNetworkGetSignalStrength
2011-05-01T22:30:20.470086Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSQ'. Waiting (5s) for answer...
2011-05-01T22:30:20.470238Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 8 bytes in buffer
2011-05-01T22:30:20.470330Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 8 bytes
2011-05-01T22:30:20.475208Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSQ" -> [ "+CSQ: 99,99", "OK" ]
2011-05-01T22:30:20.475452Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCSQ
2011-05-01T22:30:20.476885Z [DEBUG] SinglelineModem <>: Created mediator FsoGsmNetworkGetStatus
2011-05-01T22:30:20.477251Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CSQ'. Waiting (5s) for answer...
2011-05-01T22:30:20.477404Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 8 bytes in buffer
2011-05-01T22:30:20.477495Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 8 bytes
2011-05-01T22:30:20.481123Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CSQ" -> [ "+CSQ: 99,99", "OK" ]
2011-05-01T22:30:20.481367Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCSQ
2011-05-01T22:30:20.481703Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CREG?'. Waiting (5s) for answer...
2011-05-01T22:30:20.481947Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 10 bytes in buffer
2011-05-01T22:30:20.482038Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 10 bytes
2011-05-01T22:30:20.491490Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CREG?" -> [ "+CREG: 0,3", "OK" ]
2011-05-01T22:30:20.491704Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCREG
2011-05-01T22:30:20.492069Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CREG=2;+CREG?;+CREG=0'. Waiting (5s) for answer...
2011-05-01T22:30:20.492222Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 26 bytes in buffer
2011-05-01T22:30:20.492313Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 26 bytes
2011-05-01T22:30:20.501491Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CREG=2;+CREG?;+CREG=0" -> [ "+CREG: 2,3", "OK" ]
2011-05-01T22:30:20.501704Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCREG
2011-05-01T22:30:20.502101Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,0;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:20.502284Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:20.502375Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:20.511492Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,0;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:20.511736Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:20.512102Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,1;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:20.512284Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:20.512376Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:20.521492Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,1;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:20.521706Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:20.522072Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+COPS=3,2;+COPS?'. Waiting (120s) for answer...
2011-05-01T22:30:20.522224Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 20 bytes in buffer
2011-05-01T22:30:20.522316Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 20 bytes
2011-05-01T22:30:20.531493Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+COPS=3,2;+COPS?" -> [ "+COPS: 2", "OK" ]
2011-05-01T22:30:20.531737Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCOPS
2011-05-01T22:30:20.532103Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CGREG?'. Waiting (5s) for answer...
2011-05-01T22:30:20.532255Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 11 bytes in buffer
2011-05-01T22:30:20.532347Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 11 bytes
2011-05-01T22:30:20.541493Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CGREG?" -> [ "+CGREG: 0,3", "OK" ]
2011-05-01T22:30:20.541707Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCGREG
2011-05-01T22:30:20.542073Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: Wrote '+CGREG=2;+CGREG?;+CGREG=0'. Waiting (5s) for answer...
2011-05-01T22:30:20.542225Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback called with 29 bytes in buffer
2011-05-01T22:30:20.542317Z [DEBUG] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: WriteCallback wrote 29 bytes
2011-05-01T22:30:20.551494Z [INFO] libfsotransport <Serial /dev/smd0@115200 (fd 11)>: SRC: "+CGREG=2;+CGREG?;+CGREG=0" -> [ "+CGREG: 2,3", "OK" ]
2011-05-01T22:30:20.551768Z [DEBUG] SinglelineModem <>: Did receive a valid response for FsoGsmPlusCGREG