It is currently 28 Mar 2017, 16:52



Sound problems with CCG Server 2.1 - May 19 build

Help to specific problems with your setup, or discussing CasparCG bugs

Moderators: Macey, Jonas Hummelstrand, didikunz

Sound problems with CCG Server 2.1 - May 19 build

Postby menoc » 23 May 2016, 20:38

Having problems with playing sound files with the latest build of Caspar CG Server 2.1 (19-May-2016 build). I getting the following ERRORS in the log:

If you look at the bottom of the log, it seems as though the server is not able to find the sound files. Is everyone having the same issue? Is this a known bug in this build?

Code: Select all
[2016-05-23 15:33:13.720] [14676] [info]    ############################################################################
[2016-05-23 15:33:13.720] [14676] [info]    CasparCG Server is distributed by the Swedish Broadcasting Corporation (SVT)
[2016-05-23 15:33:13.720] [14676] [info]    under the GNU General Public License GPLv3 or higher.
[2016-05-23 15:33:13.720] [14676] [info]    Please see LICENSE.TXT for details.
[2016-05-23 15:33:13.721] [14676] [info]    http://www.casparcg.com/
[2016-05-23 15:33:13.721] [14676] [info]    ############################################################################
[2016-05-23 15:33:13.721] [14676] [info]    Starting CasparCG Video and Graphics Playout Server 2.1.0.0b5b222 UNSTABLE
[2016-05-23 15:33:13.721] [14676] [info]    on Windows 7 Enterprise Service Pack 1
[2016-05-23 15:33:13.721] [14676] [info]    Intel(R) Xeon(R) CPU           W3530  @ 2.80GHz Physical Threads: 4
[2016-05-23 15:33:13.721] [14676] [info]    Precision WorkStation T3500
[2016-05-23 15:33:13.722] [14676] [info]    Initialized ffmpeg module.
[2016-05-23 15:33:13.723] [14676] [info]    Initialized oal module.
[2016-05-23 15:33:13.735] [14676] [info]    Initialized bluefish module.
[2016-05-23 15:33:13.735] [14676] [info]    Initialized decklink module.
[2016-05-23 15:33:13.735] [14676] [info]    Initialized screen module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized html module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized psd module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized flash module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized newtek module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized image module.
[2016-05-23 15:33:13.757] [14676] [info]    Initialized reroute module.
[2016-05-23 15:33:13.779] [14676] [info]    ffmpeg.avcodec 56.41.100
[2016-05-23 15:33:13.779] [14676] [info]    ffmpeg.avformat 56.36.100
[2016-05-23 15:33:13.779] [14676] [info]    ffmpeg.avfilter 5.16.101
[2016-05-23 15:33:13.779] [14676] [info]    ffmpeg.avutil 54.27.100
[2016-05-23 15:33:13.779] [14676] [info]    ffmpeg.swscale 3.1.101
[2016-05-23 15:33:13.779] [14676] [info]    bluefish.version Not found
[2016-05-23 15:33:13.779] [14676] [info]    decklink.version 10.6.4
[2016-05-23 15:33:13.779] [14676] [info]    decklink.device DeckLink 4K Extreme [1]
[2016-05-23 15:33:13.779] [14676] [info]    cef.version 3.1750.1805
[2016-05-23 15:33:13.779] [14676] [info]    cef.chromeversion 33.0.1750.170
[2016-05-23 15:33:13.779] [14676] [info]    flash 11.8.800.94
[2016-05-23 15:33:13.779] [14676] [info]    newtek-ivga.version unavailable (Processing.AirSend.x64.dll)
[2016-05-23 15:33:13.779] [14676] [info]    freeimage 3.16.0
[2016-05-23 15:33:13.779] [14676] [info]    casparcg.config:
[2016-05-23 15:33:13.779] [14676] [info]    -----------------------------------------
[2016-05-23 15:33:13.779] [14676] [info]    <?xml version="1.0" encoding="utf-8"?>
[2016-05-23 15:33:13.779] [14676] [info]    <configuration>
[2016-05-23 15:33:13.779] [14676] [info]       <paths>
[2016-05-23 15:33:13.779] [14676] [info]          <media-path>F:\media\</media-path>
[2016-05-23 15:33:13.779] [14676] [info]          <log-path>log\</log-path>
[2016-05-23 15:33:13.779] [14676] [info]          <data-path>F:\data\</data-path>
[2016-05-23 15:33:13.779] [14676] [info]          <template-path>F:\templates\</template-path>
[2016-05-23 15:33:13.779] [14676] [info]          <thumbnails-path>thumbnails\</thumbnails-path>
[2016-05-23 15:33:13.779] [14676] [info]       </paths>
[2016-05-23 15:33:13.779] [14676] [info]       <channels>
[2016-05-23 15:33:13.779] [14676] [info]          <channel>
[2016-05-23 15:33:13.779] [14676] [info]             <video-mode>1080p2398</video-mode>
[2016-05-23 15:33:13.779] [14676] [info]             <consumers>
[2016-05-23 15:33:13.779] [14676] [info]                <decklink>
[2016-05-23 15:33:13.779] [14676] [info]                   <device>1</device>
[2016-05-23 15:33:13.779] [14676] [info]                   <embedded-audio>true</embedded-audio>
[2016-05-23 15:33:13.779] [14676] [info]                   <channel-layout>stereo</channel-layout>
[2016-05-23 15:33:13.779] [14676] [info]                   <latency>normal</latency>
[2016-05-23 15:33:13.779] [14676] [info]                   <keyer>external</keyer>
[2016-05-23 15:33:13.779] [14676] [info]                   <key-only>false</key-only>
[2016-05-23 15:33:13.779] [14676] [info]                   <buffer-depth>3</buffer-depth>
[2016-05-23 15:33:13.779] [14676] [info]                   <custom-allocator>true</custom-allocator>
[2016-05-23 15:33:13.779] [14676] [info]                </decklink>
[2016-05-23 15:33:13.779] [14676] [info]             </consumers>
[2016-05-23 15:33:13.779] [14676] [info]          </channel>
[2016-05-23 15:33:13.779] [14676] [info]       </channels>
[2016-05-23 15:33:13.779] [14676] [info]       <controllers>
[2016-05-23 15:33:13.779] [14676] [info]          <tcp>
[2016-05-23 15:33:13.779] [14676] [info]             <port>5250</port>
[2016-05-23 15:33:13.779] [14676] [info]             <protocol>AMCP</protocol>
[2016-05-23 15:33:13.779] [14676] [info]          </tcp>
[2016-05-23 15:33:13.779] [14676] [info]          <tcp>
[2016-05-23 15:33:13.779] [14676] [info]             <port>3250</port>
[2016-05-23 15:33:13.779] [14676] [info]             <protocol>LOG</protocol>
[2016-05-23 15:33:13.779] [14676] [info]          </tcp>
[2016-05-23 15:33:13.779] [14676] [info]       </controllers>
[2016-05-23 15:33:13.779] [14676] [info]    </configuration>
[2016-05-23 15:33:13.779] [14676] [info]    -----------------------------------------
[2016-05-23 15:33:13.780] [14676] [info]    Initialized audio config.
[2016-05-23 15:33:13.781] [14676] [info]    Initializing OpenGL Device.
[2016-05-23 15:33:13.957] [14676] [info]    Successfully initialized OpenGL 4.5.0 NVIDIA 362.13 NVIDIA Corporation
[2016-05-23 15:33:13.963] [14676] [info]    Initialized OpenGL Accelerated GPU Image Mixer for channel 1
[2016-05-23 15:33:13.964] [14676] [info]    video_channel[1|1080p2398] Successfully Initialized.
[2016-05-23 15:33:13.971] [6340]  [info]    DeckLink 4K Extreme [1-1|1080p2398] Enabled embedded-audio.
[2016-05-23 15:33:13.971] [6340]  [info]    DeckLink 4K Extreme [1-1|1080p2398] Disabled low-latency mode.
[2016-05-23 15:33:13.971] [6340]  [info]    DeckLink 4K Extreme [1-1|1080p2398] Enabled external keyer.
[2016-05-23 15:33:13.984] [6340]  [info]    DeckLink 4K Extreme [1-1|1080p2398] Reference signal: not detected.
[2016-05-23 15:33:14.038] [14676] [info]    DeckLink 4K Extreme [1-1|1080p2398] Initialized.
[2016-05-23 15:33:14.038] [14676] [info]    Initialized channels.
[2016-05-23 15:33:14.038] [14676] [info]    Initialized OpenGL Accelerated GPU Image Mixer for channel 0
[2016-05-23 15:33:14.039] [14676] [info]    Initialized thumbnail generator.
[2016-05-23 15:33:14.040] [14676] [info]    Initialized controllers.
[2016-05-23 15:33:14.040] [14676] [info]    Initialized osc.
[2016-05-23 15:33:14.040] [14676] [info]    Started initial media information retrieval.
[2016-05-23 15:33:15.275] [9496]  [info]    Initial media information retrieval finished.
[2016-05-23 15:33:28.327] [10036] [info]    async_event_server[:5250] Accepted connection from 10.105.197.252 (1 connections).
[2016-05-23 15:33:28.405] [10036] [info]    Received message from 10.105.197.252: VERSION SERVER\r\n
[2016-05-23 15:33:28.405] [9388]  [info]    Sent message to 10.105.197.252:201 VERSION OK\r\n2.1.0.0b5b222 UNSTABLE\r\n
[2016-05-23 15:33:28.406] [10036] [info]    Received message from 10.105.197.252: INFO\r\n
[2016-05-23 15:33:28.406] [9388]  [info]    Sent message to 10.105.197.252:200 INFO OK\r\n1 1080p2398 PLAYING\r\n\r\n
[2016-05-23 15:33:28.406] [10036] [info]    Received message from 10.105.197.252: CLS\r\n
[2016-05-23 15:33:28.457] [9388]  [info]    Sent more than 512 bytes to 10.105.197.252
[2016-05-23 15:33:28.459] [10036] [info]    Received message from 10.105.197.252: TLS\r\n
[2016-05-23 15:33:28.459] [10036] [info]    Received message from 10.105.197.252: DATA LIST\r\n
[2016-05-23 15:33:28.459] [10036] [info]    Received message from 10.105.197.252: THUMBNAIL LIST\r\n
[2016-05-23 15:33:28.489] [9388]  [info]    Sent more than 512 bytes to 10.105.197.252
[2016-05-23 15:33:28.489] [9388]  [info]    Sent message to 10.105.197.252:200 DATA LIST OK\r\n\r\n
[2016-05-23 15:33:28.563] [9388]  [info]    Sent more than 512 bytes to 10.105.197.252
[2016-05-23 15:33:45.625] [10036] [info]    Received message from 10.105.197.252: PLAY 1-30 "WMES/AUDIO/INTRO NEWS III FULL" CUT 1 Linear RIGHT\r\n
[2016-05-23 15:33:45.650] [4308]  [error]   [ffmpeg] [Parsed_abuffer_0 @ 0000000009B35600] Changing frame properties on the fly is not supported.
[2016-05-23 15:33:45.726] [4308]  [error]   C:\Program Files (x86)\Jenkins\jobs\CasparCG_Server_2.1.0\workspace\modules\ffmpeg\producer\filter\audio_filter.cpp(219): Throw in function caspar::ffmpeg::audio_filter::implementation::push::<lambda_063064cb49531dd0dd163160a2a92831>::operator ()
[2016-05-23 15:33:45.726] [4308]  [error]   Dynamic exception type: class boost::exception_detail::clone_impl<struct caspar::ffmpeg::ffmpeg_error>
[2016-05-23 15:33:45.726] [4308]  [error]   [struct boost::errinfo_api_function_ * __ptr64] = av_buffersrc_add_frame( audio_graph_inputs_.at(input_pad_id), src_av_frame.get())
[2016-05-23 15:33:45.726] [4308]  [error]   22, "Invalid argument"[struct caspar::tag_msg_info * __ptr64] = Invalid argument
[2016-05-23 15:33:45.726] [4308]  [error]   [struct caspar::tag_source_info * __ptr64] =
[2016-05-23 15:33:45.726] [4308]  [error]   Caught at (ffmpeg-pipeline: [file_source F:\media\//WMES/AUDIO\Intro news III full.wav] -> [memory_sink]):
[2016-05-23 15:33:45.726] [4308]  [error]   
[2016-05-23 15:33:45.726] [4308]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\modules\ffmpeg\ffmpeg_pipeline_backend_internal.cpp (1162): `caspar::ffmpeg::ffmpeg_pipeline_backend_internal::run'::`1'::catch$2
[2016-05-23 15:33:45.726] [4308]  [error]       000007FEF5C6C710 (VCRUNTIME140): (filename not available): _C_specific_handler
[2016-05-23 15:33:45.726] [4308]  [error]       000007FEF5C62AD2 (VCRUNTIME140): (filename not available): _FrameUnwindFilter
[2016-05-23 15:33:45.726] [4308]  [error]       0000000077A4CD51 (ntdll): (filename not available): RtlRestoreContext
[2016-05-23 15:33:45.726] [4308]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\modules\ffmpeg\ffmpeg_pipeline_backend_internal.cpp (1122): caspar::ffmpeg::ffmpeg_pipeline_backend_internal::run
[2016-05-23 15:33:45.726] [4308]  [error]       000000013FD84883 (casparcg): (filename not available): boost::detail::shared_count::swap
[2016-05-23 15:33:45.726] [4308]  [error]       000007FEF300BE1D (ucrtbase): (filename not available): crt_at_quick_exit
[2016-05-23 15:33:45.726] [4308]  [error]       00000000778F59ED (kernel32): (filename not available): BaseThreadInitThunk
[2016-05-23 15:33:45.726] [4308]  [error]       0000000077A2B371 (ntdll): (filename not available): RtlUserThreadStart
[2016-05-23 15:33:45.726] [4308]  [error]   
[2016-05-23 15:33:45.796] [2180]  [error]   C:\Program Files (x86)\Jenkins\jobs\CasparCG_Server_2.1.0\workspace\modules\ffmpeg\producer\filter\audio_filter.cpp(219): Throw in function caspar::ffmpeg::audio_filter::implementation::push::<lambda_063064cb49531dd0dd163160a2a92831>::operator ()
[2016-05-23 15:33:45.796] [2180]  [error]   Dynamic exception type: class boost::exception_detail::clone_impl<struct caspar::ffmpeg::ffmpeg_error>
[2016-05-23 15:33:45.796] [2180]  [error]   [struct boost::errinfo_api_function_ * __ptr64] = av_buffersrc_add_frame( audio_graph_inputs_.at(input_pad_id), src_av_frame.get())
[2016-05-23 15:33:45.796] [2180]  [error]   22, "Invalid argument"[struct caspar::tag_msg_info * __ptr64] = Invalid argument
[2016-05-23 15:33:45.796] [2180]  [error]   [struct caspar::tag_source_info * __ptr64] =
[2016-05-23 15:33:45.796] [2180]  [error]   Caught at (AMCPCommandQueue Channel 1 for TCP Port 5250):
[2016-05-23 15:33:45.796] [2180]  [error]   
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\core\producer\frame_producer.cpp (318): `<lambda_dd21df7e9b250d33e0c98201cc5c3b03>::operator()'::`1'::catch$2
[2016-05-23 15:33:45.796] [2180]  [error]       000007FEF5C6C710 (VCRUNTIME140): (filename not available): _C_specific_handler
[2016-05-23 15:33:45.796] [2180]  [error]       000007FEF5C62AD2 (VCRUNTIME140): (filename not available): _FrameUnwindFilter
[2016-05-23 15:33:45.796] [2180]  [error]       0000000077A4CD51 (ntdll): (filename not available): RtlRestoreContext
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\core\producer\frame_producer.cpp (307): <lambda_dd21df7e9b250d33e0c98201cc5c3b03>::operator()
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\core\producer\frame_producer.cpp (303): caspar::core::do_create_producer
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\core\producer\frame_producer.cpp (369): caspar::core::frame_producer_registry::create_producer
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\protocol\amcp\amcpcommandsimpl.cpp (381): caspar::protocol::amcp::loadbg_command
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\protocol\amcp\amcpcommandsimpl.cpp (441): caspar::protocol::amcp::play_command
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\type_traits (1408): std::_Invoker_functor::_Call<std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> > (__cdecl*& __ptr64)(caspar::protocol::amcp::command_context & __ptr64),caspar::protocol::amcp::command_context & __ptr64>
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\type_traits (1476): std::invoke<std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> > (__cdecl*& __ptr64)(caspar::protocol::amcp::command_context & __ptr64),caspar::protocol::amcp::command_context & __ptr64>
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\type_traits (1501): std::_Invoke_ret<std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> >,std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> > (__cdecl*& __ptr64)(caspar::protocol::amcp::command_context & __ptr64),caspar::protocol::amcp::command_context & __ptr64>
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\functional (209): std::_Func_impl<std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> > (__cdecl*)(caspar::protocol::amcp::command_context & __ptr64),std::allocator<int>,std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> >,caspar::protocol::amcp::command_context & __ptr64>::_Do_call
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\functional (275): std::_Func_class<std::basic_string<wchar_t,std::char_traits<wchar_t>,std::allocator<wchar_t> >,caspar::protocol::amcp::command_context & __ptr64>::operator()
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\protocol\amcp\amcpcommand.h (111): caspar::protocol::amcp::AMCPCommand::Execute
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\protocol\amcp\amcpcommandqueue.cpp (106): <lambda_0102cca86d98b89d9b35fb667dc9403c>::operator()
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\functional (209): std::_Func_impl<<lambda_2101d17151b7ae1c7926261be6ab99b0>,std::allocator<int>,void>::_Do_call
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\microsoft visual studio 14.0\vc\include\future (750): std::_Packaged_state<void __cdecl(void)>::_Call_immediate
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\common\executor.h (235): <lambda_acfc9f6bbaaf035cd9f646dd1e0bda0e>::operator()
[2016-05-23 15:33:45.796] [2180]  [error]       c:\program files (x86)\jenkins\jobs\casparcg_server_2.1.0\workspace\common\executor.h (277): caspar::executor::run
[2016-05-23 15:33:45.796] [2180]  [error]       000000013FD84883 (casparcg): (filename not available): boost::detail::shared_count::swap
[2016-05-23 15:33:45.796] [2180]  [error]       000007FEF300BE1D (ucrtbase): (filename not available): crt_at_quick_exit
[2016-05-23 15:33:45.796] [2180]  [error]       00000000778F59ED (kernel32): (filename not available): BaseThreadInitThunk
[2016-05-23 15:33:45.796] [2180]  [error]       0000000077A2B371 (ntdll): (filename not available): RtlUserThreadStart
[2016-05-23 15:33:45.796] [2180]  [error]   
[2016-05-23 15:33:45.809] [2180]  [error]   No match found for supplied commands. Check syntax. Turn on log level debug for stacktrace.
[2016-05-23 15:33:45.809] [2180]  [info]    Sent message to 10.105.197.252:404 PLAY FAILED\r\n
[2016-05-23 15:34:35.340] [8596]  [info]    Received message from Console: KILL\r\n
[2016-05-23 15:34:35.342] [10036] [info]    async_event_server[:5250] Client 10.105.197.252 disconnected (0 connections).
[2016-05-23 15:34:35.342] [10036] [info]    video_channel[1|1080p2398] Uninitializing.
[2016-05-23 15:34:35.436] [8784]  [info]    DeckLink 4K Extreme [1-1|1080p2398] Scheduled playback has stopped.
[2016-05-23 15:34:35.459] [10036] [info]    DeckLink 4K Extreme [1-1|1080p2398] Uninitialized.
[2016-05-23 15:34:35.571] [14676] [info]    Successfully shutdown CasparCG Server.

menoc
 
Posts: 19
Joined: 24 Feb 2016, 23:55

Return to Help & Support

Who is online

Users browsing this forum: tridenator and 1 guest