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 an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.