Dec 29 02:12:57 (1) (16077) log_welcome: welcome to para_server git (Sun Dec 29 01:49:51 MET 2013)
Dec 29 02:12:57 (0) (16077) populate_user_list: found entry for user maan
Dec 29 02:12:57 (0) (16077) populate_user_list: found 4 perm entries
Dec 29 02:12:57 (0) (16077) populate_user_list: found entry for user sshmaan
Dec 29 02:12:57 (1) (16077) get_asymmetric_key: decoding public rsa-ssh key /home/maan/.ssh/id_rsa.pub
Dec 29 02:12:57 (0) (16077) check_ssh_key_header: type: ssh-rsa, rlen: 7
Dec 29 02:12:57 (0) (16077) read_bignum: bnsize: 3
Dec 29 02:12:57 (0) (16077) read_bignum: bnsize: 257
Dec 29 02:12:57 (0) (16077) populate_user_list: found 4 perm entries
Dec 29 02:12:57 (0) (16077) populate_user_list: found entry for user install
Dec 29 02:12:57 (3) (16077) populate_user_list: public key /home/maan/.paraslash/key.pub.install too short (64)
Dec 29 02:12:57 (0) (16077) populate_user_list: found entry for user www
Dec 29 02:12:57 (3) (16077) populate_user_list: public key /home/maan/.paraslash/key.pub.www too short (64)
Dec 29 02:12:57 (0) (16077) populate_user_list: found entry for user maan@albrecht
Dec 29 02:12:57 (0) (16077) populate_user_list: found 2 perm entries
Dec 29 02:12:57 (2) (16077) server_init: initializing audio format handlers
Dec 29 02:12:57 (1) (16077) afh_init: supported audio formats: mp3 wma ogg spx aac flac
Dec 29 02:12:57 (2) (16077) afh_init: initializing mp3 handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing ogg handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing aac handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing wma handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing spx handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing flac handler
Dec 29 02:12:57 (2) (16077) afh_init: initializing opus handler
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 10
Dec 29 02:12:57 (0) (16077) para_block_signal: blocking signal 17
Dec 29 02:12:57 (2) (16077) server_init: initializing the audio file selector
Dec 29 02:12:57 (1) (16077) init_afs: afs_socket: 4, afs_socket_cookie: 3213854017
Dec 29 02:12:57 (2) (16077) init_signal_task: setting up signal handling
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 2
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 15
Dec 29 02:12:57 (0) (16078) para_sigaction: catching signal 13
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 1
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 17
Dec 29 02:12:57 (0) (16077) para_sigaction: catching signal 13
Dec 29 02:12:57 (1) (16077) register_task: registering signal task (0x807c3e4)
Dec 29 02:12:57 (0) (16077) register_task: pre_select: 0x807c3e4
Dec 29 02:12:57 (0) (16077) register_task: post_select: 0x807c3e8
Dec 29 02:12:57 (0) (16077) para_unblock_signal: unblocking signal 17
Dec 29 02:12:57 (2) (16077) server_init: initializing virtual streaming system
Dec 29 02:12:57 (1) (16078) register_signal_task: signal pipe: fd 4
Dec 29 02:12:57 (0) (16078) para_sigaction: catching signal 2
Dec 29 02:12:57 (0) (16078) para_sigaction: catching signal 15
Dec 29 02:12:57 (1) (16077) init_vss_task: announce timeval: 300ms
Dec 29 02:12:57 (0) (16078) para_sigaction: catching signal 1
Dec 29 02:12:57 (2) (16077) init_vss_task: initializing http sender
Dec 29 02:12:57 (1) (16078) register_task: registering signal task (0x807cec4)
Dec 29 02:12:57 (0) (16078) register_task: pre_select: 0x807cec4
Dec 29 02:12:57 (1) (16077) acl_add_entry: adding 192.168.0.0/24 to access list
Dec 29 02:12:57 (0) (16078) register_task: post_select: 0x807cec8
Dec 29 02:12:57 (1) (16077) acl_add_entry: adding 127.0.0.1/32 to access list
Dec 29 02:12:57 (1) (16078) get_database_dir: afs_database dir /home/maan/.paraslash/afs_database-0.4
Dec 29 02:12:57 (2) (16078) open_afs_tables: opening 7 osl tables in /home/maan/.paraslash/afs_database-0.4
Dec 29 02:12:57 (1) (16077) para_listen: listening on TCP port 8000, fd 7
Dec 29 02:12:57 (2) (16077) init_vss_task: initializing dccp sender
Dec 29 02:12:57 (1) (16077) para_listen: listening on DCCP port 8000, fd 8
Dec 29 02:12:57 (2) (16077) init_vss_task: initializing udp sender
Dec 29 02:12:57 (1) (16077) udp_com_add: adding to target list (224.0.1.38:8000)
Dec 29 02:12:57 (1) (16077) udp_com_add: adding to target list (192.168.0.2:8000)
Dec 29 02:12:57 (0) (16077) udp_send_init: udp sender init complete
Dec 29 02:12:57 (1) (16077) register_task: registering vss task (0x807c65c)
Dec 29 02:12:57 (0) (16077) register_task: pre_select: 0x807c65c
Dec 29 02:12:57 (0) (16077) register_task: post_select: 0x807c660
Dec 29 02:12:57 (2) (16077) init_server_command_task: initializing tcp command socket
Dec 29 02:12:57 (1) (16077) para_listen: listening on TCP port 2990, fd 11
Dec 29 02:12:57 (1) (16077) register_task: registering server command task (0x807c2c0)
Dec 29 02:12:57 (0) (16077) register_task: pre_select: 0x807c2c0
Dec 29 02:12:57 (0) (16077) register_task: post_select: 0x807c2c4
Dec 29 02:12:57 (2) (16077) server_init: server init complete
Dec 29 02:12:57 (0) (16077) status_refresh: 0 events, forcing status update
Dec 29 02:12:57 (1) (16078) aft_open: audio file table contains 9212 files
Dec 29 02:12:57 (1) (16078) afs_init: server_socket: 5, afs_socket_cookie: 3213854017
Dec 29 02:12:57 (0) (16078) parse_mood_line: accept entry added, method: 0x806fdac
Dec 29 02:12:57 (2) (16078) change_current_mood: computing statistics of admissible files
Dec 29 02:12:57 (1) (16078) log_statistics: last_played mean: 1385279255, last_played sigma: 2805987
Dec 29 02:12:57 (1) (16078) log_statistics: num_played mean: 48, num_played sigma: 18
Dec 29 02:12:57 (1) (16078) change_current_mood: 26 admissible files
Dec 29 02:12:57 (2) (16078) change_current_mood: loaded mood gulp
Dec 29 02:12:57 (1) (16078) setup_command_socket_or_die: listening on socket /var/paraslash/afs_command_socket-0.4 (fd 7)
Dec 29 02:12:57 (1) (16078) register_task: registering afs command task (0x807cfec)
Dec 29 02:12:57 (0) (16078) register_task: pre_select: 0x807cfec
Dec 29 02:12:57 (0) (16078) register_task: post_select: 0x807cff0
Dec 29 02:12:57 (1) (16077) command_post_select: got connection from 192.168.0.4:60731, forking
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 11
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 10
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 9
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 8
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 7
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 5
Dec 29 02:12:57 (0) (16079) close_listed_fds: closing fd 4
Dec 29 02:12:57 (0) (16079) para_sigaction: catching signal 17
Dec 29 02:12:57 (0) (16079) para_sigaction: catching signal 2
Dec 29 02:12:57 (0) (16079) para_sigaction: catching signal 15
Dec 29 02:12:57 (0) (16079) para_sigaction: catching signal 1
Dec 29 02:12:57 (0) (16079) parse_auth_request: received auth request for user maan
Dec 29 02:12:57 (0) (16079) handle_connect: received auth request for user maan
Dec 29 02:12:57 (0) (16079) handle_connect: sending 64 byte challenge + rc4 keys (256 bytes)
Dec 29 02:12:57 (0) (16079) handle_connect: received 20 bytes challenge response
Dec 29 02:12:57 (1) (16079) handle_connect: good auth for maan
Dec 29 02:12:57 (0) (16079) check_perms: checking permissions
Dec 29 02:12:57 (2) (16079) handle_connect: calling com_stat() for maan@192.168.0.4:60731
Dec 29 02:12:57 (0) (16079) para_sigaction: catching signal 10
Dec 29 02:13:02 (1) (16077) command_post_select: got connection from 192.168.0.1:58527, forking
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 11
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 10
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 9
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 8
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 7
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 5
Dec 29 02:13:02 (0) (16080) close_listed_fds: closing fd 4
Dec 29 02:13:02 (0) (16080) para_sigaction: catching signal 17
Dec 29 02:13:02 (0) (16080) para_sigaction: catching signal 2
Dec 29 02:13:02 (0) (16080) para_sigaction: catching signal 15
Dec 29 02:13:02 (0) (16080) para_sigaction: catching signal 1
Dec 29 02:13:02 (0) (16080) parse_auth_request: received auth request for user maan
Dec 29 02:13:02 (0) (16080) handle_connect: received auth request for user maan
Dec 29 02:13:02 (0) (16080) handle_connect: sending 64 byte challenge + rc4 keys (256 bytes)
Dec 29 02:13:02 (0) (16080) handle_connect: received 20 bytes challenge response
Dec 29 02:13:02 (1) (16080) handle_connect: good auth for maan
Dec 29 02:13:02 (0) (16080) check_perms: checking permissions
Dec 29 02:13:02 (2) (16080) handle_connect: calling com_stat() for maan@192.168.0.1:58527
Dec 29 02:13:02 (0) (16080) para_sigaction: catching signal 10
Dec 29 02:13:09 (1) (16077) command_post_select: got connection from 192.168.0.1:58528, forking
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 11
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 10
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 9
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 8
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 7
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 5
Dec 29 02:13:09 (0) (16089) close_listed_fds: closing fd 4
Dec 29 02:13:09 (0) (16089) para_sigaction: catching signal 17
Dec 29 02:13:09 (0) (16089) para_sigaction: catching signal 2
Dec 29 02:13:09 (0) (16089) para_sigaction: catching signal 15
Dec 29 02:13:09 (0) (16089) para_sigaction: catching signal 1
Dec 29 02:13:09 (0) (16089) parse_auth_request: received auth request for user maan
Dec 29 02:13:09 (0) (16089) handle_connect: received auth request for user maan
Dec 29 02:13:09 (0) (16089) handle_connect: sending 64 byte challenge + rc4 keys (256 bytes)
Dec 29 02:13:09 (0) (16089) handle_connect: received 20 bytes challenge response
Dec 29 02:13:09 (1) (16089) handle_connect: good auth for maan
Dec 29 02:13:09 (0) (16089) check_perms: checking permissions
Dec 29 02:13:09 (2) (16089) handle_connect: calling com_play() for maan@192.168.0.1:58528
Dec 29 02:13:09 (0) (16077) para_next_signal: next signal: 17
Dec 29 02:13:09 (0) (16077) para_reap_child: child 16089 exited. Exit status: 0
Dec 29 02:13:09 (0) (16077) vss_pre_select: ready and playing, but no audio file
Dec 29 02:13:09 (0) (16077) status_refresh: 1 events, forcing status update
Dec 29 02:13:09 (2) (16077) vss_post_select: requesting new fd from afs
Dec 29 02:13:09 (2) (16078) open_next_audio_file: getting next audio file
Dec 29 02:13:09 (2) (16078) open_and_update_audio_file: /home/mp3/checked/dvd_07/cd_45/The_G.U.L.P.__Popstar.ogg
Dec 29 02:13:09 (1) (16078) shm_get_shmmax: shmmax: 33554432
Dec 29 02:13:09 (0) (16078) mood_update_audio_file: score: -11
Dec 29 02:13:09 (0) (16078) mood_update_audio_file: moving from rank 26 to 29%
Dec 29 02:13:09 (0) (16078) score_update: new score: -13, rank 8/26
Dec 29 02:13:09 (0) (16078) score_update: new score: -67, rank 1/26
Dec 29 02:13:09 (0) (16078) pass_afd: passing 8 bytes and fd 8
Dec 29 02:13:09 (0) (16077) recv_afs_result: fd: 12, code: 0, shmid: 121700353
Dec 29 02:13:09 (0) (16077) vorbis_get_header_callback: processing ogg packet #0
Dec 29 02:13:09 (0) (16077) add_ogg_page: header/body/old/new: 28/30/0/58
Dec 29 02:13:09 (1) (16077) process_packets_2_and_3: ogg page serial: 867600341
Dec 29 02:13:09 (0) (16077) vorbis_get_header_callback: processing ogg packet #1
Dec 29 02:13:09 (1) (16077) vorbis_get_header_callback: replacing metadata packet
Dec 29 02:13:09 (1) (16077) process_packets_2_and_3: ogg page serial: 867600341
Dec 29 02:13:09 (0) (16077) vorbis_get_header_callback: processing ogg packet #2
Dec 29 02:13:09 (0) (16077) add_ogg_page: header/body/old/new: 45/4247/58/4350
Dec 29 02:13:09 (1) (16077) vorbis_get_header: created 4350 byte ogg vorbis header
Dec 29 02:13:09 (0) (16077) chk_barrier: data send barrier: 203ms left
Dec 29 02:13:09 (0) (16077) status_refresh: 2 events, forcing status update
Dec 29 02:13:09 (0) (16079) connect_local_socket: connecting to /var/paraslash/afs_command_socket-0.4
Dec 29 02:13:09 (0) (16080) connect_local_socket: connecting to /var/paraslash/afs_command_socket-0.4
Dec 29 02:13:09 (2) (16077) udp_init_session: sending to udp 192.168.0.2:8000
Dec 29 02:13:09 (1) (16077) udp_init_fec: current MPS = 548 bytes
Dec 29 02:13:09 (1) (16077) initialize_fec_client: mps: 548, k: 26, n: 28, extra slices: 12
Dec 29 02:13:09 (0) (16077) set_group_timing: durations (group/chunk/slice): 226/226/8
Dec 29 02:13:09 (0) (16077) setup_next_fec_group: FEC group 0: 1 chunks (0 - 0), 4350 bytes
Dec 29 02:13:09 (0) (16077) setup_next_fec_group: slice_bytes: 168, 0 header slices, 26 data slices
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:0 (168 bytes)
Dec 29 02:13:09 (2) (16077) udp_init_session: sending to udp 224.0.1.38:8000
Dec 29 02:13:09 (1) (16077) udp_init_fec: current MPS = 548 bytes
Dec 29 02:13:09 (1) (16077) initialize_fec_client: mps: 548, k: 26, n: 27, extra slices: 24
Dec 29 02:13:09 (0) (16077) set_group_timing: durations (group/chunk/slice): 226/226/8
Dec 29 02:13:09 (0) (16077) setup_next_fec_group: FEC group 0: 1 chunks (0 - 0), 4350 bytes
Dec 29 02:13:09 (0) (16077) setup_next_fec_group: slice_bytes: 168, 0 header slices, 26 data slices
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:0 (168 bytes)
Dec 29 02:13:09 (0) (16077) status_refresh: 3 events, forcing status update
Dec 29 02:13:09 (0) (16079) connect_local_socket: connecting to /var/paraslash/afs_command_socket-0.4
Dec 29 02:13:09 (0) (16080) connect_local_socket: connecting to /var/paraslash/afs_command_socket-0.4
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:1 (168 bytes)
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:1 (168 bytes)
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:2 (168 bytes)
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:2 (168 bytes)
Dec 29 02:13:09 (0) (16077) vss_send: sending 0:3 (168 bytes)