Warning: Can't synchronize with repository "(default)" (No changeset 96d22ec3fa3ef6de3ea8dc0d7d398adc9aa071cf in the repository). Look in the Trac log for more information.

Ticket #688 (closed defect: fixed)

Opened 12 months ago

Last modified 11 months ago

GTA04: "Failed to active PDP context: OK"

Reported by: GNUtoo Owned by: morphis
Priority: major Milestone: cornucopia-0.12
Component: cornucopia/general Version: 0.11
Keywords: Cc:

Description

  • I used internet.wind as APN since it's my APN in shr-settings
  • I tried connect and also the equivalent in the command line: 
    root@om-gta04:~# mdbus2 -s org.freesmartphone.ogsmd /org/freesmartphone/GSM/Device org.freesmartphone.GSM.PDP.ActivateContext
[ERR]: GDBus.Error:org.freesmartphone.InternalError: Failed to active PDP context: OK
root@om-gta04:~# mdbus2 -s org.freesmartphone.ogsmd /org/freesmartphone/GSM/Device org.freesmartphone.GSM.PDP.GetCredentials 
('internet.wind', 'internet', 'internet')

Both fail.

here's the INFO log: 

2000-01-02T18:00:30.282653Z [INFO]  Gtm601Modem <>: SIM Auth status FREE_SMARTPHONE_GSM_SIM_AUTH_STATUS_READY
2000-01-02T18:00:33.822113Z [INFO]  Gtm601Modem <>: Modem Status changed to FSO_GSM_MODEM_STATUS_ALIVE_REGISTERED
2000-01-02T18:01:57.528564Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_OUTGOING
2000-01-02T18:01:58.646331Z [WARN]  Gtm601Modem <>: Unexpected length 1 for Gtm601UnderscoreOWANDATA
2000-01-02T18:01:58.650421Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_RELEASED
2000-01-02T18:06:18.743591Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_OUTGOING
2000-01-02T18:06:19.645693Z [WARN]  Gtm601Modem <>: Unexpected length 1 for Gtm601UnderscoreOWANDATA
2000-01-02T18:06:19.651704Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_RELEASED
2000-01-02T18:08:33.399902Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_OUTGOING
2000-01-02T18:08:34.647795Z [WARN]  Gtm601Modem <>: Unexpected length 1 for Gtm601UnderscoreOWANDATA
2000-01-02T18:08:34.650816Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_RELEASED

I'll reboot and attach a debug log.

Change History

comment:1 Changed 12 months ago by GNUtoo

DEBUG log: 

root@om-gta04:~# tail -f /var/log/fsogsmd.log 
2000-01-02T18:13:56.477142Z [DEBUG] Gtm601Modem <>: Created mediator FsoGsmNetworkGetStatus
2000-01-02T18:13:56.485534Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCSQ
2000-01-02T18:13:56.488220Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCREG
2000-01-02T18:13:56.490875Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCOPS
2000-01-02T18:13:56.495422Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCOPS
2000-01-02T18:13:56.499572Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCOPS
2000-01-02T18:13:56.503875Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCGREG
2000-01-02T18:13:56.506256Z [DEBUG] Gtm601Modem <>: Did receive a valid response for FsoGsmPlusCGREG
2000-01-02T18:14:24.378631Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '21,0'
2000-01-02T18:14:24.379150Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:14:30.377227Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '24,0'
2000-01-02T18:14:30.377746Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:14:39.439849Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '26,0'
2000-01-02T18:14:39.440185Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:15:02.957183Z [DEBUG] Gtm601Modem <>: Created mediator FsoGsmPdpSetCredentials
2000-01-02T18:15:03.093933Z [DEBUG] Gtm601Modem <>: Created mediator FsoGsmPdpActivateContext
2000-01-02T18:15:03.094512Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_OUTGOING
2000-01-02T18:15:04.675231Z [WARN]  Gtm601Modem <>: Unexpected length 1 for Gtm601UnderscoreOWANDATA
2000-01-02T18:15:04.680236Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_RELEASED
2000-01-02T18:15:06.087219Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '28,0'
2000-01-02T18:15:06.087677Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:15:09.574981Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '+CGEV', 'ME DEACT IP, 0.0.0.0, 1'
2000-01-02T18:15:09.575531Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '25,0'
2000-01-02T18:15:09.575836Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:15:12.550018Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '28,0'
2000-01-02T18:15:12.550476Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:15:22.754669Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '27,0'
2000-01-02T18:15:22.755157Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2000-01-02T18:15:29.300231Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '29,0'
2000-01-02T18:15:29.300750Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ

comment:2 Changed 12 months ago by GNUtoo 

2012-06-06T19:25:07.652252Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: URC: [ "_OSIGQ: 24,0" ]
2012-06-06T19:25:07.652587Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '24,0'
2012-06-06T19:25:07.652801Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ
2012-06-06T19:25:08.379669Z [DEBUG] Gtm601Modem <>: Created mediator FsoGsmPdpSetCredentials
2012-06-06T19:25:08.380401Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Attemping to write next command to transport; we have 1 commands pending!
2012-06-06T19:25:08.380767Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Wrote '+CGDCONT=1,"IP","internet.wind"'. Waiting (5s) for answer...
2012-06-06T19:25:08.381134Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback called with 35 bytes in buffer
2012-06-06T19:25:08.381378Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback wrote 35 bytes
2012-06-06T19:25:08.392700Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: SRC: "+CGDCONT=1,"IP","internet.wind"" -> [ "OK" ]
2012-06-06T19:25:08.408294Z [DEBUG] Gtm601Modem <>: Created mediator FsoGsmPdpActivateContext
2012-06-06T19:25:08.408752Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_OUTGOING
2012-06-06T19:25:08.427612Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Attemping to write next command to transport; we have 1 commands pending!
2012-06-06T19:25:08.428222Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Wrote '_OWANCALL=1,1,1'. Waiting (5s) for answer...
2012-06-06T19:25:08.428619Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback called with 19 bytes in buffer
2012-06-06T19:25:08.429107Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback wrote 19 bytes
2012-06-06T19:25:08.431823Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: SRC: "_OWANCALL=1,1,1" -> [ "OK" ]
2012-06-06T19:25:09.753326Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Attemping to write next command to transport; we have 1 commands pending!
2012-06-06T19:25:09.753814Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Wrote '_OWANDATA?'. Waiting (5s) for answer...
2012-06-06T19:25:09.754211Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback called with 14 bytes in buffer
2012-06-06T19:25:09.754455Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback wrote 14 bytes
2012-06-06T19:25:09.759429Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: SRC: "_OWANDATA?" -> [ "OK" ]
2012-06-06T19:25:09.759826Z [WARN]  Gtm601Modem <>: Unexpected length 1 for Gtm601UnderscoreOWANDATA
2012-06-06T19:25:09.760528Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Attemping to write next command to transport; we have 1 commands pending!
2012-06-06T19:25:09.760833Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: Wrote '_OWANCALL=1,0,1'. Waiting (5s) for answer...
2012-06-06T19:25:09.761199Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback called with 19 bytes in buffer
2012-06-06T19:25:09.761444Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: WriteCallback wrote 19 bytes
2012-06-06T19:25:09.763488Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: SRC: "_OWANCALL=1,0,1" -> [ "OK" ]
2012-06-06T19:25:09.767425Z [INFO]  PdpOptionGtm601 <>: PDP Context Status now FREE_SMARTPHONE_GSM_CONTEXT_STATUS_RELEASED
2012-06-06T19:25:14.246429Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: URC: [ "+CGEV: ME DEACT IP, 0.0.0.0, 1" ]
2012-06-06T19:25:14.246826Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '+CGEV', 'ME DEACT IP, 0.0.0.0, 1'
2012-06-06T19:25:27.598327Z [DEBUG] libfsotransport <Serial /dev/ttyHS_Application@115200 (fd 20)>: URC: [ "_OSIGQ: 26,0" ]
2012-06-06T19:25:27.598785Z [DEBUG] Gtm601UnsolicitedResponseHandler : Dispatching AT unsolicited '_OSIGQ', '26,0'
2012-06-06T19:25:27.599121Z [DEBUG] Gtm601Modem <>: Did receive a valid response for Gtm601UnderscoreOSIGQ

comment:3 Changed 12 months ago by morphis

  • Owner changed from mickey to morphis
  • Status changed from new to accepted
  • Version changed from milestone5.5 to 0.11
  • Milestone set to 0.12

comment:4 Changed 11 months ago by morphis

In d613f9e818f49532780234b19068bc1e10799580 I increased the timeout before we send the _OWANDATA command to retrieve the PDP context configuration. In my local test (connected GTA04A4 via USB to my laptop) that worked fine. Can you please verify it's working for you too?

This is only a workaround as there should be a _OWANCALL unsolicited response sent when the PDP context is configured successfully from the modem - but it does not.

Last edited 11 months ago by morphis (previous) (diff)

comment:5 Changed 11 months ago by morphis

  • Status changed from accepted to in_testing

comment:6 Changed 11 months ago by morphis

  • Status changed from in_testing to closed
  • Resolution set to fixed
Note: See TracTickets for help on using tickets.