From: Christopher Vogl <christopher.vogl@hale.at>
To: ofono@ofono.org
Subject: Re: Re-activating a context.
Date: Fri, 13 Jul 2012 12:12:27 +0200 [thread overview]
Message-ID: <4FFFF48B.7040903@hale.at> (raw)
In-Reply-To: <4FFF3858.8000605@gmail.com>
[-- Attachment #1: Type: text/plain, Size: 26320 bytes --]
Hi Denis,
On 12/07/12 22:49, Denis Kenzior wrote:
> Hi Christopher,
>
> On 07/12/2012 09:50 AM, Christopher Vogl wrote:
>> Hello,
>>
>> I am trying to simulate a connection loss and after a valid ppp
>> connection I disconnect the antenna to get detached from GPRS.
>> After reconnecting the antenna everything seems to work fine just until
>> activating the context.
>>
>> Ofono seems to get stuck in
>> drivers/atmodem/gprs-context.c:at_gprs_activate_primary() and
>> AT+CGDCONT=1 is missing on the debug output.
>> When I retry to activate the context after some minutes with a dbus-send
>> call, I receive an operation in progress error.
>>
>> Below I have attached ofono's debug output and dbus-send calls for
>> important interfaces
>> - before removing the antenna
>> - after removing the antenna
>> - after reconnecting the antenna
>>
>> Modem in use: Telit UC864-G
>>
>> connMan in the debug output is my own simple connection manger. I am not
>> sure if I missed to set some ofono state or if there is a known issue.
>> I really would appreciate a hint in this case.
>>
>
> To me it looks like your PPP connection has not been dropped when the
> antenna has been removed. Today oFono assumes that the PPP connection
> will be dropped by the modem in this case, and your modem does not do
> this. This leads to problems because the AT channel on which the PPP
> connection is being run can not accept additional commands. This is
> why you're not seeing the CGDCONT.
>
> A while ago Guillaume posted a patch to fix this problem (we saw this
> with Huawei PPP based devices). The patch looked good to me but
> needed further testing (e.g. for regressions). Can you dig the
> mailing archives and test that patch?
>
> Or maybe Guillaume will be nice enough to repost it to the mailing list.
Thanks Denis!
I found the patch from Guillaume and applied it (ofono 1.7 & 1.8).
The ppp connection seems to get dropped now when I remove the antenna
but does not come up again correctly when I reconnect the antenna.
Regards,
Christopher
After disconnecting the antenna:
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CREG: 2\r\n
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
src/network.c:ofono_netreg_status_notify() /telit_0 status 2 tech -1
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
src/network.c:current_operator_callback() 0x1bed30, (nil)
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:netreg_status_changed() 2
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: Aux: > AT+CGATT=0\r
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG: 2\r\n
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 2
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:at_gprs_release_primary() cid 1
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 9:OPENED
Jan 1 01:18:53 mx31tt01 user.debug connMan[803]: Got network
registration signal Status: 'searching'
Jan 1 01:18:53 mx31tt01 user.err connMan[803]:
checkAndSetOperatorSelection()
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: event: 3 (Close),
action: 8224, new_state: 4 (CLOSING)
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_initialize_restart_count: current state 9:OPENED
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_send_terminate_request: current state 9:OPENED
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 9:OPENED
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: event: 1 (Down),
action: 201, new_state: 1 (STARTING)
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 5
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
plugins/udevng.c:remove_device() /sys/devices/virtual/net/ppp0
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
plugins/udev.c:udev_event() subsystem net remove
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
plugins/udev.c:remove_modem() /devices/virtual/net/ppp0
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
plugins/udev.c:udev_event() subsystem net finished
Jan 1 01:18:53 mx31tt01 user.debug connMan[803]: Got connection manager
signal Attached, Val = false
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_process_terminate_ack: current state 4:CLOSING
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 4:CLOSING
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: event: 11 (RTA),
action: 802, new_state: 2 (CLOSED)
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_this_layer_finished: current state 2:CLOSED
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 0
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:at_gprs_deactivate_primary() cid 1
Jan 1 01:18:53 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_dead()
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:ppp_disconnect()
Jan 1 01:18:53 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:gprs_remove_context() Unregistering context: /telit_0/context1
Jan 1 01:18:53 mx31tt01 user.debug connMan[803]: Got connection manager
signal Powered, Val = false
Jan 1 01:18:57 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CME ERROR:
30\r\n
Jan 1 01:18:57 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:gprs_attach_callback() /telit_0 error = 1
Jan 1 01:18:57 mx31tt01 daemon.info ofonod[810]: Aux: > AT+CGREG?\r
Jan 1 01:18:57 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG:
2,2\r\n\r\nOK\r\n
Jan 1 01:18:57 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:registration_status_cb() /telit_0 error 0 status 2
Jan 1 01:18:57 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 2
Jan 1 01:19:30 mx31tt01 daemon.info ofonod[810]: Modem: < \r\nNO
CARRIER\r\n
After reconnecting the antenna:
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Modem: < \r\n+CGEV: ME
DETACH\r\n
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGEV: ME
DETACH\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_detached_notify() /telit_0
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Modem: < \r\n+CREG:
1,"0340","C149"\r\n
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CREG:
1,"0340","C149"\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/network.c:ofono_netreg_status_notify() /telit_0 status 1 tech 3
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal Status: 'registered'
Jan 1 01:20:12 mx31tt01 user.err connMan[803]:
checkAndSetOperatorSelection()
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:netreg_status_changed() 1
Jan 1 01:20:12 mx31tt01 user.notice ttsession: void
hale::gtk::StatusBar::on_tech_changed(const std::string&), this =
0x1316000, New technology: edge
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+COPS=3,2\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG: 0\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 0
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Modem: < \r\n+CGREG:
0\r\n
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal LocationAreaCode: uint16 832
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal CellId: uint32 49481
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal Technology: 'edge'
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got connection manager
signal Powered, Val = true
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+COPS?\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+COPS:
0,2,"26202",0\r\n\r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/network-registration.c:cops_numeric_cb() Cops numeric
got mcc: 262, mnc: 02
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+CIND?\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CIND:
4,99,1,0,0,0,0,0,1\r\n\r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/network.c:ofono_netreg_strength_notify() strength 20
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal Strength: byte 0x14
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+CGATT=1\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:gprs_attach_callback() /telit_0 error = 0
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+COPS=3,0\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+COPS?\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+COPS:
0,0,"Vodafone D2",0\r\n\r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/network-registration.c:cops_cb() cops_cb: Vodafone D2,
262 02 0
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/network.c:current_operator_callback() 0x1bed30, (nil)
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal Name: 'Vodafone D2'
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:netreg_status_changed() 1
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal MobileCountryCode: '262'
Jan 1 01:20:12 mx31tt01 user.debug connMan[803]: Got network
registration signal MobileNetworkCode: '02'
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: > AT+CGREG?\r
Jan 1 01:20:12 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG:
2,0\r\n\r\nOK\r\n
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:registration_status_cb() /telit_0 error 0 status 0
Jan 1 01:20:12 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 0
Jan 1 01:20:14 mx31tt01 daemon.info ofonod[810]: Modem: < \r\n+CGREG:
2\r\n
Jan 1 01:20:14 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG: 2\r\n
Jan 1 01:20:14 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 2
Jan 1 01:20:14 mx31tt01 daemon.info ofonod[810]: Modem: < \r\n+CGREG:
1,"0340","C149"\r\n
Jan 1 01:20:14 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGREG:
1,"0340","C149"\r\n
Jan 1 01:20:14 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:ofono_gprs_status_notify() /telit_0 status 1
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Got connection manager
signal Attached, Val = true
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Getting first internet
context ...
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: No internet context
found! Adding a new internet context.
Jan 1 01:20:14 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:add_context() Registering new context
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Context path:
/telit_0/context2
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Connecting to
org.ofono.ConnectionContext PropertyChanged signal.
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Connecting to
org.ofono.ConnectionContext ContextAdded signal.
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Connecting to
org.ofono.ConnectionContext ContextRemoved signal.
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]:
set_and_activate_context()
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: Setting and Activating
the Internet connection context.
Jan 1 01:20:14 mx31tt01 user.debug connMan[803]: mccmnc = '26202', len = 5
Jan 1 01:20:15 mx31tt01 user.debug connMan[803]: Provider name '1&1'
Jan 1 01:20:15 mx31tt01 user.debug connMan[803]: Set AccessPointName:
web.vodafone.de
Jan 1 01:20:15 mx31tt01 user.debug connMan[803]: Set Username:
Jan 1 01:20:15 mx31tt01 user.debug connMan[803]: Set Password:
Jan 1 01:20:15 mx31tt01 user.debug connMan[803]: Trying to set
context:web.vodafone.de
Jan 1 01:20:15 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:at_gprs_activate_primary() cid 1
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: Modem: >
AT+CGDCONT=1,"IP","web.vodafone.de"\r
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: Modem: < \r\nOK\r\n
Jan 1 01:20:15 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:at_cgdcont_cb() ok 1
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: Modem: >
AT+CGDATA="PPP",1\r
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: Modem: < \r\nCONNECT\r\n
Jan 1 01:20:15 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:at_cgdata_cb() ok 1
Jan 1 01:20:15 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:setup_ppp()
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 0:INITIAL
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 0 (Up),
action: 2, new_state: 2 (CLOSED)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 2:CLOSED
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 2 (Open),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_initialize_restart_count: current state 2:CLOSED
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_send_configure_request: current state 2:CLOSED
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 1
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_process_configure_request: current state 6:REQSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 6 (RCR+),
action: 2008, new_state: 8 (ACKSENT)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_send_configure_ack: current state 6:REQSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_process_configure_ack: current state 8:ACKSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 8:ACKSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 8 (RCA),
action: 129, new_state: 9 (OPENED)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_initialize_restart_count: current state 8:ACKSENT
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 2
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 3
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 0:INITIAL
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 2 (Open),
action: 401, new_state: 1 (STARTING)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 1:STARTING
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: event: 0 (Up),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 1:STARTING
Jan 1 01:20:15 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 1:STARTING
Jan 1 01:20:16 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:16 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:16 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:16 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:16 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:17 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:17 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:17 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:17 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:17 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:18 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:18 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:18 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:18 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:18 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:19 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:19 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:19 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:19 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:19 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:20 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:20 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:20 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:20 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:20 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_process_configure_nak: current state 6:REQSENT
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: PPP: event: 9 (RCN),
action: 1026, new_state: 6 (REQSENT)
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_initialize_restart_count: current state 6:REQSENT
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:21 mx31tt01 daemon.info ofonod[810]: Aux: < \r\n+CGEV: ME
DEACT IP, , 1\r\n
Jan 1 01:20:24 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:24 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:24 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:24 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:27 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:27 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:27 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:27 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:30 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:30 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:30 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:30 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:33 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:33 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:33 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:33 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:36 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:36 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:36 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:36 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:39 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:39 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:39 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:39 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:40 mx31tt01 user.debug connMan[803]: Got connection context
signal AccessPointName, Val = 'web.vodafone.de'
Jan 1 01:20:40 mx31tt01 user.info connMan[803]: APN: web.vodafone.de
Jan 1 01:20:42 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:42 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:42 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:42 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:45 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:45 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:45 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:45 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:48 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:48 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:48 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 1006, new_state: 6 (REQSENT)
Jan 1 01:20:48 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_send_configure_request: current state 6:REQSENT
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_timeout: current state 6:REQSENT
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 6:REQSENT
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: event: 5 (TO-),
action: 803, new_state: 3 (STOPPED)
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_this_layer_finished: current state 3:STOPPED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 3:STOPPED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: event: 3 (Close),
action: 2, new_state: 2 (CLOSED)
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 9:OPENED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: event: 3 (Close),
action: 8224, new_state: 4 (CLOSING)
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_initialize_restart_count: current state 9:OPENED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_send_terminate_request: current state 9:OPENED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: ipcp:
pppcp_generate_event: current state 2:CLOSED
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP: event: 1 (Down),
action: 0, new_state: 0 (INITIAL)
Jan 1 01:20:51 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 5
Jan 1 01:20:54 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_timeout: current state 4:CLOSING
Jan 1 01:20:54 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 4:CLOSING
Jan 1 01:20:54 mx31tt01 daemon.info ofonod[810]: PPP: event: 4 (TO+),
action: 8004, new_state: 4 (CLOSING)
Jan 1 01:20:54 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_send_terminate_request: current state 4:CLOSING
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_timeout: current state 4:CLOSING
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_generate_event: current state 4:CLOSING
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP: event: 5 (TO-),
action: 802, new_state: 2 (CLOSED)
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP: lcp:
pppcp_this_layer_finished: current state 2:CLOSED
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_enter_phase() 0
Jan 1 01:20:57 mx31tt01 daemon.info ofonod[810]: PPP:
gatchat/gatppp.c:ppp_dead()
Jan 1 01:20:57 mx31tt01 daemon.debug ofonod[810]:
drivers/atmodem/gprs-context.c:ppp_disconnect()
Jan 1 01:20:57 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:pri_activate_callback() 0x1ce500
Jan 1 01:20:57 mx31tt01 daemon.debug ofonod[810]:
src/gprs.c:pri_activate_callback() Activating context failed with error:
Unknown error type
--
Scanned by MailScanner.
next prev parent reply other threads:[~2012-07-13 10:12 UTC|newest]
Thread overview: 7+ messages / expand[flat|nested] mbox.gz Atom feed top
2012-07-12 14:50 Re-activating a context Christopher Vogl
2012-07-12 20:49 ` Denis Kenzior
2012-07-13 10:12 ` Christopher Vogl [this message]
2012-07-13 13:39 ` Guillaume Zajac
2012-07-16 12:31 ` Christopher Vogl
2012-08-29 18:40 ` Chrisopher Vogl
2012-08-29 23:56 ` Denis Kenzior
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=4FFFF48B.7040903@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