linphone-desktop icon indicating copy to clipboard operation
linphone-desktop copied to clipboard

Published 20 hours ago • verified publisher icon BelledonneCommunications

intant messages can be received and notified but not shown in message history window

Open kilosens opened this issue 3 years ago • 8 comments

i have configured linphone-desktop 4.2.5 in windows 10 1809 with asterisk 18. i have configured instant message in asterisk and is working well. the problem is, when a message is received, linphone can show the message in popup window, but when the popup window timeout, the message is gone and cannot be found in message window.

i'm wondering is this designed intentionally or a bug, and if there's plan to fix

this snapshot shows that the message is received and notified but not shown in the message history window. notice the receive time of the message. 捕获2

the snapshot below shows message history in datatase, linphone.db, by third party tool. the message received time is 09:27:26 and is because of time zone, and the message in database is identical to the notify window in previous snapshot 捕获3

below is the log file when the message was received.

2022-02-19 17:26:57:155 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]
2022-02-19 17:27:07:155 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]
2022-02-19 17:27:13:936 [/belle-sip] MESSAGE Changing [server] [NOTIFY] transaction [0ACA6858], from state [COMPLETED] to [TERMINATED]
2022-02-19 17:27:13:936 [/belle-sip] MESSAGE Server NOTIFY transaction [0ACA6858] terminated
2022-02-19 17:27:13:937 [/liblinphone] MESSAGE Unhandled transaction terminated [0ACA6858]
2022-02-19 17:27:13:937 [/belle-sip] MESSAGE Transaction [0ACA6858] deleted
2022-02-19 17:27:17:155 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]
2022-02-19 17:27:19:681 [/liblinphone] MESSAGE linphone_core_get_call_history_2(): completed in 1 ms
2022-02-19 17:27:19:681 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\chat\ChatModel.cpp:451: "ChatModel (sip:[email protected], sip:[email protected]) loaded in 6 milliseconds."
2022-02-19 17:27:19:809 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\core\event-count-notifier\AbstractEventCountNotifier.cpp:71: "Notify event count: 0."
2022-02-19 17:27:26:280 [/belle-sip] MESSAGE channel [0B22B5E8]: received [470] new bytes from [UDP://192.168.1.210:5060]:
MESSAGE sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.1.210:5060;rport;branch=z9hG4bKPjd46073ae-0e15-470c-9a3a-43a8c59af15c

From: <sip:[email protected]>;tag=bb6b949d-2598-42a4-a183-982661250cf1

To: <sip:[email protected]>

Contact: <sip:[email protected]:5060>

Call-ID: 56056507-217d-4ee6-9cb8-e9efc87d821a

CSeq: 55400 MESSAGE

Max-Forwards: 70

User-Agent: FPBX-15.0.17.68(18.3.0)

Content-Type: text/plain

Content-Length:    12



testtesttest
2022-02-19 17:27:26:286 [/belle-sip] MESSAGE channel [0B22B5E8] [458] bytes parsed
2022-02-19 17:27:26:286 [/belle-sip] MESSAGE channel [0B22B5E8] read [12] bytes of body from [192.168.1.210:5060]
2022-02-19 17:27:26:289 [/belle-sip] MESSAGE Changing [server] [MESSAGE] transaction [0B2F5548], from state [INIT] to [TRYING]
2022-02-19 17:27:26:290 [/liblinphone] MESSAGE Chat message 0B621D80: moving from Idle to Delivered
2022-02-19 17:27:26:293 [/liblinphone] MESSAGE MainDb::addEvent() of type 5
2022-02-19 17:27:26:322 [/liblinphone] MESSAGE Linphone core [04E50BB0] notified [is_composing_received]
2022-02-19 17:27:26:323 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\sip-addresses\SipAddressesModel.cpp:302: Handle message received.
2022-02-19 17:27:26:324 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\sip-addresses\SipAddressesModel.cpp:485: "Update (`sip:[email protected]`, `sip:[email protected]`) from chat message."
2022-02-19 17:27:26:326 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\core\event-count-notifier\AbstractEventCountNotifier.cpp:71: "Notify event count: 0."
2022-02-19 17:27:26:340 [/belle-sip] WARNING no host found in this uri
2022-02-19 17:27:26:340 [/belle-sip] WARNING no host found in this uri
2022-02-19 17:27:26:341 [/belle-sip] WARNING no host found in this uri
2022-02-19 17:27:26:354 [/belle-sip] WARNING no host found in this uri
2022-02-19 17:27:26:374 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\notifier\Notifier.cpp:192: "Create notifications:" NotificationReceivedMessage_QMLTYPE_292(0xae90d88, name="__internalWrapper", parent=0xb67ebd8, geometry=0,0 0x0)
2022-02-19 17:27:26:375 [/liblinphone] MESSAGE [ToneManager] linphoneCorePlayLocal
2022-02-19 17:27:26:584 [/bctbx] MESSAGE Wasapi playback output initialized at 44100 Hz, 2 channels, with buffer size 88200 (500 ms), device period is 10, frames are on 32 bits
2022-02-19 17:27:26:584 [/mediastreamer] MESSAGE speex_lib_ctl init with neon ? 0
2022-02-19 17:27:26:584 [/mediastreamer] MESSAGE configuring resampler output to rate=[44100], nchannels=[2]
2022-02-19 17:27:26:585 [/mediastreamer] MESSAGE ms_filter_link: MSFilePlayer:0B534AA8,0-->MSDtmfGen:0B534F68,0
2022-02-19 17:27:26:585 [/mediastreamer] MESSAGE ms_filter_link: MSDtmfGen:0B534F68,0-->MSResample:0B535128,0
2022-02-19 17:27:26:585 [/mediastreamer] MESSAGE ms_filter_link: MSResample:0B535128,0-->MSWASAPIWrite:0B5349E8,0
2022-02-19 17:27:26:585 [/mediastreamer] MESSAGE Initializing speex resampler in mode [voip] 
2022-02-19 17:27:26:585 [/mediastreamer] MESSAGE win32 timer resolution set to 2 ms
2022-02-19 17:27:26:588 [/mediastreamer] MESSAGE Configuring resampler input with rate=[44100], nchannels=[1]
2022-02-19 17:27:26:588 [/mediastreamer] MESSAGE MSFilePlayer[0B534AA8]: C:\Program Files (x86)\Linphone\share/sounds/linphone/incoming_chat.wav opened: rate=44100,channel=1, length=580 ms
2022-02-19 17:27:26:588 [/liblinphone] MESSAGE Linphone core [04E50BB0] notified [message_received]
2022-02-19 17:27:26:592 [/liblinphone] MESSAGE Skipping top route of initial route-set because same as request-uri
2022-02-19 17:27:26:593 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B40DE40], from state [INIT] to [TRYING]
2022-02-19 17:27:26:593 [/belle-sip] MESSAGE Body has been compressed: 282->201:
<?xml version="1.0" encoding="UTF-8" standalone="no" ?><imdn xmlns="urn:ietf:params:xml:ns:imdn"><message-id>56056507-217d-4ee6-9cb8-e9efc87d821a</message-id><datetime>2022-02-19T09:27:26Z</datetime><delivery-notification><status><delivered/></status></delivery-notification></imdn>
2022-02-19 17:27:26:594 [/belle-sip] WARNING message [0B1F03C8] has Content-Length [282] and body size [201] which are inconsistent, fixing it.
2022-02-19 17:27:26:594 [/belle-sip] MESSAGE channel [0B22B5E8]: found binary data in buffer, will stop logging it now.
2022-02-19 17:27:26:594 [/belle-sip] MESSAGE channel [0B22B5E8]: message sent to [UDP://192.168.1.210:5060], size: [711] bytes
MESSAGE sip:[email protected] SIP/2.0

Via: SIP/2.0/UDP 192.168.1.130:5060;branch=z9hG4bK.MseiIjvpR;rport

From: <sip:[email protected]>;tag=~19O3xFJx

To: sip:[email protected]

CSeq: 20 MESSAGE

Call-ID: WejfXPQvJb

Max-Forwards: 70

Supported: replaces, outbound, gruu

Date: Sat, 19 Feb 2022 09:27:26 GMT

Content-Encoding: deflate

Content-Type: message/imdn+xml

Content-Length: 201

Priority: non-urgent

User-Agent: Linphone Desktop/4.2.5 (Windows 10 Version 1809, Qt 5.14.2) LinphoneCore/4.4.19



x渦徑n!刜裲郟?磲蜲?槿眊!Kt嗳y"V毚笸'袜闉v馝G墮俳h)堊"_鵹9?E冞3?9KqrS`裈.嫾l#胀~[l关GE:LT娍泥鶤鰿疓0?鄷h€y龢€f谥i撻<?_┢D蝖c@桄嫗??轖=1赾{?渒茆阫?蚰擟??$鮔額... (first 706 bytes shown)
2022-02-19 17:27:26:594 [/belle-sip] MESSAGE Changing [server] [MESSAGE] transaction [0B2F5548], from state [TRYING] to [COMPLETED]
2022-02-19 17:27:26:594 [/belle-sip] MESSAGE channel [0B22B5E8]: message sent to [UDP://192.168.1.210:5060], size: [297] bytes
SIP/2.0 200 Ok

Via: SIP/2.0/UDP 192.168.1.210:5060;rport;branch=z9hG4bKPjd46073ae-0e15-470c-9a3a-43a8c59af15c

From: <sip:[email protected]>;tag=bb6b949d-2598-42a4-a183-982661250cf1

To: <sip:[email protected]>;tag=f9HT~-X

Call-ID: 56056507-217d-4ee6-9cb8-e9efc87d821a

CSeq: 55400 MESSAGE




2022-02-19 17:27:26:595 [/liblinphone] MESSAGE Destroying op [0B224A80] of type [SalOpMessage]
2022-02-19 17:27:26:831 [/belle-sip] MESSAGE channel [0B22B5E8]: received [460] new bytes from [UDP://192.168.1.210:5060]:
SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 192.168.1.130:5060;rport=5060;received=192.168.1.130;branch=z9hG4bK.MseiIjvpR

Call-ID: WejfXPQvJb

From: <sip:[email protected]>;tag=~19O3xFJx

To: <sip:[email protected]>;tag=z9hG4bK.MseiIjvpR

CSeq: 20 MESSAGE

WWW-Authenticate: Digest realm="asterisk",nonce="1645262769/716f2cf832e18fe9beba968a98189382",opaque="07a4dc7a3d05373b",algorithm=md5,qop="auth"

Server: FPBX-15.0.17.68(18.3.0)

Content-Length:  0




2022-02-19 17:27:26:834 [/belle-sip] MESSAGE channel [0B22B5E8] [460] bytes parsed
2022-02-19 17:27:26:834 [/belle-sip] MESSAGE Found transaction matching response.
2022-02-19 17:27:26:835 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B40DE40], from state [TRYING] to [COMPLETED]
2022-02-19 17:27:26:835 [/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=1001, realm=asterisk
2022-02-19 17:27:26:835 [/liblinphone] MESSAGE AuthStack::authFound() for Username[1001];Userid[];Realm[asterisk];Domain[192.168.1.130];Algorithm[];
2022-02-19 17:27:26:835 [/belle-sip] MESSAGE Auth info found for [1001] realm [asterisk]
2022-02-19 17:27:26:837 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B9EB0E0], from state [INIT] to [TRYING]
2022-02-19 17:27:26:838 [/belle-sip] MESSAGE channel [0B22B5E8]: found binary data in buffer, will stop logging it now.
2022-02-19 17:27:26:838 [/belle-sip] MESSAGE channel [0B22B5E8]: message sent to [UDP://192.168.1.210:5060], size: [990] bytes
MESSAGE sip:[email protected] SIP/2.0

Via: SIP/2.0/UDP 192.168.1.130:5060;branch=z9hG4bK.QDxG4In0k;rport

From: <sip:[email protected]>;tag=~19O3xFJx

To: sip:[email protected]

CSeq: 21 MESSAGE

Call-ID: WejfXPQvJb

Max-Forwards: 70

Supported: replaces, outbound, gruu

Date: Sat, 19 Feb 2022 09:27:26 GMT

Content-Encoding: deflate

Content-Type: message/imdn+xml

Content-Length: 201

Priority: non-urgent

User-Agent: Linphone Desktop/4.2.5 (Windows 10 Version 1809, Qt 5.14.2) LinphoneCore/4.4.19

Authorization:  Digest realm="asterisk", nonce="1645262769/716f2cf832e18fe9beba968a98189382", algorithm=md5, opaque="07a4dc7a3d05373b", username="1001",  uri="sip:[email protected]", response="97c6f13b123d445b65450854d574972e", cnonce="dtddhfK-bu6pv1LB", nc=00000001, qop=auth



x渦徑n!刜裲郟?磲蜲?槿眊!Kt嗳y"V毚笸'袜闉v馝G墮俳h)堊"_鵹9?E冞3?9KqrS`裈.嫾l#胀~[l关GE:LT娍泥鶤鰿疓0?鄷h€y龢€f谥i撻<?_┢D蝖c@桄嫗??轖=1赾{?渒茆阫?蚰擟??$鮔額... (first 985 bytes shown)
2022-02-19 17:27:26:855 [/belle-sip] MESSAGE channel [0B22B5E8]: received [324] new bytes from [UDP://192.168.1.210:5060]:
SIP/2.0 415 Unsupported Media Type

Via: SIP/2.0/UDP 192.168.1.130:5060;rport=5060;received=192.168.1.130;branch=z9hG4bK.QDxG4In0k

Call-ID: WejfXPQvJb

From: <sip:[email protected]>;tag=~19O3xFJx

To: <sip:[email protected]>;tag=z9hG4bK.QDxG4In0k

CSeq: 21 MESSAGE

Server: FPBX-15.0.17.68(18.3.0)

Content-Length:  0




2022-02-19 17:27:26:857 [/belle-sip] MESSAGE channel [0B22B5E8] [324] bytes parsed
2022-02-19 17:27:26:858 [/belle-sip] MESSAGE Found transaction matching response.
2022-02-19 17:27:26:858 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B9EB0E0], from state [TRYING] to [COMPLETED]
2022-02-19 17:27:27:155 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]
2022-02-19 17:27:27:186 [/liblinphone] MESSAGE [ToneManager] onFilePlayerEnd
2022-02-19 17:27:27:186 [/liblinphone] MESSAGE [ToneManager] doStopTone
2022-02-19 17:27:27:206 [/mediastreamer] MESSAGE ms_filter_unlink: MSFilePlayer:0B534AA8,0-->MSDtmfGen:0B534F68,0
2022-02-19 17:27:27:206 [/mediastreamer] MESSAGE ms_filter_unlink: MSDtmfGen:0B534F68,0-->MSResample:0B535128,0
2022-02-19 17:27:27:206 [/mediastreamer] MESSAGE ms_filter_unlink: MSResample:0B535128,0-->MSWASAPIWrite:0B5349E8,0
2022-02-19 17:27:27:216 [/mediastreamer] MESSAGE Ring MSTicker thread exiting
2022-02-19 17:27:27:219 [/mediastreamer] MESSAGE Cleaned [1] pending event(s) generated by MSFilter [MSFilePlayer:0B534AA8]
2022-02-19 17:27:31:837 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\core\event-count-notifier\AbstractEventCountNotifier.cpp:71: "Notify event count: 0."
2022-02-19 17:27:31:852 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B40DE40], from state [COMPLETED] to [TERMINATED]
2022-02-19 17:27:31:852 [/belle-sip] MESSAGE Client MESSAGE transaction [0B40DE40] terminated
2022-02-19 17:27:31:852 [/liblinphone] MESSAGE Unhandled transaction terminated [0B40DE40]
2022-02-19 17:27:31:876 [/belle-sip] MESSAGE Changing [client] [MESSAGE] transaction [0B9EB0E0], from state [COMPLETED] to [TERMINATED]
2022-02-19 17:27:31:876 [/belle-sip] MESSAGE Client MESSAGE transaction [0B9EB0E0] terminated
2022-02-19 17:27:31:876 [/liblinphone] MESSAGE Unhandled transaction terminated [0B9EB0E0]
2022-02-19 17:27:31:876 [/liblinphone] MESSAGE Destroying op [0B223F70] of type [SalOpMessage]
2022-02-19 17:27:31:876 [/belle-sip] MESSAGE Transaction [0B40DE40] deleted
2022-02-19 17:27:31:877 [/belle-sip] MESSAGE Transaction [0B9EB0E0] deleted
2022-02-19 17:27:35:732 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\core\event-count-notifier\AbstractEventCountNotifier.cpp:71: "Notify event count: 0."
2022-02-19 17:27:36:376 [/qt] MESSAGE QT: C:\GitLab-Runner\builds\651dcee5\2\BC\public\linphone-desktop\linphone-app\src\components\notifier\Notifier.cpp:234: "Delete notification:" NotificationReceivedMessage_QMLTYPE_292(0xae90d88, name = "__internalWrapper")
2022-02-19 17:27:37:157 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]
2022-02-19 17:27:41:531 [/belle-sip] MESSAGE channel [0B22B5E8]: received [429] new bytes from [UDP://192.168.1.210:5060]:
OPTIONS sip:[email protected]:5060 SIP/2.0

Via: SIP/2.0/UDP 192.168.1.210:5060;rport;branch=z9hG4bKPjfbeafc84-4f22-4602-942c-d729b23576a0

From: <sip:[email protected]>;tag=a3985d31-e99b-49df-b57d-4b50d6829976

To: <sip:[email protected]>

Contact: <sip:[email protected]:5060>

Call-ID: 3db4c68d-1d10-4812-ae94-06ee49d1078d

CSeq: 25763 OPTIONS

Max-Forwards: 70

User-Agent: FPBX-15.0.17.68(18.3.0)

Content-Length:  0




2022-02-19 17:27:41:537 [/belle-sip] MESSAGE channel [0B22B5E8] [429] bytes parsed
2022-02-19 17:27:41:538 [/belle-sip] MESSAGE channel [0B22B5E8]: message sent to [UDP://192.168.1.210:5060], size: [295] bytes
SIP/2.0 200 Ok

Via: SIP/2.0/UDP 192.168.1.210:5060;rport;branch=z9hG4bKPjfbeafc84-4f22-4602-942c-d729b23576a0

From: <sip:[email protected]>;tag=a3985d31-e99b-49df-b57d-4b50d6829976

To: <sip:[email protected]>;tag=iv53t

Call-ID: 3db4c68d-1d10-4812-ae94-06ee49d1078d

CSeq: 25763 OPTIONS




2022-02-19 17:27:47:154 [/belle-sip] MESSAGE channel [0B22B5E8]: keep alive sent to [UDP://192.168.1.210:5060]

kilosens avatar Feb 24 '22 15:02 kilosens

tested with linphone 4.3.2, and the problem still exist.

kilosens avatar Feb 25 '22 08:02 kilosens

Same issue with me. Does anyone have some clues?

Steins423 avatar May 16 '22 02:05 Steins423

In my experience I could get de im to work using flexisip server, however I must acomplish with an asterisk server . I realized that asterisk only accepts content-type= text/plan, so I imagine that this is the reason why is not working.

LucasfoliveiraSe avatar May 30 '22 17:05 LucasfoliveiraSe

In my experience I could get de im to work using flexisip server, however I must acomplish with an asterisk server . I realized that asterisk only accepts content-type= text/plan, so I imagine that this is the reason why is not working.

this has nothing to do with sip server. the posted picture shows database of LINPHONE not sip server. that is, the message is actually received by linphone and stored into its database, but not displayed in message history window somehow.

kilosens avatar May 31 '22 06:05 kilosens

The same happens to me , I just receive a notification with the message . In other topic it was told that is the asterisk fault because it sends in the "to" field the ip instead of the domain, and belledone has no intetion to be more flexible on this matter simply because it follows the rfc correctly.

LucasfoliveiraSe avatar May 31 '22 11:05 LucasfoliveiraSe

Bug still alive with both desktop Qt5.15.2 Core 4.4.21

Linphone-5.0.0-beta.50+fee7046f.AppImage

xtronics avatar Nov 22 '22 00:11 xtronics

Still an issue with 5.1.1 release. I'm excited about being able to send and receive SMS messages to my voip.ms hosted DID. This bug, along with not yet being able to reply to the messages using the Linphone app, are showstoppers.

sysfu avatar Aug 24 '23 17:08 sysfu

Still an issue with the 5.1.2 release. I am able to see the inbound message pop-up notices, click on the notice, and it will open the message up in the contact message conversation view. That message will literally disappear before my eyes while I am reading it, unable to be retrieved. Will attempt to recreate this problem while capturing debug logs, and post them on this issue.

sysfu avatar Aug 31 '23 20:08 sysfu