Confirmed. It only works the second time, and only if NM ignores the error on ATZ...
Here are the logs. First time: Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Activation (ttyS0) starting connection 'Movistar' Dec 16 21:25:45 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 3 -> 4 Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) scheduled... Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) started... Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.360066] nm_serial_device_open(): (ttyS0) opening device... Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) complete. Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.460799] nm_serial_debug(): Sending: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.493567] nm_serial_debug(): Got: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.497491] nm_serial_debug(): Got: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ERROR ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.497592] nm_serial_debug(): Sending: 'AT+CPIN? ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.508652] nm_serial_debug(): Got: 'AT+CPI' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.509334] nm_serial_debug(): Got: 'AT+CPIN? +CPIN: SIM PIN OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.509453] nm_serial_debug(): Sending: 'AT+CPIN="4553" ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.528491] nm_serial_debug(): Got: 'AT+CPIN="455' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.528926] nm_serial_debug(): Got: 'AT+CPIN="4553" ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.628577] nm_serial_debug(): Got: 'AT+CPIN="4553" OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.628821] nm_serial_debug(): Sending: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.662414] nm_serial_debug(): Got: 'ATZ E0 V1 X4 &C1 +FCLASS=0' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.664543] nm_serial_debug(): Got: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.672619] nm_serial_debug(): Got: 'ATZ E0 V1 X4 &C1 +FCLASS=0 OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <info> (ttyS0): powering up... Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.672925] nm_serial_debug(): Sending: 'AT+CFUN=1 ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.688693] nm_serial_debug(): Got: ' OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.688980] nm_serial_debug(): Sending: 'AT+CREG? ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.704622] nm_serial_debug(): Got: ' +CREG: 0,2 OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Searching for a network... Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.705202] nm_serial_debug(): Sending: 'AT+CREG? ' Dec 16 21:25:45 fiol-laptop NetworkManager: <debug> [1229459145.721618] nm_serial_debug(): Got: ' +CREG: 0,2 OK ' Dec 16 21:25:45 fiol-laptop NetworkManager: <info> Searching for a network... [... many of these ...] Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.317349] nm_serial_debug(): Sending: 'AT+CREG? ' Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.384808] nm_serial_debug(): Got: ' +CREG: 0,2 OK ' Dec 16 21:25:50 fiol-laptop NetworkManager: <info> Searching for a network... Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.385329] nm_serial_debug(): Sending: 'AT+CREG? ' Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.400810] nm_serial_debug(): Got: ' +CREG: 0,1 OK ' Dec 16 21:25:50 fiol-laptop NetworkManager: <info> Registered on Home network Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.401282] nm_serial_debug(): Sending: 'AT+COPS? ' Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.768863] nm_serial_debug(): Got: ' +COPS: 0,0,"M^YK6 ]0r",2 OK ' Dec 16 21:25:50 fiol-laptop NetworkManager: <info> Associated with network: +COPS: 0,0,"M^YK6 Dec 16 21:25:50 fiol-laptop NetworkManager: <debug> [1229459150.769468] nm_serial_debug(): Sending: 'AT+CGDCONT=1, "IP", "movistar.es" ' Dec 16 21:25:51 fiol-laptop NetworkManager: <debug> [1229459151.108802] nm_serial_debug(): Got: ' OK ' Dec 16 21:25:51 fiol-laptop NetworkManager: <debug> [1229459151.109608] nm_serial_debug(): Sending: 'ATD*99***1# ' Dec 16 21:25:51 fiol-laptop NetworkManager: <debug> [1229459151.164804] nm_serial_debug(): Got: ' CONNECT ' Dec 16 21:25:51 fiol-laptop NetworkManager: <info> Connected, Woo! Dec 16 21:25:51 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) scheduled... Dec 16 21:25:51 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) starting... Dec 16 21:25:51 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 4 -> 5 Dec 16 21:25:51 fiol-laptop NetworkManager: <info> Starting pppd connection Dec 16 21:25:51 fiol-laptop NetworkManager: <debug> [1229459151.237338] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock debug user movistar ttyS0 noipdefault usepeerdns ipparam /org/freedesktop/NetworkManager/PPP/3 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so Dec 16 21:25:51 fiol-laptop NetworkManager: <debug> [1229459151.239104] nm_ppp_manager_start(): ppp started with pid 24338 Dec 16 21:25:51 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) complete. Dec 16 21:25:51 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 5 -> 6 Dec 16 21:25:51 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 6 -> 7 Dec 16 21:26:06 fiol-laptop NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module Dec 16 21:26:06 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 7 -> 9 Dec 16 21:26:06 fiol-laptop NetworkManager: <debug> [1229459166.247688] nm_serial_device_close(): Closing device 'ttyS0' Dec 16 21:26:06 fiol-laptop NetworkManager: <info> Marking connection 'Movistar' invalid. Dec 16 21:26:06 fiol-laptop NetworkManager: <info> Activation (ttyS0) failed. Dec 16 21:26:06 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 9 -> 3 Dec 16 21:26:06 fiol-laptop NetworkManager: <info> (ttyS0): deactivating device (reason: 0). Dec 16 21:26:06 fiol-laptop NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed Dec 16 21:26:06 fiol-laptop NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed Dec 16 21:26:08 fiol-laptop NetworkManager: <debug> [1229459168.252708] ensure_killed(): waiting for ppp pid 24338 to exit Dec 16 21:26:08 fiol-laptop NetworkManager: <debug> [1229459168.252904] ensure_killed(): ppp pid 24338 cleaned up Second time: Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) starting connection 'Movistar' Dec 16 21:26:20 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 3 -> 4 Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) scheduled... Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) started... Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.428758] nm_serial_device_open(): (ttyS0) opening device... Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) complete. Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.533779] nm_serial_debug(): Sending: 'ATZ E0 V1 X4 &C1 +FCLASS=0 ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.566899] nm_serial_debug(): Got: ' NO CARRIER ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.584552] nm_serial_debug(): Got: ' NO CARRIER OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.584750] nm_serial_debug(): Sending: 'AT+CPIN? ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.601682] nm_serial_debug(): Got: ' +CPIN: READY OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <info> (ttyS0): powering up... Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.602094] nm_serial_debug(): Sending: 'AT+CFUN=1 ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.620760] nm_serial_debug(): Got: ' OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.621071] nm_serial_debug(): Sending: 'AT+CREG? ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.640756] nm_serial_debug(): Got: ' +CREG: 0,1 OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Registered on Home network Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.641227] nm_serial_debug(): Sending: 'AT+COPS? ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.744616] nm_serial_debug(): Got: ' +COPS: 0,0,"M^YK6 ]0r",2 OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Associated with network: +COPS: 0,0,"M^YK6 Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.745041] nm_serial_debug(): Sending: 'AT+CGDCONT=1, "IP", "movistar.es" ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.964829] nm_serial_debug(): Got: ' OK ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.965166] nm_serial_debug(): Sending: 'ATD*99***1# ' Dec 16 21:26:20 fiol-laptop NetworkManager: <debug> [1229459180.984761] nm_serial_debug(): Got: ' CONNECT ' Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Connected, Woo! Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) scheduled... Dec 16 21:26:20 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) starting... Dec 16 21:26:20 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 4 -> 5 Dec 16 21:26:21 fiol-laptop NetworkManager: <info> Starting pppd connection Dec 16 21:26:21 fiol-laptop NetworkManager: <debug> [1229459181.055724] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock debug user movistar ttyS0 noipdefault usepeerdns ipparam /org/freedesktop/NetworkManager/PPP/4 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so Dec 16 21:26:21 fiol-laptop NetworkManager: <debug> [1229459181.058352] nm_ppp_manager_start(): ppp started with pid 24348 Dec 16 21:26:21 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) complete. Dec 16 21:26:21 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 5 -> 6 Dec 16 21:26:21 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 6 -> 7 Dec 16 21:26:23 fiol-laptop NetworkManager: <info> PPP manager(IP Config Get) reply received. Dec 16 21:26:23 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 4 of 5 (IP Configure Get) scheduled... Dec 16 21:26:23 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 4 of 5 (IP Configure Get) started... Dec 16 21:26:23 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 5 of 5 (IP Configure Commit) scheduled... Dec 16 21:26:23 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 4 of 5 (IP Configure Get) complete. Dec 16 21:26:23 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 5 of 5 (IP Configure Commit) started... Dec 16 21:26:24 fiol-laptop NetworkManager: <info> (ttyS0): device state change: 7 -> 8 Dec 16 21:26:24 fiol-laptop NetworkManager: <info> Policy set 'Movistar' (ppp0) as default for routing and DNS. Dec 16 21:26:24 fiol-laptop NetworkManager: <info> Activation (ttyS0) successful, device activated. Dec 16 21:26:24 fiol-laptop NetworkManager: <info> Activation (ttyS0) Stage 5 of 5 (IP Configure Commit) complete. -- UMTS PCMCIA modem not picked up https://bugs.launchpad.net/bugs/248630 You received this bug notification because you are a member of Ubuntu Bugs, which is subscribed to Ubuntu. -- ubuntu-bugs mailing list [email protected] https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
