From: Christopher Vogl <christopher.vogl@hale.at>
To: ofono@ofono.org
Subject: Re: [PATCH] telit: notify sim inserted when sim ready
Date: Fri, 27 Jul 2012 11:37:35 +0200 [thread overview]
Message-ID: <5012615F.4010004@hale.at> (raw)
In-Reply-To: <CAC+thW2t=r2xXvpWhjrg=MNqObkSgKEUWWuVmBCemh=p0qLOuQ@mail.gmail.com>
[-- Attachment #1: Type: text/plain, Size: 22177 bytes --]
On 26/07/12 17:19, Andrea Galbusera wrote:
> I did some preliminary test with your patch. See the logs below:
>
> -------- with patch applied --------
>
> ofonod[30959]: plugins/telit.c:telit_enable() 0x9e281c8
> ofonod[30959]: src/modem.c:get_modem_property() modem 0x9e281c8 property Modem
> ofonod[30959]: plugins/telit.c:open_device() Modem /dev/ttyUSB0
> ofonod[30959]: Modem: > ATE0 +CMEE=1\r
> ofonod[30959]: Modem: < ATE0 +CMEE=1\r
> ofonod[30959]: Modem: < \r\nOK\r\n
> ofonod[30959]: Modem: > AT+CFUN=4\r
> ofonod[30959]: Modem: < \r\nOK\r\n
> ofonod[30959]: plugins/telit.c:cfun_enable_cb() 0x9e281c8
> ofonod[30959]: examples/emulator.c:powered_watch() Adding modem
> 0x9e281c8 to the list
> ofonod[30959]: examples/emulator.c:create_tcp() Created server_watch: 38
> ofonod[30959]: examples/emulator.c:create_tcp() Created server_watch: 39
> ofonod[30959]: src/modem.c:modem_change_state() old state: 0, new state: 1
> ofonod[30959]: plugins/telit.c:telit_pre_sim() 0x9e281c8
> ofonod[30959]: Modem: > AT+GCAP\r
> ofonod[30959]: src/sim.c:ofono_sim_add_state_watch() 0x9e2a0d8
> ofonod[30959]: src/sim.c:ofono_sim_add_state_watch() 0x9e2a0d8
> ofonod[30959]: src/sim.c:ofono_sim_add_state_watch() 0x9e2a0d8
> ofonod[30959]: Modem: < \r\n+GCAP: +CGSM,+DS,+FCLASS,+MS,+ES\r\n\r\nOK\r\n
> ofonod[30959]: Modem: > AT+CRC=1\r
> ofonod[30959]: Modem: < \r\nOK\r\n
> ofonod[30959]: Modem: > AT+CLIP=1\r
> ofonod[30959]: Modem: < \r\nOK\r\n
> ofonod[30959]: Modem: > AT+CDIP=1\r
> ofonod[30959]: Modem: < \r\nERROR\r\n
> ofonod[30959]: Modem: > AT+CNAP=1\r
> ofonod[30959]: Modem: < \r\nERROR\r\n
> ofonod[30959]: Modem: > AT+COLP=1\r
> ofonod[30959]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[30959]: Modem: > AT+CSSN=1,1\r
> ofonod[30959]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[30959]: Modem: > AT+VTD?\r
> ofonod[30959]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[30959]: Modem: > AT+CCWA=1\r
> ofonod[30959]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[30959]: drivers/atmodem/voicecall.c:at_voicecall_initialized()
> voicecall_init: registering to notifications
> ofonod[30959]: src/sim.c:ofono_sim_add_state_watch() 0x9e2a0d8
> ofonod[30959]: Modem: > AT#QSS=2\r
> ofonod[30959]: Modem: < \r\nOK\r\n
> ofonod[30959]: Modem: > AT#QSS?\r
> ofonod[30959]: Modem: < \r\n#QSS: 2,1\r\n\r\nOK\r\n
> ofonod[30959]: plugins/telit.c:telit_qss_cb() 0x9e281c8
> ofonod[30959]: plugins/telit.c:switch_sim_state_status() 0x9e281c8
> ofonod[30959]: plugins/telit.c:switch_sim_state_status() SIM inserted
> ofonod[30959]: Modem: > AT+CGMI\r
> ofonod[30959]: Modem: < \r\nTelit\r\n\r\nOK\r\n
> ofonod[30959]: Modem: > AT+CLCC\r
> ofonod[30959]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[30959]: Modem: > AT+CGMM\r
> ofonod[30959]: Modem: < \r\nUC864-E\r\n\r\nOK\r\n
> ofonod[30959]: Modem: > AT+CGMR\r
> ofonod[30959]: Modem: < \r\n08.01.005\r\n\r\nOK\r\n
> ofonod[30959]: Modem: > AT+CGSN\r
> ofonod[30959]: Modem: < \r\n352636020412669\r\n\r\nOK\r\n
>
> No sim readyness notification comes unless the modem is brought
> online. Is this expected?
In telit_enable() AT+CFUN=4 is issued. This disables TX and RX - causes
the modem to do a network deregistration (if registered) - and does a
SIM deactivation according to the Telit AT reference manual.
So I suppose that the SIM readyness notificytion is not emitted until
AT+CFUN=1 is set in telit_set_online().
I don't know if this is the expected behaviour, some plugins set
AT+CFUN=1 in the enable function.
> Issuing ./online-modem brings the process further with:
>
> ofonod[31135]: plugins/telit.c:telit_set_online() modem 0x99a81c8 online
> ofonod[31135]: Modem: > AT+CFUN=1\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: src/modem.c:common_online_cb() Online in PRE SIM state
> ofonod[31135]: Modem: < \r\n#QSS: 2\r\n
> ofonod[31135]: plugins/telit.c:telit_qss_notify() 0x99a81c8
> ofonod[31135]: plugins/telit.c:switch_sim_state_status() 0x99a81c8
> ofonod[31135]: plugins/telit.c:switch_sim_state_status() SIM inserted
> and PIN unlocked
> ofonod[31135]: Modem: < \r\n#QSS: 3\r\n
> ofonod[31135]: plugins/telit.c:telit_qss_notify() 0x99a81c8
> ofonod[31135]: plugins/telit.c:switch_sim_state_status() 0x99a81c8
> ofonod[31135]: plugins/telit.c:switch_sim_state_status() SIM inserted and ready
> ofonod[31135]: Modem: > AT+CRSM=192,28599\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: src/voicecall.c:ecc_g2_read_cb() 0
> ofonod[31135]: Modem: > AT+CRSM=192,28599\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: src/voicecall.c:ecc_g3_read_cb() 0
> ofonod[31135]: Modem: > AT+CRSM=192,12258\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,0000000A2FE204000FF04401020000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CRSM=176,12258,0,0,10\r
> ofonod[31135]: Modem: < \r\n+CRSM: 144,0,989301070051275811F8\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 10
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block_cb() bufoff: 0,
> dataoff: 0, tocopy: 10
> ofonod[31135]: Modem: > AT+CRSM=192,28421\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,000000056F05040001F04401020000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CRSM=176,28421,0,0,5\r
> ofonod[31135]: Modem: < \r\n+CRSM: 144,0,0201000306\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 5
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block_cb() bufoff: 0,
> dataoff: 0, tocopy: 5
> ofonod[31135]: Modem: > AT+CRSM=192,12037\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: Modem: > AT+CPIN?\r
> ofonod[31135]: Modem: < \r\n+CPIN: READY\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_cpin_cb() crsm_pin_cb: READY
> ofonod[31135]: drivers/atmodem/sim.c:at_pin_retries_query()
> ofonod[31135]: Modem: > AT+CPINR\r
> ofonod[31135]: Modem: < \r\nERROR\r\n
> ofonod[31135]: Querying remaining pin retries failed
> ofonod[31135]: Modem: > AT+CRSM=192,28590\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,000000016FAE040004F04401020000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CRSM=176,28590,0,0,1\r
> ofonod[31135]: Modem: < \r\n+CRSM: 144,0,03\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 1
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block_cb() bufoff: 0,
> dataoff: 0, tocopy: 1
> ofonod[31135]: Modem: > AT+CRSM=192,28589\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,000000036FAD040004F04401020000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CRSM=176,28589,0,0,3\r
> ofonod[31135]: Modem: < \r\n+CRSM: 144,0,00FFFF\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 3
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block_cb() bufoff: 0,
> dataoff: 0, tocopy: 3
> ofonod[31135]: EFad should contain at least four bytes
> ofonod[31135]: Modem: > AT+CRSM=192,28438\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: Modem: > AT+CRSM=192,28472\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,0000000C6F38040014F04401020000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CRSM=176,28472,0,0,12\r
> ofonod[31135]: Modem: < \r\n+CRSM: 144,0,FF3CFF0F0F003C03000C0000\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 12
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block_cb() bufoff: 0,
> dataoff: 0, tocopy: 12
> ofonod[31135]: Modem: > AT+CRSM=192,28474\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,00001C526F3A040011F0220502011D\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CIMI\r
> ofonod[31135]: Modem: < \r\n222109001113943\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_cimi_cb() cimi_cb: 222109001113943
> ofonod[31135]: src/modem.c:modem_change_state() old state: 1, new state: 2
> ofonod[31135]: plugins/telit.c:telit_post_sim() 0x99a81c8
> ofonod[31135]: Example History Probe for modem: 0x99a81c8
> ofonod[31135]: Example Network Time Probe for modem: 0x99a81c8
> ofonod[31135]: src/modem.c:modem_change_state() old state: 2, new state: 3
> ofonod[31135]: plugins/telit.c:telit_post_online() 0x99a81c8
> ofonod[31135]: drivers/atmodem/gprs-context.c:at_gprs_context_probe()
> ofonod[31135]: Modem: > AT+CSMS=?\r
> ofonod[31135]: Registered handle for channel 13: 0x10006
> ofonod[31135]: Modem: < \r\n+CSMS: (0,1)\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sms.c:at_csms_query_cb() CSMS query
> parsed successfully
> ofonod[31135]: Modem: > AT+CREG=?\r
> ofonod[31135]: Modem: < \r\n+CREG: (0-2)\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CSCS?\r
> ofonod[31135]: Modem: < \r\n+CSCS: "IRA"\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CUSD=1\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+CAOC=2\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+CCWE=1\r
> ofonod[31135]: Modem: < \r\nERROR\r\n
> ofonod[31135]: Modem: > AT+CGDCONT=?\r
> ofonod[31135]: Modem: < \r\n+CGDCONT:
> (1-16),"IP",,,(0-2),(0-4)\r\n+CGDCONT:
> (1-16),"PPP",,,(0-2),(0-4)\r\n+CGDCONT:
> (1-16),"IPV6",,,(0-2),(0-4)\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CRSM=192,28480\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,000000576F40040011F0440502011D\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_info_cb() crsm_info_cb: 90, 00, 15
> ofonod[31135]: Modem: > AT+CSMS=1\r
> ofonod[31135]: Modem: < \r\n+CSMS: 1,1,1\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CREG=2\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+CGREG=?\r
> ofonod[31135]: Modem: < \r\n+CGREG: (0-2)\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CRSM=178,28480,1,4,29\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 29
> ofonod[31135]: Modem: > AT+CSMS?\r
> ofonod[31135]: Modem: < \r\n+CSMS: 1,1,1,1\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CIND=?\r
> ofonod[31135]: Modem: < \r\n+CIND:
> (("battchg",(0-5,99)),("signal",(0-7,99)),("service",(0,1)),("sounder",(0,1)),("message",(0,1)),("call",(0,1)),("roam",(0,1)),("smsfull",(0,1)),("rssi",(0-5,99)))\r\n\r\nOK\r\n
> ofonod[31135]: src/sim.c:ofono_sim_add_spn_watch() 0x99aa0d8
> ofonod[31135]: Modem: > AT+CGREG=2\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+CGAUTO=0\r
> ofonod[31135]: Modem: < \r\nERROR\r\n
> ofonod[31135]: Modem: > AT+CGEREP=2,1\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: src/network.c:__ofono_netreg_add_status_watch() 0x99a6748
> ofonod[31135]: Modem: > AT+CRSM=178,28480,2,4,29\r
> ofonod[31135]: Registered handle for channel 1: 0x10007
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 29
> ofonod[31135]: Modem: > AT+CMGF=?\r
> ofonod[31135]: Modem: < \r\n+CMGF: (0,1)\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CMER=3,0,0,1\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 4\r\n
> ofonod[31135]: Modem: > AT+CREG?\r
> ofonod[31135]: Modem: < \r\n+CREG: 2,1,"3AF2","04DE9AE"\r\n\r\nOK\r\n
> ofonod[31135]: src/network.c:ofono_netreg_status_notify() /telit_0
> status 1 tech -1
> ofonod[31135]: src/gprs.c:netreg_status_changed() 1
> ofonod[31135]: Modem: > AT+CPSB=1\r
> ofonod[31135]: Modem: < \r\nERROR\r\n
> ofonod[31135]: Modem: > AT+CRSM=178,28480,3,4,29\r
> ofonod[31135]: Modem: < \r\n+CRSM:
> 144,0,FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sim.c:at_crsm_read_cb() crsm_read_cb: 90, 00, 29
> ofonod[31135]: Modem: > AT+CPMS=?\r
> ofonod[31135]: Modem: < \r\n+CPMS:
> ("ME","SM","SR"),("SM","ME"),("SM","ME")\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+COPS=3,2\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+COPS?\r
> ofonod[31135]: Modem: < \r\n+COPS: 0,2,"22210",2\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/network-registration.c:cops_numeric_cb()
> Cops numeric got mcc: 222, mnc: 10
> ofonod[31135]: Modem: > AT+CIND?\r
> ofonod[31135]: Modem: < \r\n+CIND: 5,0,1,0,0,0,0,0,3\r\n\r\nOK\r\n
> ofonod[31135]: src/network.c:ofono_netreg_strength_notify() strength 60
> ofonod[31135]: Modem: > AT+CGATT=1\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: src/gprs.c:gprs_attach_callback() /telit_0 error = 0
> ofonod[31135]: Modem: > AT+CIND?\r
> ofonod[31135]: Modem: < \r\n+CIND: 5,0,1,0,0,0,0,0,3\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CRSM=192,20256\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: Modem: > AT+CMGF=0\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+COPS=3,0\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+COPS?\r
> ofonod[31135]: Modem: < \r\n+COPS: 0,0,"vodafone IT",2\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/network-registration.c:cops_cb()
> cops_cb: vodafone IT, 222 10 2
> ofonod[31135]: src/network.c:current_operator_callback() 0x99a6748, (nil)
> ofonod[31135]: src/gprs.c:netreg_status_changed() 1
> ofonod[31135]: Modem: > AT+CGREG?\r
> ofonod[31135]: Modem: < \r\n+CGREG: 2,1,"3AF2","04DE9AE"\r\n\r\nOK\r\n
> ofonod[31135]: src/gprs.c:registration_status_cb() /telit_0 error 0 status 1
> ofonod[31135]: src/gprs.c:ofono_gprs_status_notify() /telit_0 status 1
> ofonod[31135]: Modem: > AT+CRSM=192,28618\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 22\r\n
> ofonod[31135]: Unable to read waiting messages numbers from SIM
> ofonod[31135]: Modem: > AT+CPMS="ME","ME","ME"\r
> ofonod[31135]: Modem: < \r\n+CPMS: 0,50,0,50,0,50\r\n\r\nOK\r\n
> ofonod[31135]: Modem: > AT+CRSM=192,28617\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 22\r\n
> ofonod[31135]: Unable to read mailbox identifies from SIM
> ofonod[31135]: Modem: > AT+CNMI=?\r
> ofonod[31135]: Modem: < \r\n+CNMI: (0-3),(0-3),(0,2),(0-2),(0,1)\r\n\r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sms.c:build_cnmi_string()
> ofonod[31135]: drivers/atmodem/sms.c:construct_ack_pdu()
> ofonod[31135]: Modem: > AT+CRSM=192,28433\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: src/message-waiting.c:mw_cphs_mwis_read_cb() No CPHS MWIS on SIM
> ofonod[31135]: Modem: > AT+CNMI=2,2,2,1,0\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: src/network.c:__ofono_netreg_add_status_watch() 0x99a6748
> ofonod[31135]: src/sms.c:sms_restore_tx_queue()
> ofonod[31135]: plugins/push-notification.c:sms_watch() registered
> ofonod[31135]: plugins/smart-messaging.c:sms_watch() registered
> ofonod[31135]: Modem: > AT+CRSM=192,28435\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
> ofonod[31135]: Modem: > AT+CMGL=4\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: drivers/atmodem/sms.c:at_cmgl_done()
> ofonod[31135]: Modem: > AT+CGSMS=3\r
> ofonod[31135]: Modem: < \r\nOK\r\n
> ofonod[31135]: Modem: > AT+CRSM=192,28613\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 22\r\n
> ofonod[31135]: src/simfs.c:sim_fs_op_read_block() bufoff: 0, seekoff:
> 39, toread: 17
> ofonod[31135]: EFspn read successfully, but couldn't parse
> ofonod[31135]: Modem: > AT+CRSM=192,28437\r
> ofonod[31135]: Modem: < \r\n+CME ERROR: 21\r\n
>
> A problem I see is that the test is not passing at second and
> subsequent tries if I do restart ofono. At second run, I don't see the
> whole thing evolving and bringing up modem services/interfaces the
> same way. To me it looks like, without power cycling the modem, I
> cannot stop and restart ofono and obtain the same behaviour.
> Sounds like, at cycles +CFUN=4 --> +CFUN=1 subsequent to the first,
> those #QSS=2 and #QSS=3 notifications are not coming from the modem:
> switch_sim_state_status() is not called and no sim readyness
> notification is making the thing evolve.
From your debug output below:
...
ofonod[31145]: Modem: > AT#QSS?\r
ofonod[31145]: Modem: < \r\n#QSS: 2,3\r\n\r\nOK\r\n
ofonod[31145]: plugins/telit.c:telit_qss_cb() 0x912a1c8
ofonod[31145]: plugins/telit.c:switch_sim_state_status() 0x912a1c8
ofonod[31145]: plugins/telit.c:switch_sim_state_status() SIM inserted and ready
...
In cfun_enable_cb() the current SIM state is queried and the modem
answeres with #QSS: 2,3
which means that SIM state notifications are enabled (2) and the SIM is
inserted and ready (3).
Strangely, the modem answers with '+CME ERROR: 10' (meaning no SIM
inserted) on a following AT+CPIN?
ofonod[31145]: Modem: > AT+CPIN?\r
ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> Do we need to ensure an hardware reset of the modem whenever ofono is
restarted?
I hope someone else will comment on that. I have no idea why your modem
behaves like that, with our UC864-G it is working without any reset.
>
> Unfortunately this is my first experience with ofono and I don't have
> an officially supported modem at hand: ofono features and behaviour
> understanding and modem support verification will necessarily go in
> parallel! Hope this feedback is useful.
>
> Here is the relevant log when repeating the test after restarting ofono:
>
> --> first test with positive results
> --> SIGTERM and restart ofono
> --> ./enable-modem
>
> ofonod[31145]: plugins/telit.c:telit_enable() 0x912a1c8
> ofonod[31145]: src/modem.c:get_modem_property() modem 0x912a1c8 property Modem
> ofonod[31145]: plugins/telit.c:open_device() Modem /dev/ttyUSB0
> ofonod[31145]: Modem: > ATE0 +CMEE=1\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: Modem: > AT+CFUN=4\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: plugins/telit.c:cfun_enable_cb() 0x912a1c8
> ofonod[31145]: examples/emulator.c:powered_watch() Adding modem
> 0x912a1c8 to the list
> ofonod[31145]: examples/emulator.c:create_tcp() Created server_watch: 38
> ofonod[31145]: examples/emulator.c:create_tcp() Created server_watch: 39
> ofonod[31145]: src/modem.c:modem_change_state() old state: 0, new state: 1
> ofonod[31145]: plugins/telit.c:telit_pre_sim() 0x912a1c8
> ofonod[31145]: Modem: > AT+GCAP\r
> ofonod[31145]: src/sim.c:ofono_sim_add_state_watch() 0x912c0d8
> ofonod[31145]: src/sim.c:ofono_sim_add_state_watch() 0x912c0d8
> ofonod[31145]: src/sim.c:ofono_sim_add_state_watch() 0x912c0d8
> ofonod[31145]: Modem: < \r\n+CREG: 1,"3AF2","04DE9AE"\r\n
> ofonod[31145]: Modem: < \r\n+CGREG: 1,"3AF2","04DE9AE"\r\n
> ofonod[31145]: Modem: < \r\n+GCAP: +CGSM,+DS,+FCLASS,+MS,+ES\r\n\r\nOK\r\n
> ofonod[31145]: Modem: > AT+CRC=1\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: Modem: > AT+CLIP=1\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: Modem: > AT+CDIP=1\r
> ofonod[31145]: Modem: < \r\nERROR\r\n
> ofonod[31145]: Modem: > AT+CNAP=1\r
> ofonod[31145]: Modem: < \r\nERROR\r\n
> ofonod[31145]: Modem: > AT+COLP=1\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: Modem: > AT+CSSN=1,1\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: Modem: > AT+VTD?\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: Modem: > AT+CCWA=1\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: drivers/atmodem/voicecall.c:at_voicecall_initialized()
> voicecall_init: registering to notifications
> ofonod[31145]: src/sim.c:ofono_sim_add_state_watch() 0x912c0d8
> ofonod[31145]: Modem: > AT#QSS=2\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: Modem: > AT#QSS?\r
> ofonod[31145]: Modem: < \r\n#QSS: 2,3\r\n\r\nOK\r\n
> ofonod[31145]: plugins/telit.c:telit_qss_cb() 0x912a1c8
> ofonod[31145]: plugins/telit.c:switch_sim_state_status() 0x912a1c8
> ofonod[31145]: plugins/telit.c:switch_sim_state_status() SIM inserted and ready
> ofonod[31145]: Modem: > AT+CGMI\r
> ofonod[31145]: Modem: < \r\nTelit\r\n\r\nOK\r\n
> ofonod[31145]: Modem: > AT+CLCC\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: Modem: > AT+CRSM=192,28599\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 100\r\n
> ofonod[31145]: src/voicecall.c:ecc_g2_read_cb() 0
> ofonod[31145]: Modem: > AT+CGMM\r
> ofonod[31145]: Modem: < \r\nUC864-E\r\n\r\nOK\r\n
> ofonod[31145]: Modem: > AT+CRSM=192,28599\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 100\r\n
> ofonod[31145]: src/voicecall.c:ecc_g3_read_cb() 0
> ofonod[31145]: Modem: > AT+CGMR\r
> ofonod[31145]: Modem: < \r\n08.01.005\r\n\r\nOK\r\n
> ofonod[31145]: Modem: > AT+CRSM=192,12258\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 100\r\n
> ofonod[31145]: Modem: > AT+CGSN\r
> ofonod[31145]: Modem: < \r\n352636020412669\r\n\r\nOK\r\n
> ofonod[31145]: Modem: > AT+CRSM=192,28421\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 100\r\n
> ofonod[31145]: Modem: > AT+CRSM=192,12037\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 100\r\n
> ofonod[31145]: Modem: > AT+CPIN?\r
> ofonod[31145]: Modem: < \r\n+CME ERROR: 10\r\n
> ofonod[31145]: Querying PIN authentication state failed
>
> ---> ./online-modem
>
> ofonod[31145]: Modem: < \r\n+CREG: 0\r\n
> ofonod[31145]: Modem: < \r\n+CGREG: 0\r\n
> ofonod[31145]: plugins/telit.c:telit_set_online() modem 0x912a1c8 online
> ofonod[31145]: Modem: > AT+CFUN=1\r
> ofonod[31145]: Modem: < \r\nOK\r\n
> ofonod[31145]: src/modem.c:common_online_cb() Online in PRE SIM state
> ofonod[31145]: Modem: < \r\n+CGEV: ME DETACH\r\n
> ofonod[31145]: Modem: < \r\n+CREG: 2\r\n
> ofonod[31145]: Modem: < \r\n+CREG: 1,"3AF2","04DE9AE"\r\n
> ofonod[31145]: Modem: < \r\n+CGREG: 2\r\n
> ofonod[31145]: Modem: < \r\n+CGREG: 1,"3AF2","04DE9AE"\r\n
>
> Any comments on this?
>
--
Scanned by MailScanner.
next prev parent reply other threads:[~2012-07-27 9:37 UTC|newest]
Thread overview: 7+ messages / expand[flat|nested] mbox.gz Atom feed top
2012-07-26 13:36 [PATCH] telit: notify sim inserted when sim ready Christopher Vogl
2012-07-26 15:19 ` Andrea Galbusera
2012-07-27 9:37 ` Christopher Vogl [this message]
2012-07-27 9:58 ` Andrea Galbusera
2012-07-27 10:54 ` Christopher Vogl
2012-07-27 11:52 ` Christopher Vogl
2012-07-27 12:39 ` Andrea Galbusera
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=5012615F.4010004@hale.at \
--to=christopher.vogl@hale.at \
--cc=ofono@ofono.org \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox