Figure

Hi 诗雨
这个音量条显示不正确的问题，我们应该改过好几个BUG
此问题我在新版本上没有复现，客户使用的是0095版本，已经快一个月了，能使用新版本验证下不，谢谢

本地复现，102版本。14：23复现回弹

■ Current conclusion
通过问题出现是的日志分析，在副屏请求将音量从静音调节为大于 0 的值（非静音）时，中控会先发送一条 mute_off 的音频请求消息，然后再回复一条音量值变更的消息。由于音频服务处理中控消息是串行的，所以在收到 mute_off 消息后先处理，在收到音量值变更回复时，前一条消息还没有处理完成，导致音量值变更回复接收超时（600ms）。最终导致音量回弹现象出现。

日志如下：
05-23 14:23:15.628 1234 4314 D AudioIDCMicroNegotiator: idcm_volume:setIDCMicroVolume
05-23 14:23:15.628 1234 4314 D AudioIDCMicroNegotiator: idcm_common:requestResult method = setVolume parameters = {"functionInfo":{"IDCM_VolumeValue_Media":"4"}}
05-23 14:23:15.628 1234 4314 I MicroServiceManager: requestResult(setVolume) parameters is{"functionInfo":{"IDCM_VolumeValue_Media":"4"}}.
05-23 14:23:15.633 2020 5084 D MicroService: Send message to IDCM:SocketMessageBean{businessData='21930', srcService='FSEM_AudioService', destService='IDCM_CarAudioService', messageType='0', uuid='1684822995628914424', payloadData={"srcService":"FSEM_AudioService","dstService":"IDCM_CarAudioService","messageType":0,"uuid":1684822995628914424,"methodName":"setVolume","parameters":{"functionInfo":{"IDCM_VolumeValue_Media":"4"}}}}
05-23 14:23:15.643 2020 5085 I MicroService: Receive message: SocketMessageBean{businessData='21930', srcService='IDCM_CarAudioService', destService='FSEM_AudioService', messageType='0', uuid='531835616567', payloadData={"srcService":"IDCM_CarAudioService","dstService":"FSEM_AudioService","methodName":"setMute","messageType":0,"uuid":531835616567,"parameters":{"functionInfo":{"IDCM_VolumeMute_Media":"mute_off"}}}}
05-23 14:23:15.643 2020 5085 I MicroService: onRequest(setMute): {"functionInfo":{"IDCM_VolumeMute_Media":"mute_off"}}
05-23 14:23:15.645 1234 2067 D AudioIDCMicroNegotiator: idcm_common:request from IDCM: method = setMute parameters = {"functionInfo":{"IDCM_VolumeMute_Media":"mute_off"}}
05-23 14:23:15.645 1234 2067 D AudioIDCMicroNegotiator: idcm_volume:onSetIDCMicroAudioMute mIsTimeout= false value = mute_off isRequested=false
05-23 14:23:15.645 1234 2067 D AS.AudioService: idcm_volume:onSetIDCMicroAudioMute: mute_off
05-23 14:23:15.658 2020 5086 I MicroService: Receive message: SocketMessageBean{businessData='21930', srcService='IDCM_CarAudioService', destService='FSEM_AudioService', messageType='1', uuid='1684822995628914424', payloadData={"srcService":"IDCM_CarAudioService","dstService":"FSEM_AudioService","methodName":"setVolume","messageType":1,"uuid":1684822995628914424,"parameters":{"functionInfo":{"IDCM_VolumeValue_Media":"4"}}}}
05-23 14:23:15.658 2020 5086 I MicroService: onResponse(setVolume): {"functionInfo":{"IDCM_VolumeValue_Media":"4"}}
05-23 14:23:15.665 0 0 E [ 533.592640] DTC_Display: screen not ready(0), bl_sta(1), lvl(53)
05-23 14:23:16.229 1234 4314 D AudioIDCMicroNegotiator: idcm_volume:setIDCMicroVolume return null
05-23 14:23:16.229 1234 4314 W AS.AudioService: idcm_warn: fail to set Vol: 4, respone: null
05-23 14:23:16.229 1234 4314 W AS.AudioService: idcm_warn: exception: java.lang.Exception: fail to set Vol: 4

■ Next action
已和卿山沟通，目前讨论出的解决方案如下：

方案一：
副屏音量从静音变更为非静音时，中控只回复一条消息。

需要和中控沟通。

方案二：
音频服务在后台线程中处理消息（并行处理）。

需要卿山评估是否可行。

HI 诗雨

优化代码已经投入，请使用5月26日后的版本进行验证，谢谢！

已同步

复现率降低，原先很容易复现，现状复现率约为1/10。
14点24分音量回弹至静音.mp414点24分调节音量，音量条回弹.7z

HI ALL

根据日志分析：
该次问题是由于微服务出现报错导致调节音量失败，然后出现回弹。

103514:06-03 14:24:41.223  1544  8172 D AS.AudioService: idcm_volume:setIDCMicroVolume(stream=3, index=1, calling=com.android.settings)
103515:06-03 14:24:41.223  1544  8172 D AudioIDCMicroNegotiator: idcm_volume:setIDCMicroVolume
103516:06-03 14:24:41.223  1544  8172 D AudioIDCMicroNegotiator: idcm_common:requestResult method = setVolume parameters = {"functionInfo":{"IDCM_VolumeValue_Media":"1"}}
103517:06-03 14:24:41.223  1544  8172 I MicroServiceManager: requestResult(setVolume) parameters is{"functionInfo":{"IDCM_VolumeValue_Media":"1"}}.
103518:06-03 14:24:41.237  2043 29061 E MicroService: DTC_ETH: BaseMicroSocketClient:createClientSocket() 
103519:06-03 14:24:41.237  2043 29061 E MicroService: java.net.ConnectException: failed to connect to /192.168.4.4 (port 9999) from /192.168.4.16 (port 34147): connect failed: ECONNREFUSED (Connection refused)
103520:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.IoBridge.connect(IoBridge.java:188)
103521:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.PlainSocketImpl.socketConnect(PlainSocketImpl.java:142)
103522:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:390)
103523:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:230)
103524:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:212)
103525:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:436)
103526:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.Socket.connect(Socket.java:621)
103527:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.Socket.connect(Socket.java:570)
103528:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.Socket.<init>(Socket.java:450)
103529:06-03 14:24:41.237  2043 29061 E MicroService:     at java.net.Socket.<init>(Socket.java:292)
103530:06-03 14:24:41.237  2043 29061 E MicroService:     at com.thundercomm.microservice.base.BaseMicroSocketClient.createClientSocket(BaseMicroSocketClient.java:20)
103531:06-03 14:24:41.237  2043 29061 E MicroService:     at com.thundercomm.microservice.MicroSocketClient.lambda$sendMessage$0$MicroSocketClient(MicroSocketClient.java:72)
103532:06-03 14:24:41.237  2043 29061 E MicroService:     at com.thundercomm.microservice.MicroSocketClient$$ExternalSyntheticLambda1.run(Unknown Source:4)
103533:06-03 14:24:41.237  2043 29061 E MicroService:     at java.lang.Thread.run(Thread.java:920)
103534:06-03 14:24:41.237  2043 29061 E MicroService: Caused by: android.system.ErrnoException: connect failed: ECONNREFUSED (Connection refused)
103535:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.Linux.connect(Native Method)
103536:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.ForwardingOs.connect(ForwardingOs.java:204)
103537:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.BlockGuardOs.connect(BlockGuardOs.java:157)
103538:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.ForwardingOs.connect(ForwardingOs.java:204)
103539:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.IoBridge.connectErrno(IoBridge.java:202)
103540:06-03 14:24:41.237  2043 29061 E MicroService:     at libcore.io.IoBridge.connect(IoBridge.java:180)
103541:06-03 14:24:41.237  2043 29061 E MicroService:     ... 13 more
104149:06-03 14:24:41.824  1544  8172 D AudioIDCMicroNegotiator: idcm_volume:setIDCMicroVolume return null
104150:06-03 14:24:41.824  1544  8172 W AS.AudioService: idcm_warn: fail to set Vol: 1, respone: null
104151:06-03 14:24:41.824  1544  8172 W AS.AudioService: idcm_warn: exception: java.lang.Exception: fail to set Vol: 1
104209:06-03 14:24:41.853  1544  8172 D AS.AudioService: idcm_volume:==> adjustStreamMute: isStreamMute = false

下一步：
请天颖帮忙分析一下报错的问题，谢谢！

Hi,文亮
根据日志分析是网络连接失败
103519:06-03 14:24:41.237 2043 29061 E MicroService: java.net.ConnectException: failed to connect to /192.168.4.4 (port 9999) from /192.168.4.16 (port 34147): connect failed: ECONNREFUSED (Connection refused)

帮忙分析一下为什么网络会连接出现问题

在JIRA上跟踪， 内部关闭此票。

在FlatBuild_HH_MCE_FSE.M.D.user.01.00.C104.202306100050版本验证通过

验证结果：PASS
验证方法：执行复现步骤数十次未复现bug