[BUG][CML] IPC timed out for '0x60040000: GLB_STREAM_MSG: TRIG_START due to pipeline copy errors' #1396

Activity

1. ClarexZhou

   added

   bugSomething isn't working as expectedSomething isn't working as expected

   CMLApplies to Comet Lake platformApplies to Comet Lake platform

   HDAApplies to HD-Audio bus for codec connectionApplies to HD-Audio bus for codec connection

   on May 9, 2019
2. mengdonglin

   added

   IPC timeoutIPC timeout observedIPC timeout observed

   on May 9, 2019
3. tlauda commented on May 10, 2019

   

   tlauda

   on May 10, 2019

   Contributor

   More actions

   FW logs indicate that HDA Link pointer is stuck after trigger start, so there is something wrong with the Link DMA on the kernel side.
4. ClarexZhou commented on May 14, 2019

   

   ClarexZhou

   on May 14, 2019

   Author

   More actions

   0x60040000: GLB_STREAM_MSG: TRIG_START timed out occurred on CFL-s during playback stress test after running for about 210 times
   Error Sof-logger:

    CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
       0      1         PIPE 1.6     385989754.531250 385989760.000000 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385993446.145833      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385993492.135417        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385993497.604167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385993504.375000         6.770833 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385997196.250000      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385997242.135417        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385997247.604167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385997254.375000         6.770833 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         386000946.093750      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         386000992.031250        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         386000997.552083         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     386001004.270833         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         386004695.781250      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         386004741.770833        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         386004747.239583         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     386004753.802083         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         386008445.937500      3692.135498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         386008492.031250        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         386008497.343750         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     386008503.802083         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         386012195.833333      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         386012240.677083        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         386012245.989583         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     386012252.552083         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         386015946.875000      3694.322998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         386015991.875000        45.000000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         386015997.187500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     386016003.750000         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1       VOLUME         198345994.062500              nan   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         198345999.635417         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     198346006.354167         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         210025947.187500  11679941.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         210025994.166667        46.979168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         210025999.531250         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     210026006.041667         6.510417 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         255332196.979167  45306192.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         255332243.697917        46.718750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         255332249.218750         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     255332255.937500         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         271082197.343750  15749941.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         271082244.010417        46.666668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         271082249.427083         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     271082256.145833         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         373952197.291667 102869944.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         373952244.270833        46.979168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         373952249.687500         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     373952256.406250         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385633446.822917  11681190.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385633494.322917        47.500000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385633499.739583         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385633506.666667         6.927083 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385637196.093750      3689.427002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385637242.135417        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385637247.500000         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385637254.270833         6.770833 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385640945.833333      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385640991.614583        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385640997.187500         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385641003.854167         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385644695.885417      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385644741.927083        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385644747.291667         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385644754.322917         7.031250 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385648446.041667      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385648491.093750        45.052082   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385648496.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385648502.864583         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385652195.833333      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385652240.677083        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385652245.937500         5.260417 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385652252.552083         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385655946.302083      3693.750000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385655991.250000        44.947918   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385655996.614583         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385656003.229167         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385659696.197917      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385659741.197917        45.000000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385659746.666667         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385659753.125000         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385663446.302083      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385663492.343750        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385663497.812500         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385663504.375000         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385667196.250000      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385667242.447917        46.197918   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385667247.968750         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385667254.531250         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385670946.145833      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385670992.031250        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385670997.604167         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385671004.270833         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385674696.145833      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385674742.083333        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385674747.604167         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385674754.322917         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385678446.302083      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385678492.760417        46.458332   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385678498.229167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385678504.947917         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385682196.041667      3691.093750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385682242.031250        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385682247.552083         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385682254.270833         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385685946.145833      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385685992.291667        46.145832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385685997.760417         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385686004.427083         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385689696.302083      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385689742.343750        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385689747.708333         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385689754.375000         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385693446.197917      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385693492.083333        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385693497.500000         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385693504.322917         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385697196.302083      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385697242.395833        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385697247.760417         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385697254.583333         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385700946.093750      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385700991.875000        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385700997.447917         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385701004.166667         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385704696.093750      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385704742.083333        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385704747.552083         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385704754.114583         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385708446.197917      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385708491.197917        45.000000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385708496.562500         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385708503.020833         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385712195.833333      3692.812500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385712240.729167        44.895832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385712246.041667         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385712252.656250         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385715946.041667      3693.385498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385715990.885417        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385715996.197917         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385716002.760417         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385719696.093750      3693.333252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385719740.885417        44.791668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385719746.354167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385719752.812500         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385723446.250000      3693.437500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385723490.937500        44.687500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385723496.354167         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385723502.812500         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385727195.833333      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385727240.937500        45.104168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385727246.458333         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385727252.916667         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385730946.093750      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385730991.927083        45.833332   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385730997.500000         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385731004.218750         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385734696.197917      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385734742.083333        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385734747.656250         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385734754.375000         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385738446.041667      3691.666748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385738492.291667        46.250000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385738497.760417         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385738504.375000         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385742196.041667      3691.666748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385742242.031250        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385742247.604167         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385742254.322917         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385745945.989583      3691.666748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385745991.979167        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385745997.395833         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385746004.062500         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385749695.989583      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385749741.927083        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385749747.395833         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385749754.114583         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385753446.093750      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385753492.031250        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385753497.447917         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385753504.270833         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385757196.145833      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385757241.927083        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385757247.395833         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385757254.218750         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385760945.937500      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385760991.562500        45.625000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385760997.135417         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385761003.802083         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385764696.197917      3692.395752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385764742.395833        46.197918   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385764747.812500         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385764754.375000         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385768446.093750      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385768491.093750        45.000000   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385768496.458333         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385768502.916667         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385772195.885417      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385772240.677083        44.791668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385772245.989583         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385772252.604167         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385775946.250000      3693.645752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385775991.093750        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385775996.458333         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385776003.072917         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385779696.093750      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385779740.885417        44.791668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385779746.354167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385779752.812500         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385783446.302083      3693.489502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385783491.093750        44.791668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385783496.510417         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385783502.968750         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385787196.041667      3693.072998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385787241.093750        45.052082   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385787246.562500         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385787253.020833         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385790946.041667      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385790990.729167        44.687500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385790996.197917         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385791002.760417         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385794695.729167      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385794740.416667        44.687500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385794745.885417         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385794752.447917         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385798446.197917      3693.750000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385798492.343750        46.145832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385798497.760417         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385798504.375000         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385802195.989583      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385802241.770833        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385802247.343750         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385802254.114583         6.770833 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385805946.197917      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385805992.187500        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385805997.604167         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385806004.322917         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385809695.781250      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385809741.875000        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385809747.291667         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385809753.958333         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385813446.250000      3692.291748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385813492.187500        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385813497.604167         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385813504.427083         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385817196.041667      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385817241.822917        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385817247.239583         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385817254.062500         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385820945.989583      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385820991.770833        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385820997.291667         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385821004.010417         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385824696.093750      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385824742.083333        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385824747.500000         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385824754.062500         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385828445.937500      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385828490.885417        44.947918   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385828496.197917         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385828502.656250         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385832196.145833      3693.489502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385832242.187500        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385832247.604167         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385832254.322917         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385835946.041667      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385835992.031250        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385835997.447917         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385836004.114583         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385839696.041667      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385839741.927083        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385839747.447917         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385839754.010417         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385843446.093750      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385843491.875000        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385843497.343750         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385843503.906250         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385847195.885417      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385847241.875000        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385847247.447917         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385847254.010417         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385850945.833333      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385850991.562500        45.729168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385850997.083333         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385851003.802083         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385854696.093750      3692.291748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385854741.875000        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385854747.395833         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385854754.114583         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385858446.093750      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385858492.187500        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385858497.604167         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385858504.218750         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385862196.145833      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385862241.927083        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385862247.447917         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385862254.166667         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385865946.093750      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385865992.239583        46.145832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385865997.708333         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385866004.427083         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385869695.989583      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385869742.083333        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385869747.552083         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385869754.270833         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385873446.093750      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385873492.031250        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385873497.500000         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385873504.322917         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385877196.041667      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385877241.875000        45.833332   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385877247.291667         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385877254.114583         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385880946.145833      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385880991.822917        45.677082   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385880997.395833         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385881004.062500         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385884695.937500      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385884741.822917        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385884747.239583         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385884753.802083         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385888445.781250      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385888490.625000        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385888495.937500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385888502.395833         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385892195.937500      3693.541748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385892240.677083        44.739582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385892245.989583         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385892252.552083         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385895946.041667      3693.489502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385895990.885417        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385895996.197917         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385896002.812500         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385899696.302083      3693.489502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385899742.343750        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385899747.916667         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385899754.479167         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385903446.354167      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385903492.187500        45.833332   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385903497.656250         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385903504.218750         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385907196.041667      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385907242.187500        46.145832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385907247.760417         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385907254.322917         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385910946.197917      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385910991.927083        45.729168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385910997.500000         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385911004.218750         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385914696.197917      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385914742.135417        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385914747.708333         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385914754.427083         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385918446.302083      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385918492.291667        45.989582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385918497.760417         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385918504.322917         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385922195.885417      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385922241.979167        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385922247.552083         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385922254.218750         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385925945.937500      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385925991.979167        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385925997.447917         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385926004.166667         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385929696.041667      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385929741.979167        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385929747.447917         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385929754.166667         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385933446.197917      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385933492.239583        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385933497.656250         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385933504.479167         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385937195.833333      3691.354248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385937241.614583        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385937247.031250         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385937253.854167         6.822917 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385940946.197917      3692.343750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385940991.979167        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385940997.552083         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385941004.218750         6.666667 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385944695.989583      3691.770752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385944741.927083        45.937500   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385944747.395833         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385944753.958333         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385948446.145833      3692.187500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385948492.031250        45.885418   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385948497.395833         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385948503.854167         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385952196.093750      3692.239502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385952240.937500        44.843750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385952246.302083         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385952252.916667         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385955946.302083      3693.385498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385955991.250000        44.947918   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385955996.614583         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385956003.177083         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385959696.250000      3693.072998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385959741.041667        44.791668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385959746.510417         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385959752.968750         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385963446.145833      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385963490.885417        44.739582   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385963496.250000         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385963502.708333         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385967195.989583      3693.281250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385967242.343750        46.354168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385967247.968750         5.625000 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385967254.531250         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385970945.937500      3691.406250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385970991.718750        45.781250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385970997.291667         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385971004.010417         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385974695.989583      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385974741.822917        45.833332   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385974747.395833         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385974754.114583         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385978446.197917      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385978492.239583        46.041668   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385978497.708333         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385978504.322917         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385982196.145833      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385982242.500000        46.354168   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385982248.072917         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385982254.791667         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385985945.989583      3691.197998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385985992.083333        46.093750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385985997.500000         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     385986004.218750         6.718750 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         385989696.250000      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         385989742.395833        46.145832   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         385989747.864583         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
   
   

   Dmesg

   [ 1154.298809] sof-audio-pci 0000:00:1f.3: stream_tag 1
   [ 1154.298812] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60010000: GLB_STREAM_MSG: PCM_PARAMS
   [ 1154.299042] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60010000: GLB_STREAM_MSG: PCM_PARAMS
   [ 1154.299043] sof-audio-pci 0000:00:1f.3: pcm: stream dir 0, posn mailbox offset is 790528
   [ 1159.325816] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
   [ 1159.325834] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
   [ 1159.632870] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60040000 size 12
   [ 1159.632889] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [ 1159.633129] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [ 1159.633145] sof-audio-pci 0000:00:1f.3: error: host status 0x80000000 dsp status 0x90020000 mask 0x00000003
   [ 1159.633150] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
   [ 1159.633158]  HDA Analog: ASoC: trigger FE failed -110
   [ 1159.633170] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
   [ 1159.633189] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
   [ 1159.633382] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [ 1159.633413] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
   [ 1159.633416] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [ 1159.633502] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [ 1159.633529] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [ 1160.120274] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [ 1160.120316] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [ 1160.241105] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
   [ 1160.241115] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
   [ 1160.544866] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
   [ 1160.544887] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [ 1160.545256] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [ 1160.545284] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 1160.545289] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
   [ 1160.545322] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
   [ 1160.620683] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [ 1160.620710] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [ 1160.848354] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
   [ 1160.848376] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [ 1160.848746] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [ 1160.848774] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 1160.848779] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
   [ 1160.848786] sof-audio-pci 0000:00:1f.3: error: failed to set dai config for Analog Playback and Capture
   [ 1160.848918] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0
   [ 1162.896463] sof-audio-pci 0000:00:1f.3: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
   [ 1163.199877] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x40010000 size 76
   [ 1163.199898] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [ 1163.200246] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [ 1163.200274] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 1163.200280] sof-audio-pci 0000:00:1f.3: error: ctx_save ipc error during suspend -110
   [ 1163.200294] sof-audio-pci 0000:00:1f.3: can't suspend (snd_sof_runtime_suspend [snd_sof] returned -110)
   [ 1515.103690] sof-audio-pci 0000:00:1f.3: pcm: open stream 0 dir 0
   [ 1515.103696] sof-audio-pci 0000:00:1f.3: period min 192 max 16384 bytes
   [ 1515.103700] sof-audio-pci 0000:00:1f.3: period count 2 max 16
   [ 1515.103704] sof-audio-pci 0000:00:1f.3: buffer max 65536 bytes
   [ 1515.103965] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
   [ 1515.408848] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
   [ 1515.408869] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [ 1515.409217] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [ 1515.409245] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 1515.409251] sof-audio-pci 0000:00:1f.3: error: failed to set dai config for Analog Playback and Capture
   [ 1515.409258] sof-audio-pci 0000:00:1f.3: ASoC: can't set Analog CPU DAI hw params: -110
   [ 1515.409270]  Analog Playback and Capture: ASoC: hw_params BE failed -110
   [ 1515.409274]  HDA Analog: ASoC: hw_params BE failed -110
   [ 1515.409287] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
   

   Environment
   Rander UCM https://github.com/RanderWang/intel-UCM  
   Kernel repo：9494607 https://github.com/thesofproject/linux/tree/release/sof-v5.0
   Firmware repo: b73b9a6 https://github.com/thesofproject/sof/commits/master +PR1354  
   Topology: https://github.com/thesofproject/sof/commits/master . Same as above SOF repo
   file: tools/topology/sof-hda-generic.m4, sof-hda-generic-2ch.tplg

   ipctimedoutAplay_dmesg.log
   logger.log
   errorlogger.log
5. tlauda commented on May 14, 2019

   

   tlauda

   on May 14, 2019

   Last edited by tlauda

   Contributor

   More actions

   HDA Link problem. Please verify with the same changes as mentioned in #1413.
6. wenqingfu commented on May 14, 2019

   

   wenqingfu

   on May 14, 2019

   Last edited by wenqingfu

   More actions

   with 1354 and 895 integrated (aka, the fixes mentioned in #1413), there is still occurrence in Playabck_aplay -d 1_10000 times stressing
7. YvonneYang2 commented on May 17, 2019

   

   YvonneYang2

   on May 17, 2019

   More actions

   Issue occurred once after do steps below:
   1.System reboot with headphone connected.
   2.Paplay 1.wav
   3.Connect the external DP monitor, and choose to HDMI2/DP output setting in Sound settings -Output.
   4.Switch the setting between headphone and HDMI2/DP2 in Sound settings -Output.

   Environment
   Rander UCM https://github.com/RanderWang/intel-UCM/tree/hdmi
   Kernel repo：df06e42 https://github.com/thesofproject/linux/tree/release/sof-v5.0
   Firmware repo: b73b9a6 https://github.com/thesofproject/sof/commits/debug-ipc-timeout
   Topology: https://github.com/thesofproject/sof/commits/debug-ipc-timeout . Same as above SOF repo
   file: tools/topology/sof-hda-generic.m4, sof-hda-generic-2ch.tplg
   platform: CML laptop with ALC3204 codec

   dmesg

   [  656.975512] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  656.975552] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.001369] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.001400] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.027676] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.027708] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.053661] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.053694] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.079718] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.079757] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.105711] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.105751] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.119239] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60010000 size 108
   [  657.119267] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [  657.119542] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [  657.119558] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [  657.119564] sof-audio-pci 0000:00:1f.3: error: hw params ipc failed for stream 1
   [  657.119570] sof-audio-pci 0000:00:1f.3: ASoC: 0000:00:1f.3 hw params failed: -110
   [  657.119584]  HDA Analog: ASoC: hw_params FE failed -110
   [  657.119612] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 1
   [  657.119619] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
   [  657.131397] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.131437] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.157698] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.157738] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.183722] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.183761] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.209720] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.209760] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.235720] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.235760] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  657.261728] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  657.261768] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   

   sof-logger

    CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
       0      2          DAI 2.13    141139694.531250 141139696.000000      src/audio/dai.c:519 	dai_comp_trigger(), START
       0      2          DMA         141140871.041667      1176.510376 intel/cavs/hda-dma.c:533 	hda-dmac: 6 channel 0 -> start
       0      2          DMA         141140875.416667         4.375000 intel/cavs/hda-dma.c:369 	hda-dmac: 6 channel 0 -> enable
       0      2       BUFFER         141142122.447917      1247.031250   src/audio/buffer.c:118 	comp_update_buffer_produce(), no bytes to produce
       0      1         COMP         141142127.343750         4.895833 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      2         COMP         141142132.656250         5.312500 of/audio/component.h:724 	comp_overrun(), ((dev->comp.id << 16) | sink->free) = 655360, ((min_bytes << 16) | copy_bytes) = 0
       0      2         PIPE 2.14    141142136.406250         3.750000 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141142140.729167         4.322917      src/audio/dai.c:508 	dai_comp_trigger(), command = 8
       0      2          DAI 2.13    141142144.479167         3.750000      src/audio/dai.c:566 	dai_comp_trigger(), XRUN
       0      2          DAI 2.13    141142147.968750         3.489583      src/audio/dai.c:572 	dai_comp_trigger(), PAUSE/STOP
       0      2          DMA         141142151.875000         3.906250 intel/cavs/hda-dma.c:627 	hda-dmac: 6 channel 0 -> stop
       0      1       VOLUME         141142158.906250         7.031250   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         141142164.375000         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 13, dir = 0
       0      1         PIPE 2.14    141142170.885417         6.510417 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      2         PIPE 2.14    141142175.937500         5.052083 src/audio/pipeline.c:352 	pipeline_prepare()
       0      2          DAI 2.13    141142179.895833         3.958333      src/audio/dai.c:445 	dai_prepare()
       0      2         PIPE 2.14    141142184.427083         4.531250 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141142188.593750         4.166667      src/audio/dai.c:508 	dai_comp_trigger(), command = 1
       0      2          DAI 2.13    141142192.343750         3.750000      src/audio/dai.c:519 	dai_comp_trigger(), START
       0      2          DMA         141143370.677083      1178.333374 intel/cavs/hda-dma.c:533 	hda-dmac: 6 channel 0 -> start
       0      2          DMA         141143376.562500         5.885417 intel/cavs/hda-dma.c:369 	hda-dmac: 6 channel 0 -> enable
       0      2       BUFFER         141144622.500000      1245.937500   src/audio/buffer.c:118 	comp_update_buffer_produce(), no bytes to produce
       0      1         COMP         141144627.291667         4.791667 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      2         COMP         141144632.552083         5.260417 of/audio/component.h:724 	comp_overrun(), ((dev->comp.id << 16) | sink->free) = 655360, ((min_bytes << 16) | copy_bytes) = 0
       0      2         PIPE 2.14    141144636.250000         3.697917 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141144640.260417         4.010417      src/audio/dai.c:508 	dai_comp_trigger(), command = 8
       0      2          DAI 2.13    141144643.802083         3.541667      src/audio/dai.c:566 	dai_comp_trigger(), XRUN
       0      2          DAI 2.13    141144647.239583         3.437500      src/audio/dai.c:572 	dai_comp_trigger(), PAUSE/STOP
       0      2          DMA         141144650.937500         3.697917 intel/cavs/hda-dma.c:627 	hda-dmac: 6 channel 0 -> stop
       0      1       VOLUME         141144657.760417         6.822917   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         141144663.229167         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 13, dir = 0
       0      1         PIPE 2.14    141144669.843750         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      2         PIPE 2.14    141144674.843750         5.000000 src/audio/pipeline.c:352 	pipeline_prepare()
       0      2          DAI 2.13    141144678.593750         3.750000      src/audio/dai.c:445 	dai_prepare()
       0      2         PIPE 2.14    141144683.072917         4.479167 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141144686.927083         3.854167      src/audio/dai.c:508 	dai_comp_trigger(), command = 1
       0      2          DAI 2.13    141144690.468750         3.541667      src/audio/dai.c:519 	dai_comp_trigger(), START
       0      2          DMA         141145871.093750      1180.625000 intel/cavs/hda-dma.c:533 	hda-dmac: 6 channel 0 -> start
       0      2          DMA         141145875.729167         4.635417 intel/cavs/hda-dma.c:369 	hda-dmac: 6 channel 0 -> enable
       0      2       BUFFER         141147122.864583      1247.135376   src/audio/buffer.c:118 	comp_update_buffer_produce(), no bytes to produce
       0      1         COMP         141147128.072917         5.208333 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      2         COMP         141147133.750000         5.677083 of/audio/component.h:724 	comp_overrun(), ((dev->comp.id << 16) | sink->free) = 655360, ((min_bytes << 16) | copy_bytes) = 0
       0      2         PIPE 2.14    141147137.552083         3.802083 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141147141.875000         4.322917      src/audio/dai.c:508 	dai_comp_trigger(), command = 8
       0      2          DAI 2.13    141147145.989583         4.114583      src/audio/dai.c:566 	dai_comp_trigger(), XRUN
       0      2          DAI 2.13    141147149.583333         3.593750      src/audio/dai.c:572 	dai_comp_trigger(), PAUSE/STOP
       0      2          DMA         141147153.541667         3.958333 intel/cavs/hda-dma.c:627 	hda-dmac: 6 channel 0 -> stop
       0      1       VOLUME         141147160.885417         7.343750   src/audio/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         141147166.510417         5.625000 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 13, dir = 0
       0      1         PIPE 2.14    141147173.072917         6.562500 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      2         PIPE 2.14    141147178.177083         5.104167 src/audio/pipeline.c:352 	pipeline_prepare()
       0      2          DAI 2.13    141147182.447917         4.270833      src/audio/dai.c:445 	dai_prepare()
       0      2         PIPE 2.14    141147187.135417         4.687500 src/audio/pipeline.c:579 	pipeline_trigger()
       0      2          DAI 2.13    141147191.302083         4.166667      src/audio/dai.c:508 	dai_comp_trigger(), command = 1
       0      2          DAI 2.13    141147195.104167         3.802083      src/audio/dai.c:519 	dai_comp_trigger(), START
       0      2          DMA         141148371.093750      1175.989624 intel/cavs/hda-dma.c:533 	hda-dmac: 6 channel 0 -> start
       0      2          DMA         141148375.572917         4.479167 intel/cavs/hda-dma.c:369 	hda-dmac: 6 channel 0 -> enable
       0      2       BUFFER         141149622.552083      1246.979126   src/audio/buffer.c:118 	comp_update_buffer_produce(), no bytes to produce
       0      1         COMP         141149627.552083         5.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      2         COMP         141149632.968750         5.416667 of/audio/component.h:724 	comp_overrun(), ((dev->comp.id << 16) | sink->free) = 655360, ((min_bytes << 16) | copy_bytes) = 0
   

   ipctimeout_dmesg.log
   logger_ipctimeout.log
8. YvonneYang2 commented on May 22, 2019

   

   YvonneYang2

   on May 22, 2019

   More actions

   Issues occurred during 1000 times playback(aplay -d 1) stress test .

   Environment
   Firmware: 5fa2426 https://github.com/thesofproject/sof/tree/master
   Kernel: b58f448 https://github.com/thesofproject/linux/tree/release/sof-v5.0
   Topology: file: tools/topology/sof-hda-generic.tplg, sof-hda-generic-2ch.tplg same as firmware
   Kconfig: https://sof-ci-storage.sh.intel.com/daily/20190521/release/linux/topic/sof-dev/901cd2a5-2/config-daily-hda-20190521.txt
   platform ：CML laptop

   dmesg

   [  724.881344] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60010000: GLB_STREAM_MSG: PCM_PARAMS
   [  724.881351] sof-audio-pci 0000:00:1f.3: pcm: stream dir 0, posn mailbox offset is 790528
   [  724.887273] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
   [  724.887291] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
   [  725.191325] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60040000 size 12
   [  725.191351] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [  725.191686] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [  725.191699] sof-audio-pci 0000:00:1f.3: error: host status 0x80000000 dsp status 0x90020000 mask 0x00000003
   [  725.191703] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
   [  725.191711]  HDA Analog: ASoC: trigger FE failed -110
   [  725.191722] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
   [  725.191746] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
   [  725.191971] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
   [  725.191980] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  725.192021] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  725.192115] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  725.192146] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  725.681336] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   [  725.681369] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
   [  725.799544] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
   [  725.799555] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
   [  726.103296] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
   [  726.103317] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
   [  726.103576] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
   [  726.103586] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [  726.103590] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
   [  726.103747] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0
   [  726.180963] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
   

   error logger

       0      1         COMP         647945558.645833 647945536.000000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647945605.781250        47.135418 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647945611.250000         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647945618.020833         6.770833 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647949307.812500      3689.791748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647949352.760417        44.947918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647949358.020833         5.260417 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647949364.635417         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647953057.864583      3693.229248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647953102.760417        44.895832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647953108.020833         5.260417 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647953114.635417         6.614583 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647956807.916667      3693.281250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647956852.812500        44.895832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647956858.177083         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647956864.635417         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647960558.229167      3693.593750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647960603.177083        44.947918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647960608.489583         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647960614.947917         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647964307.916667      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
       0      1       VOLUME         647964352.916667        45.000000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
       0      1         PIPE         647964358.125000         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
       0      1         PIPE 1.6     647964364.583333         6.458333 src/audio/pipeline.c:843 	pipeline_xrun_recover()
       0      1         COMP         647968058.072917      3693.489502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
   

   playback_failed_353th_error_logger.log
   playback_failed_353th_logger.log
   playback_dmesg.log
9. ClarexZhou commented on May 24, 2019

   

   ClarexZhou

   on May 24, 2019

   Author

   More actions

   Error occurred during S3 with playback stress test.

   Environment
   Kernel: 9453da1 https://github.com/lyakh/linux/tree/ipc-timeout (with PR895)
   FW:b527fd7 https://github.com/lyakh/sof/tree/ipc-timeout (with PR1354)
   Topology:Same as above FW repo, file: tools/topology/sof-hda-generic.m4, sof-hda-generic-2ch.tplg
   kconfig:
   unset CONFIG_SND_SOC_SOF_DEBUG_ENABLE_FIRMWARE_TRACE
   unset CONFIG_SND_SOC_SOF_DEBUG_ENABLE_DEBUGFS_CACHE

   s3_test_14_dmesg.log
   s3_test_14_logger_error.log

    CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
       0      1         COMP         172202696.770833 172202704.000000 rc/audio/component.c:140 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_START
       0      1          IPC         172202701.302083         4.531250 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 1
       0      1          IPC         172202706.406250         5.104167    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x40000 failed -22
       0      1         COMP         172580911.458333    378205.062500 rc/audio/component.c:161 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_STOP
       0      1          IPC         172580915.572917         4.114583 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 0
       0      1          IPC         172580920.677083         5.104167    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x50000 failed -22
   
   

   [ 7295.580543] systemd[1]: Starting Network Name Resolution...
   [ 7295.583726] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
   [ 7295.583747] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
   [ 7295.599397] systemd[1]: Started Network Name Resolution.
   [ 7295.661268] PM: suspend exit
   [ 7295.695959] usb 1-1: new high-speed USB device number 9 using xhci_hcd
   [ 7295.823226] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=85.36
   [ 7295.823227] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
   [ 7295.823228] usb 1-1: Product: USB2.0 Hub
   [ 7295.824481] hub 1-1:1.0: USB hub found
   [ 7295.824755] hub 1-1:1.0: 4 ports detected
   [ 7295.885952] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60040000 size 12
   [ 7295.885962] sof-audio-pci 0000:00:1f.3: unknown ROM status value 0000001c
   [ 7295.886292] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000086 trace 0x00000086
   [ 7295.886303] sof-audio-pci 0000:00:1f.3: error: host status 0x80000000 dsp status 0x00000000 mask 0x00000003
   [ 7295.886305]  HDA Analog: ASoC: trigger FE failed -110
   [ 7295.886309] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
   [ 7295.886318] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
   [ 7295.886471] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60050000 size 12
   [ 7295.886472]  HDA Analog: ASoC: trigger FE failed -22
   [ 7296.083419] systemd[1]: systemd-journald.service: Main process exited, code=dumped, status=6/ABRT
   [ 7296.083455] systemd[1]: systemd-journald.service: Failed with result 'watchdog'.
   [ 7296.083791] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart.
   [ 7296.102908] usb 1-1.1: new high-speed USB device number 10 using xhci_hcd
   [ 7296.111042] systemd-journald[19336]: File /var/log/journal/c40c465ac40b49188d6908d8582430d7/system.journal corrupted or uncleanly shut down, renaming and replacing.
   [ 7296.147884] systemd-journald[19336]: File /var/log/journal/c40c465ac40b49188d6908d8582430d7/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
   [ 7296.196164] usb 1-1.1: New USB device found, idVendor=0b95, idProduct=7720, bcdDevice= 0.01
   [ 7296.196166] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
   [ 7296.196167] usb 1-1.1: Product: AX88772A
   [ 7296.196169] usb 1-1.1: Manufacturer: ASIX Elec. Corp.
   [ 7296.196169] usb 1-1.1: SerialNumber: 0004C4
   [ 7296.494534] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
   [ 7296.494543] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
   [ 7296.614743] asix 1-1.1:1.0 eth0: register 'asix' at usb-0000:00:14.0-1.1, ASIX AX88772 USB 2.0 Ethernet, 00:0e:c6:b7:2c:8c
   [ 7296.680219] usb 1-1.4: new low-speed USB device number 11 using xhci_hcd
   [ 7296.761109] usb 1-1.4: New USB device found, idVendor=0461, idProduct=4d15, bcdDevice= 2.00
   [ 7296.761114] usb 1-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
   [ 7296.761117] usb 1-1.4: Product: USB Optical Mouse
   [ 7296.767356] input: USB Optical Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:0461:4D15.0003/input/input11
   [ 7296.767610] hid-generic 0003:0461:4D15.0003: input,hidraw0: USB HID v1.11 Mouse [USB Optical Mouse] on usb-0000:00:14.0-1.4/input0
   [ 7296.783251] asix 1-1.1:1.0 enx000ec6b72c8c: renamed from eth0
   [ 7296.797938] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
   [ 7296.797955] sof-audio-pci 0000:00:1f.3: unknown ROM status value 0000001c
   [ 7296.798338] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000087 trace 0x00000087
   [ 7296.798355] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 7296.798384] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
   [ 7297.102202] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
   [ 7297.102218] sof-audio-pci 0000:00:1f.3: unknown ROM status value 0000001c
   [ 7297.102601] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000087 trace 0x00000087
   [ 7297.102616] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
   [ 7297.102621] sof-audio-pci 0000:00:1f.3: error: failed to set dai config for Analog Playback and Capture
   [ 7297.102726] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0
   
   
10. keyonjie commented on May 24, 2019

    

    keyonjie

    on May 24, 2019

    Contributor

    More actions

    [ 7297.102726] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0

    @ClarexZhou can you paste the log of 13th iteration also? I doubt error happened earlier from the log you pasted(14th one).
11. ClarexZhou commented on May 24, 2019

    

    ClarexZhou

    on May 24, 2019

    Last edited by ClarexZhou

    Author

    More actions

    @keyonjie previous logs are already cleaned. will past once reproduced again.

    And ipc error for 0x60040000 size 12 is occurred during S3 with playback test in 13th. Also add previous logs.
    s3_test_12_dmesg.log
    s3_test_12_logger_error.log
    s3_test_13_dmesg.log
    s3_test_13_logger_error.log

     CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
        0      1         COMP          14412256.614583  14412257.000000 rc/audio/component.c:140 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_START
        0      1          IPC          14412261.145833         4.531250 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 1
        0      1          IPC          14412275.416667        14.270833    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x40000 failed -22
        0      1         COMP          14412511.354167       235.937500 rc/audio/component.c:161 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_STOP
        0      1          IPC          14412515.468750         4.114583 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 0
        0      1          IPC          14412520.572917         5.104167    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x50000 failed -22
        0      1          DMA          15170268.281250    757747.687500 intel/cavs/hda-dma.c:639 	hda-dmac: 5 invalid channel -1
    
    

    [  269.140855] PM: suspend entry (s2idle)
    [  269.140858] PM: Syncing filesystems ... done.
    [  269.142088] Freezing user space processes ... (elapsed 0.002 seconds) done.
    [  269.144551] OOM killer disabled.
    [  269.144552] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
    [  269.145900] printk: Suspending console(s) (use no_console_suspend to debug)
    [  269.249382] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 5
    [  269.249414] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [  269.249563] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [  269.249572] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
    [  269.249808] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60030000: GLB_STREAM_MSG: PCM_FREE
    [  269.249811] sof-audio-pci 0000:00:1f.3: In hda_link_pcm_trigger cmd=5
    [  269.249814] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [  269.249988] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [  270.267569] PM: Some devices failed to suspend, or early wake event detected
    [  270.267639] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 5:0
    [  270.267696] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 6:0
    [  270.267710] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 7:0
    [  270.470813] iwlwifi 0000:00:14.3: BIOS contains WGDS but no WRDS
    [  272.330716] OOM killer enabled.
    [  272.330716] Restarting tasks ... done.
    [  272.338738] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
    [  272.338753] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
    [  272.338926] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60040000 size 12
    [  272.338948]  HDA Analog: ASoC: trigger FE failed -22
    [  272.338949] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
    [  272.338957] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [  272.339109] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60050000 size 12
    [  272.339111]  HDA Analog: ASoC: trigger FE failed -22
    [  272.403217] PM: suspend exit
    [  272.502221] asix 1-1.1:1.0 enx000ec6b72c8c: link up, 100Mbps, full-duplex, lpa 0xCDE1
    [  272.945156] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
    [  272.945166] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
    [  272.945383] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60030000: GLB_STREAM_MSG: PCM_FREE
    [  272.945416] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [  272.945629] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [  272.945756] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0
    
    
12. keyonjie commented on May 24, 2019

    

    keyonjie

    on May 24, 2019

    Contributor

    More actions

    @ClarexZhou yes, better to paste logs for iteration:
    [n-1]: normal iteration(no any errors in dmesg and sof-logger) right before the the error happen, and
    [n]: the first error iteration.

    BTW, better to apply thesofproject/linux#888 to survive the sof-logger error logs.
13. ClarexZhou commented on May 27, 2019

    

    ClarexZhou

    on May 27, 2019

    Author

    More actions

    Track ipc error in #1477
14. ClarexZhou commented on May 28, 2019

    

    ClarexZhou

    on May 28, 2019

    Author

    More actions

    Error occurred during s3 with playback stress test at 29th.
    Environment
    Kernel: https://github.com/lyakh/linux/tree/ipc-timeout commit 9453da1 (with PR895)
    FW:https://github.com/lyakh/sof/tree/ipc-timeout commit 47dc14d (with PR1354 and PR1470)
    Kconfig: disable CONFIG_SND_SOC_SOF_DEBUG_ENABLE_DEBUGFS_CACHE in kernel config.

     CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
        0      1          EDF         14585734516.197918              nan c/lib/edf_schedule.c:359 	unexpected task state 5 at edf_task completion
        0      1         COMP         14676669603.281250  90935088.000000 rc/audio/component.c:140 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_START
        0      1          IPC         14676669609.635418         6.354167 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 1
        0      1          IPC         14676669616.614584         6.979167    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x40000 failed -22
        0      1         COMP         14677056627.500000    387010.875000 rc/audio/component.c:161 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_STOP
        0      1          IPC         14677056633.489584         5.989583 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 0
        0      1          IPC         14677056640.364584         6.875000    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x50000 failed -22
    
    

    [  421.876128] PM: suspend entry (s2idle)
    [  421.876132] PM: Syncing filesystems ... done.
    [  421.879257] Freezing user space processes ... 
    [  424.355763] Freezing of tasks aborted after 2.476 seconds (1 tasks refusing to freeze, wq_busy=0):
    [  424.355772] OOM killer enabled.
    [  424.355774] Restarting tasks ... done.
    [  424.410778] PM: suspend exit
    [  425.805657] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
    [  425.805678] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
    [  426.113457] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60040000 size 12
    [  426.113475] sof-audio-pci 0000:00:1f.3: unknown ROM status value 00000027
    [  426.113717] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000087 trace 0x00000087
    [  426.113727] sof-audio-pci 0000:00:1f.3: error: host status 0x80000000 dsp status 0x90020000 mask 0x00000003
    [  426.113732] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
    [  426.113886]  HDA Analog: ASoC: trigger FE failed -110
    [  426.113889] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113896] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113900] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113904] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113907] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113912] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113914] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.113943] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
    [  426.114103] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    [  426.114141] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [  426.114167] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114407] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114417] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114427] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114450] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114454] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114481] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114558] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114767] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114796] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114802] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114809] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114823] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114854] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.114900] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115202] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115242] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115292] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115302] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [  426.115316] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115427] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60050000 size 12
    [  426.115438]  HDA Analog: ASoC: trigger FE failed -22
    [  426.115561] sof-audio-pci 0000:00:1f.3: error: trace IO error
    [  426.115592] sof-audio-pci 0000:00:1f.3: error: trace IO error
    
    

    s3_test_28_logger_all.log
    s3_test_28_logger_error.log
    s3_test_29_logger_all.log
    s3_test_29_logger_error.log
    test_28.log
    test_29.log
15. lgirdwood

    changed the title [-][BUG][CML]IPC timed out for '0x60040000: GLB_STREAM_MSG: TRIG_START'[/-] [+][BUG][CML] IPC timed out for '0x60040000: GLB_STREAM_MSG: TRIG_START due to pipeline copy errors'[/+] on May 28, 2019
16. lgirdwood commented on May 28, 2019

    

    lgirdwood

    on May 28, 2019

    Member

    More actions

    @ClarexZhou your last comment is a different bug, can you open a new issue for it. Your last comment looks like a FW crash as I can see a scheduler error message which puts pipeline into an inconsistent state..

    CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
        0      1          EDF         14585734516.197918              nan c/lib/edf_schedule.c:359 	unexpected task state 5 at edf_task completion
        0      1         COMP         14676669603.281250  90935088.000000 rc/audio/component.c:140 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_START
        0      1          IPC         14676669609.635418         6.354167 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 1
        0      1          IPC         14676669616.614584         6.979167    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x40000 failed -22
        0      1         COMP         14677056627.500000    387010.875000 rc/audio/component.c:161 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_STOP
        0      1          IPC         14677056633.489584         5.989583 src/audio/pipeline.c:606 	pipeline_trigger() error: ret = -22, host->comp.id = 1, cmd = 0
        0      1          IPC         14677056640.364584         6.875000    src/ipc/handler.c:513 	ipc: comp 1 trigger 0x50000 failed -22
    
17. ClarexZhou commented on May 28, 2019

    

    ClarexZhou

    on May 28, 2019

    Author

    More actions

    keep monitor, and will report new issue once reproduce the scheduler error message caused ipc timed out issue again.
18. ClarexZhou commented on May 28, 2019

    

    ClarexZhou

    on May 28, 2019

    Author

    More actions

    Error occurred during aplay stress test at 25th.

    Environment
    Kernel: https://github.com/lyakh/linux/tree/ipc-timeout commit 9453da1 (with PR895)
    FW:https://github.com/lyakh/sof/tree/ipc-timeout commit 47dc14d (with PR1354 and PR1470)
    Kconfig: disable CONFIG_SND_SOC_SOF_DEBUG_ENABLE_DEBUGFS_CACHE in kernel config.

     CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
        0      1         COMP         111679722066.145844              nan rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679722113.125000        46.979168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679722118.593750         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679722125.416672         6.822917 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679725814.635422      3689.218750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679725860.781250        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679725866.093750         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679725872.760422         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679729564.531250      3691.770752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679729610.729172        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679729616.197922         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679729622.916672         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679733314.427094      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679733360.625000        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679733366.197922         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679733372.760422         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679737064.687500      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679737110.885422        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679737116.458344         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679737123.020844         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679740814.479172      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679740860.989594        46.510418 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679740866.302094         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679740872.812500         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679744564.739594      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679744610.885422        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679744616.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679744623.072922         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679748314.531250      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679748360.781250        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679748366.093750         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679748372.916672         6.822917 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679752064.531250      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679752110.937500        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679752116.302094         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679752123.072922         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679755814.739594      3691.666748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679755860.989594        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679755866.458344         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679755873.177094         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679759564.687500      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679759610.937500        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679759616.250000         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679759622.968750         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679763314.479172      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679763360.625000        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679763365.937500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679763372.656250         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679767064.635422      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679767110.833344        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679767116.197922         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679767122.864594         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679770814.427094      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679770859.791672        45.364582 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679770865.000000         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679770871.614594         6.614583 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679774564.479172      3692.864502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679774609.583344        45.104168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679774614.947922         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679774621.406250         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679778314.843750      3693.437500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679778360.260422        45.416668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679778365.468750         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679778371.927094         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679782064.687500      3692.760498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679782110.104172        45.416668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679782115.312500         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679782121.770844         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679785814.947922      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679785861.250000        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679785866.562500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679785873.281250         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679789564.687500      3691.406250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679789611.041672        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679789616.510422         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679789623.177094         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679793314.687500      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679793360.989594        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679793366.614594         5.625000 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679793373.177094         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679797064.531250      3691.354248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679797110.729172        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679797116.302094         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679797122.864594         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679800814.427094      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679800860.937500        46.510418 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679800866.250000         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679800872.760422         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679804564.739594      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679804610.833344        46.093750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679804616.145844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679804623.020844         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679808314.479172      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679808360.729172        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679808366.041672         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679808372.916672         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679812064.687500      3691.770752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679812110.989594        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679812116.302094         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679812123.072922         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679815814.583344      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679815861.041672        46.458332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679815866.510422         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679815873.177094         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679819564.739594      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679819611.145844        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679819616.458344         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679819623.177094         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679823314.687500      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679823360.937500        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679823366.250000         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679823372.916672         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679827064.739594      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679827110.781250        46.041668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679827116.093750         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679827122.760422         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679830814.739594      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679830860.677094        45.937500 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679830865.885422         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679830872.447922         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679834564.583344      3692.135498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679834609.739594        45.156250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679834615.104172         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679834621.562500         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679838314.739594      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679838360.208344        45.468750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679838365.416672         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679838371.875000         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679842064.739594      3692.864502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679842109.895844        45.156250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679842115.104172         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679842121.562500         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679845814.791672      3693.229248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679845859.947922        45.156250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679845865.104172         5.156250 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679845871.718750         6.614583 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679849564.375000      3692.656250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679849609.843750        45.468750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679849615.208344         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679849621.822922         6.614583 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679853314.375000      3692.552002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679853360.468750        46.093750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679853366.041672         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679853372.604172         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679857064.531250      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679857110.625000        46.093750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679857116.145844         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679857122.708344         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679860814.687500      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679860861.406250        46.718750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679860866.718750         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679860873.229172         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679864564.583344      3691.354248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679864610.625000        46.041668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679864615.937500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679864622.760422         6.822917 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679868314.843750      3692.083252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679868361.041672        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679868366.354172         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679868373.229172         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679872064.375000      3691.145752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679872110.729172        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679872116.041672         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679872122.812500         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679875814.427094      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679875860.677094        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679875866.145844         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679875872.812500         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679879564.635422      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679879610.885422        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679879616.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679879622.864594         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679883314.479172      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679883360.885422        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679883366.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679883372.864594         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679887064.479172      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679887110.677094        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679887115.989594         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679887122.656250         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679890814.583344      3691.927002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679890860.885422        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679890866.093750         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679890872.708344         6.614583 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679894564.739594      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679894609.947922        45.208332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679894615.312500         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679894621.770844         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679898314.687500      3692.916748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679898360.104172        45.416668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679898365.312500         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679898371.770844         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679902064.791672      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679902109.791672        45.000000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679902115.000000         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679902121.458344         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679905814.895844      3693.437500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679905860.468750        45.572918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679905865.677094         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679905872.291672         6.614583 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679909564.531250      3692.239502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679909609.791672        45.260418 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679909615.156250         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679909621.718750         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679913314.375000      3692.656250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679913359.427094        45.052082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679913364.947922         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679913371.406250         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679917064.479172      3693.072998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679917109.791672        45.312500 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679917115.208344         5.416667 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679917121.666672         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679920814.687500      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679920861.302094        46.614582 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679920866.614594         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679920873.125000         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679924564.739594      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679924611.197922        46.458332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679924616.510422         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679924623.385422         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679928314.739594      3691.354248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679928360.989594        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679928366.302094         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679928373.125000         6.822917 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679932064.479172      3691.354248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679932110.833344        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679932116.145844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679932122.916672         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679935814.791672      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679935861.145844        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679935866.614594         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679935873.281250         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679939564.791672      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679939611.093750        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679939616.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679939623.125000         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679943314.687500      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679943360.885422        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679943366.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679943372.864594         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679947064.375000      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679947110.572922        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679947115.885422         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679947122.604172         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679950814.635422      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679950861.614594        46.979168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679950866.822922         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679950873.385422         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679954564.791672      3691.406250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679954611.041672        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679954616.510422         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679954623.072922         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679958314.531250      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679958361.093750        46.562500 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679958366.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679958372.968750         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679962064.739594      3691.770752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679962110.885422        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679962116.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679962122.760422         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679965814.739594      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679965861.093750        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679965866.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679965873.125000         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679969564.791672      3691.666748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679969611.093750        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679969616.562500         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679969623.281250         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679973314.791672      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679973361.093750        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679973366.666672         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679973373.229172         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679977064.479172      3691.250000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679977110.677094        46.197918 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679977116.197922         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679977122.760422         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679980814.583344      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679980861.093750        46.510418 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679980866.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679980872.916672         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679984564.739594      3691.822998 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679984611.458344        46.718750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679984616.770844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679984623.645844         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679988314.635422      3690.989502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679988361.093750        46.458332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679988366.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679988373.281250         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679992064.583344      3691.302002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679992110.833344        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679992116.145844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679992122.916672         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679995814.375000      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679995860.677094        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679995866.145844         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679995872.864594         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111679999564.739594      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111679999611.041672        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111679999616.406250         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111679999623.125000         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680003314.635422      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680003360.677094        46.041668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680003365.989594         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680003372.656250         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680007064.687500      3692.031250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680007110.833344        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680007116.145844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680007122.864594         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680010814.739594      3691.875000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680010861.093750        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680010866.406250         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680010873.125000         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680014564.375000      3691.250000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680014609.531250        45.156250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680014614.895844         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680014621.354172         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680018314.531250      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680018360.000000        45.468750 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680018365.208344         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680018371.666672         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680022064.531250      3692.864502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680022110.833344        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680022116.145844         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680022122.708344         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680025815.000000      3692.291748 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680025861.562500        46.562500 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680025866.822922         5.260417 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680025873.489594         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680029564.739594      3691.250000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680029611.041672        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680029616.510422         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680029623.229172         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680033314.791672      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680033361.093750        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680033366.718750         5.625000 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680033373.281250         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680037064.427094      3691.145752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680037110.677094        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680037116.197922         5.520833 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680037122.760422         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680040814.739594      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680040861.145844        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680040866.458344         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680040872.968750         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680044564.427094      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680044610.885422        46.458332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680044616.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680044623.072922         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680048314.479172      3691.406250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680048361.145844        46.666668 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680048366.458344         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680048373.333344         6.875000 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680052064.583344      3691.250000 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680052111.145844        46.562500 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680052116.458344         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680052123.229172         6.770833 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680055814.635422      3691.406250 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680055861.041672        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680055866.510422         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680055873.177094         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680059564.687500      3691.510498 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680059610.937500        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680059616.250000         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680059622.916672         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680063314.479172      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680063360.625000        46.145832 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680063365.937500         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680063372.656250         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680067064.635422      3691.979248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680067110.885422        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680067116.197922         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680067122.916672         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680070814.635422      3691.718750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680070861.041672        46.406250 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680070866.354172         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680070873.072922         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680074564.635422      3691.562500 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680074609.843750        45.208332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680074615.208344         5.364583 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680074621.666672         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680078314.843750      3693.177002 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680078360.208344        45.364582 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680078365.416672         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680078371.875000         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680082064.843750      3692.968750 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680082110.052094        45.208332 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680082115.260422         5.208333 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680082121.718750         6.458333 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680085814.739594      3693.020752 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680085860.104172        45.364582 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680085865.364594         5.260417 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680085872.031250         6.666667 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680089564.635422      3692.604248 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680089610.937500        46.302082 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680089616.406250         5.468750 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680089623.125000         6.718750 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680093314.583344      3691.458252 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680093360.937500        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680093366.510422         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680093373.072922         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680097064.687500      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680097110.937500        46.250000 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680097116.510422         5.572917 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680097123.072922         6.562500 src/audio/pipeline.c:844 	pipeline_xrun_recover()
        0      1         COMP         111680100814.687500      3691.614502 rc/audio/component.c:261 	comp_get_copy_limits() error: sink component buffer has not enough free bytes for copy
        0      1       VOLUME         111680100861.041672        46.354168 udio/volume/volume.c:510 	volume_copy(): Failed comp_get_copy_limits()
        0      1         PIPE         111680100866.354172         5.312500 src/audio/pipeline.c:732 	pipeline_copy() error: ret = -5, start->comp.id = 2, dir = 1
        0      1         PIPE 1.6     111680100872.864594         6.510417 src/audio/pipeline.c:844 	pipeline_xrun_recover()
    

    [ 2451.211486] sof-audio-pci 0000:00:1f.3: period min 192 max 16384 bytes
    [ 2451.211490] sof-audio-pci 0000:00:1f.3: period count 2 max 16
    [ 2451.211494] sof-audio-pci 0000:00:1f.3: buffer max 65536 bytes
    [ 2451.212035] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [ 2451.212189] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [ 2451.212218] sof-audio-pci 0000:00:1f.3: format_val=49, rate=48000, ch=2, format=10
    [ 2451.212232] sof-audio-pci 0000:00:1f.3: pcm: hw params stream 0 dir 0
    [ 2451.212238] sof-audio-pci 0000:00:1f.3: generating page table for 00000000a06333b1 size 0xffc0 pages 16
    [ 2451.212267] sof-audio-pci 0000:00:1f.3: period_bytes:0x3fc0
    [ 2451.212269] sof-audio-pci 0000:00:1f.3: periods:4
    [ 2451.212288] sof-audio-pci 0000:00:1f.3: stream_tag 1
    [ 2451.212300] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60010000: GLB_STREAM_MSG: PCM_PARAMS
    [ 2451.212452] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60010000: GLB_STREAM_MSG: PCM_PARAMS
    [ 2451.212457] sof-audio-pci 0000:00:1f.3: pcm: stream dir 0, posn mailbox offset is 790528
    [ 2458.233713] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
    [ 2458.233733] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
    [ 2458.538172] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60040000 size 12
    [ 2458.538198] sof-audio-pci 0000:00:1f.3: unknown ROM status value 000000e3
    [ 2458.538456] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000113 trace 0x00000113
    [ 2458.538467] sof-audio-pci 0000:00:1f.3: error: host status 0x80000000 dsp status 0x90020000 mask 0x00000003
    [ 2458.538471] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
    [ 2458.538480]  HDA Analog: ASoC: trigger FE failed -110
    [ 2458.538492] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
    [ 2458.538504] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [ 2458.538697] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
    [ 2458.538714] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    [ 2458.538775] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [ 2458.538857] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    [ 2458.538881] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [ 2459.027398] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    [ 2459.027432] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [ 2459.146432] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
    [ 2459.146444] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
    [ 2459.450146] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
    [ 2459.450169] sof-audio-pci 0000:00:1f.3: error: signature verification failed - code 0000002c
    [ 2459.450426] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000114 trace 0x00000114
    [ 2459.450437] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
    [ 2459.450441] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
    [ 2459.450481] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [ 2459.527263] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    [ 2459.527297] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [ 2459.754184] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
    [ 2459.754209] sof-audio-pci 0000:00:1f.3: error: signature verification failed - code 0000002c
    [ 2459.754476] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000114 trace 0x00000114
    [ 2459.754496] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
    [ 2459.754501] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
    [ 2459.754509] sof-audio-pci 0000:00:1f.3: error: failed to set dai config for Analog Playback and Capture
    [ 2459.754688] sof-audio-pci 0000:00:1f.3: pcm: close stream 0 dir 0
    
    

    aplay_error_25_dmesg.log
    aplay_error_25_logger_all.log
    aplay_error_25_logger_error.log
19. emilchudzik commented on May 31, 2019

    

    emilchudzik

    on May 31, 2019

    Contributor

    More actions

    @ClarexZhou reporting issue on non-official branch (official is master or alpha/beta/pv branches) is only debug, so please report it directly to owner od code.

    Please double check issue on master or cml-hda... branch.
20. jajanusz commented on Jun 17, 2019

    

    jajanusz

    on Jun 17, 2019

    Contributor

    More actions

    @emilchudzik @ClarexZhou Can we close it? Looks like it turned out to be kernel-side issue.
21. ClarexZhou commented on Jun 18, 2019

    

    ClarexZhou

    on Jun 18, 2019

    Author

    More actions

    Close for not reproduced
22. ClarexZhou

    closed this as completedon Jun 18, 2019