newly created sip account is not saved following a jami restart
Hi,
I have this surprising problem, only one one out of two machines:
- Add existing sip account, by accessing the advanced menu, and entering the SIP host name, user name and password.
- The account is added and connects fine.
- Quit Jami.
- Start jami again. Look at the accounts list. The just added SIP account is not there.
Since I can't reproduce on one machine, it probably has to do with the state of the account files on disk. The logs don't say much (creating the SIP account then quitting Jami):
[1668397168.658|2044|nat_pmp.cpp :78 ] NAT-PMP: Trying to initialize IGD
[1668397168.659|2044|nat_pmp.cpp :106 ] NAT-PMP: Initialized on gateway 192.168.10.1
[1668397168.662|2044|nat_pmp.cpp :560 ] NAT-PMP: Read response on IGD 192.168.10.1 failed - NOGATEWAYSUPPORT
[1668397168.662|2044|nat_pmp.cpp :242 ] NAT-PMP: Setup failed after 3 trials. NAT-PMP will be disabled!
[1668397178.183|2045|pupnp.cpp :352 ] PUPnP: Setup failed after 3 trials. PUPnP will be disabled!
[1668397199.767|2100|sipaccount.cpp :2346] All audio codecs disabled, enabling all
[1668397199.768|2100|sipaccount.cpp :2350] All video codecs disabled, enabling all
[1668397199.769|2100|manager.cpp :2726] Adding account 73930db6b10f132e
[1668397199.772|2100|sipaccount.cpp :2346] All audio codecs disabled, enabling all
[1668397199.772|2100|sipaccount.cpp :2350] All video codecs disabled, enabling all
[1668397199.773|2100|upnp_control.cpp :39 ] Controller@0x7f72e15eb980: Created UPnP Controller session
[1668397199.773|2036|upnp_context.cpp :415 ] Successfully registered controller 0x7f72e15eb980
[1668397199.773|2100|sipaccount.cpp :1779] Set SIP registration EXPIRE to 3600 - current 3600
[1668397199.774|2100|sipaccount.cpp :1833] Presence enabled for 73930db6b10f132e : false.
[1668397199.774|2100|sipaccount.cpp :655 ] No credentials set, inferring them...
[1668397199.775|2100|manager.cpp :1704] Saving Configuration to XDG directory /home/mcournoyer/.config/jami/dring.yml
[1668397199.780|2100|sipaccount.cpp :830 ] doRegister
[1668397199.780|2100|sipaccount.cpp :834 ] UPnP: waiting for IGD to register SIP account
[1668397199.780|2100|upnp_context.cpp :315 ] Try to find mapping for port 5060 [UDP]
[1668397199.781|2100|upnp_context.cpp :347 ] Did not find any available mapping. Will request one now
AccountModelPimpl::slotVolatileAccountDetailsChanged, can't find "73930db6b10f132e"
[1668397199.781|2100|upnp_context.cpp :1143] No IGD available. Mapping will be requested when an IGD becomes available
[1668397199.781|2100|mapping.cpp :86 ] Changing mapping JAMI-UDP:5060 state from AVAILABLE to UNAVAILABLE
[1668397199.781|2100|sipaccount.cpp :763 ] [Account 73930db6b10f132e] Failed to open port 5060: registering SIP account anyway
[1668397199.783|2100|siptransport.cpp :331 ] Created UDP transport on address 0.0.0.0:5060
[1668397199.783|2100|siptransport.cpp :79 ] SipTransport@0x7f72e15f4010 tr=0x7f72e15f3c58 rc=2
[1668397199.783|2100|sipaccount.cpp :333 ] Set new transport [0x7f72e15f4010]
[1668397199.784|2100|message_engine.cpp :267 ] [Account 73930db6b10f132e] couldn't load messages from /home/mcournoyer/.cache/jami/73930db6b10f132e/messages: basic_ios::clear: iostream error
[1668397199.784|2100|sipaccount.cpp :837 ] UPnP: UPNP request failed, try to register SIP account anyway
[1668397199.784|2036|upnp_context.cpp :628 ] UPNP/NAT-PMP enabled, but no valid IGDs available
AccountModelPimpl::slotVolatileAccountDetailsChanged, can't find "73930db6b10f132e"
[1668397199.784|2100|siptransport.cpp :290 ] Reusing transport 0.0.0.0:5060
[1668397199.787|2100|manager.cpp :1704] Saving Configuration to XDG directory /home/mcournoyer/.config/jami/dring.yml
Syncing lrc accounts list with the daemon
"detected new account 73930db6b10f132e"
database "/home/mcournoyer/.local/share/jami/73930db6b10f132e/history.db" version set to: "1"
[1668397199.810|2029|sipaccount.cpp :326 ] Removing old transport [0x7f72e15f4010] from account
[1668397199.810|2029|siptransport.cpp :100 ] ~SipTransport@0x7f72e15f4010 tr=0x7f72e15f3c58 rc=2
[1668397199.811|2029|sipaccount.cpp :333 ] Set new transport [(nil)]
[1668397199.811|2029|sipaccount.cpp :830 ] doRegister
[1668397199.811|2029|sipaccount.cpp :834 ] UPnP: waiting for IGD to register SIP account
[1668397199.811|2029|upnp_context.cpp :315 ] Try to find mapping for port 5060 [UDP]
[1668397199.811|2029|upnp_context.cpp :347 ] Did not find any available mapping. Will request one now
[1668397199.811|2029|upnp_context.cpp :1133] Mapping request for JAMI-UDP:5060 already added!
[1668397199.811|2041|message_engine.cpp :333 ] [Account 73930db6b10f132e] saved 0 messages to /home/mcournoyer/.cache/jami/73930db6b10f132e/messages
[1668397199.811|2036|upnp_context.cpp :628 ] UPNP/NAT-PMP enabled, but no valid IGDs available
[1668397199.811|2029|sipaccount.cpp :837 ] UPnP: UPNP request failed, try to register SIP account anyway
[1668397199.812|2029|siptransport.cpp :294 ] Recycling transport 0.0.0.0:5060
[1668397199.812|2029|siptransport.cpp :79 ] SipTransport@0x9195fc0 tr=0x7f72e15f3c58 rc=2
[1668397199.812|2029|sipaccount.cpp :333 ] Set new transport [0x9195fc0]
[1668397199.812|2029|manager.cpp :2668] Set account details for 73930db6b10f132e
[1668397199.813|2029|sipaccount.cpp :326 ] Removing old transport [0x9195fc0] from account
[1668397199.813|2029|siptransport.cpp :100 ] ~SipTransport@0x9195fc0 tr=0x7f72e15f3c58 rc=2
[1668397199.813|2029|sipaccount.cpp :333 ] Set new transport [(nil)]
[1668397199.813|2029|account.cpp :522 ] Couldn't find key "Account.allModeratorEnabled"
[1668397199.813|2029|account.cpp :505 ] Couldn't find key "Account.bindAddress"
[1668397199.813|2029|sipaccount.cpp :1779] Set SIP registration EXPIRE to 3600 - current 3600
[1668397199.813|2029|sipaccount.cpp :1833] Presence enabled for 73930db6b10f132e : false.
[1668397199.814|2029|manager.cpp :1704] Saving Configuration to XDG directory /home/mcournoyer/.config/jami/dring.yml
[1668397199.815|2029|sipaccount.cpp :830 ] doRegister montreal6.voip.ms
[1668397199.816|2029|sipaccount.cpp :834 ] UPnP: waiting for IGD to register SIP account
[1668397199.816|2043|message_engine.cpp :333 ] [Account 73930db6b10f132e] saved 0 messages to /home/mcournoyer/.cache/jami/73930db6b10f132e/messages
[1668397199.816|2029|upnp_context.cpp :315 ] Try to find mapping for port 5060 [UDP]
[1668397199.816|2029|upnp_context.cpp :347 ] Did not find any available mapping. Will request one now
[1668397199.816|2029|upnp_context.cpp :1133] Mapping request for JAMI-UDP:5060 already added!
[1668397199.817|2029|sipaccount.cpp :837 ] UPnP: UPNP request failed, try to register SIP account anyway
[1668397199.817|2029|sipvoiplink.cpp :1521] try to resolve 'montreal6.voip.ms' (port: 0)
[1668397199.817|2036|upnp_context.cpp :628 ] UPNP/NAT-PMP enabled, but no valid IGDs available
[1668397199.829|2029|manager.cpp :2610] Set accounts order : 73930db6b10f132e/fea4dc25d9650b0c/
[1668397199.829|2029|manager.cpp :1704] Saving Configuration to XDG directory /home/mcournoyer/.config/jami/dring.yml
Syncing lrc accounts list with the daemon
[1668397199.833|2039|sipaccount.cpp :930 ] Creating transport
[1668397199.833|2039|siptransport.cpp :294 ] Recycling transport 0.0.0.0:5060
[1668397199.833|2039|siptransport.cpp :79 ] SipTransport@0x7f72e80342e0 tr=0x7f72e15f3c58 rc=2
[1668397199.833|2039|sipaccount.cpp :333 ] Set new transport [0x7f72e80342e0]
[1668397199.833|2039|sipaccount.cpp :1022] Using contact header <sip:179835_laptop@192.168.10.172:5060> in registration
[1668397199.861|2039|sipaccount.cpp :1922] [Account 73930db6b10f132e] Checking IP route after the registration
[1668397199.861|2039|sipaccount.cpp :1947] Checking received VIA address: 66.158.152.88
[1668397199.861|2039|sipaccount.cpp :2025] [account 73930db6b10f132e] Contact address changed: (192.168.10.172:5060 --> 66.158.152.88:5060). Updating registration.
[1668397199.862|2039|sipaccount.cpp :1153] New contact: <sip:179835_laptop@66.158.152.88:5060>
[1668397199.862|2039|sipvoiplink.cpp :776 ] username = 179835_laptop, server = 208.100.60.24, from = 208.100.60.24
[1668397199.865|2039|sipaccount.cpp :1873] Matching account id in request is a fullmatch 179835_laptop@208.100.60.24
AccountModel::getAccountInfo, can't find fd11be3e4a2b141b81e3856cd876d531b1f1129a ; Using default avatar
[1668397225.814|2036|upnp_context.cpp :62 ] Shutdown UPnPContext instance [0x7f72d8008780]
[1668397225.814|2036|upnp_context.cpp :150 ] Stopping UPNP context
[1668397225.814|2029|upnp_context.cpp :92 ] Waiting for shutdown ...
[1668397225.815|2036|upnp_context.cpp :1181] Unregistered mapping JAMI-UDP:5060
[1668397225.815|2036|upnp_context.cpp :1181] Unregistered mapping JAMI-UDP:20297
[1668397225.815|2045|pupnp.cpp :312 ] PUPnP: clearing IGDs and devices lists
[1668397225.815|2044|igd.cpp :48 ] IGD 192.168.10.1 [NAT-PMP] was disabled
[1668397225.815|2045|pupnp.cpp :214 ] PUPnP: Terminate instance 0x7f72d40013c0
[1668397225.817|2036|pupnp.cpp :253 ] PUPnP: Shutdown completed
[1668397225.817|2036|nat_pmp.cpp :167 ] NAT-PMP: Shutdown completed
[1668397225.817|2036|nat_pmp.cpp :40 ] NAT-PMP: Instance [0x7f72d4000b70] destroyed
[1668397225.818|2036|pupnp.cpp :116 ] PUPnP: Instance [0x7f72d40013c0] destroyed
[1668397225.818|2029|upnp_context.cpp :95 ] Shutdown completed
[1668397225.818|2029|manager.cpp :871 ] Hangup 0 remaining call(s)
[1668397225.819|2029|manager.cpp :1704] Saving Configuration to XDG directory /home/mcournoyer/.config/jami/dring.yml
[1668397225.822|2029|sipaccount.cpp :326 ] Removing old transport [0x7f72e80342e0] from account
[1668397225.823|2029|siptransport.cpp :100 ] ~SipTransport@0x7f72e80342e0 tr=0x7f72e15f3c58 rc=6
[1668397225.823|2029|sipaccount.cpp :333 ] Set new transport [(nil)]
[1668397225.824|2029|jamiaccount.cpp :346 ] [Account fea4dc25d9650b0c] Shutdown connections
[1668397225.824|2085|tls_session.cpp :1539] [TLS] shutdown
[1668397225.824|2088|channeled_transport.cpp :186 ] [SIPS] process disconnect event
[1668397225.824|2088|siptransport.cpp :209 ] pjsip transport@0x7f72e804a520 TLS to 192.168.10.157 -> DISCONNECTED
[1668397225.824|2088|channeled_transport.cpp :186 ] [SIPS] process disconnect event
[1668397225.824|2088|siptransport.cpp :209 ] pjsip transport@0x97bbd60 TLS to 192.168.10.157 -> DISCONNECTED
[1668397225.825|2036|siptransport.cpp :100 ] ~SipTransport@0x7f72e8012d30 tr=0x7f72e804a520 rc=1
[1668397225.825|2036|siptransport.cpp :100 ] ~SipTransport@0x2fb7d50 tr=0x97bbd60 rc=1
[1668397225.825|2029|ftp_server.cpp :65 ] [FTP] server closed
[1668397225.826|2088|p2p.cpp :308 ] Channel down for incoming transfer with id 4150722487061967
[1668397225.826|2084|tls_session.cpp :1539] [TLS] shutdown
[1668397225.826|2090|ice_transport.cpp :338 ] [ice:0x7f72c8128b80] destroying 0x7f72c812d7e8
[1668397225.827|2088|p2p.cpp :308 ] Channel down for incoming transfer with id 3288263630474919
[1668397225.827|2029|ftp_server.cpp :65 ] [FTP] server closed
[1668397225.827|2029|ftp_server.cpp :65 ] [FTP] server closed
[1668397225.827|2029|ftp_server.cpp :65 ] [FTP] server closed
[1668397225.827|2029|gitserver.cpp :478 ] GitServer destroyed
[1668397225.827|2029|gitserver.cpp :478 ] GitServer destroyed
[1668397225.827|2029|gitserver.cpp :478 ] GitServer destroyed
[1668397225.827|2029|gitserver.cpp :478 ] GitServer destroyed
[1668397225.827|2029|jamiaccount.cpp :2445] [Account fea4dc25d9650b0c] unregistering account 0x7f72d0000b70
[1668397225.828|2088|ice_transport.cpp :338 ] [ice:0x7f72c8089690] destroying 0x7f72c808e2f8
[1668397225.842|2039|sipaccount.cpp :1144] Unregistration success
[1668397225.842|2039|sipaccount.cpp :1185] Registrar returned EXPIRE value [0 s] different from the requested [3600 s]
[1668397225.843|2040|message_engine.cpp :333 ] [Account 73930db6b10f132e] saved 0 messages to /home/mcournoyer/.cache/jami/73930db6b10f132e/messages
[1668397225.843|2059|jamiaccount.cpp :2448] [Account fea4dc25d9650b0c] dht shutdown complete
[1668397225.844|2036|jamiaccount.cpp :1910] Buddy d873d0ebe6c3b57f77a362fe867dd03a4f9efb76 offline
[1668397225.845|2029|jamiaccount.cpp :2502] [Account fea4dc25d9650b0c] disconnected
[1668397225.845|2029|upnp_control.cpp :44 ] Controller@0x7f72e15eb980: Destroying UPnP Controller session
[1668397225.846|2036|upnp_context.cpp :379 ] Mapping JAMI-UDP:5060 does not exist or was already removed
[1668397225.846|2036|upnp_context.cpp :431 ] Controller 0x7f72e15eb980 was already removed
[1668397225.846|2036|upnp_context.cpp :150 ] Stopping UPNP context
[1668397225.847|2029|ringbuffer.cpp :60 ] Destroy RingBuffer urgentRingBuffer_id
[1668397225.847|2029|manager.cpp :892 ] Stopping schedulers and worker threads
[1668397225.854|2090|ice_transport.cpp :352 ] [ice:0x7f72c8128b80] Destroying ice_strans 0x7f72c812d7e8
[1668397226.189|2088|ice_transport.cpp :352 ] [ice:0x7f72c8089690] Destroying ice_strans 0x7f72c808e2f8
[1668397226.355|2090|ice_transport.cpp :671 ] [ice:0x7f72c8128b80] Timer heap flushed after 500ms
[1668397226.355|2090|ice_transport.cpp :384 ] [ice:0x7f72c8128b80] done destroying
[1668397226.690|2088|ice_transport.cpp :671 ] [ice:0x7f72c8089690] Timer heap flushed after 500ms
[1668397226.691|2088|ice_transport.cpp :384 ] [ice:0x7f72c8089690] done destroying
[1668397226.692|2029|sipvoiplink.cpp :750 ] Shutdown SIPVoIPLink@0x28474e0...
[1668397226.845|2029|channeled_transport.cpp :128 ] ChanneledSIPTransport@0x7f72e804a4b0: destroying
[1668397226.845|2029|channeled_transport.cpp :198 ] ~ChanneledSIPTransport@0x7f72e804a4b0 {tr=0x7f72e804a520}
[1668397226.845|2029|channeled_transport.cpp :215 ] ~ChanneledSIPTransport@0x7f72e804a4b0 {tr=0x7f72e804a520} bye
[1668397226.845|2029|channeled_transport.cpp :128 ] ChanneledSIPTransport@0x97bbcf0: destroying
[1668397226.845|2029|channeled_transport.cpp :198 ] ~ChanneledSIPTransport@0x97bbcf0 {tr=0x97bbd60}
[1668397226.845|2029|channeled_transport.cpp :215 ] ~ChanneledSIPTransport@0x97bbcf0 {tr=0x97bbd60} bye
[1668397226.846|2029|siptransport.cpp :201 ] destroying SipTransportBroker@0x285d6d0
[1668397226.846|2029|sipvoiplink.cpp :768 ] SIPVoIPLink@0x28474e0 is shutdown
Destroying connectivity monitor
[1668397227.132|2029|ringbuffer.cpp :60 ] Destroy RingBuffer audiolayer_id
As a workaround, I could export the jami accounts I cared about, the remove the account files (rm -rf ~/.local/jami && rm -rf ~/.config/jami
), re-import the accounts and create the SIP account; this time it got persisted to disk.