2023-03-11 16:29:08.349 T:114303 info : Opening stream: 1 source: 256 2023-03-11 16:29:08.349 T:114303 info : Finding audio codec for: 86018 2023-03-11 16:29:08.353 T:114303 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-03-11 16:29:08.353 T:114303 info : Creating audio thread 2023-03-11 16:29:08.353 T:114311 debug : Thread VideoPlayerAudio start, auto delete: false 2023-03-11 16:29:08.353 T:114311 info : running thread: CVideoPlayerAudio::Process() 2023-03-11 16:29:08.353 T:114311 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false 2023-03-11 16:29:08.354 T:114303 info : Opening stream: 2 source: 256 2023-03-11 16:29:08.354 T:114303 debug : Created subtitles overlay codec: FFmpeg Subtitle Decoder 2023-03-11 16:29:08.354 T:114303 debug : Prepare - Start position set to last stopped position: 312000 2023-03-11 16:29:08.357 T:114303 debug : SeekTime - seek ended up on time 310227 2023-03-11 16:29:08.357 T:114303 debug : CVideoPlayer::FlushBuffers - flushing buffers 2023-03-11 16:29:08.357 T:114310 debug : CDVDVideoCodecDRMPRIME::Reset - flush buffers 2023-03-11 16:29:08.364 T:114311 debug : CDVDAudio::Pause - pausing audio stream 2023-03-11 16:29:08.364 T:114311 debug : CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE 2023-03-11 16:29:08.364 T:114310 debug : CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 2023-03-11 16:29:08.364 T:114303 debug : CVideoPlayer::SetCaching - caching state 2 2023-03-11 16:29:08.364 T:114303 debug : CDVDClock::SetSpeedAdjust - adjusted:0.000000 2023-03-11 16:29:08.364 T:114303 debug : Prepare - starting demuxer from: 312000 2023-03-11 16:29:08.364 T:114303 debug : CVideoPlayer::HandleMessages - player 2 reported state: 0 2023-03-11 16:29:08.364 T:114303 debug : CVideoPlayer::HandleMessages - player 1 reported state: 0 2023-03-11 16:29:08.364 T:114242 debug : OnAVChange: CApplication::OnAVChange 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310227000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114310 debug : CVideoPlayerVideo - Stillframe left, switching to normal playback 2023-03-11 16:29:08.367 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310228000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310249000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310270000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310291000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310313000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310334000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310355000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310376000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310399000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310420000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310441000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310462000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310484000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.367 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310505000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310526000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310547000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310435000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CVideoPlayer::CheckContinuity - wrapback :2, prev:310435000.000000, curr:310227000.000000, diff:-208000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310227000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310268000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310310000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310352000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114304 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 21(CRA_NUT), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310569000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310590000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310611000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310632000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310654000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310675000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310696000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.368 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310717000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310393000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310435000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310477000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310519000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310740000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310761000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310782000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310803000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310825000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310846000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310867000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310888000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.369 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] Format drm_prime chosen by get_format(). 2023-03-11 16:29:08.370 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] Format drm_prime requires hwaccel initialisation. 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310560000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310602000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] ff_v4l2_request_init: avctx=0xfffeb005dc20 hw_device_ctx=0xfffeb0053e30 hw_frames_ctx=(nil) 2023-03-11 16:29:08.370 T:114214 debug : CVideoGUIInfo::InitCurrentItem(/mnt/NAS/Series/Westworld/Westworld.S01E01.The.Original.1080p.10bit.BluRay.AAC5.1.HEVC-Vyndros.mkv) 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310644000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310685000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310911000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310932000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310953000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310974000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:310996000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311017000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311038000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311059000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_probe_media_device: avctx=0xfffeb005dc20 ctx=0xffff040771f0 path=/dev/media0 driver=rkvdec 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310727000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310769000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.370 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310810000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310852000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_probe_video_device: avctx=0xfffeb005dc20 ctx=0xffff040771f0 path=/dev/video4 capabilities=69222400 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311081000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 info : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_try_format: pixelformat 842094158 not supported for type 9 2023-03-11 16:29:08.371 T:114310 info : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_try_format: pixelformat 842093651 not supported for type 9 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311102000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311123000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 info : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_try_format: pixelformat 909203022 not supported for type 9 2023-03-11 16:29:08.371 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] v4l2_request_probe_video_device: select capture format failed 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311144000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311166000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311187000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 error : ffmpeg[0xaaaaeb027490]: [hevc] Failed setup for format drm_prime: hwaccel initialisation returned error. 2023-03-11 16:29:08.371 T:114310 debug : ffmpeg[0xaaaaeb027490]: [hevc] Format drm_prime not usable, retrying get_format() without it. 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311208000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 error : CDVDVideoCodecDRMPRIME::GetFormat - unsupported pixel format 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311229000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114304 debug : ffmpeg[0x0]: [hevc] Error parsing NAL unit #0. 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310894000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.371 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.371 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310936000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:310977000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311019000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311252000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311273000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311294000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311315000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311337000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311358000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311379000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311400000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311061000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114305 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 1(TRAIL_R), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311102000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311144000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311186000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311423000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311444000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311465000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311486000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.372 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311508000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311529000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311550000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311571000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311228000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311269000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311311000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311353000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:2 dts:311394000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311593000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311614000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311635000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311656000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.373 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311678000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.374 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311699000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.374 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311720000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.374 T:114303 debug : CheckPlayerInit - dropping packet type:1 dts:311741000.000000 to get to start point at 312000000.000000 2023-03-11 16:29:08.374 T:114311 debug : CDVDAudio::Pause - pausing audio stream 2023-03-11 16:29:08.379 T:114305 error : ffmpeg[0x0]: [hevc] Could not find ref with POC 14 2023-03-11 16:29:08.388 T:114214 debug : CPlayerGUIInfo::InitCurrentItem(/mnt/NAS/Series/Westworld/Westworld.S01E01.The.Original.1080p.10bit.BluRay.AAC5.1.HEVC-Vyndros.mkv) 2023-03-11 16:29:08.389 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.390 T:114306 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 1(TRAIL_R), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.396 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.397 T:114307 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 0(TRAIL_N), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.405 T:114307 debug : ffmpeg[0x0]: [hevc] Output frame with POC 15. 2023-03-11 16:29:08.405 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.406 T:114308 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 0(TRAIL_N), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.413 T:114308 debug : ffmpeg[0x0]: [hevc] Output frame with POC 16. 2023-03-11 16:29:08.413 T:114310 debug : CVideoPlayerVideo::CalcDropRequirement - hurry: 0 2023-03-11 16:29:08.414 T:114309 debug : ffmpeg[0x0]: [hevc] nal_unit_type: 0(TRAIL_N), nuh_layer_id: 0, temporal_id: 0 2023-03-11 16:29:08.421 T:114309 debug : ffmpeg[0x0]: [hevc] Output frame with POC 17. 2023-03-11 16:29:08.421 T:114310 error : CDVDVideoCodecDRMPRIME::AddData - send packet failed: Operation not permitted (-1)