Performance issues between PlaySMS and Nexmo Gateway

Hi,

Recently im experiencing a ‘weird’ situation when sending my messages trough NexMo. It has came to my attention that regardless the amount of SMS sent I always get an effectiveness rates of about 20%. For example, i want to send messages to a list of 1000 contacts. After sending my messages i only get around 200 delivered messages. If i export those failed 800 messages and put them in a new group and then i resend the message, then i got delivered about 160 messages. And so on.
At the end of the process the total effectiveness is what you could expect. But for some reason each lot is only effective in about 20%.

Has anyone experienced the same?

Could it be a PlaySMS tunning issue?
Could it be a Nexmo issue?
Could it be a carrier issue?

Thanks. I’d appreciate your comments.

That is odd. Please tell us the version you’re using.

anton

Hi Anton,

Im using playSMS-1.0-rc9.

Ic, it is possible that there is a bug in 1.0-rc9.

If you see this: https://github.com/antonraharja/playSMS/commits/master
You’ll notice that I have major fix around the queue after 1.0-rc9 before 1.0.

So I suggest to upgrade your installation playSMS 1.0.

anton

Thanks for the advise. I will update tonight and post my comments.

Hi,

Anton, I was able to update but now, when i want to send a message i got this message:

Send message failed due to unable to prepare queue e3a40ec6d98e8437f3f1773a4e3e215a

Sorry if i didnt open a new topic.

Your upgrade failed, read other topics here, there are similar issues and how to fix them

anton

Hi,

We finally upgraded. We made a test but again the effectiveness of a lot of 1750 it was 450. To our calculations it was about 25%. For the case, it’s still the same.

Are there some settings we could tweak to try to increase that effectiveness?

Thanks.

not sure what’s really happens, it should be all delivered, I thought 1.0 should fixed it, but apparently its not

so lets talk about the issue again.

I would like to know:

  • how did you sent the message to 1000 contacts
  • how did you get to know that only 200 delivered
  • how did you export the 800 contacts with failed status

anton

Thanks Anton. The general problem is that in my supply chain: PlaySMS + NexMo + Carrier, no matter what i do, the effectiveness of a sending lot is always the same: around 20% (message delivered / messages sent).

So let me answer your questions:

  • I prepare a message template, a group and i send the template to the group.
  • I can count delivered messages on the amount of money Nexmo took from my balance, on the amount of credit that were removed from my account in PlaySMS and from the report in Sent Messages.
  • I go to Sent Messages, export all sent messages and then remove the delivered ones; then i create another group with the result.

As I said, im not sure if it;s a Nexmo issue, a Carrier issue or a bug in PlaySMS. However the situation is that regardless of the sent messages, it always tends to a 20% delivered messages. That is what i found weird.

It seems to me its on Nexmo or Carrier side, have you talk to Nexmo ?

anton

Yes. They gave me an answer suggesting because it’s a bulk sending i may get filtered (on the content, sender ID, etc). But i’ve tried at least 5 different DIDs (Sender IDs), different times of day, etc, and i always get the same result. The funny story is that those undelivered messages when i put them on a different list they are sent.
To me is like somewhere something is limiting to 20%.

what if you sent low volume like 10 or 100 ?

anton

The same. Only around 20% messages are delivered. That’s why we use the whole list. At the end of the day it turns into a very demanding task: send - then filter - then send - then filter again - then send again - and so on.

ok, lets debug

run this on Linux shell:

tail -f /var/log/playsms/playsms.log | tee test1.txt

and then send 10 sms

ctrl+c the tail and pm me test1.txt

anton

Hi Anton,

Please find the log for those SMS.

Thanks again.

67.14.242.244 sms.readytoanswer.com 2015-06-10 10:20:31 PID557847af86af6 - L2 auth_validate_login # valid login u:mtavera uid:2 ip:67.14.242.244
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:20:31 PID557847af86af6 - L2 login # u:mtavera uid:2 status:3 sid:jegq6p6d810ojksunk51kg0qf5 ip:67.14.242.244
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms # start uid:2 sender_id:[13056771040] smsc:[]
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_create # saving queue_code:08039b472a358b03ca41095515d25b5b src:13056771040 scheduled:2015-06-10 10:22:30
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_create # saved queue_code:08039b472a358b03ca41095515d25b5b id:207
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms # dst:10 sms_count:10 total_charges:10
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058561488
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59094
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058561597
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59095
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058561802
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59096
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058561834
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59097
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058561947
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59098
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058562020
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59099
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058562200
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59100
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058562232
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59101
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058562252
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59102
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saving queue_code:08039b472a358b03ca41095515d25b5b dst:13058562300
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms_queue_push # saved queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59103
67.14.242.244 sms.readytoanswer.com 2015-06-10 10:22:30 PID55784825c1f3a mtavera L2 sendsms # end queue_code:08039b472a358b03ca41095515d25b5b queue_count:10 sms_count:10 failed_queue:0 failed_sms:0

    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # start processing queue_code:08039b472a358b03ca41095515d25b5b queue_count:10 sms_count:10 scheduled:2015-06-10 10:22:30 uid:2 gpid:0 sender_id:13056771040
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59094 to:13058561488 sms_count:10 counter:1
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058561488 credit:1427.000 count:1 rate:1.000 charge:1 balance:1426
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms # saving smslog_id:59094 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058561488 type:text unicode:0 status:0
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59094 id:59075
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59094 message_id:030000007D726FF9 status:0 error:
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__deduct # enter smslog_id:59094
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saving uid:2 balance:1426
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saved uid:2 balance:1426
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__deduct # user uid:2 parent_uid: smslog_id:59094 msglen:138 count:1 rate:1.000 charge:1 credit:1427.000 balance:1426
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplebilling__post # saving smslog_id:59094 rate:1.000 credit:1427.000 count:1 charge:1
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplebilling__post # saved smslog_id:59094 id:32987
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058561488 flag:1 smslog_id:59094
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59095 to:13058561597 sms_count:10 counter:2
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058561597 credit:1426.000 count:1 rate:1.000 charge:1 balance:1425
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms # saving smslog_id:59095 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058561597 type:text unicode:0 status:0
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59095 id:59076
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59095 message_id:020000006674668E status:0 error:
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__deduct # enter smslog_id:59095
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saving uid:2 balance:1425
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saved uid:2 balance:1425
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__deduct # user uid:2 parent_uid: smslog_id:59095 msglen:138 count:1 rate:1.000 charge:1 credit:1426.000 balance:1425
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplebilling__post # saving smslog_id:59095 rate:1.000 credit:1426.000 count:1 charge:1
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplebilling__post # saved smslog_id:59095 id:32988
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058561597 flag:1 smslog_id:59095
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59096 to:13058561802 sms_count:10 counter:3
      174.36.197.201 162.242.242.235 2015-06-10 10:22:31 PID557848278be9e - L2 nexmo__callback # pushed msisdn:13058561488 to:13056771040 network-code:US-UNKNOWN messageId:030000007D726FF9 price:0 status:failed scts:1506101422 err-code:1 client-ref:59094 message-timestamp:2015-06-10 14:22:38
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058561802 credit:1425.000 count:1 rate:1.000 charge:1 balance:1424
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms # saving smslog_id:59096 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058561802 type:text unicode:0 status:0
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59096 id:59077
      174.36.197.201 162.242.242.235 2015-06-10 10:22:31 PID557848278be9e - L2 nexmo__callback # dlr uid:2 smslog_id:59094 message_id:030000007D726FF9 status:failed
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59096 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 813 ] and retry
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058561802 flag:2 smslog_id:59096
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59097 to:13058561834 sms_count:10 counter:4
    • 2015-06-10 10:22:31 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058561834 credit:1425.000 count:1 rate:1.000 charge:1 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59097 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058561834 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59097 id:59078
    • 2015-06-10 10:22:32 PID557708595fc7e - L2 simplebilling__finalize # saving smslog_id:59094
    • 2015-06-10 10:22:32 PID557708595fc7e - L2 simplebilling__finalize # saved smslog_id:59094
    • 2015-06-10 10:22:32 PID557708595fc7e - L2 simplebilling__finalize # saving smslog_id:59095
      174.36.197.195 162.242.242.235 2015-06-10 10:22:32 PID55784827b113e - L2 nexmo__callback # pushed msisdn:13058561597 to:13056771040 network-code:US-FIXED messageId:020000006674668E price:0 status:failed scts:1506101422 err-code:1 client-ref:59095 message-timestamp:2015-06-10 14:22:38
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59097 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 580 ] and retry
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058561834 flag:2 smslog_id:59097
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59098 to:13058561947 sms_count:10 counter:5
    • 2015-06-10 10:22:32 PID557708595fc7e - L2 simplebilling__finalize # saved smslog_id:59095
      174.36.197.195 162.242.242.235 2015-06-10 10:22:32 PID55784827b113e - L2 nexmo__callback # dlr uid:2 smslog_id:59095 message_id:020000006674668E status:failed
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058561947 credit:1425.000 count:1 rate:1.000 charge:1 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59098 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058561947 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59098 id:59079
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59098 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 390 ] and retry
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058561947 flag:2 smslog_id:59098
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59099 to:13058562020 sms_count:10 counter:6
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058562020 credit:1425.000 count:1 rate:1.000 charge:1 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59099 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058562020 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59099 id:59080
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59099 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 231 ] and retry
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058562020 flag:2 smslog_id:59099
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59100 to:13058562200 sms_count:10 counter:7
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058562200 credit:1425.000 count:1 rate:1.000 charge:1 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59100 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058562200 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59100 id:59081
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59100 message_id:030000007D727035 status:0 error:
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__deduct # enter smslog_id:59100
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saving uid:2 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saved uid:2 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__deduct # user uid:2 parent_uid: smslog_id:59100 msglen:138 count:1 rate:1.000 charge:1 credit:1425.000 balance:1424
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplebilling__post # saving smslog_id:59100 rate:1.000 credit:1425.000 count:1 charge:1
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplebilling__post # saved smslog_id:59100 id:32989
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058562200 flag:1 smslog_id:59100
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59101 to:13058562232 sms_count:10 counter:8
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058562232 credit:1424.000 count:1 rate:1.000 charge:1 balance:1423
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59101 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058562232 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59101 id:59082
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59101 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 800 ] and retry
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058562232 flag:2 smslog_id:59101
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59102 to:13058562252 sms_count:10 counter:9
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058562252 credit:1424.000 count:1 rate:1.000 charge:1 balance:1423
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms # saving smslog_id:59102 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058562252 type:text unicode:0 status:0
    • 2015-06-10 10:22:32 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59102 id:59083
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59102 message_id:02000000667466D4 status:0 error:
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 simplerate__deduct # enter smslog_id:59102
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saving uid:2 balance:1423
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 credit_hook_rate_setusercredit # saved uid:2 balance:1423
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 simplerate__deduct # user uid:2 parent_uid: smslog_id:59102 msglen:138 count:1 rate:1.000 charge:1 credit:1424.000 balance:1423
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 simplebilling__post # saving smslog_id:59102 rate:1.000 credit:1424.000 count:1 charge:1
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 simplebilling__post # saved smslog_id:59102 id:32990
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058562252 flag:1 smslog_id:59102
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsmsd # sending queue_code:08039b472a358b03ca41095515d25b5b smslog_id:59103 to:13058562300 sms_count:10 counter:10
      174.36.197.201 162.242.242.235 2015-06-10 10:22:33 PID557848289b267 - L2 nexmo__callback # pushed msisdn:13058562200 to:13056771040 network-code:US-UNKNOWN messageId:030000007D727035 price:0 status:failed scts:1506101422 err-code:1 client-ref:59100 message-timestamp:2015-06-10 14:22:39
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms_process # start
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 simplerate__cansend # allowed user uid:2 sms_to:13058562300 credit:1423.000 count:1 rate:1.000 charge:1 balance:1422
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms # saving smslog_id:59103 u:2 g:0 gw:nexmo smsc:linkedipulcnm001 s:13056771040 d:13058562300 type:text unicode:0 status:0
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms_process # saved smslog_id:59103 id:59084
      174.36.197.201 162.242.242.235 2015-06-10 10:22:33 PID557848289b267 - L2 nexmo__callback # dlr uid:2 smslog_id:59100 message_id:030000007D727035 status:failed
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 nexmo__outgoing # sent smslog_id:59103 message_id: status:1 error:Throughput Rate Exceeded - please wait [ 361 ] and retry
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms_process # fail no hook for sendsms
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsms_process # end
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsmsd # result queue_code:08039b472a358b03ca41095515d25b5b to:13058562300 flag:2 smslog_id:59103
    • 2015-06-10 10:22:33 PID5578482754c90 - L2 sendsmsd # finish processing queue_code:08039b472a358b03ca41095515d25b5b uid:2 sender_id:13056771040 queue_count:10 sms_count:10
      174.36.197.195 162.242.242.235 2015-06-10 10:22:33 PID557848290328a - L2 nexmo__callback # pushed msisdn:13058562252 to:13056771040 network-code:US-FIXED messageId:02000000667466D4 price:0 status:failed scts:1506101422 err-code:1 client-ref:59102 message-timestamp:2015-06-10 14:22:39
      174.36.197.195 162.242.242.235 2015-06-10 10:22:33 PID557848290328a - L2 nexmo__callback # dlr uid:2 smslog_id:59102 message_id:02000000667466D4 status:failed
    • 2015-06-10 10:22:34 PID557708595fc7e - L2 simplebilling__finalize # saving smslog_id:59100
    • 2015-06-10 10:22:34 PID557708595fc7e - L2 simplebilling__finalize # saved smslog_id:59100
    • 2015-06-10 10:22:34 PID557708595fc7e - L2 simplebilling__finalize # saving smslog_id:59102
    • 2015-06-10 10:22:34 PID557708595fc7e - L2 simplebilling__finalize # saved smslog_id:59102

Please read the log and tell me what do you think, can you at least guess what happenned?

Anton

Hi,

I see this message repeated: Throughput Rate Exceeded - please wait [ 813 ] and retry

Is this at Nexmo or at Nexmo’s carriers?

Does this mean im sending the messages to fast?

What could i do?

Its coming from Nexmo, so Nexmo should have the answer to that.

What I’ve heard about this, you should pick one solution:

  1. To slow down the sending, by adding sleep 1 on every submission
  2. To get some arrangement with Nexmo so that you can send on much higher throughput

But please note that a user experienced before that current playSMS cannot do faster than 2-3 tps (text per second) with Nexmo, that is due to the way playSMS submit request to Nexmo on per 1 message bases rather than multiple messages. For this we will have the solution soon, but not today.

Have you considered other services ? Perhaps Twilio, Clickatell or other gateway supported by playSMS or via SMPP so that you can have much higher throughput ?

anton

Try to slow down the submission as its easier, although I prefer to talk to Nexmo and increase the throughput.

Here is how to limit the throughput by 1 tps:

Edit plugin/gateway/nexmo/fn.php, go to line 72 and remove comment on sleep(1), the changes should be like this:

// fixme anton
// rate limit to 1 second per submit - nexmo rule
sleep(1);

And then restart playsmsd (and make sure its running again):

playsmsd restart
playsmsd check

Test again, watch the log just like what you’ve done previously using tail command.

anton