Re: [Users] Re : Mbuni mm1 problem
Hi,
I don't think I could add anything more than I wrote
previously. Response you get from server mbuni tries to talk to gives
error 400 ('Bad Request! Check spelling of requested URL'). I had
similar problem when I used wrong URL (mmsc-url setting). It could
happen if you use proxy where you shouldn't or give mmsc ip as proxy
where it uses different ip. I cannot say what's specifically wrong in
your case because: first - I don't know the configuration settings of
network you use to send MMS and second - you blanked IP addresses and
URL in your config.
I also wonder why you used port=8080 setting. It's nothing technically
illegal in it but it's a well known port for proxies so IMHO it's not
a good idea to use it for your listener thread.
Kind regards,
Piotr
On Fri, Sep 10, 2010 at 10:45:10AM +, Dr Ox wrote:
> Hi Piotr
> Thanks for your fast response and let me provide you with more logs.
> mmsbox logs
> 2010-09-10 18:02:20 [21803] [3] DEBUG: HTTP: Creating HTTPClient for
> `xxx.xxx.xxx.xxx'.
> 2010-09-10 18:02:20 [21803] [3] DEBUG: HTTP: Created HTTPClient area
> 0x8b71f70.
> 2010-09-10 18:02:20 [21803] [14] DEBUG: WSP: Mapping `text/plain', WSP 1.2 to
> 0x0003.
> 2010-09-10 18:02:20 [21803] [14] INFO: mmsbox.c:1366
> [mmsbox] [n/a] MMSBox: Queued message from service [sendmms-user], [transid
> [Mbuni-msg.2940.x1.3.84]: b-qf2940.1.x803.41
> 2010-09-10 18:02:20 [21803] [14] DEBUG: HTTP: Destroying HTTPClient area
> 0x8b71f70.
> 2010-09-10 18:02:20 [21803] [14] DEBUG: HTTP: Destroying HTTPClient for
> `xxx.xxx.xxx.xxx'.
> 2010-09-10 18:02:20 [21803] [14] INFO: mmsbox.c:1613
> [mmsbox] [n/a] MMSBox.mmssend: u=know1221, Queued [Accepted:
> Mbuni-msg.2940.x1.3.84]
> 2010-09-10 18:02:24 [21803] [8] DEBUG: Queued to thread 0 for
> /var/www/html/mms/mmsbox_outgoing/b/qf2940.1.x803.41, sendt=1284112940,
> tnow=1284112944
>
>SMSC `TEST' shut down
>
> arg 0: pppd
> arg 1: call
> arg 2: test-auth
> chat: Sep 10 18:02:31 CONNECT 115200
> Serial connection established.
> using channel 32
> Using interface ppp0
> Connect: ppp0 <--> /dev/ttyUSB0
> sent [LCP ConfReq id=0x1]
> rcvd [LCP ConfRej id=0x1 ]
> sent [LCP ConfReq id=0x2 ]
> rcvd [LCP ConfAck id=0x2 ]
> rcvd [LCP ConfReq id=0x1 pap>]
> sent [LCP ConfAck id=0x1 pap>]
> sent [PAP AuthReq id=0x1 user="xxx" password=]
> cat: /var/run/ppp0-mbuni.pid: No such file or directory
> 2010-09-10 18:02:34 [21803] [5] INFO: mmsbox_mm1.c:659
> [mmsbox-mm1] [n/a] waiting for connection: 0, pid=21829 cpid=0, ifexited=1,
> exitstatus=0
> rcvd [PAP AuthAck id=0x1 "Welcome!"]
> Remote message: Welcome!
> PAP authentication succeeded
> sent [CCP ConfReq id=0x1 ]
> sent [IPCP ConfReq id=0x1 ]
> rcvd [IPCP ConfReq id=0x1 ]
> sent [IPCP ConfAck id=0x1 ]
> rcvd [LCP ProtRej id=0x20 80 fd 01 01 00 0c 1a 04 78 00 18 04 78 00]
> Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
> rcvd [IPCP ConfNak id=0x1
> ]
> sent [IPCP ConfReq id=0x2
> ]
> rcvd [IPCP ConfAck id=0x2
> ]
> local IP address 10.236.116.182
> remote IP address 192.168.111.111
> primary DNS address xxx.xxx.xxx.xxx
> secondary DNS address xxx.xxx.xxx.xxx
> Script /etc/ppp/ip-up started (pid 21847)
> Script /etc/ppp/ip-up finished (pid 21847), status = 0x0
> 2010-09-10 18:02:39 [21803] [5] INFO: mmsbox_mm1.c:379
> [mmsbox-mm1] [n/a] start_gprs returned PID: 21829
> 2010-09-10 18:02:39 [21803] [5] DEBUG: WSP: Mapping `text/plain', WSP 1.2 to
> 0x0003.
> 2010-09-10 18:02:41 [21803] [5] INFO: mmsbox_mm1.c:589
> [mmsbox-mm1] [n/a] write_data called with nmemn=401, size=1
> 2010-09-10 18:02:41 [21803] [5] WARNING: Error parsing application-header.
> 2010-09-10 18:02:41 [21803] [5] DEBUG: Octet string at 0x8b71f88:
> 2010-09-10 18:02:41 [21803] [5] DEBUG: len: 401
> 2010-09-10 18:02:41 [21803] [5] DEBUG: size: 1024
> 2010-09-10 18:02:41 [21803] [5] DEBUG: immutable: 0
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 3c 3f 78 6d 6c 20 76 65 72 73
> 69 6f 6e 3d 27 312010-09-10 18:02:41 [21803] [5] DEBUG: data: 2e 30 27 3f 3e 0a 3c 21 44 4f
> 43 54 59 50 45 20 .0'?>. 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 68 74 6d 6c 20 50 55 42 4c 49
> 43 20 27 2d 2f 2f html PUBLIC '-//
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 57 41 50 46 4f 52 55 4d 2f 2f
> 44 54 44 20 58 48 WAPFORUM//DTD XH
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 54 4d 4c 20 4d 6f 62 69 6c 65
> 20 31 2e 30 2f 2f TML Mobile 1.0//
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 45 4e 27 0a 27 68 74 74 70 3a
> 2f 2f 77 77 77 2e EN'.'http://www.
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 77 61 70 66 6f 72 75 6d 2e 6f
> 72 67 2f 44 54 44 wapforum.org/DTD
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 2f 78 68 74 6d 6c 2d 6d 6f 62
> 69 6c 65 31 30 2e /xhtml-mobile10.
> 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 64 74 64 27 3e 0a 3c 68 74 6d
> 6c 20 78 6d 6c 6e dtd'>. 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 73 3d 27 68
[Users] Re : Mbuni mm1 problem
Hi Piotr Thanks for your fast response and let me provide you with more logs. mmsbox logs 2010-09-10 18:02:20 [21803] [3] DEBUG: HTTP: Creating HTTPClient for `xxx.xxx.xxx.xxx'. 2010-09-10 18:02:20 [21803] [3] DEBUG: HTTP: Created HTTPClient area 0x8b71f70. 2010-09-10 18:02:20 [21803] [14] DEBUG: WSP: Mapping `text/plain', WSP 1.2 to 0x0003. 2010-09-10 18:02:20 [21803] [14] INFO: mmsbox.c:1366 [mmsbox] [n/a] MMSBox: Queued message from service [sendmms-user], [transid [Mbuni-msg.2940.x1.3.84]: b-qf2940.1.x803.41 2010-09-10 18:02:20 [21803] [14] DEBUG: HTTP: Destroying HTTPClient area 0x8b71f70. 2010-09-10 18:02:20 [21803] [14] DEBUG: HTTP: Destroying HTTPClient for `xxx.xxx.xxx.xxx'. 2010-09-10 18:02:20 [21803] [14] INFO: mmsbox.c:1613 [mmsbox] [n/a] MMSBox.mmssend: u=know1221, Queued [Accepted: Mbuni-msg.2940.x1.3.84] 2010-09-10 18:02:24 [21803] [8] DEBUG: Queued to thread 0 for /var/www/html/mms/mmsbox_outgoing/b/qf2940.1.x803.41, sendt=1284112940, tnow=1284112944 SMSC `TEST' shut down arg 0: pppd arg 1: call arg 2: test-auth chat: Sep 10 18:02:31 CONNECT 115200 Serial connection established. using channel 32 Using interface ppp0 Connect: ppp0 <--> /dev/ttyUSB0 sent [LCP ConfReq id=0x1] rcvd [LCP ConfRej id=0x1 ] sent [LCP ConfReq id=0x2 ] rcvd [LCP ConfAck id=0x2 ] rcvd [LCP ConfReq id=0x1 ] sent [LCP ConfAck id=0x1 ] sent [PAP AuthReq id=0x1 user="xxx" password=] cat: /var/run/ppp0-mbuni.pid: No such file or directory 2010-09-10 18:02:34 [21803] [5] INFO: mmsbox_mm1.c:659 [mmsbox-mm1] [n/a] waiting for connection: 0, pid=21829 cpid=0, ifexited=1, exitstatus=0 rcvd [PAP AuthAck id=0x1 "Welcome!"] Remote message: Welcome! PAP authentication succeeded sent [CCP ConfReq id=0x1 ] sent [IPCP ConfReq id=0x1 ] rcvd [IPCP ConfReq id=0x1 ] sent [IPCP ConfAck id=0x1 ] rcvd [LCP ProtRej id=0x20 80 fd 01 01 00 0c 1a 04 78 00 18 04 78 00] Protocol-Reject for 'Compression Control Protocol' (0x80fd) received rcvd [IPCP ConfNak id=0x1 ] sent [IPCP ConfReq id=0x2 ] rcvd [IPCP ConfAck id=0x2 ] local IP address 10.236.116.182 remote IP address 192.168.111.111 primary DNS address xxx.xxx.xxx.xxx secondary DNS address xxx.xxx.xxx.xxx Script /etc/ppp/ip-up started (pid 21847) Script /etc/ppp/ip-up finished (pid 21847), status = 0x0 2010-09-10 18:02:39 [21803] [5] INFO: mmsbox_mm1.c:379 [mmsbox-mm1] [n/a] start_gprs returned PID: 21829 2010-09-10 18:02:39 [21803] [5] DEBUG: WSP: Mapping `text/plain', WSP 1.2 to 0x0003. 2010-09-10 18:02:41 [21803] [5] INFO: mmsbox_mm1.c:589 [mmsbox-mm1] [n/a] write_data called with nmemn=401, size=1 2010-09-10 18:02:41 [21803] [5] WARNING: Error parsing application-header. 2010-09-10 18:02:41 [21803] [5] DEBUG: Octet string at 0x8b71f88: 2010-09-10 18:02:41 [21803] [5] DEBUG: len: 401 2010-09-10 18:02:41 [21803] [5] DEBUG: size: 1024 2010-09-10 18:02:41 [21803] [5] DEBUG: immutable: 0 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 3c 3f 78 6d 6c 20 76 65 72 73 69 6f 6e 3d 27 31 .http://www. 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 77 61 70 66 6f 72 75 6d 2e 6f 72 67 2f 44 54 44 wapforum.org/DTD 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 2f 78 68 74 6d 6c 2d 6d 6f 62 69 6c 65 31 30 2e /xhtml-mobile10. 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 64 74 64 27 3e 0a 3c 68 74 6d 6c 20 78 6d 6c 6e dtd'>... 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 54 68 65 20 72 65 71 75 65 73 74 20 66 61 69 6c The request fail 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 65 64 3c 2f 74 69 74 6c 65 3e 0a 3c 2f 68 65 61 ed...The request is 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 20 6e 6f 74 20 75 6e 64 65 72 73 74 6f 6f 64 2enot understood. 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 3c 2f 62 69 67 3e 3c 2f 70 3e 0a 3c 70 3e 0a 3c ..< 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 69 3e 54 65 63 68 6e 69 63 61 6c 20 64 65 73 63 i>Technical desc 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 72 69 70 74 69 6f 6e 3a 3c 2f 69 3e 3c 62 72 2f ription:400 Bad Request 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 20 2d 20 43 68 65 63 6b 20 79 6f 75 72 20 73 70- Check your sp 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 65 6c 6c 69 6e 67 20 66 6f 72 20 74 68 65 20 72 elling for the r 2010-09-10 18:02:41 [21803] [5] DEBUG: data: 65 71 75 65 73 74 65 64 20 55 52 4c 2e 3c 2f 70 equested URL... 2010-09-10 18:02:41 [21803] [5] DEBUG: Octet string dump ends. 2010-09-10 18:02:41 [21803] [5] ERROR: mmsbox_mm1.c:499 [mmsbox-mm1] [n/a] Sending failed: (none), (none)! 2010-09-10 18:02:41 [21803] [5] INFO: mmsbox_mm1.c:524 [mmsbox-mm1] [n/a] GPRS turned off returned: 0 2010-09-10 18:02:41 [21803] [19] INFO: mmsbox_mm1.c:345 [mmsbox-mm1] [n/a] sent message, type=m-send-req, result=(none) 2010-09-10 18:02:41 [21803] [19] INFO: bearerbox.c:1519 [MM7] [n/a] Retry later MMSBox Outgoing Queue MMS Send: From +/TYPE=PLMN, to +/TYPE=PLMN, msgsize=112: msgid=[N/A] Terminating on
