Merge branch 'maint'
[paraslash.git] / web / screenshots / server.log
1 Dec 08 19:28:58 2: (21285) log_welcome: welcome to para_server git (Sat Dec  8 13:42:52 MET 2007)
2 Dec 08 19:28:58 1: (21285) log_welcome: using loglevel 1
3 Dec 08 19:28:58 1: (21285) populate_user_list: found entry for maan
4 Dec 08 19:28:58 1: (21285) populate_user_list: found 4 perm entries
5 Dec 08 19:28:58 1: (21285) populate_user_list: found entry for install
6 Dec 08 19:28:58 1: (21285) populate_user_list: found 4 perm entries
7 Dec 08 19:28:58 1: (21285) populate_user_list: found entry for www
8 Dec 08 19:28:58 1: (21285) populate_user_list: found 4 perm entries
9 Dec 08 19:28:58 2: (21285) daemon_init: daemonizing
10 Dec 08 19:28:58 3: (21286) server_init: initializing audio format handlers
11 Dec 08 19:28:58 2: (21286) afh_init: supported audio formats: mp3 ogg aac
12 Dec 08 19:28:58 3: (21286) afh_init: initializing mp3 handler
13 Dec 08 19:28:58 3: (21286) afh_init: initializing ogg handler
14 Dec 08 19:28:58 3: (21286) afh_init: initializing aac handler
15 Dec 08 19:28:58 3: (21286) server_init: initializing virtual streaming system
16 Dec 08 19:28:58 2: (21286) vss_init: announce timeval: 300ms
17 Dec 08 19:28:58 3: (21286) vss_init: initializing http sender
18 Dec 08 19:28:58 2: (21286) para_listen: listening on TCP port 8000, fd 4
19 Dec 08 19:28:58 1: (21286) http_send_init: http sender init complete
20 Dec 08 19:28:58 3: (21286) vss_init: initializing dccp sender
21 Dec 08 19:28:58 2: (21286) para_listen: listening on DCCP port 5001, fd 5
22 Dec 08 19:28:58 3: (21286) vss_init: initializing ortp sender
23 Dec 08 19:28:58 1: (21286) ortp_send_init: ortp sender init complete
24 Dec 08 19:28:58 3: (21286) setup_signal_handling: setting up signal handlers
25 Dec 08 19:28:58 1: (21286) para_install_sighandler: catching signal 2
26 Dec 08 19:28:58 1: (21286) para_install_sighandler: catching signal 15
27 Dec 08 19:28:58 1: (21286) para_install_sighandler: catching signal 1
28 Dec 08 19:28:58 1: (21286) para_install_sighandler: catching signal 17
29 Dec 08 19:28:58 1: (21286) para_install_sighandler: catching signal 10
30 Dec 08 19:28:58 3: (21286) server_init: initializing the audio file selector
31 Dec 08 19:28:58 2: (21287) get_database_dir: afs_database dir /home/maan/.paraslash/afs_database
32 Dec 08 19:28:58 3: (21287) open_afs_tables: opening 7 osl tables in /home/maan/.paraslash/afs_database
33 Dec 08 19:28:58 2: (21287) osl_open_table: opening table audio_files
34 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'audio_files' from table description
35 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 32
36 Dec 08 19:28:58 1: (21287) map_table: mapping table 'audio_files' (index: /home/maan/.paraslash/afs_database/audio_files/index)
37 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/index: size 218667
38 Dec 08 19:28:58 1: (21287) read_table_desc: 5 columns
39 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'audio_files' matches on-disk data, good
40 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/2346ad27d7568ba9896f1b7da6b5991251debdf2: size 143430
41 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/3150ecd5e0294534a81ae047ddac559de481d774: size 436636
42 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/94ea39e309f3f31357ab60b190b6b8c32f21620b: size 225390
43 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/b063ad096f9d142a388ca2a10d46b56904e26cda: size 1174409
44 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 6830
45 Dec 08 19:28:58 2: (21286) init_afs: afs_socket: 8, afs_socket_cookie: 1363241225
46 Dec 08 19:28:58 3: (21286) server_init: initializing tcp command socket
47 Dec 08 19:28:58 2: (21286) para_listen: listening on TCP port 2990, fd 9
48 Dec 08 19:28:58 3: (21286) server_init: server init complete
49 Dec 08 19:28:58 1: (21286) chk_barrier: autoplay_delay barrier: 14981ms left
50 Dec 08 19:28:58 1: (21286) status_refresh: 0 events, forcing status update
51 Dec 08 19:28:58 1: (21286) para_next_signal: next signal: 10
52 Dec 08 19:28:58 1: (21286) chk_barrier: autoplay_delay barrier: 14981ms left
53 Dec 08 19:28:58 1: (21286) para_next_signal: next signal: 10
54 Dec 08 19:28:58 1: (21286) chk_barrier: autoplay_delay barrier: 14981ms left
55 Dec 08 19:28:58 2: (21287) aft_open: audio file table contains 6830 files
56 Dec 08 19:28:58 2: (21287) osl_open_table: opening table attributes
57 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'attributes' from table description
58 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 16
59 Dec 08 19:28:58 1: (21287) map_table: mapping table 'attributes' (index: /home/maan/.paraslash/afs_database/attributes/index)
60 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/attributes/index: size 396
61 Dec 08 19:28:58 1: (21287) read_table_desc: 2 columns
62 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'attributes' matches on-disk data, good
63 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/attributes/fd1e48caeff7212c45fc08608b7187feb10a7a2d: size 42
64 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/attributes/6ae999552a0d2dca14d62e2bc8b764d377b1dd6c: size 141
65 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 21
66 Dec 08 19:28:58 2: (21287) osl_open_table: opening table score
67 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'score' from table description
68 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 0
69 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 0
70 Dec 08 19:28:58 2: (21287) osl_open_table: opening table moods
71 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'moods' from table description
72 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 16
73 Dec 08 19:28:58 1: (21287) map_table: mapping table 'moods' (index: /home/maan/.paraslash/afs_database/moods/index)
74 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/moods/index: size 235
75 Dec 08 19:28:58 1: (21287) read_table_desc: 3 columns
76 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'moods' matches on-disk data, good
77 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/moods/87ea5dfc8b8e384d848979496e706390b497e547: size 50
78 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/moods/6ae999552a0d2dca14d62e2bc8b764d377b1dd6c: size 67
79 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 10
80 Dec 08 19:28:58 2: (21287) osl_open_table: opening table lyrics
81 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'lyrics' from table description
82 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 16
83 Dec 08 19:28:58 1: (21287) map_table: mapping table 'lyrics' (index: /home/maan/.paraslash/afs_database/lyrics/index)
84 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/lyrics/index: size 347
85 Dec 08 19:28:58 1: (21287) read_table_desc: 3 columns
86 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'lyrics' matches on-disk data, good
87 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/lyrics/87ea5dfc8b8e384d848979496e706390b497e547: size 85
88 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/lyrics/6ae999552a0d2dca14d62e2bc8b764d377b1dd6c: size 478
89 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 17
90 Dec 08 19:28:58 2: (21287) osl_open_table: opening table images
91 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'images' from table description
92 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 16
93 Dec 08 19:28:58 1: (21287) map_table: mapping table 'images' (index: /home/maan/.paraslash/afs_database/images/index)
94 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/images/index: size 7227
95 Dec 08 19:28:58 1: (21287) read_table_desc: 3 columns
96 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'images' matches on-disk data, good
97 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/images/87ea5dfc8b8e384d848979496e706390b497e547: size 2235
98 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/images/6ae999552a0d2dca14d62e2bc8b764d377b1dd6c: size 12950
99 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 447
100 Dec 08 19:28:58 2: (21287) osl_open_table: opening table playlists
101 Dec 08 19:28:58 1: (21287) init_table_structure: creating table structure for 'playlists' from table description
102 Dec 08 19:28:58 1: (21287) init_table_structure: OK. Index entry size: 16
103 Dec 08 19:28:58 1: (21287) map_table: mapping table 'playlists' (index: /home/maan/.paraslash/afs_database/playlists/index)
104 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/playlists/index: size 107
105 Dec 08 19:28:58 1: (21287) read_table_desc: 3 columns
106 Dec 08 19:28:58 1: (21287) compare_table_descriptions: table description of 'playlists' matches on-disk data, good
107 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/playlists/87ea5dfc8b8e384d848979496e706390b497e547: size 10
108 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/playlists/6ae999552a0d2dca14d62e2bc8b764d377b1dd6c: size 7
109 Dec 08 19:28:58 1: (21287) osl_open_table: num rows: 2
110 Dec 08 19:28:58 2: (21287) afs_init: server_socket: 9, afs_socket_cookie: 1363241225
111 Dec 08 19:28:58 1: (21287) osl_open_disk_object: filename: /home/maan/.paraslash/afs_database/moods/f3f1dd33eb2a8b380b64a830e5fd90eab77d9ff3/9d/b063f3b5e0adfd0d29a03db0a1c207b3740a94
112 Dec 08 19:28:58 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/moods/f3f1dd33eb2a8b380b64a830e5fd90eab77d9ff3/9d/b063f3b5e0adfd0d29a03db0a1c207b3740a94: size 31
113 Dec 08 19:28:58 1: (21287) parse_mood_line: accept entry added, method: 0x806c0f4
114 Dec 08 19:28:58 3: (21287) change_current_mood: computing statistics of admissible files
115 Dec 08 19:28:58 2: (21287) log_statistics: last_played mean: 1193159581, last_played sigma: 4161524
116 Dec 08 19:28:58 2: (21287) log_statistics: num_played mean: 32, num_played sigma: 21
117 Dec 08 19:28:58 2: (21287) change_current_mood: 26 admissible files 
118 Dec 08 19:28:58 3: (21287) change_current_mood: loaded mood gulp
119 Dec 08 19:28:58 2: (21287) register_signal_task: signal pipe: fd 8
120 Dec 08 19:28:58 1: (21287) para_install_sighandler: catching signal 2
121 Dec 08 19:28:58 1: (21287) para_install_sighandler: catching signal 15
122 Dec 08 19:28:58 1: (21287) para_install_sighandler: catching signal 13
123 Dec 08 19:28:58 1: (21287) para_install_sighandler: catching signal 1
124 Dec 08 19:28:58 2: (21287) init_sched: initializing scheduler
125 Dec 08 19:28:58 2: (21287) register_task: registering signal task (0x806f244)
126 Dec 08 19:28:58 1: (21287) register_task: pre_select: 0x806f248
127 Dec 08 19:28:58 1: (21287) register_task: post_select: 0x806f248
128 Dec 08 19:28:58 2: (21287) setup_command_socket_or_die: listening on socket /var/paraslash/afs_command_socket (fd 1)
129 Dec 08 19:28:58 2: (21287) register_task: registering command task (0x806f118)
130 Dec 08 19:28:58 1: (21287) register_task: pre_select: 0x806f11c
131 Dec 08 19:28:58 1: (21287) register_task: post_select: 0x806f11c
132 Dec 08 19:28:59 2: (21286) main: got connection from ::ffff:127.0.0.1#42344, forking
133 Dec 08 19:28:59 1: (21356) close_listed_fds: closing fd 9
134 Dec 08 19:28:59 1: (21356) close_listed_fds: closing fd 8
135 Dec 08 19:28:59 1: (21356) close_listed_fds: closing fd 6
136 Dec 08 19:28:59 1: (21356) close_listed_fds: closing fd 5
137 Dec 08 19:28:59 1: (21356) close_listed_fds: closing fd 4
138 Dec 08 19:28:59 1: (21286) chk_barrier: autoplay_delay barrier: 13507ms left
139 Dec 08 19:28:59 1: (21356) handle_connect: received rc4 request for user maan
140 Dec 08 19:28:59 1: (21356) handle_connect: sending 64 byte challenge
141 Dec 08 19:28:59 2: (21356) handle_connect: good auth for maan (1081806111)
142 Dec 08 19:28:59 1: (21356) init_rc4_keys: rc4 keys initialized (84:105)
143 Dec 08 19:28:59 2: (21356) enable_crypt: rc4 encryption activated for fd 10
144 Dec 08 19:28:59 3: (21356) handle_connect: invalid command
145 Dec 08 19:28:59 1: (21286) para_next_signal: next signal: 17
146 Dec 08 19:28:59 1: (21286) para_reap_child: child 21356 exited. Exit status: 1
147 Dec 08 19:28:59 1: (21286) chk_barrier: autoplay_delay barrier: 13498ms left
148 Dec 08 19:29:03 2: (21286) main: got connection from ::ffff:127.0.0.1#42345, forking
149 Dec 08 19:29:03 1: (21362) close_listed_fds: closing fd 9
150 Dec 08 19:29:03 1: (21362) close_listed_fds: closing fd 8
151 Dec 08 19:29:03 1: (21362) close_listed_fds: closing fd 6
152 Dec 08 19:29:03 1: (21362) close_listed_fds: closing fd 5
153 Dec 08 19:29:03 1: (21362) close_listed_fds: closing fd 4
154 Dec 08 19:29:03 1: (21286) chk_barrier: autoplay_delay barrier: 9951ms left
155 Dec 08 19:29:03 1: (21362) handle_connect: received rc4 request for user maan
156 Dec 08 19:29:03 1: (21362) handle_connect: sending 64 byte challenge
157 Dec 08 19:29:03 2: (21362) handle_connect: good auth for maan (705434988)
158 Dec 08 19:29:03 1: (21362) init_rc4_keys: rc4 keys initialized (18:65)
159 Dec 08 19:29:03 2: (21362) enable_crypt: rc4 encryption activated for fd 10
160 Dec 08 19:29:03 1: (21362) check_perms: checking permissions
161 Dec 08 19:29:03 3: (21362) handle_connect: calling com_stat() for maan@::ffff:127.0.0.1#42345
162 Dec 08 19:29:13 1: (21286) chk_barrier: autoplay_delay barrier: 3ms left
163 Dec 08 19:29:13 1: (21286) vss_preselect: ready and playing, but no audio file
164 Dec 08 19:29:13 3: (21286) vss_post_select: requesting new fd from afs
165 Dec 08 19:29:13 1: (21287) execute_server_command: received: new
166 Dec 08 19:29:13 3: (21287) open_next_audio_file: getting next audio file
167 Dec 08 19:29:13 1: (21287) osl_open_disk_object: filename: /home/maan/.paraslash/afs_database/audio_files/7e078c9876ccabef154017c770e05195c85b5e4d/55/b5e38d467105bd88133cf5ded70e551e582593
168 Dec 08 19:29:13 1: (21287) mmap_full_file: /home/maan/.paraslash/afs_database/audio_files/7e078c9876ccabef154017c770e05195c85b5e4d/55/b5e38d467105bd88133cf5ded70e551e582593: size 4532
169 Dec 08 19:29:13 1: (21287) mmap_full_file: /home/mp3/checked/dvd_07/cd_46/The_G.U.L.P.__Scheiss_Krieg.ogg: size 5274482
170 Dec 08 19:29:13 1: (21287) mood_update_audio_file: score: 6
171 Dec 08 19:29:13 1: (21287) mood_update_audio_file: moving from rank 26 to 35%
172 Dec 08 19:29:13 1: (21287) score_update: new score: 0, rank 9/26
173 Dec 08 19:29:13 1: (21287) osl_update_object: updating column 1 of score
174 Dec 08 19:29:13 1: (21287) score_update: new score: -65, rank 1/26
175 Dec 08 19:29:13 1: (21287) osl_update_object: updating column 1 of score
176 Dec 08 19:29:13 1: (21287) save_afd: size: 8932
177 Dec 08 19:29:13 1: (21287) pass_afd: passing 8 bytes and fd 12
178 Dec 08 19:29:13 1: (21286) recv_afs_result: fd: 10, code: 0, shmid: 28442631
179 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 300ms left
180 Dec 08 19:29:13 1: (21286) status_refresh: 1 events, forcing status update
181 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 300ms left
182 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 300ms left
183 Dec 08 19:29:13 1: (21286) para_next_signal: next signal: 10
184 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 300ms left
185 Dec 08 19:29:13 3: (21286) dccp_post_select: connection from ::ffff:127.0.0.1#46539
186 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 287ms left
187 Dec 08 19:29:13 1: (21286) chk_barrier: data send barrier: 287ms left
188 Dec 08 19:29:13 1: (21287) para_next_signal: next signal: 10
189 Dec 08 19:29:13 1: (21286) status_refresh: 2 events, forcing status update
190 Dec 08 19:29:13 1: (21286) para_next_signal: next signal: 10
191 Dec 08 19:29:13 1: (21287) para_next_signal: next signal: 10
192 Dec 08 19:29:14 1: (21286) cq_enqueue: 4446 bytes queued for 0x8072eb0