Smsmodem : Binding for SMS on a GSM serial modem

Hello,

This exception is raised when the binding asks from openHAB core the serial port, and openHAB returns nothing. So this is a “global” issue, not directly related to the binding.
(I want to be precise here, because if we cannot manage to help you here, you can ask in the more general part of this community forum)

That said, did you see this openHAB page ? There are other advices to make the serial communication works.

Hello,
I saw this page, but I overlooked /var/lock article.
So for el8 systems is needed add permissions:

chgrp lock /run/lock
chmod g+w /run/lock

and binding running.

Thanks a lot,
PM.

Is planned extension for dialing? For urgent triggers may be usable ringing phone instead notification. ATD command can be easier than SMS.

I didn’t even know it was possible !
It could be very fun to add, especially with the voice/multimedia functionnality of openHAB.
With STT and TTS and dialog processing, you could imagine talking to your openHAB with a simple voice call…
But unfortunately it’s not on the library I use for modem processing, so it means I would have to dig and code this and test… I will add this to my todo/fun list, but let’s be honest, this list is at the moment way too long :sweat_smile:
As usual, anybody is welcome to open a PR and I will be glad to help with testing and reviewing.

I don’t know howto you call AT commands for SMS, but I’d like use dialing only for ringing my phone, and then without voice processing may be work quite easier, maybe simplified as message processing. Ther will be only DIALING, CALL and HANGING signals.

Hi,

first: your Binding is really interesting. Thanks for your work.

I was able to receive SMS with my modem, but I’m not able to send some.
Always I got the following error message:
[WARN ] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB0:4800 ERR==> +CMS ERROR: 500

I tried to change the Baud Rate, the format of the telephone number, but nothing works.

Could you or someone help me?

Regards,
Inc.

Check if your modem knows its IMSI (AT+CIMI=? or AT+CIMI).
Check the SIM card account balance. :slight_smile:

1 Like

WOW, thanks a lot.
You are totally right. It was the balance. In Germany the budget expires, when you put not new money on the account for a year. It works great!

Hi. I recently updated from version 4.1.0 to version 4.2.0 and it turned out that sending SMS is broken. If you try to send a long SMS, there will be an error in the logs and the message will not be sent

  1. Long, consisting of two or three messages at once (160 chars * 2 or ~160 chars * 3) in default encoding
val sms_text = 'Report:\nGuard: family\nDoor is closed at 08:28:08 07.08.24\nVibrating alarm at 08:32:40 07.08.24\nIntercom rang at 15:35:41 04.08.24\nDoorbell rang at 15:36:50 04.08.24\nNo motion from 08:29:12 07.08.24\nFamily left at 08:45:31 07.08.24\nFridge -16.29 C'
getActions("smsmodem", "smsmodem:smsmodembridge:huaweie175").sendSMS("+12345678901", sms_text)
  1. Long, more than 70 chars, in “EncUcs2” encoding
val sms_text = 'Report:\nGuard: family\nDoor is closed at 08:28:08 07.08.24\nVibrating.'
getActions("smsmodem", "smsmodem:smsmodembridge:huaweie175").sendSMS("+12345678901", sms_text, "EncUcs2")

Exception from log:

2024-08-07 20:32:09.225 [ERROR] [org.smslib.MessageSender            ] - Send failed!
org.smslib.CommunicationException: Cannot get response
	at org.smslib.driver.AbstractModemDriver.getResponse(AbstractModemDriver.java:189) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.atSendPDUMessage(AbstractModemDriver.java:450) ~[bundleFile:?]
	at org.smslib.Modem.send(Modem.java:226) ~[bundleFile:?]
	at org.smslib.MessageSender.run(MessageSender.java:50) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.util.concurrent.TimeoutException: Timeout elapsed for COM5:19200
	at org.smslib.driver.AbstractModemDriver.getLineFromBuffer(AbstractModemDriver.java:201) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.getResponse(AbstractModemDriver.java:129) ~[bundleFile:?]
	... 9 more

In 4.1.0 everything was fine and long messages were sent.

Hello,

I just updated to 4.2 to test and I don’t have such issue.
I tried with a long sms (360+ character) and it went fine.
Does it mean that your short SMS are OK ?
DId you try stopping openhab, disconnecting the usb device, waiting a little bit (at least 10 seconds) reconnecting it, restarting openhab ? And maybe the computer even so ?
Usb serial connections are tricky, and devices (or driver) are often full of little bug, so my first guess is to try on this side.

1 Like

I’ve been using your great bindning since the very first version.

My specs:

Between 4.1.0 and 4.2.0 I upgrade only the Java JDK to latest version.

  • M$ Window$ $server 2022 Latest
  • Zulu Java v17.52.17 Latest
  • OpenHAB v4.2.0
  • Modem Huawei E175 3G
  • Driver HUAWEI Mobile Connect - 3G PC UI Interface
    v2.0.6.725 28.11.2016 (I always used only this version)

My binding settings:

I rebooted the computer twice and reconnected the USB device.

Sent without problems:

  • Short message in 160 chars in default encoding
  • Long 254 chars in default encoding
  • Short 70 chars in EncUcs2 encoding

I checked a few more times. The problem occurs when using the EncUcs2 encoding. With default encoding no problem (maybe I wasn’t careful yesterday).
EncUcs2 is needed to send non-Latin characters.

My sample text (it’s my security report)

val sms_text = "Report:\nGuard: family\nDoor is closed at 08:28:08 07.08.24\nVibrating alarm at 08:32:40 07.08.24\nIntercom rang at 15:35:41 04.08.24\nDoorbell rang at 15:36:50 04.08.24\nNo motion from 08:29:12 07.08.24\nFamily left at 08:45:31 07.08.24\nFridge -16.29 C"
getActions("smsmodem", "smsmodem:smsmodembridge:huaweie175").sendSMS("+12345678901", sms_text, "EncUcs2")

According to billing, the SMS is sent (payment for sending occurs). But in fact it does not arrive to the addressee

2024-08-08 10:26:32.515 [ERROR] [org.smslib.MessageSender            ] - Send failed!
org.smslib.CommunicationException: Cannot get response
	at org.smslib.driver.AbstractModemDriver.getResponse(AbstractModemDriver.java:189) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.atSendPDUMessage(AbstractModemDriver.java:450) ~[bundleFile:?]
	at org.smslib.Modem.send(Modem.java:226) ~[bundleFile:?]
	at org.smslib.MessageSender.run(MessageSender.java:50) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.util.concurrent.TimeoutException: Timeout elapsed for COM5:19200
	at org.smslib.driver.AbstractModemDriver.getLineFromBuffer(AbstractModemDriver.java:201) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.getResponse(AbstractModemDriver.java:129) ~[bundleFile:?]
	... 9 more

There is also this error when receiving an incoming message, but everything works fine, despite the error

2024-08-08 10:39:20.178 [ERROR] [org.smslib.MessageReader            ] - Unhandled exception while trying to read new messages
org.smslib.CommunicationException: Cannot write to device
	at org.smslib.driver.AbstractModemDriver.write(AbstractModemDriver.java:112) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.write(AbstractModemDriver.java:83) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.write(AbstractModemDriver.java:76) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.atSwitchMemoryLocation(AbstractModemDriver.java:483) ~[bundleFile:?]
	at org.smslib.driver.AbstractModemDriver.atGetMessages(AbstractModemDriver.java:487) ~[bundleFile:?]
	at org.smslib.MessageReader.run(MessageReader.java:58) [bundleFile:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.IOException: Input/output error in writeByte
	at gnu.io.RXTXPort.writeByte(Native Method) ~[?:?]
	at gnu.io.RXTXPort$SerialOutputStream.write(RXTXPort.java:1203) ~[?:?]
	at org.smslib.driver.AbstractModemDriver.write(AbstractModemDriver.java:107) ~[bundleFile:?]
	... 11 more
2024-08-08 10:39:20.182 [WARN ] [org.smslib.Modem                    ] - Gateway stopping, message not delivered : 0
2024-08-08 10:39:30.640 [WARN ] [org.smslib.Modem                    ] - Gateway stopping, message not delivered : 0

Hi! I added a little information, could you check it out? post above.

The coincidence in the version upgrade and your issue seems to say that some changes did occur that breaks long SMS. But I checked all changes in the SMS binding between 4.1 and 4.2, and there is nothing fishy, not even remotely related to your issue (it is merely minor aesthetic or other openHAB “all or nothing” API change that should break earlier).

Furthermore, I’m at a loss here: I just sucessfully sent your exact security report message in the EncUcs2 encoding to my personal phone number. No error in the log.
I also try to send it to an ukrainian number (using the quackr.io service) for the receiving end, and then I have the same exception as you. But it is probably caused by my operator or/and country, because I cannot send any message to it (event a short one with default encoding)
It’s interesting nonetheless, because it shows that operator can provoke the same kind of error you saw in your log.

There is also this error when receiving an incoming message, but everything works fine, despite the error

And here again, I don’t have any exception when receiving message. Is the message you sent in another encoding ? Or is it long, or other peculiar trait ?

Next week I will find time and want to check the sending through debugging and inform you about the results.

The environment is ready but I’m busy with work

1 Like

is someone using a Huawei E303C, I was using this with bautrate 115200 with the old sms gateway on openhab with gammu, I would like to use this binding.
I get the following errors in the logs every 30 seconds:

[WARN ] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ERR==> ERROR

the binding is online.

Hello,

Sorry, I cannot really answer your question.
I should have managed more agressively a list of devices known to work. I updated the open post to ask users for information about compatibility, but I do not have high hopes as very few people will read this :sweat_smile:

I have a dumb question, but did you try to send/receive something anyway ? Despite the log message, maybe it could work ?
Can you provide log on the debug level for packages org.smslib and org.openhab.binding.smsmodem ?
(Disclaimer: Debug log worths a try, but I probably won’t be really able to help… As I said previously, I only managed to use a third party library and I know nothing about debugging the serial protocol talking with the stick)

I put the sim into a phone and there i can send and receive, but no message in the item of the sms conversation thing, it is a new sim for that
Thank you for the info with debug log, i will check today

these are the logs I get:

org.smslib:

2024-09-12 16:44:13.515 [WARN ] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ERR==> ERROR
2024-09-12 16:44:13.516 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ==> 
2024-09-12 16:44:13.518 [DEBUG] [org.smslib.MessageReader            ] - Stopped!
2024-09-12 16:44:28.519 [DEBUG] [org.smslib.MessageReader            ] - Started!
2024-09-12 16:44:28.521 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 <== AT+CPMS="SM"
2024-09-12 16:44:29.356 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> 
2024-09-12 16:44:29.358 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> +CPMS: 0,20,0,20,0,20
2024-09-12 16:44:29.359 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> 
2024-09-12 16:44:29.361 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> OK
2024-09-12 16:44:29.362 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ==> +CPMS: 0,20,0,20,0,20$
2024-09-12 16:44:29.364 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 <== AT+CMGL=4
2024-09-12 16:44:30.168 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> 
2024-09-12 16:44:30.169 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> OK
2024-09-12 16:44:30.171 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ==> 
2024-09-12 16:44:30.172 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 <== AT+CPMS="SR"
2024-09-12 16:44:31.006 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> 
2024-09-12 16:44:31.008 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 >>> ERROR
2024-09-12 16:44:31.009 [WARN ] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ERR==> ERROR
2024-09-12 16:44:31.011 [DEBUG] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ==> 
2024-09-12 16:44:31.011 [DEBUG] [org.smslib.MessageReader            ] - Stopped!

org.openhab.binding.smsmodem:

2024-09-12 17:19:29.109 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : BundleComponentActivator : ComponentHolder created.
2024-09-12 17:19:29.120 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2024-09-12 17:19:29.128 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : BundleComponentActivator : ComponentHolder created.
2024-09-12 17:19:29.141 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-09-12 17:19:29.145 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Component created: DS=DS13, implementation=org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService]
2024-09-12 17:19:29.150 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Component Services: scope=prototype, services=[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService]
2024-09-12 17:19:29.153 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-09-12 17:19:29.155 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Querying state disabled
2024-09-12 17:19:29.157 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Querying state disabled
2024-09-12 17:19:29.164 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Component can not be activated since it is in state disabled
2024-09-12 17:19:29.169 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService] : Querying state disabled
2024-09-12 17:19:29.171 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Updating target filters
2024-09-12 17:19:29.180 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:29.182 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-09-12 17:19:29.185 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-09-12 17:19:29.189 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-09-12 17:19:29.194 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-09-12 17:19:29.201 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-09-12 17:19:29.203 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : dm osgi.ds.satisfying.condition tracker opened
2024-09-12 17:19:29.211 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:29.213 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Changed state from disabled to unsatisfiedReference
2024-09-12 17:19:29.215 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Component enabled
2024-09-12 17:19:29.231 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : ActivateInternal
2024-09-12 17:19:29.233 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.235 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.238 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Activating component from state unsatisfiedReference
2024-09-12 17:19:29.240 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.243 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.245 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Changed state from unsatisfiedReference to satisfied
2024-09-12 17:19:29.248 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : registration change queue [registered]
2024-09-12 17:19:29.255 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Dependency Manager created SerialPortManagerinterface=org.openhab.core.io.transport.serial.SerialPortManager, filter=null, policy=static, cardinality=1..1, bind=setSerialPortManager, unbind=unsetSerialPortManager, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-09-12 17:19:29.258 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-09-12 17:19:29.261 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Component created: DS=DS13, implementation=org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.smsmodem]
2024-09-12 17:19:29.264 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2024-09-12 17:19:29.271 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-09-12 17:19:29.273 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Querying state disabled
2024-09-12 17:19:29.286 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Querying state disabled
2024-09-12 17:19:29.306 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Component can not be activated since it is in state disabled
2024-09-12 17:19:29.309 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory] : Querying state disabled
2024-09-12 17:19:29.318 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Updating target filters
2024-09-12 17:19:29.322 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : No change in target property for dependency SerialPortManager: currently registered: false
2024-09-12 17:19:29.327 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] :  No existing service listener to unregister for dependency SerialPortManager
2024-09-12 17:19:29.330 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Setting target property for dependency SerialPortManager to null
2024-09-12 17:19:29.337 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : New service tracker for SerialPortManager, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.transport.serial.SerialPortManager), initialReferenceFilter (objectClass=org.openhab.core.io.transport.serial.SerialPortManager)
2024-09-12 17:19:29.339 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm SerialPortManager tracker reset (closed)
2024-09-12 17:19:29.343 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm SerialPortManager tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=503, service.bundleid=261, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=329} (enter)
2024-09-12 17:19:29.356 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm SerialPortManager tracking 1 SingleStatic active: false trackerOpened: false optional: false
2024-09-12 17:19:29.359 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm SerialPortManager tracking 1 SingleStatic added {org.openhab.core.io.transport.serial.SerialPortManager}={service.id=503, service.bundleid=261, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=329} (exit)
2024-09-12 17:19:29.363 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm SerialPortManager tracker opened
2024-09-12 17:19:29.366 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : registering service listener for dependency SerialPortManager
2024-09-12 17:19:29.370 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:29.373 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-09-12 17:19:29.375 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-09-12 17:19:29.383 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-09-12 17:19:29.388 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-09-12 17:19:29.392 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm osgi.ds.satisfying.condition tracking 2 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-09-12 17:19:29.399 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : dm osgi.ds.satisfying.condition tracker opened
2024-09-12 17:19:29.407 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:29.409 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Changed state from disabled to unsatisfiedReference
2024-09-12 17:19:29.411 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Component enabled
2024-09-12 17:19:29.428 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : ActivateInternal
2024-09-12 17:19:29.434 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.443 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.445 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Activating component from state unsatisfiedReference
2024-09-12 17:19:29.458 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.460 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state unsatisfiedReference
2024-09-12 17:19:29.462 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Changed state from unsatisfiedReference to satisfied
2024-09-12 17:19:29.464 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : registration change queue [registered]
2024-09-12 17:19:29.499 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Checking constructor public org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory()
2024-09-12 17:19:29.502 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Found constructor with 0 arguments : public org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory()
2024-09-12 17:19:29.504 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : getting bind: setSerialPortManager
2024-09-12 17:19:29.506 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Locating method setSerialPortManager in class org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory
2024-09-12 17:19:29.509 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : doFindMethod: Looking for method org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.setSerialPortManager
2024-09-12 17:19:29.512 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Declared Method org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.setSerialPortManager([interface org.osgi.framework.ServiceReference]) not found
2024-09-12 17:19:29.515 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Declared Method org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.setSerialPortManager([interface org.osgi.service.component.ComponentServiceObjects]) not found
2024-09-12 17:19:29.517 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.io.transport.serial.SerialPortManager through loader of org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory
2024-09-12 17:19:29.519 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : getClassFromComponentClassLoader: Found class org.openhab.core.io.transport.serial.SerialPortManager
2024-09-12 17:19:29.521 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.io.transport.serial.SerialPortManager
2024-09-12 17:19:29.523 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : doFindMethod: Found Method protected void org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
2024-09-12 17:19:29.525 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Found bind method: protected void org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.setSerialPortManager(org.openhab.core.io.transport.serial.SerialPortManager)
2024-09-12 17:19:29.530 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : This thread collected dependencies
2024-09-12 17:19:29.532 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : getService (single component manager) dependencies collected.
2024-09-12 17:19:29.534 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state satisfied
2024-09-12 17:19:29.536 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Querying state satisfied
2024-09-12 17:19:29.540 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : For dependency SerialPortManager, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.transport.serial.SerialPortManager}={service.id=503, service.bundleid=261, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl, component.id=329}] service: [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl@1f27aa4]]]
2024-09-12 17:19:29.542 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-09-12 17:19:29.786 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : invoking bind: setSerialPortManager: parameters [org.openhab.core.io.transport.serial.internal.SerialPortManagerImpl]
2024-09-12 17:19:29.792 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : invoked bind: setSerialPortManager
2024-09-12 17:19:29.801 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : getting activate: activate
2024-09-12 17:19:29.806 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Locating method activate in class org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory
2024-09-12 17:19:29.821 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Declared Method org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-09-12 17:19:29.831 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2024-09-12 17:19:29.853 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2024-09-12 17:19:29.861 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2024-09-12 17:19:29.876 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : invoked activate: activate
2024-09-12 17:19:29.878 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Set implementation object for component
2024-09-12 17:19:29.883 [DEBUG] [odem.internal.SMSModemHandlerFactory] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSModemHandlerFactory(337)] : Changed state from satisfied to active
2024-09-12 17:19:29.917 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-09-12 17:19:29.920 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Component created: DS=DS13, implementation=org.openhab.binding.smsmodem.internal.actions.SMSModemActions, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.smsmodem.internal.actions.SMSModemActions]
2024-09-12 17:19:29.931 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Component Services: scope=prototype, services=[org.openhab.binding.smsmodem.internal.actions.SMSModemActions]
2024-09-12 17:19:29.933 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-09-12 17:19:29.938 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Querying state disabled
2024-09-12 17:19:29.950 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Querying state disabled
2024-09-12 17:19:29.952 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Component can not be activated since it is in state disabled
2024-09-12 17:19:29.954 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions] : Querying state disabled
2024-09-12 17:19:29.965 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Updating target filters
2024-09-12 17:19:29.968 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:29.970 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-09-12 17:19:29.972 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-09-12 17:19:29.982 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-09-12 17:19:29.994 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-09-12 17:19:30.007 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-09-12 17:19:30.009 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : dm osgi.ds.satisfying.condition tracker opened
2024-09-12 17:19:30.011 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-09-12 17:19:30.013 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Changed state from disabled to unsatisfiedReference
2024-09-12 17:19:30.015 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Component enabled
2024-09-12 17:19:30.019 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : ActivateInternal
2024-09-12 17:19:30.025 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Querying state unsatisfiedReference
2024-09-12 17:19:30.030 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Querying state unsatisfiedReference
2024-09-12 17:19:30.037 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Activating component from state unsatisfiedReference
2024-09-12 17:19:30.042 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Querying state unsatisfiedReference
2024-09-12 17:19:30.049 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Querying state unsatisfiedReference
2024-09-12 17:19:30.052 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Changed state from unsatisfiedReference to satisfied
2024-09-12 17:19:30.054 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : registration change queue [registered]
2024-09-12 17:19:31.736 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : ServiceFactory.getService()
2024-09-12 17:19:31.739 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Checking constructor public org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService()
2024-09-12 17:19:31.742 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Found constructor with 0 arguments : public org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService()
2024-09-12 17:19:31.744 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : This thread collected dependencies
2024-09-12 17:19:31.745 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : getService (ServiceFactory) dependencies collected.
2024-09-12 17:19:31.747 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Querying state satisfied
2024-09-12 17:19:31.749 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-09-12 17:19:31.752 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : getting activate: activate
2024-09-12 17:19:31.754 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Locating method activate in class org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService
2024-09-12 17:19:31.756 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Declared Method org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-09-12 17:19:31.758 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Locating method activate in class org.openhab.core.config.discovery.AbstractThingHandlerDiscoveryService
2024-09-12 17:19:31.761 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Declared Method org.openhab.core.config.discovery.AbstractThingHandlerDiscoveryService.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-09-12 17:19:31.762 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Found activate method: public void org.openhab.core.config.discovery.AbstractThingHandlerDiscoveryService.activate(java.util.Map)
2024-09-12 17:19:31.765 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.helper.ReadOnlyDictionary]
2024-09-12 17:19:31.767 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : invoked activate: activate
2024-09-12 17:19:31.769 [DEBUG] [rnal.SMSConversationDiscoveryService] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.SMSConversationDiscoveryService(336)] : Changed state from satisfied to active
2024-09-12 17:19:31.786 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : ServiceFactory.getService()
2024-09-12 17:19:31.789 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Checking constructor public org.openhab.binding.smsmodem.internal.actions.SMSModemActions()
2024-09-12 17:19:31.791 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Found constructor with 0 arguments : public org.openhab.binding.smsmodem.internal.actions.SMSModemActions()
2024-09-12 17:19:31.793 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : This thread collected dependencies
2024-09-12 17:19:31.794 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : getService (ServiceFactory) dependencies collected.
2024-09-12 17:19:31.796 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Querying state satisfied
2024-09-12 17:19:31.798 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-09-12 17:19:31.800 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : getting activate: activate
2024-09-12 17:19:31.802 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Locating method activate in class org.openhab.binding.smsmodem.internal.actions.SMSModemActions
2024-09-12 17:19:31.805 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Declared Method org.openhab.binding.smsmodem.internal.actions.SMSModemActions.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-09-12 17:19:31.807 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Locating method activate in class java.lang.Object
2024-09-12 17:19:31.809 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-09-12 17:19:31.811 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : activate method [activate] not found, ignoring
2024-09-12 17:19:31.813 [DEBUG] [dem.internal.actions.SMSModemActions] - bundle org.openhab.binding.smsmodem:4.2.1 (266)[org.openhab.binding.smsmodem.internal.actions.SMSModemActions(338)] : Changed state from satisfied to active

system:

2024-09-12 17:04:23.121 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'smsmodem:smsmodembridge:9a57d5dff1' changed from UNINITIALIZED (NOT_YET_READY) to INITIALIZING
2024-09-12 17:04:23.138 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'smsmodem:smsmodembridge:9a57d5dff1' changed from INITIALIZING to UNKNOWN
2024-09-12 17:04:23.141 [DEBUG] [ternal.handler.SMSModemBridgeHandler] - Initializing smsmodem
2024-09-12 17:04:23.184 [DEBUG] [ternal.handler.SMSModemBridgeHandler] - Now trying to start SMSModem /dev/ttyUSB2 | 115200
2024-09-12 17:04:49.587 [DEBUG] [ternal.handler.SMSModemBridgeHandler] - SMSModem /dev/ttyUSB2 | 115200 started
2024-09-12 17:04:49.591 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'smsmodem:smsmodembridge:9a57d5dff1' changed from UNKNOWN to ONLINE
2024-09-12 17:05:06.282 [WARN ] [rg.smslib.driver.AbstractModemDriver] - /dev/ttyUSB2:115200 ERR==> ERROR

It seems that your modem doesn’t support the “status report message area”, according to this documentation.
If gammu works for you, it also means that it shouldn’t be mandatory. But unfortunately the SMSLIB I use in this binding seems to want it.
A last try:
Did you enable the “deliveryReport” boolean ? If so, try disabling it (“status report message area” seems some kind of memory for delivery report ?)

Beyond that, I’m not able to help you furthermore, sorry.

no luck, still not working.
but thank you for the help.
I will check what device is working with this library.