Сначала UDPXY раздаёт нормально,
на клиентские машины для медиаплееров VLC 1.1.11, SimpleTV версия 0.4 (vlc 1.1.8),
даже одновременно, и нормально переключаются каналы.
Если запустить на клиентской машине IP-TV Player , он запускается и
начинает отображать вещание бесконечно долго,
но если попытаться переключить на другой канал вещания,
то сразу отключается udpxy на сервере, исчезает в процессах.
И у всех IPTV естественно пропадает.
запускать приходится
# /usr/local/etc/rc.d/udpxy start
или
# udpxy -p 4022
или сейчас
# /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v –S
и снова все нормально на всех плеерах
отображается и переключается до попытки переключить на IP-TV Player канал вещания,
хотя у него тоже указан движок от VLC .
клиенты Windows XP SP2
для просмотра UDP IPTV используют программу IPTvPlayer
взял
http://www.snet.com.ua/iptv/IpTvPlayer-setup.exe
обнаружил проблему взаимодействия с UDPXU
удалил и установил с
http://borpas.info/download/IpTvPlayer-setup.exe
проблема не исчезла, проявляется аналогично.
При проигрывании VLC media player падения UDPXY не происходит,
но при запинке VLC не самовосстанавливается, а продолжает сыпать квадратиками и
приходится останавливать VLC и снова запускать просмотр.
Или пролистывает каналы не видя потока, хотя он наверняка есть.
В общем VLC глючит.
Сервер.
# uname -a
FreeBSD INTEL 7.3-RELEASE-p4 FreeBSD 7.3-RELEASE-p4 #1: Thu Jul 14 16:42:57 EEST 2011 root@INTEL:/usr/obj/usr/src/sys/MYKERNEL i386
/etc/rc.conf
ifconfig_rl1="DHCP"
defaultrouter="NO"
gateway_enable="YES"
router_enable="YES"
firewall_type="OPEN"
dummynet_enable="yes"
natd_enable="YES"
natd_interface="rl1"
dhcpd_enable="YES"
dhcpd_ifaces="rl0"
named_enable="YES"
apache_enable="YES"
udpxy_enable="YES"
udpxy_flags="-m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300"
# ifconfig
rl0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=8<VLAN_MTU>
ether 00:0e:2e:aa:rl:00
inet 192.168.1.1 netmask 0xffffff00 broadcast 192.168.1.255
media: Ethernet autoselect (100baseTX <full-duplex>)
status: active
rl1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=8<VLAN_MTU>
ether 00:08:54:ab:rl:01
inet 192.168.0.2 netmask 0xffffe000 broadcast 192.168.0.255
media: Ethernet autoselect (100baseTX <full-duplex>)
status: active
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
inet 127.0.0.1 netmask 0xff000000
Проблема проявляется на UDPXY (build 19), (build 20), (build 18)
Изначально был установлен
udpxy 1.0-Chipmunk (build 19) standard: udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300
[more=Лог(build 19):]
Server 56494
client 56496
2011-09-04 21:51:12.243752 EEST 56494 Received TSTAT={ sender=[56496], bytes=[1316000.000000], seconds=[2.000000] }
2011-09-04 21:51:12.243923 EEST 56494 Updated context for pid=[56496]; [642.6] Kb/sec
2011-09-04 21:51:12.243950 EEST 56494 Server is waiting for input: socket=[5], pipe=[2]
2011-09-04 21:51:14.325749 EEST 56494 Received TSTAT={ sender=[56496], bytes=[1316000.000000], seconds=[2.000000] }
2011-09-04 21:51:14.325913 EEST 56494 Updated context for pid=[56496]; [642.6] Kb/sec
2011-09-04 21:51:14.325941 EEST 56494 Server is waiting for input: socket=[5], pipe=[2]
2011-09-04 21:51:14.491767 EEST 56494 server_loop: accept: Software caused connection abort
2011-09-04 21:51:14.491852 EEST 56494 Exited server loop
2011-09-04 21:51:14.491884 EEST 56494 Forcing client process [56496] to QUIT
2011-09-04 21:51:14.491964 EEST 56496 *** Caught SIGNAL 3 in process=[56496] ***
2011-09-04 21:51:14.492039 EEST 56496 read_buf interrupted
2011-09-04 21:51:14.492696 EEST 56496 write_buf: write: Broken pipe
2011-09-04 21:51:14.492751 EEST 56496 Exited relay loop: received=[1316], sent=[-1], quit=[0]
2011-09-04 21:51:14.492848 EEST 56496 Child process=[56496] must quit
2011-09-04 21:51:14.492940 EEST 56496 multicast-group [DROP]
2011-09-04 21:51:14.492989 EEST 56496 Mcast listener socket=[2] closed
2011-09-04 21:51:14.493102 EEST 56496 Child process=[56496] exits with rc=[0]
2011-09-04 21:51:14.493650 EEST 56494 *** Caught SIGCHLD in process=[56494] ***
2011-09-04 21:51:14.493824 EEST 56494 wait_children: waitpid: Interrupted system call
2011-09-04 21:51:14.493950 EEST 56494 Server [56494] exits rc=[1]
2011-09-04 21:51:14.494010 EEST 56494 udpxy is exiting with rc=[1]
2011-09-04 21:51:14.494032 EEST 56494 udpxy 1.0-Chipmunk (build 19) standard: udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300
[/more]
затем удалил (build 19) и запустил
udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v –S
[more=Лог(build 20):]
--------------------------------------------------------------------------------------------------
2011-10-02 21:11:15.473716 EEST 33851 udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v -S
2011-10-02 21:11:15.473827 EEST 33851 Starting server [33851]; capacity=[3] clients
2011-10-02 21:11:15.473935 EEST 33851 Setting up listener for [192.168.1.1:4022]
2011-10-02 21:11:15.474045 EEST 33851 Server socket=[5] is set up
2011-10-02 21:11:15.474071 EEST 33851 Entering server loop
2011-10-02 21:11:15.474092 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
Oct 2 21:11:15 INTEL udpxy[33851]: udpxy 1.0-Chipmunk (build 20) standard is starting
2011-10-02 21:11:19.552768 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:19.552902 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:19.552928 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:19.552953 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:19.563254 EEST 33851 HTTP buffer [58 bytes] received
GET /udp/238.1.3.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:19.563329 EEST 33851 Request=[udp/238.1.3.1:1234], length=[18]
2011-10-02 21:11:19.563353 EEST 33851 Command [udp] with params [238.1.3.1:1234] read from socket=[6]
2011-10-02 21:11:19.563377 EEST 33851 udp_relay : new_socket=[6] param=[238.1.3.1:1234]
2011-10-02 21:11:19.564017 EEST 33851 Added client: pid=[33852], maddr=[238.1.3.1], mport=[1234], saddr=[192.168.1.225], sport=[2260]
2011-10-02 21:11:19.564372 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:19.564410 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:19.564530 EEST 33851 Client process=[33852] started for socket=[6]
2011-10-02 21:11:19.564764 EEST 33852 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:19.564794 EEST 33852 Setting up multicast listener
2011-10-02 21:11:19.564959 EEST 33852 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:19.565009 EEST 33852 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:19.565219 EEST 33852 multicast-group [ADD]
2011-10-02 21:11:19.565307 EEST 33852 Mcast listener socket=[2] set up
2011-10-02 21:11:19.565343 EEST 33852 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:19.565366 EEST 33852 Data buffer will hold up to [1] messages
2011-10-02 21:11:19.565388 EEST 33852 UDP stream, RTP check enabled
2011-10-02 21:11:19.565493 EEST 33852 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:19.565523 EEST 33852 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:19.565550 EEST 33852 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:19.565573 EEST 33852 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:19.565598 EEST 33852 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:19.565767 EEST 33852 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:19.566010 EEST 33852 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:24.565296 EEST 33852 read_buf: read: Resource temporarily unavailable
2011-10-02 21:11:24.565381 EEST 33852 read_data - EOF
2011-10-02 21:11:24.565405 EEST 33852 Exited relay loop: received=[-1], sent=[0], quit=[0]
2011-10-02 21:11:24.565515 EEST 33852 multicast-group [DROP]
2011-10-02 21:11:24.565571 EEST 33852 Mcast listener socket=[2] closed
2011-10-02 21:11:24.565793 EEST 33852 Child process=[33852] exits with rc=[0]
Oct 2 21:11:24 INTEL udpxy[33852]: read_buf: read: Resource temporarily unavailable
2011-10-02 21:11:24.566848 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
2011-10-02 21:11:24.567339 EEST 33851 Client [33852] has exited.
2011-10-02 21:11:24.567388 EEST 33851 Deleted client: pid=[33852]
2011-10-02 21:11:24.567411 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:24.567467 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:24.567495 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:24.567520 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:24.567544 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:24.578357 EEST 33851 HTTP buffer [58 bytes] received
GET /udp/238.1.3.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:24.578400 EEST 33851 Request=[udp/238.1.3.1:1234], length=[18]
2011-10-02 21:11:24.578423 EEST 33851 Command [udp] with params [238.1.3.1:1234] read from socket=[6]
2011-10-02 21:11:24.578445 EEST 33851 udp_relay : new_socket=[6] param=[238.1.3.1:1234]
2011-10-02 21:11:24.578831 EEST 33851 Added client: pid=[33853], maddr=[238.1.3.1], mport=[1234], saddr=[192.168.1.225], sport=[2267]
2011-10-02 21:11:24.579067 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:24.579093 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:24.579303 EEST 33851 Client process=[33853] started for socket=[6]
2011-10-02 21:11:24.579485 EEST 33853 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:24.579517 EEST 33853 Setting up multicast listener
2011-10-02 21:11:24.579704 EEST 33853 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:24.579751 EEST 33853 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:24.579998 EEST 33853 multicast-group [ADD]
2011-10-02 21:11:24.580037 EEST 33853 Mcast listener socket=[2] set up
2011-10-02 21:11:24.580144 EEST 33853 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:24.580168 EEST 33853 Data buffer will hold up to [1] messages
2011-10-02 21:11:24.580189 EEST 33853 UDP stream, RTP check enabled
2011-10-02 21:11:24.580297 EEST 33853 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:24.580326 EEST 33853 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:24.580351 EEST 33853 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:24.580374 EEST 33853 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:24.580399 EEST 33853 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:24.580574 EEST 33853 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:24.580810 EEST 33853 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:24.589837 EEST 33853 Established stream as [MPEG-TS]
2011-10-02 21:11:24.589945 EEST 33853 received new [1316] bytes out of [2048], last=[0]
2011-10-02 21:11:24.590027 EEST 33853 sent [1316] bytes out of [1316], last=[0]
2011-10-02 21:11:28.510758 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:28.510924 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:28.510952 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:28.510977 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:28.511436 EEST 33851 read_command - recv (54): Connection reset by peer
2011-10-02 21:11:28.511508 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:28.511532 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
Oct 2 21:11:28 INTEL udpxy[33851]: read_command - recv (54): Connection reset by peer
2011-10-02 21:11:28.513172 EEST 33853 write_buf: write: Broken pipe
2011-10-02 21:11:28.513229 EEST 33853 Exited relay loop: received=[1316], sent=[-1], quit=[0]
2011-10-02 21:11:28.513342 EEST 33853 multicast-group [DROP]
2011-10-02 21:11:28.513464 EEST 33853 Mcast listener socket=[2] closed
2011-10-02 21:11:28.513574 EEST 33853 Child process=[33853] exits with rc=[0]
Oct 2 21:11:28 INTEL udpxy[33853]: write_buf: write: Broken pipe
2011-10-02 21:11:28.514490 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
2011-10-02 21:11:28.514611 EEST 33851 Client [33853] has exited.
2011-10-02 21:11:28.514636 EEST 33851 Deleted client: pid=[33853]
2011-10-02 21:11:28.514658 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:29.275230 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:29.275367 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:29.275393 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:29.275418 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:29.285582 EEST 33851 HTTP buffer [59 bytes] received
GET /udp/238.1.10.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:29.285674 EEST 33851 Request=[udp/238.1.10.1:1234], length=[19]
2011-10-02 21:11:29.285698 EEST 33851 Command [udp] with params [238.1.10.1:1234] read from socket=[6]
2011-10-02 21:11:29.285721 EEST 33851 udp_relay : new_socket=[6] param=[238.1.10.1:1234]
2011-10-02 21:11:29.286133 EEST 33851 Added client: pid=[33854], maddr=[238.1.10.1], mport=[1234], saddr=[192.168.1.225], sport=[2275]
2011-10-02 21:11:29.286289 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:29.286313 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:29.286415 EEST 33851 Client process=[33854] started for socket=[6]
2011-10-02 21:11:29.286575 EEST 33854 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:29.286608 EEST 33854 Setting up multicast listener
2011-10-02 21:11:29.286859 EEST 33854 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:29.286934 EEST 33854 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:29.287140 EEST 33854 multicast-group [ADD]
2011-10-02 21:11:29.287177 EEST 33854 Mcast listener socket=[2] set up
2011-10-02 21:11:29.287265 EEST 33854 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:29.287289 EEST 33854 Data buffer will hold up to [1] messages
2011-10-02 21:11:29.287310 EEST 33854 UDP stream, RTP check enabled
2011-10-02 21:11:29.287410 EEST 33854 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:29.287440 EEST 33854 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:29.287466 EEST 33854 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:29.287489 EEST 33854 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:29.287514 EEST 33854 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:29.287740 EEST 33854 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:29.287981 EEST 33854 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:29.300982 EEST 33854 Established stream as [MPEG-TS]
2011-10-02 21:11:29.301055 EEST 33854 received new [1316] bytes out of [2048], last=[0]
2011-10-02 21:11:29.301136 EEST 33854 sent [1316] bytes out of [1316], last=[0]
2011-10-02 21:11:31.355899 EEST 33851 Received TSTAT={ sender=[33854], bytes=[1316000.000000], seconds=[2.000000] }
2011-10-02 21:11:31.356142 EEST 33851 Updated context for pid=[33854]; [642.6] Kb/sec
2011-10-02 21:11:31.356177 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:33.489745 EEST 33851 Received TSTAT={ sender=[33854], bytes=[1316000.000000], seconds=[2.000000] }
2011-10-02 21:11:33.489915 EEST 33851 Updated context for pid=[33854]; [642.6] Kb/sec
2011-10-02 21:11:33.489943 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:33.833607 EEST 33851 server_loop: accept: Software caused connection abort
2011-10-02 21:11:33.833701 EEST 33851 Exited server loop
2011-10-02 21:11:33.833785 EEST 33851 Forcing client process [33854] to QUIT
2011-10-02 21:11:33.833965 EEST 33854 *** Caught SIGNAL 3 in process=[33854] ***
2011-10-02 21:11:33.834053 EEST 33854 read_buf interrupted
Oct 2 21:11:33 INTEL udpxy[33851]: server_loop: accept: Software caused connection abort
2011-10-02 21:11:33.835025 EEST 33854 Exited relay loop: received=[1316], sent=[1316], quit=[1]
2011-10-02 21:11:33.835180 EEST 33854 Child process=[33854] must quit
2011-10-02 21:11:33.835262 EEST 33854 multicast-group [DROP]
2011-10-02 21:11:33.835317 EEST 33854 Mcast listener socket=[2] closed
2011-10-02 21:11:33.835581 EEST 33854 Child process=[33854] exits with rc=[0]
2011-10-02 21:11:33.836136 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
Oct 2 21:11:33 INTEL udpxy[33851]: wait_children: waitpid: Interrupted system call
2011-10-02 21:11:33.836811 EEST 33851 wait_children: waitpid: Interrupted system call
2011-10-02 21:11:33.837004 EEST 33851 Server [33851] exits rc=[1]
Oct 2 21:11:33 INTEL udpxy[33851]: udpxy 1.0-Chipmunk (build 20) standard is exiting with rc=[1]
2011-10-02 21:11:33.837165 EEST 33851 udpxy is exiting with rc=[1]
2011-10-02 21:11:33.837197 EEST 33851 udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v -S
-------------------------------------------------------------------------------------
[/more]
В логах именно момент включения IP-TV Player,
одного переключения с канала на канал и вылетает UDPXY,
затем повторный запуск UDPXY и всё как в логе с начала.
Пробовал (build 18) проблема аналогична.
Если необходимо больше информации пишите, предоставлю.
В течении трёх месяцев обрыл все поисковые системы и наиболее информативные сайты.
Обнаружил два сообщения о такой проблеме, но решений не обнаружил.
Подскажите, какие настройки сервера FreeBSD изменить, чтобы исключить отваливание UDPXY.?
Заранее благодарен за ответ.
на клиентские машины для медиаплееров VLC 1.1.11, SimpleTV версия 0.4 (vlc 1.1.8),
даже одновременно, и нормально переключаются каналы.
Если запустить на клиентской машине IP-TV Player , он запускается и
начинает отображать вещание бесконечно долго,
но если попытаться переключить на другой канал вещания,
то сразу отключается udpxy на сервере, исчезает в процессах.
И у всех IPTV естественно пропадает.
запускать приходится
# /usr/local/etc/rc.d/udpxy start
или
# udpxy -p 4022
или сейчас
# /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v –S
и снова все нормально на всех плеерах
отображается и переключается до попытки переключить на IP-TV Player канал вещания,
хотя у него тоже указан движок от VLC .
клиенты Windows XP SP2
для просмотра UDP IPTV используют программу IPTvPlayer
взял
http://www.snet.com.ua/iptv/IpTvPlayer-setup.exe
обнаружил проблему взаимодействия с UDPXU
удалил и установил с
http://borpas.info/download/IpTvPlayer-setup.exe
проблема не исчезла, проявляется аналогично.
При проигрывании VLC media player падения UDPXY не происходит,
но при запинке VLC не самовосстанавливается, а продолжает сыпать квадратиками и
приходится останавливать VLC и снова запускать просмотр.
Или пролистывает каналы не видя потока, хотя он наверняка есть.
В общем VLC глючит.
Сервер.
# uname -a
FreeBSD INTEL 7.3-RELEASE-p4 FreeBSD 7.3-RELEASE-p4 #1: Thu Jul 14 16:42:57 EEST 2011 root@INTEL:/usr/obj/usr/src/sys/MYKERNEL i386
/etc/rc.conf
ifconfig_rl1="DHCP"
defaultrouter="NO"
gateway_enable="YES"
router_enable="YES"
firewall_type="OPEN"
dummynet_enable="yes"
natd_enable="YES"
natd_interface="rl1"
dhcpd_enable="YES"
dhcpd_ifaces="rl0"
named_enable="YES"
apache_enable="YES"
udpxy_enable="YES"
udpxy_flags="-m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300"
# ifconfig
rl0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=8<VLAN_MTU>
ether 00:0e:2e:aa:rl:00
inet 192.168.1.1 netmask 0xffffff00 broadcast 192.168.1.255
media: Ethernet autoselect (100baseTX <full-duplex>)
status: active
rl1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=8<VLAN_MTU>
ether 00:08:54:ab:rl:01
inet 192.168.0.2 netmask 0xffffe000 broadcast 192.168.0.255
media: Ethernet autoselect (100baseTX <full-duplex>)
status: active
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
inet 127.0.0.1 netmask 0xff000000
Проблема проявляется на UDPXY (build 19), (build 20), (build 18)
Изначально был установлен
udpxy 1.0-Chipmunk (build 19) standard: udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300
[more=Лог(build 19):]
Server 56494
client 56496
2011-09-04 21:51:12.243752 EEST 56494 Received TSTAT={ sender=[56496], bytes=[1316000.000000], seconds=[2.000000] }
2011-09-04 21:51:12.243923 EEST 56494 Updated context for pid=[56496]; [642.6] Kb/sec
2011-09-04 21:51:12.243950 EEST 56494 Server is waiting for input: socket=[5], pipe=[2]
2011-09-04 21:51:14.325749 EEST 56494 Received TSTAT={ sender=[56496], bytes=[1316000.000000], seconds=[2.000000] }
2011-09-04 21:51:14.325913 EEST 56494 Updated context for pid=[56496]; [642.6] Kb/sec
2011-09-04 21:51:14.325941 EEST 56494 Server is waiting for input: socket=[5], pipe=[2]
2011-09-04 21:51:14.491767 EEST 56494 server_loop: accept: Software caused connection abort
2011-09-04 21:51:14.491852 EEST 56494 Exited server loop
2011-09-04 21:51:14.491884 EEST 56494 Forcing client process [56496] to QUIT
2011-09-04 21:51:14.491964 EEST 56496 *** Caught SIGNAL 3 in process=[56496] ***
2011-09-04 21:51:14.492039 EEST 56496 read_buf interrupted
2011-09-04 21:51:14.492696 EEST 56496 write_buf: write: Broken pipe
2011-09-04 21:51:14.492751 EEST 56496 Exited relay loop: received=[1316], sent=[-1], quit=[0]
2011-09-04 21:51:14.492848 EEST 56496 Child process=[56496] must quit
2011-09-04 21:51:14.492940 EEST 56496 multicast-group [DROP]
2011-09-04 21:51:14.492989 EEST 56496 Mcast listener socket=[2] closed
2011-09-04 21:51:14.493102 EEST 56496 Child process=[56496] exits with rc=[0]
2011-09-04 21:51:14.493650 EEST 56494 *** Caught SIGCHLD in process=[56494] ***
2011-09-04 21:51:14.493824 EEST 56494 wait_children: waitpid: Interrupted system call
2011-09-04 21:51:14.493950 EEST 56494 Server [56494] exits rc=[1]
2011-09-04 21:51:14.494010 EEST 56494 udpxy is exiting with rc=[1]
2011-09-04 21:51:14.494032 EEST 56494 udpxy 1.0-Chipmunk (build 19) standard: udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -S -v -c 20 -M 300
[/more]
затем удалил (build 19) и запустил
udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v –S
[more=Лог(build 20):]
--------------------------------------------------------------------------------------------------
2011-10-02 21:11:15.473716 EEST 33851 udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v -S
2011-10-02 21:11:15.473827 EEST 33851 Starting server [33851]; capacity=[3] clients
2011-10-02 21:11:15.473935 EEST 33851 Setting up listener for [192.168.1.1:4022]
2011-10-02 21:11:15.474045 EEST 33851 Server socket=[5] is set up
2011-10-02 21:11:15.474071 EEST 33851 Entering server loop
2011-10-02 21:11:15.474092 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
Oct 2 21:11:15 INTEL udpxy[33851]: udpxy 1.0-Chipmunk (build 20) standard is starting
2011-10-02 21:11:19.552768 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:19.552902 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:19.552928 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:19.552953 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:19.563254 EEST 33851 HTTP buffer [58 bytes] received
GET /udp/238.1.3.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:19.563329 EEST 33851 Request=[udp/238.1.3.1:1234], length=[18]
2011-10-02 21:11:19.563353 EEST 33851 Command [udp] with params [238.1.3.1:1234] read from socket=[6]
2011-10-02 21:11:19.563377 EEST 33851 udp_relay : new_socket=[6] param=[238.1.3.1:1234]
2011-10-02 21:11:19.564017 EEST 33851 Added client: pid=[33852], maddr=[238.1.3.1], mport=[1234], saddr=[192.168.1.225], sport=[2260]
2011-10-02 21:11:19.564372 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:19.564410 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:19.564530 EEST 33851 Client process=[33852] started for socket=[6]
2011-10-02 21:11:19.564764 EEST 33852 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:19.564794 EEST 33852 Setting up multicast listener
2011-10-02 21:11:19.564959 EEST 33852 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:19.565009 EEST 33852 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:19.565219 EEST 33852 multicast-group [ADD]
2011-10-02 21:11:19.565307 EEST 33852 Mcast listener socket=[2] set up
2011-10-02 21:11:19.565343 EEST 33852 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:19.565366 EEST 33852 Data buffer will hold up to [1] messages
2011-10-02 21:11:19.565388 EEST 33852 UDP stream, RTP check enabled
2011-10-02 21:11:19.565493 EEST 33852 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:19.565523 EEST 33852 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:19.565550 EEST 33852 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:19.565573 EEST 33852 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:19.565598 EEST 33852 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:19.565767 EEST 33852 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:19.566010 EEST 33852 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:24.565296 EEST 33852 read_buf: read: Resource temporarily unavailable
2011-10-02 21:11:24.565381 EEST 33852 read_data - EOF
2011-10-02 21:11:24.565405 EEST 33852 Exited relay loop: received=[-1], sent=[0], quit=[0]
2011-10-02 21:11:24.565515 EEST 33852 multicast-group [DROP]
2011-10-02 21:11:24.565571 EEST 33852 Mcast listener socket=[2] closed
2011-10-02 21:11:24.565793 EEST 33852 Child process=[33852] exits with rc=[0]
Oct 2 21:11:24 INTEL udpxy[33852]: read_buf: read: Resource temporarily unavailable
2011-10-02 21:11:24.566848 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
2011-10-02 21:11:24.567339 EEST 33851 Client [33852] has exited.
2011-10-02 21:11:24.567388 EEST 33851 Deleted client: pid=[33852]
2011-10-02 21:11:24.567411 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:24.567467 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:24.567495 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:24.567520 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:24.567544 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:24.578357 EEST 33851 HTTP buffer [58 bytes] received
GET /udp/238.1.3.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:24.578400 EEST 33851 Request=[udp/238.1.3.1:1234], length=[18]
2011-10-02 21:11:24.578423 EEST 33851 Command [udp] with params [238.1.3.1:1234] read from socket=[6]
2011-10-02 21:11:24.578445 EEST 33851 udp_relay : new_socket=[6] param=[238.1.3.1:1234]
2011-10-02 21:11:24.578831 EEST 33851 Added client: pid=[33853], maddr=[238.1.3.1], mport=[1234], saddr=[192.168.1.225], sport=[2267]
2011-10-02 21:11:24.579067 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:24.579093 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:24.579303 EEST 33851 Client process=[33853] started for socket=[6]
2011-10-02 21:11:24.579485 EEST 33853 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:24.579517 EEST 33853 Setting up multicast listener
2011-10-02 21:11:24.579704 EEST 33853 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:24.579751 EEST 33853 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:24.579998 EEST 33853 multicast-group [ADD]
2011-10-02 21:11:24.580037 EEST 33853 Mcast listener socket=[2] set up
2011-10-02 21:11:24.580144 EEST 33853 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:24.580168 EEST 33853 Data buffer will hold up to [1] messages
2011-10-02 21:11:24.580189 EEST 33853 UDP stream, RTP check enabled
2011-10-02 21:11:24.580297 EEST 33853 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:24.580326 EEST 33853 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:24.580351 EEST 33853 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:24.580374 EEST 33853 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:24.580399 EEST 33853 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:24.580574 EEST 33853 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:24.580810 EEST 33853 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:24.589837 EEST 33853 Established stream as [MPEG-TS]
2011-10-02 21:11:24.589945 EEST 33853 received new [1316] bytes out of [2048], last=[0]
2011-10-02 21:11:24.590027 EEST 33853 sent [1316] bytes out of [1316], last=[0]
2011-10-02 21:11:28.510758 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:28.510924 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:28.510952 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:28.510977 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:28.511436 EEST 33851 read_command - recv (54): Connection reset by peer
2011-10-02 21:11:28.511508 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:28.511532 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
Oct 2 21:11:28 INTEL udpxy[33851]: read_command - recv (54): Connection reset by peer
2011-10-02 21:11:28.513172 EEST 33853 write_buf: write: Broken pipe
2011-10-02 21:11:28.513229 EEST 33853 Exited relay loop: received=[1316], sent=[-1], quit=[0]
2011-10-02 21:11:28.513342 EEST 33853 multicast-group [DROP]
2011-10-02 21:11:28.513464 EEST 33853 Mcast listener socket=[2] closed
2011-10-02 21:11:28.513574 EEST 33853 Child process=[33853] exits with rc=[0]
Oct 2 21:11:28 INTEL udpxy[33853]: write_buf: write: Broken pipe
2011-10-02 21:11:28.514490 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
2011-10-02 21:11:28.514611 EEST 33851 Client [33853] has exited.
2011-10-02 21:11:28.514636 EEST 33851 Deleted client: pid=[33853]
2011-10-02 21:11:28.514658 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:29.275230 EEST 33851 Accepted socket=[6]
2011-10-02 21:11:29.275367 EEST 33851 socket 6: RCV timeout set to 1 sec, 0 usec
2011-10-02 21:11:29.275393 EEST 33851 socket 6: SEND timeout set to 1 sec, 0 usec
2011-10-02 21:11:29.275418 EEST 33851 Reading command from socket [6]
2011-10-02 21:11:29.285582 EEST 33851 HTTP buffer [59 bytes] received
GET /udp/238.1.10.1:1234 HTTP/1.1
Host: 192.168.1.1:4022
2011-10-02 21:11:29.285674 EEST 33851 Request=[udp/238.1.10.1:1234], length=[19]
2011-10-02 21:11:29.285698 EEST 33851 Command [udp] with params [238.1.10.1:1234] read from socket=[6]
2011-10-02 21:11:29.285721 EEST 33851 udp_relay : new_socket=[6] param=[238.1.10.1:1234]
2011-10-02 21:11:29.286133 EEST 33851 Added client: pid=[33854], maddr=[238.1.10.1], mport=[1234], saddr=[192.168.1.225], sport=[2275]
2011-10-02 21:11:29.286289 EEST 33851 Closed accepted socket [6]
2011-10-02 21:11:29.286313 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:29.286415 EEST 33851 Client process=[33854] started for socket=[6]
2011-10-02 21:11:29.286575 EEST 33854 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:29.286608 EEST 33854 Setting up multicast listener
2011-10-02 21:11:29.286859 EEST 33854 current receive buffer size is [41600] bytes for socket [2]
2011-10-02 21:11:29.286934 EEST 33854 receive buffer size set to [65536] bytes for socket [2]
2011-10-02 21:11:29.287140 EEST 33854 multicast-group [ADD]
2011-10-02 21:11:29.287177 EEST 33854 Mcast listener socket=[2] set up
2011-10-02 21:11:29.287265 EEST 33854 min socket buffer = [65536], max space to use = [1500], Rmsgs = [1]
2011-10-02 21:11:29.287289 EEST 33854 Data buffer will hold up to [1] messages
2011-10-02 21:11:29.287310 EEST 33854 UDP stream, RTP check enabled
2011-10-02 21:11:29.287410 EEST 33854 socket 2: RCV timeout set to 5 sec, 0 usec
2011-10-02 21:11:29.287440 EEST 33854 socket 2: SEND timeout set to 5 sec, 0 usec
2011-10-02 21:11:29.287466 EEST 33854 current send buffer size is [33580] bytes for socket [6]
2011-10-02 21:11:29.287489 EEST 33854 current receive buffer size is [65536] bytes for socket [2]
2011-10-02 21:11:29.287514 EEST 33854 send buffer size set to [65536] bytes for socket [6]
2011-10-02 21:11:29.287740 EEST 33854 Sent HTTP response code=[200], reason=[OK] to socket=[6]
HTTP/1.1 200 OK
Content-Type:application/octet-stream
2011-10-02 21:11:29.287981 EEST 33854 Relaying traffic from socket[2] to socket[6], buffer size=[2048], Rmsgs=[1], pauses=[0]
2011-10-02 21:11:29.300982 EEST 33854 Established stream as [MPEG-TS]
2011-10-02 21:11:29.301055 EEST 33854 received new [1316] bytes out of [2048], last=[0]
2011-10-02 21:11:29.301136 EEST 33854 sent [1316] bytes out of [1316], last=[0]
2011-10-02 21:11:31.355899 EEST 33851 Received TSTAT={ sender=[33854], bytes=[1316000.000000], seconds=[2.000000] }
2011-10-02 21:11:31.356142 EEST 33851 Updated context for pid=[33854]; [642.6] Kb/sec
2011-10-02 21:11:31.356177 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:33.489745 EEST 33851 Received TSTAT={ sender=[33854], bytes=[1316000.000000], seconds=[2.000000] }
2011-10-02 21:11:33.489915 EEST 33851 Updated context for pid=[33854]; [642.6] Kb/sec
2011-10-02 21:11:33.489943 EEST 33851 Server is waiting for input: socket=[5], pipe=[2]
2011-10-02 21:11:33.833607 EEST 33851 server_loop: accept: Software caused connection abort
2011-10-02 21:11:33.833701 EEST 33851 Exited server loop
2011-10-02 21:11:33.833785 EEST 33851 Forcing client process [33854] to QUIT
2011-10-02 21:11:33.833965 EEST 33854 *** Caught SIGNAL 3 in process=[33854] ***
2011-10-02 21:11:33.834053 EEST 33854 read_buf interrupted
Oct 2 21:11:33 INTEL udpxy[33851]: server_loop: accept: Software caused connection abort
2011-10-02 21:11:33.835025 EEST 33854 Exited relay loop: received=[1316], sent=[1316], quit=[1]
2011-10-02 21:11:33.835180 EEST 33854 Child process=[33854] must quit
2011-10-02 21:11:33.835262 EEST 33854 multicast-group [DROP]
2011-10-02 21:11:33.835317 EEST 33854 Mcast listener socket=[2] closed
2011-10-02 21:11:33.835581 EEST 33854 Child process=[33854] exits with rc=[0]
2011-10-02 21:11:33.836136 EEST 33851 *** Caught SIGCHLD in process=[33851] ***
Oct 2 21:11:33 INTEL udpxy[33851]: wait_children: waitpid: Interrupted system call
2011-10-02 21:11:33.836811 EEST 33851 wait_children: waitpid: Interrupted system call
2011-10-02 21:11:33.837004 EEST 33851 Server [33851] exits rc=[1]
Oct 2 21:11:33 INTEL udpxy[33851]: udpxy 1.0-Chipmunk (build 20) standard is exiting with rc=[1]
2011-10-02 21:11:33.837165 EEST 33851 udpxy is exiting with rc=[1]
2011-10-02 21:11:33.837197 EEST 33851 udpxy 1.0-Chipmunk (build 20) standard: /usr/tmp/udpxy-1.0-Chipmunk-20/udpxy -m 192.168.0.2 -p 4022 -a 192.168.1.1 -l /var/log/udpxy.log -v -S
-------------------------------------------------------------------------------------
[/more]
В логах именно момент включения IP-TV Player,
одного переключения с канала на канал и вылетает UDPXY,
затем повторный запуск UDPXY и всё как в логе с начала.
Пробовал (build 18) проблема аналогична.
Если необходимо больше информации пишите, предоставлю.
В течении трёх месяцев обрыл все поисковые системы и наиболее информативные сайты.
Обнаружил два сообщения о такой проблеме, но решений не обнаружил.
Подскажите, какие настройки сервера FreeBSD изменить, чтобы исключить отваливание UDPXY.?
Заранее благодарен за ответ.