CentOS Zip File Issue

[Mug]

I've got PS3 Media Server running on CentOS 6.3. I'm attempting to switch from the windows version to the linux edition. I've gotten everything else working (including tsMuxeR!). I'm utilizing the complied version of the decoders from the Sticky in this forum (v.1110). The last feature I'm trying to get onboard with is playing back from Compressed Archives. Below is the debug output from a smaller ZIP (80 megs) of a 720p H.264 MP4.

Normal playback is fine, transcode via mencoder is fine. Transcode from inside the Zip fails. It appears that either 1) the FIFO is having an issue--I don't think so as I can confirm the creation of it or 2) Something about unpacking the archive and routing it where it needs to go is the problem. Any thoughts?

Code: Select all

DEBUG 2012-09-11 10:47:35.497 [mencoder-9] Starting /usr/bin/mencoder -ss 0 - -msglevel statusline=2 -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 6 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=448:threads=2:keyint=5:vqscale=1:vqmin=2:vrc_maxrate=54000:vrc_buf_size=7000 -subdelay 20000 -ofps 24000/1001 -lavdopts fast -mc 0.1 -af lavcresample=48000 -srate 48000 -cache 8192 -o /tmp/ps3mediaserver/mencoder1347385655447
DEBUG 2012-09-11 10:47:35.500 [mencoder-9] Reading pipe: /tmp/ps3mediaserver/mencoder1347385655447
DEBUG 2012-09-11 10:47:35.504 [Thread-36] get_path("config") problem
DEBUG 2012-09-11 10:47:40.599 [Hanging External Process Stopper] Stopping Unix process 7135: mencoder-9
ERROR 2012-09-11 10:47:40.598 [New I/O server worker #1-2] There is no inputstream to return for The Bourne Ultimatum - Trailer.mp4 [MEncoder]
DEBUG 2012-09-11 10:47:40.600 [Hanging External Process Stopper] Stopping Unix process 7130: mkfifo-10
DEBUG 2012-09-11 10:47:40.602 [Thread-36] Parent process disappeared, exiting cache process.
DEBUG 2012-09-11 10:47:40.602 [Thread-36] Error consuming stream of spawned process: Stream Closed
INFO  2012-09-11 10:47:40.605 [New I/O server worker #1-3] Starting transcode/remux of The Bourne Ultimatum - Trailer.mp4
DEBUG 2012-09-11 10:47:40.634 [mkfifo-12] Starting mkfifo --mode=777 /tmp/ps3mediaserver/mencoder1347385660634
DEBUG 2012-09-11 10:47:40.637 [mkfifo-12] Unix process ID (mkfifo): 7143
DEBUG 2012-09-11 10:47:40.684 [mencoder-11] Starting /usr/bin/mencoder -ss 0 - -msglevel statusline=2 -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 6 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=448:threads=2:keyint=5:vqscale=1:vqmin=2:vrc_maxrate=54000:vrc_buf_size=7000 -subdelay 20000 -ofps 24000/1001 -lavdopts fast -mc 0.1 -af lavcresample=48000 -srate 48000 -cache 8192 -o /tmp/ps3mediaserver/mencoder1347385660634
DEBUG 2012-09-11 10:47:40.687 [mencoder-11] Reading pipe: /tmp/ps3mediaserver/mencoder1347385660634
DEBUG 2012-09-11 10:47:40.691 [Thread-41] get_path("config") problem
ERROR 2012-09-11 10:47:45.785 [New I/O server worker #1-3] There is no inputstream to return for The Bourne Ultimatum - Trailer.mp4 [MEncoder]
DEBUG 2012-09-11 10:47:45.786 [Hanging External Process Stopper] Stopping Unix process 7148: mencoder-11
DEBUG 2012-09-11 10:47:45.787 [Hanging External Process Stopper] Stopping Unix process 7143: mkfifo-12
DEBUG 2012-09-11 10:47:45.789 [Thread-41] Parent process disappeared, exiting cache process.
DEBUG 2012-09-11 10:47:45.789 [Thread-41] Error consuming stream of spawned process: Stream Closed


[chocolateboy]

I'm utilizing the complied version of the decoders from the Sticky in this forum (v.1110).

Are you?

Code: Select all

Starting /usr/bin/mencoder

Custom (third-party) binaries should not be installed in /bin or /usr/bin: viewtopic.php?f=3&t=14556&p=70277#p70277

[Mug]

Understood. I'll revert my changes and move them to a new location. I had moved the original binary's before I dropped those in.

I assume /usr/local/bin should be OK? Or do I need to into where I've dropped PMS (/usr/local/share/pms in my case).

[Mug]

Relocated to /usr/local/bin and I've restored the original binaries. Restarted PS3 media server. Logs now show it pulling from /usr/local/bin/mencoder. Same issue though. Since I can transcode the extracted file fine, I really want to blame the compressed archive, just not sure the right solution yet.

Code: Select all

DEBUG 2012-09-11 14:42:08.823 [pool-10-thread-1] Starting /usr/local/bin/ffmpeg -ss 1 -i - -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-09-11 14:42:08.836 [pool-10-thread-1] Attaching thread: ffmpeg-2
DEBUG 2012-09-11 14:42:08.839 [pool-10-thread-1] Unix process ID (/usr/local/bin/ffmpeg): 7854
DEBUG 2012-09-11 14:42:08.876 [Zip Extractor] Unpack error, maybe it's normal, as backend can be terminated: Broken pipe
DEBUG 2012-09-11 14:42:11.378 [Buffered IO End Checker] Stopping Unix process 7854: ffmpeg-2
INFO  2012-09-11 14:42:19.027 [New I/O server worker #1-3] Starting transcode/remux of The Bourne Ultimatum - Trailer.mp4
DEBUG 2012-09-11 14:42:19.105 [mkfifo-4] Starting mkfifo --mode=777 /tmp/ps3mediaserver/mencoder1347399739104
DEBUG 2012-09-11 14:42:19.107 [mkfifo-4] Unix process ID (mkfifo): 7865
DEBUG 2012-09-11 14:42:19.155 [mencoder-3] Starting /usr/local/bin/mencoder -ss 0 - -msglevel statusline=2 -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 6 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=448:threads=2:keyint=5:vqscale=1:vqmin=2:vrc_maxrate=54000:vrc_buf_size=7000 -subdelay 20000 -ofps 24000/1001 -lavdopts fast -mc 0.1 -af lavcresample=48000 -srate 48000 -cache 8192 -o /tmp/ps3mediaserver/mencoder1347399739104
DEBUG 2012-09-11 14:42:19.157 [mencoder-3] Reading pipe: /tmp/ps3mediaserver/mencoder1347399739104
DEBUG 2012-09-11 14:42:19.160 [Thread-18] get_path("config") problem
DEBUG 2012-09-11 14:42:24.257 [Hanging External Process Stopper] Stopping Unix process 7870: mencoder-3
ERROR 2012-09-11 14:42:24.256 [New I/O server worker #1-3] There is no inputstream to return for The Bourne Ultimatum - Trailer.mp4 [MEncoder]
DEBUG 2012-09-11 14:42:24.257 [Hanging External Process Stopper] Stopping Unix process 7865: mkfifo-4
DEBUG 2012-09-11 14:42:24.258 [Thread-18] Parent process disappeared, exiting cache process.
DEBUG 2012-09-11 14:42:24.259 [Thread-18] Error consuming stream of spawned process: Stream Closed
INFO  2012-09-11 14:42:24.263 [New I/O server worker #1-4] Starting transcode/remux of The Bourne Ultimatum - Trailer.mp4
DEBUG 2012-09-11 14:42:24.282 [mkfifo-6] Starting mkfifo --mode=777 /tmp/ps3mediaserver/mencoder1347399744282
DEBUG 2012-09-11 14:42:24.285 [mkfifo-6] Unix process ID (mkfifo): 7878
DEBUG 2012-09-11 14:42:24.333 [mencoder-5] Starting /usr/local/bin/mencoder -ss 0 - -msglevel statusline=2 -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 6 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=448:threads=2:keyint=5:vqscale=1:vqmin=2:vrc_maxrate=54000:vrc_buf_size=7000 -subdelay 20000 -ofps 24000/1001 -lavdopts fast -mc 0.1 -af lavcresample=48000 -srate 48000 -cache 8192 -o /tmp/ps3mediaserver/mencoder1347399744282
DEBUG 2012-09-11 14:42:24.335 [mencoder-5] Reading pipe: /tmp/ps3mediaserver/mencoder1347399744282
DEBUG 2012-09-11 14:42:24.338 [Thread-23] get_path("config") problem
DEBUG 2012-09-11 14:42:29.434 [Hanging External Process Stopper] Stopping Unix process 7883: mencoder-5
ERROR 2012-09-11 14:42:29.434 [New I/O server worker #1-4] There is no inputstream to return for The Bourne Ultimatum - Trailer.mp4 [MEncoder]
DEBUG 2012-09-11 14:42:29.434 [Hanging External Process Stopper] Stopping Unix process 7878: mkfifo-6
DEBUG 2012-09-11 14:42:29.436 [Thread-23] Parent process disappeared, exiting cache process.
DEBUG 2012-09-11 14:42:29.436 [Thread-23] Error consuming stream of spawned process: Stream Closed

[chocolateboy]

Since I can transcode the extracted file fine, I really want to blame the compressed archive, just not sure the right solution yet.

Hard to say without a more detailed (trace) log:

FAQ: How can I supply details to help diagnose my problem?

Also: the file looks like a plain MP4 from the log. Is it a valid zip file? Is an example/sample available? &c.

Problem Reporting Guidelines

[Mug]

No problem.

Trace log is attached. I reset my debug.log and recreated the issue so it should contain just the server startup and the problem (set to TRACE). I've included my PMS.conf and PS3.conf.

Sample of the MP4 can be found here (where I grabbed it from):
Removed link, listed as forbidden now. Uploading a copy.
Edit:
New link: http://www.gachis.net/ps3mediaserver/bo ... railer.zip

Just to clear up any other questions on the system.
1. Intel Core2Duo 2.33Ghz (E6550), 2 GB ram, CentOS 6.3 x86_64, PMS 1.70.0
2. Streaming to a PS3
3. Gigabit etherent connection: Server -> Switch (media room) -> Switch (living room) -> PS3
4. Engine used: Mencoder, tsMuxeR

[chocolateboy]

New link

upload a file sample [ ...] to a public file-sharing site e.g. http://mediafire.com/

[Mug]

Roger

http://www.mediafire.com/file/25b0w4ega ... railer.zip

[chocolateboy]

Trace log is attached.

That's just the debug.log. Did you set the root level to TRACE in /usr/local/share/pms-1.70.0/logback.headless.xml?

[Mug]

Yup! Below is my logback.headless.xml. Let me know if I've followed the directions incorrectly.

Code: Select all

<configuration debug="true">
        <!-- See http://logback.qos.ch/manual/index.html for more information -->
        <!-- on how to configure the Logback logging engine. -->
        <!-- Available levels: ALL < TRACE < DEBUG < INFO < WARN < ERROR < OFF -->

        <define name="debugLogPath" class="net.pms.logging.DebugLogPathDefiner" />

        <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
                <!-- only show event WARN and higher on the console. -->
                <filter class="ch.qos.logback.classic.filter.ThresholdFilter">
                        <level>WARN</level>
                </filter>
                <encoder class="ch.qos.logback.classic.encoder.PatternLayoutEncoder">
                        <!-- This pattern mimics the original PMS debug.log format -->
                        <pattern>%-5level %d{yyyy-MM-dd HH:mm:ss.SSS} [%thread] %msg%n</pattern>
                </encoder>
        </appender>

        <appender name="debug.log" class="ch.qos.logback.core.FileAppender">
                <!-- only log event DEBUG and higher to the debug.log. -->
                <filter class="ch.qos.logback.classic.filter.ThresholdFilter">
                        <level>DEBUG</level>
                </filter>
                <file>${debugLogPath}${file.separator}debug.log</file>
                <append>false</append>  <!-- Start new debug.log on each application start -->
                <encoder class="ch.qos.logback.classic.encoder.PatternLayoutEncoder">
                        <!-- This pattern mimics the original PMS debug.log format -->
                        <pattern>%-5level %d{yyyy-MM-dd HH:mm:ss.SSS} [%thread] %msg%n</pattern>
                </encoder>
        </appender>

        <!-- If unlimited growth of debug.log is not desired use the "debug.log.10MB" -->
        <!-- appender instead of the "debug.log" appender. This appender will -->
        <!-- roll the debug.log file after 10 MBytes and will keep a maximum -->
        <!-- of 5 old and compressed logs. -->
        <appender name="debug.log.10MB" class="ch.qos.logback.core.rolling.RollingFileAppender">
                <!-- only log event DEBUG and higher to the rolling debug.log. -->
                <filter class="ch.qos.logback.classic.filter.ThresholdFilter">
                        <level>DEBUG</level>
                </filter>
                <file>${debugLogPath}${file.separator}debug.log</file>
                <rollingPolicy class="ch.qos.logback.core.rolling.FixedWindowRollingPolicy">
                        <fileNamePattern>${debugLogPath}debug.log.%i.zip</fileNamePattern>
                        <minIndex>1</minIndex>
                        <maxIndex>5</maxIndex>
                </rollingPolicy>
                <triggeringPolicy class="ch.qos.logback.core.rolling.SizeBasedTriggeringPolicy">
                        <maxFileSize>10MB</maxFileSize>
                </triggeringPolicy>
                <encoder class="ch.qos.logback.classic.encoder.PatternLayoutEncoder">
                        <pattern>%-5level %d{yyyy-MM-dd HH:mm:ss.SSS} [%thread] %msg%n</pattern>
                </encoder>
        </appender>

        <root level="TRACE">
                <appender-ref ref="STDOUT" />
                <appender-ref ref="debug.log" />
                <!-- <appender-ref ref="debug.log.10MB" /> -->
        </root>
</configuration>