Sep 13 14:55:50 vimes ModemManager[1360]: Simple connect started... Sep 13 14:55:50 vimes ModemManager[1360]: PIN: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Operator ID: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Allowed roaming: yes Sep 13 14:55:50 vimes ModemManager[1360]: APN: h2g2 Sep 13 14:55:50 vimes NetworkManager[1435]: [1505339750.6248] (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested) Sep 13 14:55:50 vimes ModemManager[1360]: IP family: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Allowed authentication: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: User: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Password: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Number: unspecified Sep 13 14:55:50 vimes ModemManager[1360]: Simple connect state (4/8): Wait to get fully enabled Sep 13 14:55:50 vimes ModemManager[1360]: Simple connect state (5/8): Register Sep 13 14:55:50 vimes ModemManager[1360]: Already registered in network '310260', automatic registration not launched... Sep 13 14:55:50 vimes ModemManager[1360]: Simple connect state (6/8): Bearer Sep 13 14:55:50 vimes ModemManager[1360]: Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'... Sep 13 14:55:50 vimes ModemManager[1360]: Simple connect state (7/8): Connect Sep 13 14:55:50 vimes ModemManager[1360]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0' Sep 13 14:55:50 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting) Sep 13 14:55:50 vimes ModemManager[1360]: Launching connection with data port (net/wwp0s20u4) Sep 13 14:55:50 vimes ModemManager[1360]: Activating packet service... Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 52 <<<<<< data = 03:00:00:00:34:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 52 <<<<<< type = command (0x00000003) <<<<<< transaction = 26 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'packet-service' (0x0000000a) <<<<<< type = 'set' (0x00000001) Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 76 >>>>>> data = 03:00:00:80:4C:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 76 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 26 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'packet-service' (0x0000000a) Sep 13 14:55:50 vimes ModemManager[1360]: Packet service update: Sep 13 14:55:50 vimes ModemManager[1360]: state: 'attached' Sep 13 14:55:50 vimes ModemManager[1360]: data class: 'lte' Sep 13 14:55:50 vimes ModemManager[1360]: uplink: '50000000' bps Sep 13 14:55:50 vimes ModemManager[1360]: downlink: '100000000' bps Sep 13 14:55:50 vimes ModemManager[1360]: Listing provisioned contexts... Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 27 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'provisioned-contexts' (0x0000000d) <<<<<< type = 'query' (0x00000000) Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 52 >>>>>> data = 03:00:00:80:34:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 52 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 27 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'provisioned-contexts' (0x0000000d) Sep 13 14:55:50 vimes ModemManager[1360]: Provisioned contexts found (0): Sep 13 14:55:50 vimes ModemManager[1360]: No specific IP family requested, defaulting to ipv4 Sep 13 14:55:50 vimes ModemManager[1360]: Launching ipv4 connection with APN 'h2g2'... Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 116 <<<<<< data = 03:00:00:00:74:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:44:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:68:00:32:00:67:00:32:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 116 <<<<<< type = command (0x00000003) <<<<<< transaction = 28 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'connect' (0x0000000c) <<<<<< type = 'set' (0x00000001) Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 48 >>>>>> data = 03:00:00:80:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:1C:00:00:00:00:00:00:00 Sep 13 14:55:50 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 48 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 28 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'OperationNotAllowed' (0x0000001c) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'connect' (0x0000000c) Sep 13 14:55:50 vimes ModemManager[1360]: Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'OperationNotAllowed' Sep 13 14:55:50 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered) Sep 13 14:55:50 vimes ModemManager[1360]: Couldn't connect bearer: 'OperationNotAllowed' Sep 13 14:55:50 vimes NetworkManager[1435]: [1505339750.6384] (cdc-wdm0): modem state changed, 'connecting' --> 'registered' (reason: user-requested)