Starting Live Log...
info: Live Log process terminated: null
info: CALLMETHOD: system_controller system enableLiveLog false
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: CALLMETHOD: system_controller system enableLiveLog false
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Starting Cleanup of Temporary Directories...
info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
systemd-tmpfiles-clean.service: Succeeded.
Started Cleanup of Temporary Directories.
------------------------------------ BT MESSAGE: BT STATUS: running
info: Disabling plugin serialampcontroller
info: Done.
info: [SERIALAMPCONTROLLER] openSerialPort: Port is now closed.
info: [SERIALAMPCONTROLLER] onStop: closed serial Port
pl2303 ttyUSB0: error sending break = -32
info: Enabling plugin serialampcontroller
info: Loading plugin "serialampcontroller"...
info: Applying required configuration parameters for plugin serialampcontroller
info: Applying required configuration parameters for plugin serialampcontroller
info: PLUGIN START: serialampcontroller
info: [SERIALAMPCONTROLLER] loadI18nStrings: /data/plugins/system_hardware/serialampcontroller/i18n/strings_it.json
info: [SERIALAMPCONTROLLER] loadI18nStrings: it not found. Fallback to en
info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"amps":[{"vendor":"Rotel","model":"A12","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A14","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A11","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Aux1","Tuner","Phono","Bluetooth"],"sourceCmd":["cd!","aux1!","tuner!","phono!","bluetooth!"],"sourceRespPostfix":["cd","aux1","tuner","phono","bluetooth"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"RC-1570","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","PC-USB","XLR"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","pc_usb!","bal_xlr!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","pc_usb","bal_xlr"],"delimiter":"!","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"volume_up!","volDown":"volume_down!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":"get_product_type!"}},{"vendor":"Meridian","model":"DSP420","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Radio","LP","TV","Tape 1","Tape 2","CDR","Cable","DVD","VCR 1","VCR 2","Laser Disc"],"sourceCmd":["CD","RD","LP","TV","T1","T2","CR","CB","DV","V1","V2","LD"],"sourceRespPostfix":["CD","Radio","LP","TV","Tape1","Tape2","CDR","Cable","DVD","VCR1","VCR2","LDisc"],"delimiter":"\r\n","commands":{"powerToggle":"SB","volUp":"VP","volDown":"VM","volValue":"VN##!","mute":"MU"},"responses":[{"rx":"([A-Za-z0-9]{2,5}) *([0-9]{1,2})","cmd":["respSource","respVolume","respMuteOff"]},{"rx":"Mute","cmd":["respMuteOn"]},{"rx":"Standby","cmd":["respPowerOff"]}],"statusRequests":{}}]},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/serialampcontroller/ampCommands.json"}
info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions for 5 Amplifiers.

info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: [SERIALAMPCONTROLLER] listSerialDevices: [{"path":"/dev/ttyAMA0"},{"manufacturer":"Prolific Technology Inc.","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller-if00-port0","vendorId":"067b","productId":"2303","path":"/dev/ttyUSB0"}]
info: [SERIALAMPCONTROLLER] listSerialDevices: found 1 devices.[{"manufacturer":"Prolific Technology Inc.","pnpId":"usb-Prolific_Technology_Inc._USB-Serial_Controller-if00-port0","vendorId":"067b","productId":"2303","path":"/dev/ttyUSB0"}]
info: [SERIALAMPCONTROLLER] setActiveAmp: {"vendor":"Rotel","model":"RC-1570","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","PC-USB","XLR"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","pc_usb!","bal_xlr!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","pc_usb","bal_xlr"],"delimiter":"!","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"volume_up!","volDown":"volume_down!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":"get_product_type!"}}
info: [SERIALAMPCONTROLLER] setActiveAmp: can send these responses: respPowerOn,respPowerOff,respVolume,respMuteOn,respMuteOff,respSource.
info: [SERIALAMPCONTROLLER] openSerialPort: connect to /dev/ttyUSB0 configured with: {"autoOpen":false,"lock":true,"baudRate":115200,"dataBits":8,"stopBits":1,"parity":"none","rtscts":false,"xon":false,"xoff":false,"xany":false}
info: [SERIALAMPCONTROLLER] openSerialPort: Connection established.
error: [SERIALAMPCONTROLLER] openSerialPort: Now trying to open port
info: [SERIALAMPCONTROLLER] alsavolume: Set volume "25"
info: [SERIALAMPCONTROLLER] alsavolume: either Serial Interface, listener or Ampconfig missing
info: [SERIALAMPCONTROLLER] updateVolumeSettings: Amp, serial Interface not yet set or listener not yet active.
info: [SERIALAMPCONTROLLER] onStart: successfully started plugin
info: Done.
pl2303 ttyUSB0: pl2303_get_line_request - failed: -32
info: [SERIALAMPCONTROLLER] openSerialPort: Port is now open. Connecting Parser with delimiter: !
info: [SERIALAMPCONTROLLER] getAmpStatus: sending status requests to Amp
info: [SERIALAMPCONTROLLER] sendStatusRequest: Sent command for "reqModel": get_product_type!
info: [SERIALAMPCONTROLLER] sendStatusRequest: Sent command for "reqPower": get_current_power!
info: [SERIALAMPCONTROLLER] sendStatusRequest: Sent command for "reqVolume": get_volume!
info: [SERIALAMPCONTROLLER] sendStatusRequest: Sent command for "reqMute": get_mute_status!
info: [SERIALAMPCONTROLLER] sendStatusRequest: Sent command for "reqSource": get_current_source!
info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
info: Discovery: Getting this device information
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses

info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root

info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
info: Listing playlists
info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
info: Received Get System Version
info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
info: VolumeController::SetAlsaVolume25
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume40
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume45
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume50
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume40
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: VolumeController::SetAlsaVolume30
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume25
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume20
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
info: VolumeController::SetAlsaVolume10
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
info: MRS: Pushing multiroomSync output update for this device
info: MRS: Pushing multiroomSync output
info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop
process exited with code null