MusicPlayerDaemon / MPD

Music Player Daemon
https://www.musicpd.org/
GNU General Public License v2.0
2.18k stars 351 forks source link

Some files cause the log file size to explode #590

Closed i30817 closed 5 years ago

i30817 commented 5 years ago

Bug report

Describe the bug

Remember me complaining about the size of the log file? Yeah, i thought it was because of the database update. Turns out that ffmpeg is freaking the fuck out about some (playable) m4a files and dumping the whole debug output to the mpd log, to the point that after half a minute of database update i had a 360 mb log.

Expected Behavior

Not sure. Maybe i did screw up with the file conversion, but i didn't expect mpd to use ffmpeg with such a verbose output. Report possible problems, sure, not dump maybe gb to file.

Actual Behavior

Dunno.

Version

Music Player Daemon 0.21.4 (0.21.4)

Log

Jun 24 17:22 : exception: RTIOThread could not get realtime scheduling, continuing anyway: sched_setscheduler failed: Operation not permit
ted
Jun 24 17:22 : avahi: Service 'Music Player @ AIVAS' successfully established.
Jun 24 17:23 : client: [0] opened from local
Jun 24 17:23 : client: [0] closed
Jun 24 17:23 : client: [1] opened from local
Jun 24 17:23 : client: [1] closed
Jun 24 17:23 : client: [2] opened from local
Jun 24 17:23 : avahi: Initializing interface
Jun 24 17:23 : exception: RTIOThread could not get realtime scheduling, continuing anyway: sched_setscheduler failed: Operation not permit
ted
Jun 24 17:23 : avahi: Client changed to state 2
Jun 24 17:23 : avahi: Client is RUNNING
Jun 24 17:23 : avahi: Registering service _mpd._tcp/Music Player @ AIVAS
Jun 24 17:23 : avahi: Service group changed to state 0
Jun 24 17:23 : avahi: Service group is UNCOMMITED
Jun 24 17:23 : update: spawned thread for update job id 1
Jun 24 17:23 : state_file: Loading state file /home/i30817/.config/mpd/mpdstate
Jun 24 17:23 : avahi: Service group changed to state 1
Jun 24 17:23 : avahi: Service group is REGISTERING
Jun 24 17:23 : update: starting
Jun 24 17:23 : update: reading Atlus Sound Team/PERSONA2 INNOCENT SIN. ORIGINAL SOUNDTRACK/4-07 Time Castle.m4a
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'ftyp' parent:'root' sz: 24 8 1984284
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: ISO: File Type Major Brand: M4A
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'free' parent:'root' sz: 8 32 1984284
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'mdat' parent:'root' sz: 1900460 40 1984284
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'moov' parent:'root' sz: 83792 1900500 1984284
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'mvhd' parent:'moov' sz: 108 8 83784
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: time scale = 1000
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'trak' parent:'moov' sz: 27794 116 83784
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'tkhd' parent:'trak' sz: 92 8 27786
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'edts' parent:'trak' sz: 36 100 27786
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'elst' parent:'edts' sz: 28 8 28
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: track[0].edit_count = 1
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: duration=158361 time=0 rate=1.000000
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'mdia' parent:'trak' sz: 27658 136 27786
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'mdhd' parent:'mdia' sz: 32 8 27650
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'hdlr' parent:'mdia' sz: 45 40 27650
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: ctype=[0][0][0][0]
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: stype=soun
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'minf' parent:'mdia' sz: 27573 85 27650
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'smhd' parent:'minf' sz: 16 8 27565
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'dinf' parent:'minf' sz: 36 24 27565
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'dref' parent:'dinf' sz: 28 8 28
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: Unknown dref type 0x206c7275 size 12
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stbl' parent:'minf' sz: 27513 60 27565
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stsd' parent:'stbl' sz: 117 8 27505
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: size=101 4CC=mp4a codec_type=1
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: audio channels 2
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: version =0, isom =1
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'esds' parent:'stsd' sz: 65 8 65
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: MPEG-4 description: tag=0x03 len=48
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: MPEG-4 description: tag=0x04 len=34
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: esds object type id 0x40
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: MPEG-4 description: tag=0x05 len=16
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: Specific MPEG-4 header len=16
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: mp4a config channels 2 obj 2 ext obj 0 sample rate 44100 ext sample rate 0
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stts' parent:'stbl' sz: 24 125 27505
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: track[0].stts.entries = 1
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: sample_count=6820, sample_duration=1024
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stsc' parent:'stbl' sz: 40 149 27505
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: track[0].stsc.entries = 2
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stsz' parent:'stbl' sz: 27300 189 27505
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: sample_size = 0 sample_count = 6820
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: type:'stco' parent:'stbl' sz: 24 27489 27505
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: AVIndex stream 0, sample 0, offset 28, dts 0, size 278, distance 0, keyframe 1
Jun 24 17:23 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: AVIndex stream 0, sample 1, offset 13e, dts 1024, size 279, distance 0, keyframe 1

.... and then apparently it keeps going for the whole size of the m4a file, repeat.

MaxKellermann commented 5 years ago

MPD uses the log level you configured. What is wrong with that?

i30817 commented 5 years ago

Sorry, my mistake, i didn't notice the -v.