pw doesn't set stream from connecting -> paused if PW_STREAM_FLAG_INACTIVE is passed
I'm trying to integrate PW output into an audio app I'm working on, but as of 0.3.13, unless I call pw_stream_set_active(stream, true) the stream I have created never transitions from connecting to paused.
Listening and handling param_changed events with pw_ has no effect. Calling pw_stream_set_active(stream, true) seems to force the stream to finish connecting. Playback works fine however even if the stream is still in a connecting state.
Here's a debug log from just after calling pa_stream_connect. At the end of the log the buffer underrun message is from my onProcess callback, so the stream is presumably running (the node status is updated to running), but this is never reflected in the output.
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:249 pw_load_spa_handle] pw: load lib:'support/libspa-support' factory-name:'support.system'[05/Oct/2020:16:20:06 +1300] [WARNING] [MPRIS.swift:429 appendMetadata(to:)] No metadata available
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:348 impl_init] pw: system 0x7f0344004668: initialized
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:249 pw_load_spa_handle] pw: load lib:'support/libspa-support' factory-name:'support.loop'
[05/Oct/2020:16:20:06 +1300] [DEBUG] [MPRIS.swift:642 Earthquake] MPRIS: PlaybackStatus
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:112 impl_pollfd_create] pw: system 0x7f0344004668: new fd:19
[05/Oct/2020:16:20:06 +1300] [DEBUG] [MPRIS.swift:667 Earthquake] MPRIS: Metadata
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x7f0344004668: new fd:20
[05/Oct/2020:16:20:06 +1300] [INFO] [MPRIS.swift:423 appendMetadata(to:)] Got metadata request
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x7f0344004668: new fd:21
[05/Oct/2020:16:20:06 +1300] [WARNING] [MPRIS.swift:429 appendMetadata(to:)] No metadata available
[05/Oct/2020:16:20:06 +1300] [DEBUG] [loop.c:812 impl_init] pw: loop 0x7f0344004dd8: initialized
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x7f0344004668: new fd:22
[05/Oct/2020:16:20:06 +1300] [DEBUG] [MPRIS.swift:642 Earthquake] MPRIS: PlaybackStatus
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:149 pw_mempool_new] pw: mempool 0x7f034400df90: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:249 pw_load_spa_handle] pw: load lib:'support/libspa-dbus' factory-name:'support.dbus'
[05/Oct/2020:16:20:06 +1300] [DEBUG] [MPRIS.swift:667 Earthquake] MPRIS: Metadata
[05/Oct/2020:16:20:06 +1300] [INFO] [MPRIS.swift:423 appendMetadata(to:)] Got metadata request
[05/Oct/2020:16:20:06 +1300] [WARNING] [MPRIS.swift:429 appendMetadata(to:)] No metadata available
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:131 open_plugin] pw: loaded plugin:'/usr/lib64/spa-0.2/support/libspa-dbus.so'
[05/Oct/2020:16:20:06 +1300] [DEBUG] [dbus.c:403 impl_init] pw: dbus 0x7f034400f248: initialized
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-core.c:408 pw_context_create_core] pw: impl-core 0x7f034400fc20: new pipewire-ryan-141966
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f03440101c0: new PipeWire:Interface:Core 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f03440101c0: registered 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:83 load_module_profile] pw: context 0x7f0344003090: module profile default
[05/Oct/2020:16:20:06 +1300] [DEBUG] [data-loop.c:73 do_loop] pw: data-loop 0x7f0344003e10: enter thread
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:87 load_module_profile] pw: context 0x7f0344003090: loading default profile
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-protocol-native (/usr/lib64/pipewire-0.3/libpipewire-module-protocol-native.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f03440127b0: new PipeWire:Interface:Module 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:70 pw_protocol_new] pw: protocol 0x7f0344012ca0: Created protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Core/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Registry/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Module/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Device/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Node/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Port/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Factory/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Client/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Link/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Core/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Registry/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Module/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Node/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Port/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Factory/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Client/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Link/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-protocol-native.c:1220 pipewire__module_init] pw: protocol-native 0x7f0344012ca0: new debug:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-protocol-native.c:1057 create_server] pw: protocol-native 0x7f0344012ca0: created server 0x7f0344017970
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f0344012580: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f03440127b0: registered 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f0344012580: loaded module: libpipewire-module-protocol-native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-client-node (/usr/lib64/pipewire-0.3/libpipewire-module-client-node.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344019660: new PipeWire:Interface:Module 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f03440199c0: new client-node
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-client-node.c:168 pipewire__module_init] pw: module 0x7f0344002140: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:ClientNode/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:ClientNode/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:Node to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type Spa:Pointer:Interface:Node to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f0344002140: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344019660: registered 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f03440199c0: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034401b800: new PipeWire:Interface:Factory 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034401b800: registered 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f0344002140: loaded module: libpipewire-module-client-node
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-client-device (/usr/lib64/pipewire-0.3/libpipewire-module-client-device.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034401d380: new PipeWire:Interface:Module 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034401d850: new client-device
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-client-device.c:182 pipewire__module_init] pw: module 0x7f034401d150: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal Spa:Pointer:Interface:Device/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type Spa:Pointer:Interface:Device to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f034401d150: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034401d380: registered 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034401d850: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034401ef00: new PipeWire:Interface:Factory 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034401ef00: registered 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f034401d150: loaded module: libpipewire-module-client-device
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-adapter (/usr/lib64/pipewire-0.3/libpipewire-module-adapter.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344020c80: new PipeWire:Interface:Module 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f03440210b0: new adapter
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-adapter.c:315 pipewire__module_init] pw: module 0x7f034400cee0: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f034400cee0: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344020c80: registered 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f03440210b0: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344022450: new PipeWire:Interface:Factory 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344022450: registered 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f034400cee0: loaded module: libpipewire-module-adapter
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-metadata (/usr/lib64/pipewire-0.3/libpipewire-module-metadata.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344024280: new PipeWire:Interface:Module 8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Metadata/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Metadata/3 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f0344024e40: new metadata
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-metadata.c:168 pipewire__module_init] pw: module 0x7f0344024060: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:Metadata to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f0344024060: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344024280: registered 8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f0344024e40: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344026380: new PipeWire:Interface:Factory 9
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344026380: registered 9
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f0344024060: loaded module: libpipewire-module-metadata
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:184 pw_context_load_module] pw: moduledir set to: /usr/lib64/pipewire-0.3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:192 pw_context_load_module] pw: trying to load module: libpipewire-module-session-manager (/usr/lib64/pipewire-0.3/libpipewire-module-session-manager.so) args((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344027f80: new PipeWire:Interface:Module 10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:ClientEndpoint/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:ClientSession/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:EndpointLink/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:EndpointStream/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Endpoint/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Session/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:EndpointLink/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:EndpointStream/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Endpoint/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [protocol.c:152 pw_protocol_add_marshal] pw: protocol 0x7f0344012ca0: Add marshal PipeWire:Interface:Session/0 to protocol PipeWire:Protocol:Native
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402a710: new client-endpoint
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402ab70: new client-session
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402b110: new session
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:Session to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402bb60: new endpoint
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:Endpoint to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402c4f0: new endpoint-stream
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:EndpointStream to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:70 pw_context_create_factory] pw: factory 0x7f034402ce80: new endpoint-link
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1078 pw_context_register_export_type] pw: context 0x7f0344003090: Add export type PipeWire:Interface:EndpointLink to context
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:358 pw_impl_module_update_properties] pw: module 0x7f034400dc60: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344027f80: registered 10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402a710: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034402e060: new PipeWire:Interface:Factory 11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034402e060: registered 11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402ab70: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034402ebb0: new PipeWire:Interface:Factory 12
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034402ebb0: registered 12
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402b110: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034402f780: new PipeWire:Interface:Factory 13
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034402f780: registered 13
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402bb60: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344030300: new PipeWire:Interface:Factory 14
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344030300: registered 14
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402c4f0: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344030ed0: new PipeWire:Interface:Factory 15
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344030ed0: registered 15
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-factory.c:158 pw_impl_factory_update_properties] pw: factory 0x7f034402ce80: updated 1 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344031aa0: new PipeWire:Interface:Factory 16
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344031aa0: registered 16
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-module.c:256 pw_context_load_module] pw: module 0x7f034400dc60: loaded module: libpipewire-module-session-manager
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:324 pw_context_new] pw: context 0x7f0344003090: created
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1114 stream_new] pw: stream 0x7f03440329a0: new "Tsunami-Output"
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1437 pw_stream_connect] pw: stream 0x7f03440329a0: connect target:-1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1390 find_format] pw: stream 0x7f03440329a0: Spa:Enum:MediaType:audio/Spa:Enum:MediaSubtype:raw
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:287 stream_set_state] pw: stream 0x7f03440329a0: update state from unconnected -> connecting ((null))
[05/Oct/2020:16:20:06 +1300] [WARNING] [PipewireOutput.swift:261 onStateChanged(userdata:previousState:newState:error:)] pipewire: stream state changed unconnected -> connecting
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:36 state] Output mode closed => opening
[05/Oct/2020:16:20:06 +1300] [VERBOSE] [Player.swift:237 processOutput(state:)] player: got output state opening
[05/Oct/2020:16:20:06 +1300] [WARNING] [Player.swift:248 processOutput(state:)] Unhandled mode opening
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:319 core_new] pw: core 0x7f0344035630: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:149 pw_mempool_new] pw: mempool 0x7f0344036130: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-protocol-native.c:917 impl_new_client] pw: protocol-native 0x7f0344012ca0: new client 0x7f03440367b0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [connection.c:248 pw_protocol_native_connection_new] pw: connection 0x7f0344036c60: new fd:-1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [module-protocol-native.c:941 impl_new_client] pw: protocol-native 0x7f0344012ca0: connect generic
[05/Oct/2020:16:20:06 +1300] [DEBUG] [proxy.c:112 pw_proxy_new] pw: proxy 0x7f0344049140: new 1 type PipeWire:Interface:Client/3 core-proxy:0x7f0344035630, marshal:0x7f03523b8340
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:411 pw_context_connect] pw: core 0x7f0344035630: connect
[05/Oct/2020:16:20:06 +1300] [INFO] [local-socket.c:96 try_connect] pw: connecting to 'pipewire-0' runtime_dir:/run/user/1000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [connection.c:275 pw_protocol_native_connection_set_fd] pw: connection 0x7f0344036c60: fd:23
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1527 pw_stream_connect] pw: stream 0x7f03440329a0: creating node
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x7f0344004668: new fd:24
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1093 pw_context_create_node] pw: node 0x7f0344049ff0: new fd:24
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f034400df90: block:0x7f034404ab80 fd:26 map:0x7f0344014eb0 ptr:0x7f035a418000 (0 4096) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f034400df90: map:0x7f034404ad40 block:0x7f034404ab80 fd:26 ptr:0x7f035a418000 (0 2312) mapping:0x7f0344014eb0 ref:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:523 pw_mempool_alloc] pw: mempool 0x7f034400df90: block:0x7f034404ab80 id:0 type:2 size:2312
[05/Oct/2020:16:20:06 +1300] [DEBUG] [work-queue.c:108 pw_work_queue_new] pw: work-queue 0x7f034404b520: new
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x55ee592b3e48: new fd:27
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:856 check_properties] pw: node 0x7f0344049ff0: driver:0 recalc:0 active:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1584 pw_impl_node_set_implementation] pw: node 0x7f0344049ff0: implementation 0x7f0344032ae0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:92 core_event_bound_id] pw: core 0x7f0344035630: proxy id 1 bound 73
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344049ff0: flags:00000001 change_mask:00000001 max_in:0 max_out:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [proxy.c:163 pw_proxy_set_bound_id] pw: proxy 0x7f0344049140: id:1 bound:73
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [context.c:878 pw_context_recalc_graph] pw: context 0x7f0344003090: busy:0 reason:node info changed
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344049ff0: return output port 0: (nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1300 node_port_info] pw: node 0x7f0344049ff0: output port 0 added
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:390 pw_context_create_port] pw: port 0x7f034404c7e0: new output 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:470 pw_impl_port_set_mix] pw: port 0x7f034404c7e0: mix node (nil)->0x7f034404ce30
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1062 pw_impl_port_for_each_param] pw: port 0x7f034404c7e0: params id:7 (Spa:Enum:ParamId:IO) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 7 (Spa:Enum:ParamId:IO) start:0 num:-1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:660 check_param_io] pw: port 0x7f034404c7e0: got io id:1 (Spa:Enum:IO:Buffers)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1074 pw_impl_port_for_each_param] pw: port 0x7f034404c7e0: res 0: (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:887 pw_impl_port_add] pw: port 0x7f034404c7e0: setting node io
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:482 impl_port_set_io] pw: stream 0x7f03440329a0: set io id 1 (Spa:Enum:IO:Buffers) 0x7f034404cd58 8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:899 pw_impl_port_add] pw: port 0x7f034404c7e0: 0 add to node 0x7f0344049ff0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f034404c7e0: state init -> configure ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [adapter.c:208 pw_adapter_new] pw: adapter 0x7f0344049ff0: in 0/0 out 1/1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [adapter.c:182 find_format] pw: adapter 0x7f0344049ff0: Spa:Enum:MediaType:audio/Spa:Enum:MediaSubtype:raw
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:1051 pw_context_load_spa_handle] pw: context 0x7f0344003090: load factory audio.adapt
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:249 pw_load_spa_handle] pw: load lib:'audioconvert/libspa-audioconvert' factory-name:'audio.adapt'
[05/Oct/2020:16:20:06 +1300] [DEBUG] [pipewire.c:131 open_plugin] pw: loaded plugin:'/usr/lib64/spa-0.2/audioconvert/libspa-audioconvert.so'
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:974 impl_init] pw: mode:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:628 reconfigure_mode] pw: audioconvert 0x7f03516de390: mode 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:395 clean_convert] pw: audioconvert 0x7f03516de390: 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:628 reconfigure_mode] pw: audioconvert 0x7f03516de390: mode 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:395 clean_convert] pw: audioconvert 0x7f03516de390: 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:625 follower_info] pw: audioadapter 0x7f03516de038: follower info Output
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:1043 configure_adapt] pw: 0x7f03516de038: configure convert 0x7f03516de3a8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:760 impl_node_set_param] pw: mode:3 direction:1 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:628 reconfigure_mode] pw: audioconvert 0x7f03516de390: mode 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:395 clean_convert] pw: audioconvert 0x7f03516de390: 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:152 link_io] pw: audioadapter 0x7f03516de038: controls
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:482 impl_port_set_io] pw: stream 0x7f03440329a0: set io id 8 (Spa:Enum:IO:RateMatch) 0x7f03516de240 48
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:161 link_io] pw: audioadapter 0x7f03516de038: set RateMatch on follower disabled -2 No such file or directory
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:482 impl_port_set_io] pw: stream 0x7f03440329a0: set io id 1 (Spa:Enum:IO:Buffers) 0x7f03516de238 8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:1045 impl_node_port_set_io] pw: set io 1 0 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:769 impl_node_port_set_io] pw: fmtconvert 0x7f035191fe78: port 0:0 update io 1 0x7f03516de238
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:386 impl_node_set_param] pw: audioadapter 0x7f03516de038: set param 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x7f0344004668: new fd:25
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1093 pw_context_create_node] pw: node 0x7f0344050ad0: new fd:25
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f034400df90: block:0x7f0344018e20 fd:29 map:0x7f034401a040 ptr:0x7f035a417000 (0 4096) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f034400df90: map:0x7f0344015210 block:0x7f0344018e20 fd:29 ptr:0x7f035a417000 (0 2312) mapping:0x7f034401a040 ref:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:523 pw_mempool_alloc] pw: mempool 0x7f034400df90: block:0x7f0344018e20 id:1 type:2 size:2312
[05/Oct/2020:16:20:06 +1300] [DEBUG] [work-queue.c:108 pw_work_queue_new] pw: work-queue 0x7f0344051990: new
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:218 impl_eventfd_create] pw: system 0x55ee592b3e48: new fd:28
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:856 check_properties] pw: node 0x7f0344050ad0: driver:0 recalc:0 active:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1584 pw_impl_node_set_implementation] pw: node 0x7f0344050ad0: implementation 0x7f03516de050
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:625 follower_info] pw: audioadapter 0x7f03516de038: follower info Output
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344050ad0: flags:00000039 change_mask:00000005 max_in:0 max_out:128
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 0 id:3 (Spa:Enum:ParamId:EnumFormat) 00000000:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 1 id:1 (Spa:Enum:ParamId:PropInfo) 00000000:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 2 id:2 (Spa:Enum:ParamId:Props) 00000000:00000007
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 3 id:4 (Spa:Enum:ParamId:Format) 00000000:00000004
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 4 id:10 (Spa:Enum:ParamId:EnumPortConfig) 00000000:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 5 id:11 (Spa:Enum:ParamId:PortConfig) 00000000:00000006
[05/Oct/2020:16:20:06 +1300] [INFO] [context.c:878 pw_context_recalc_graph] pw: context 0x7f0344003090: busy:0 reason:node info changed
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1978 pw_impl_node_set_active] pw: node 0x7f0344050ad0: activate
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:694 pw_impl_node_initialized] pw: node 0x7f0344050ad0 initialized
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:304 node_update_state] pw: node 0x7f0344050ad0: (node) creating -> suspended ((null))
[05/Oct/2020:16:20:06 +1300] [INFO] [impl-node.c:311 node_update_state] pw: (node-0) creating -> suspended
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:640 pw_impl_node_register] pw: node 0x7f0344050ad0: register
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344054db0: new PipeWire:Interface:Node 17
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:694 pw_impl_node_initialized] pw: node 0x7f0344050ad0 initialized
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344054db0: registered 17
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:594 update_io] pw: node 0x7f0344050ad0: id:17
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:525 impl_node_set_io] pw: audioconvert 0x7f03516de390: io 7 0x7f035a417230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:229 impl_node_set_io] pw: resample 0x7f035193eb28: io 7 0x7f035a417230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:227 impl_node_set_io] pw: fmtconvert 0x7f035191fe78: io 7 0x7f035a417230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:237 impl_node_set_io] pw: splitter 0x7f0351948e48: io 7 0x7f035a417230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:363 impl_set_io] pw: stream 0x7f03440329a0: set io id 7 (Spa:Enum:IO:Position) 0x7f035a417230 1688
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:289 onIOChanged(userdata:id:area:size:)] pipewire: Got io change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:600 update_io] pw: node 0x7f0344050ad0: set position 0x7f035a417230
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:525 impl_node_set_io] pw: audioconvert 0x7f03516de390: io 3 0x7f035a417230/160
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:363 impl_set_io] pw: stream 0x7f03440329a0: set io id 3 (Spa:Enum:IO:Clock) 0x7f035a417230 160
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:289 onIOChanged(userdata:id:area:size:)] pipewire: Got io change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:609 update_io] pw: node 0x7f0344050ad0: set clock 0x7f035a417230
[05/Oct/2020:16:20:06 +1300] [INFO] [context.c:878 pw_context_recalc_graph] pw: context 0x7f0344003090: busy:0 reason:register active node
[05/Oct/2020:16:20:06 +1300] [DEBUG] [context.c:936 pw_context_recalc_graph] pw: context 0x7f0344003090: unassigned node 0x7f0344050ad0: 'node' active:1 want_driver:0 target:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1924 pw_impl_node_set_state] pw: node 0x7f0344050ad0: set state suspended -> suspended, active 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:340 suspend_node] pw: node 0x7f0344050ad0: suspend node state:suspended
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1575 pw_stream_connect] pw: stream 0x7f03440329a0: export node 0x7f0344050ad0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [proxy.c:112 pw_proxy_new] pw: proxy 0x7f0351655010: new 2 type PipeWire:Interface:ClientNode/3 core-proxy:0x7f0344035630, marshal:0x7f0352380460
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:943 do_node_init] pw: 0x7f0351655088: node 0x7f0344050ad0 init
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741828
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:1 num:1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:103 core_event_add_mem] pw: core 0x7f0344035630: add mem 0 type:2 fd:30 flags:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:581 pw_mempool_import] pw: mempool 0x7f0344036130: block:0x7f033c004cd0 id:0 flags:00000003 type:2 fd:30
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:92 core_event_bound_id] pw: core 0x7f0344035630: proxy id 2 bound 74[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [proxy.c:163 pw_proxy_set_bound_id] pw: proxy 0x7f0351655010: id:2 bound:74
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1113 client_node_bound] pw: 0x7f0351655088: bound 74
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f0344036130: block:0x7f033c004cd0 fd:30 map:0x7f033c005c00 ptr:0x7f0351654000 (0 4096) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c006010 block:0x7f033c004cd0 fd:30 ptr:0x7f0351654000 (0 2312) mapping:0x7f033c005c00 ref:1[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:265 client_node_transport] pw: remote-node 0x7f0351655010: fds:32 31 node:74 activation:0x7f0351654000[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [system.c:85 impl_close] pw: system 0x7f0344004668: close fd:25
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:769 pw_mempool_find_tag] pw: mempool 0x7f0344036130: find tag 74:7:0:0:0 size:20
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c005c00 offset:(0 <= 560) end:(4096 >= 2248)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:292 pw_core_export] pw: core 0x7f0344035630: export:PipeWire:Interface:Node proxy:0x7f0351655010
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c004cd0 id:0 fd:30 offs:560 size:1688 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c007830 block:0x7f033c004cd0 fd:30 ptr:0x7f0351654230 (560 1688) mapping:0x7f033c005c00 ref:2
[05/Oct/2020:16:20:06 +1300] [VERBOSE] [PipewireOutput.swift:176 open(format:)] Created pipewire output stream, state connecting
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:394 pw_memblock_map] pw: mempool 0x7f0344036130: tag:74:7:0:0:0
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:36 state] Output mode opening => paused
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:453 client_node_set_io] pw: node 0x7f0351655010: set io Spa:Enum:IO:Position 0x7f0351654230
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:525 impl_node_set_io] pw: audioconvert 0x7f03516de390: io 7 0x7f0351654230/1688
[05/Oct/2020:16:20:06 +1300] [VERBOSE] [Player.swift:237 processOutput(state:)] player: got output state paused
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:229 impl_node_set_io] pw: resample 0x7f035193eb28: io 7 0x7f0351654230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:227 impl_node_set_io] pw: fmtconvert 0x7f035191fe78: io 7 0x7f0351654230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:237 impl_node_set_io] pw: splitter 0x7f0351948e48: io 7 0x7f0351654230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:363 impl_set_io] pw: stream 0x7f03440329a0: set io id 7 (Spa:Enum:IO:Position) 0x7f0351654230 1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [RealTimeMessageQueue.swift:144 startPolling()] Realtime Message Queue polling started
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1752 pw_stream_set_active] pw: stream 0x7f03440329a0: active:1
[05/Oct/2020:16:20:06 +1300] [VERBOSE] [Player.swift:255 processPlaybackActionQueue()] Playback action queue empty
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:289 onIOChanged(userdata:id:area:size:)] pipewire: Got io change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:769 pw_mempool_find_tag] pw: mempool 0x7f0344036130: find tag 74:3:0:0:0 size:20
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c005c00 offset:(0 <= 560) end:(4096 >= 720)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c004cd0 id:0 fd:30 offs:560 size:160 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c00a140 block:0x7f033c004cd0 fd:30 ptr:0x7f0351654230 (560 160) mapping:0x7f033c005c00 ref:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:394 pw_memblock_map] pw: mempool 0x7f0344036130: tag:74:3:0:0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:453 client_node_set_io] pw: node 0x7f0351655010: set io Spa:Enum:IO:Clock 0x7f0351654230
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:525 impl_node_set_io] pw: audioconvert 0x7f03516de390: io 3 0x7f0351654230/160
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:363 impl_set_io] pw: stream 0x7f03440329a0: set io id 3 (Spa:Enum:IO:Clock) 0x7f0351654230 160
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:289 onIOChanged(userdata:id:area:size:)] pipewire: Got io change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:407 client_node_set_param] pw: node 0x7f0351655010: set_param Spa:Enum:ParamId:Props:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:386 impl_node_set_param] pw: audioadapter 0x7f03516de038: set param 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344050ad0: flags:00000039 change_mask:00000004 max_in:0 max_out:128
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 0 id:3 (Spa:Enum:ParamId:EnumFormat) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 1 id:1 (Spa:Enum:ParamId:PropInfo) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 2 id:2 (Spa:Enum:ParamId:Props) 00000007:00000006
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 3 id:4 (Spa:Enum:ParamId:Format) 00000004:00000004
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 4 id:10 (Spa:Enum:ParamId:EnumPortConfig) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 5 id:11 (Spa:Enum:ParamId:PortConfig) 00000006:00000006
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1006 node_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:1 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:1 (Spa:Enum:ParamId:PropInfo) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:972 node_event_param] pw: stream 0x7f03440329a0: add control 65539 (Volume) (def:1.000000 min:0.000000 max:10.000000)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:972 node_event_param] pw: stream 0x7f03440329a0: add control 65540 (Mute) (def:0.000000 min:0.000000 max:1.000000)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:972 node_event_param] pw: stream 0x7f03440329a0: add control 65544 (Channel Volumes) (def:1.000000 min:0.000000 max:10.000000)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:2 (Spa:Enum:ParamId:Props) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1017 node_event_param] pw: stream 0x7f03440329a0: control 65539 (Volume) changed 1:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1020 node_event_param] pw: stream 0x7f03440329a0: value 0 1.000000
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:283 onControlInfo(userdata:id:controlInfo:)] Got control info
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1017 node_event_param] pw: stream 0x7f03440329a0: control 65540 (Mute) changed 1:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1020 node_event_param] pw: stream 0x7f03440329a0: value 0 0.000000
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:283 onControlInfo(userdata:id:controlInfo:)] Got control info
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1017 node_event_param] pw: stream 0x7f03440329a0: control 65544 (Channel Volumes) changed 0:
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:283 onControlInfo(userdata:id:controlInfo:)] Got control info
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:256 emit_params] pw: node 0x7f0344050ad0: emit 1 params
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741835
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:407 client_node_set_param] pw: node 0x7f0351655010: set_param Spa:Enum:ParamId:PortConfig:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:386 impl_node_set_param] pw: audioadapter 0x7f03516de038: set param 11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:760 impl_node_set_param] pw: mode:3 direction:1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:628 reconfigure_mode] pw: audioconvert 0x7f03516de390: mode 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:395 clean_convert] pw: audioconvert 0x7f03516de390: 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:679 reconfigure_mode] pw: audioconvert 0x7f03516de390: port config 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:296 impl_node_set_param] pw: splitter 0x7f0351948e48: port config 48000/2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:187 init_port] pw: splitter 0x7f0351948e48: init port 1:0 position:FL
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:187 init_port] pw: splitter 0x7f0351948e48: init port 1:1 position:FR
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 0: (nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1300 node_port_info] pw: node 0x7f0344050ad0: output port 0 added
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:390 pw_context_create_port] pw: port 0x7f034405b750: new output 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:470 pw_impl_port_set_mix] pw: port 0x7f034405b750: mix node (nil)->0x7f034405bda0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:270 update_properties] pw: port 0x7f034405b750: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1027 node_port_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1062 pw_impl_port_for_each_param] pw: port 0x7f034405b750: params id:7 (Spa:Enum:ParamId:IO) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:660 check_param_io] pw: port 0x7f034405b750: got io id:1 (Spa:Enum:IO:Buffers)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:660 check_param_io] pw: port 0x7f034405b750: got io id:8 (Spa:Enum:IO:RateMatch)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1074 pw_impl_port_for_each_param] pw: port 0x7f034405b750: res 0: (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:887 pw_impl_port_add] pw: port 0x7f034405b750: setting node io
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:848 impl_node_port_set_io] pw: set io 0 1 1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:1045 impl_node_port_set_io] pw: set io 1 1 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:899 pw_impl_port_add] pw: port 0x7f034405b750: 0 add to node 0x7f0344050ad0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f034405f5a0: new PipeWire:Interface:Port 18
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f034405f5a0: registered 18
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f034405b750: state init -> configure ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 1: (nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1300 node_port_info] pw: node 0x7f0344050ad0: output port 1 added
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:390 pw_context_create_port] pw: port 0x7f03440601d0: new output 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:470 pw_impl_port_set_mix] pw: port 0x7f03440601d0: mix node (nil)->0x7f0344060820
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:270 update_properties] pw: port 0x7f03440601d0: updated 3 properties
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1027 node_port_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1062 pw_impl_port_for_each_param] pw: port 0x7f03440601d0: params id:7 (Spa:Enum:ParamId:IO) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:660 check_param_io] pw: port 0x7f03440601d0: got io id:1 (Spa:Enum:IO:Buffers)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:660 check_param_io] pw: port 0x7f03440601d0: got io id:8 (Spa:Enum:IO:RateMatch)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1074 pw_impl_port_for_each_param] pw: port 0x7f03440601d0: res 0: (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:887 pw_impl_port_add] pw: port 0x7f03440601d0: setting node io
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:848 impl_node_port_set_io] pw: set io 1 1 1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:1045 impl_node_port_set_io] pw: set io 1 1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:899 pw_impl_port_add] pw: port 0x7f03440601d0: 1 add to node 0x7f0344050ad0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:107 pw_global_new] pw: global 0x7f0344066cb0: new PipeWire:Interface:Port 19
[05/Oct/2020:16:20:06 +1300] [DEBUG] [global.c:152 pw_global_register] pw: global 0x7f0344066cb0: registered 19
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f03440601d0: state init -> configure ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344050ad0: flags:00000019 change_mask:00000001 max_in:0 max_out:128
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1006 node_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [INFO] [context.c:878 pw_context_recalc_graph] pw: context 0x7f0344003090: busy:0 reason:node info changed
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741837
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:103 core_event_add_mem] pw: core 0x7f0344035630: add mem 1 type:2 fd:25 flags:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:581 pw_mempool_import] pw: mempool 0x7f0344036130: block:0x7f033c016d70 id:1 flags:00000003 type:2 fd:25
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f0344036130: block:0x7f033c016d70 fd:25 map:0x7f033c017260 ptr:0x7f0350e52000 (0 4096) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c017510 block:0x7f033c016d70 fd:25 ptr:0x7f0350e52000 (0 2312) mapping:0x7f033c017260 ref:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:881 client_node_set_activation] pw: node 0x7f0344050ad0: set activation 48 0x7f0350e52000 0 2312
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:902 client_node_set_activation] pw: node 0x7f0344050ad0: link 0x7f033c017f70: fd:33 id:48 state 0x7f0350e52008 required 0, pending 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c017260 offset:(0 <= 0) end:(4096 >= 2312)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c016d70 id:1 fd:25 offs:0 size:2312 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c018b40 block:0x7f033c016d70 fd:25 ptr:0x7f0350e52000 (0 2312) mapping:0x7f033c017260 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:881 client_node_set_activation] pw: node 0x7f0344050ad0: set activation 48 0x7f0350e52000 0 2312
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:902 client_node_set_activation] pw: node 0x7f0344050ad0: link 0x7f033c019330: fd:34 id:48 state 0x7f0350e52008 required 0, pending 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 0: 0x7f034405b750
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:578 client_node_port_set_param] pw: port 0x7f034405b750: set_param Spa:Enum:ParamId:Format 0x7f0344040ed8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1152 pw_impl_port_set_param] pw: port 0x7f034405b750: 1 set param 4 0x7f0344040ed8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:825 impl_node_port_set_param] pw: 0 4 1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:992 impl_node_port_set_param] pw: audioconvert 0x7f03516de390: set param 4 on port 1:0 0x7f0344040ed8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:627 port_set_format] pw: splitter 0x7f0351948e48: set format
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:675 port_set_format] pw: splitter 0x7f0351948e48: 0 4 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 0: 0x7f034405b750
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1294 node_port_info] pw: node 0x7f0344050ad0: output port 0 changed
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:312 emit_params] pw: port 0x7f034405b750: emit 2 params
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:318 emit_params] pw: port 0x7f034405b750: emit param 0/2: 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:318 emit_params] pw: port 0x7f034405b750: emit param 1/2: 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1027 node_port_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1159 pw_impl_port_set_param] pw: port 0x7f034405b750: 1 set param on node 1:0 id:4 (Spa:Enum:ParamId:Format): 0 (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1185 pw_impl_port_set_param] pw: port 0x7f034405b750: 1 0x7f0344040ed8 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [buffers.c:357 pw_buffers_clear] pw: buffers 0x7f034405bbf0: clear 0 buffers:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [buffers.c:357 pw_buffers_clear] pw: buffers 0x7f034405bc70: clear 0 buffers:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f034405b750: state configure -> ready ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741842
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 1: 0x7f03440601d0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:578 client_node_port_set_param] pw: port 0x7f03440601d0: set_param Spa:Enum:ParamId:Format 0x7f0344040ff0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1152 pw_impl_port_set_param] pw: port 0x7f03440601d0: 1 set param 4 0x7f0344040ff0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:825 impl_node_port_set_param] pw: 1 4 1 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:992 impl_node_port_set_param] pw: audioconvert 0x7f03516de390: set param 4 on port 1:1 0x7f0344040ff0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:627 port_set_format] pw: splitter 0x7f0351948e48: set format
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:675 port_set_format] pw: splitter 0x7f0351948e48: 1 4 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 1: 0x7f03440601d0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1294 node_port_info] pw: node 0x7f0344050ad0: output port 1 changed
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:312 emit_params] pw: port 0x7f03440601d0: emit 2 params
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:318 emit_params] pw: port 0x7f03440601d0: emit param 0/2: 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:318 emit_params] pw: port 0x7f03440601d0: emit param 1/2: 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1027 node_port_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 6
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 7
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:808 impl_node_port_enum_params] pw: audioadapter 0x7f03516de038: 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 1.1 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1159 pw_impl_port_set_param] pw: port 0x7f03440601d0: 1 set param on node 1:1 id:4 (Spa:Enum:ParamId:Format): 0 (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1185 pw_impl_port_set_param] pw: port 0x7f03440601d0: 1 0x7f0344040ff0 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [buffers.c:357 pw_buffers_clear] pw: buffers 0x7f0344060670: clear 0 buffers:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [buffers.c:357 pw_buffers_clear] pw: buffers 0x7f03440606f0: clear 0 buffers:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f03440601d0: state configure -> ready ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741844
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:103 core_event_add_mem] pw: core 0x7f0344035630: add mem 2 type:2 fd:36 flags:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:581 pw_mempool_import] pw: mempool 0x7f0344036130: block:0x7f0344014f60 id:2 flags:00000003 type:2 fd:36
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 0: 0x7f034405b750
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:235 pw_impl_port_init_mix] pw: port 0x7f034405b750: init mix n_mix:1 0.0 io:(nil): (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:542 clear_buffers] pw: port 0x7f034405b750: clear 0 buffers mix:-1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1258 pw_impl_port_use_buffers] pw: port 0x7f034405b750: 1:0.0: 0 buffers flags:0 state:2 n_mix:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f0344036130: block:0x7f0344014f60 fd:36 map:0x7f0344016c50 ptr:0x7f0350e49000 (0 36864) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f0344015d60 block:0x7f0344014f60 fd:36 ptr:0x7f0350e49000 (0 32832) mapping:0x7f0344016c50 ref:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:674 client_node_port_use_buffers] pw: add buffer mem:2 id:0 offset:0 size:32832 0x7f034405a9d0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:717 client_node_port_use_buffers] pw: data 0 id:0 -> mem:0x7f0350e49040 offs:64 maxsize:32768
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1258 pw_impl_port_use_buffers] pw: port 0x7f034405b750: 1:0.0: 1 buffers flags:0 state:2 n_mix:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1236 negotiate_mixer_buffers] pw: port 0x7f034405b750: 1.0 use 1 buffers on node: 0x7f03516de050
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:872 impl_node_port_use_buffers] pw: audioadapter 0x7f03516de038: 1 1:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:764 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: use buffers 1 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 0 data 0 flags:00000003 0x7f0350e49040
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f034405b750: state ready -> paused ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741847
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:103 core_event_add_mem] pw: core 0x7f0344035630: add mem 3 type:2 fd:37 flags:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:581 pw_mempool_import] pw: mempool 0x7f0344036130: block:0x7f0344035160 id:3 flags:00000003 type:2 fd:37
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 1: 0x7f03440601d0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:235 pw_impl_port_init_mix] pw: port 0x7f03440601d0: init mix n_mix:1 1.0 io:(nil): (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:542 clear_buffers] pw: port 0x7f03440601d0: clear 0 buffers mix:-1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1258 pw_impl_port_use_buffers] pw: port 0x7f03440601d0: 1:1.0: 0 buffers flags:0 state:2 n_mix:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f0344036130: block:0x7f0344035160 fd:37 map:0x7f03440153f0 ptr:0x7f0350e40000 (0 36864) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f034404c000 block:0x7f0344035160 fd:37 ptr:0x7f0350e40000 (0 32832) mapping:0x7f03440153f0 ref:1
[05/Oct/2020:16:20:06 +1300] [WARNING] [remote-node.c:650 client_node_port_use_buffers] pw: Failed to mlock memory 0x7f0350e40000 32832: This is not a problem but for best performance, consider increasing RLIMIT_MEMLOCK
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:674 client_node_port_use_buffers] pw: add buffer mem:3 id:0 offset:0 size:32832 0x7f03440176a0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:717 client_node_port_use_buffers] pw: data 0 id:0 -> mem:0x7f0350e40040 offs:64 maxsize:32768
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1258 pw_impl_port_use_buffers] pw: port 0x7f03440601d0: 1:1.0: 1 buffers flags:0 state:2 n_mix:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:1236 negotiate_mixer_buffers] pw: port 0x7f03440601d0: 1.1 use 1 buffers on node: 0x7f03516de050
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:872 impl_node_port_use_buffers] pw: audioadapter 0x7f03516de038: 1 1:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:764 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: use buffers 1 on port 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 0 data 0 flags:00000003 0x7f0350e40040
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:108 pw_impl_port_update_state] pw: port 0x7f03440601d0: state ready -> paused ((null))
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741850
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [PipewireOutput.swift:105 init()] event-loop: got pipewire event
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c017260 offset:(0 <= 0) end:(4096 >= 2312)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c016d70 id:1 fd:25 offs:0 size:2312 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c0018d0 block:0x7f033c016d70 fd:25 ptr:0x7f0350e52000 (0 2312) mapping:0x7f033c017260 ref:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:881 client_node_set_activation] pw: node 0x7f0344050ad0: set activation 48 0x7f0350e52000 0 2312
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:902 client_node_set_activation] pw: node 0x7f0344050ad0: link 0x7f033c02d810: fd:35 id:48 state 0x7f0350e52008 required 4, pending 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:769 pw_mempool_find_tag] pw: mempool 0x7f0344036130: find tag 74:7:0:0:0 size:20
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:775 pw_mempool_find_tag] pw: mempool 0x7f0344036130: found 0x7f033c007830
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c017260 offset:(0 <= 560) end:(4096 >= 2248)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c016d70 id:1 fd:25 offs:560 size:1688 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c02e870 block:0x7f033c016d70 fd:25 ptr:0x7f0350e52230 (560 1688) mapping:0x7f033c017260 ref:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:394 pw_memblock_map] pw: mempool 0x7f0344036130: tag:74:7:0:0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:453 client_node_set_io] pw: node 0x7f0351655010: set io Spa:Enum:IO:Position 0x7f0350e52230
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:525 impl_node_set_io] pw: audioconvert 0x7f03516de390: io 7 0x7f0350e52230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:229 impl_node_set_io] pw: resample 0x7f035193eb28: io 7 0x7f0350e52230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:227 impl_node_set_io] pw: fmtconvert 0x7f035191fe78: io 7 0x7f0350e52230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:237 impl_node_set_io] pw: splitter 0x7f0351948e48: io 7 0x7f0350e52230/1688
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:363 impl_set_io] pw: stream 0x7f03440329a0: set io id 7 (Spa:Enum:IO:Position) 0x7f0350e52230 1688
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:289 onIOChanged(userdata:id:area:size:)] pipewire: Got io change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:426 pw_memmap_free] pw: mempool 0x7f0344036130: map:0x7f033c007830 block:0x7f033c004cd0 fd:30 ptr:0x7f0351654230 mapping:0x7f033c005c00 ref:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:103 core_event_add_mem] pw: core 0x7f0344035630: add mem 4 type:2 fd:38 flags:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:581 pw_mempool_import] pw: mempool 0x7f0344036130: block:0x7f033c030d60 id:4 flags:00000003 type:2 fd:38
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 0: 0x7f034405b750
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:235 pw_impl_port_init_mix] pw: port 0x7f034405b750: init mix n_mix:2 0.1 io:(nil): (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:769 pw_mempool_find_tag] pw: mempool 0x7f0344036130: find tag 74:1:0:0:1 size:20
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:326 memblock_map] pw: mempool 0x7f0344036130: block:0x7f033c030d60 fd:38 map:0x7f033c032270 ptr:0x7f0350e3f000 (0 4096) block-ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c032520 block:0x7f033c030d60 fd:38 ptr:0x7f0350e3f000 (0 8) mapping:0x7f033c032270 ref:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:394 pw_memblock_map] pw: mempool 0x7f0344036130: tag:74:1:0:0:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:786 client_node_port_set_io] pw: port 0x7f034405b750: set io:Spa:Enum:IO:Buffers new:0x7f0350e3f000 old:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:201 activate_mix] pw: node 0x7f0351655088: mix 0x7f03516551b8 activate
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:491 client_node_command] pw: node 0x7f0351655010: start
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1924 pw_impl_node_set_state] pw: node 0x7f0344050ad0: set state suspended -> running, active 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1895 node_activate] pw: node 0x7f0344050ad0: activate
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:191 start_node] pw: node 0x7f0344050ad0: start node
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:508 impl_node_send_command] pw: audioadapter 0x7f03516de038: command 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:457 negotiate_format] pw: audioadapter 0x7f03516de038: negiotiate
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 0.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:459 impl_node_port_enum_params] pw: 0x7f035191fe78: enum params port 0.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:347 port_enum_formats] pw: fmtconvert 0x7f035191fe78: enum 0x7f03519247a0 0 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:351 configure_format] pw: audioadapter0x7f03516de038: configure format:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:992 impl_node_port_set_param] pw: audioconvert 0x7f03516de390: set param 4 on port 0:0 0x7f03522e6388
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:667 impl_node_port_set_param] pw: fmtconvert 0x7f035191fe78: set param 4 on port 0:0 0x7f03522e6388
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:643 port_set_format] pw: fmtconvert 0x7f035191fe78: set format on port 0:0 res:2 stride:8
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:625 impl_port_set_param] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) changed: 0x7f03522e6388
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Object: size 240, type Spa:Pod:Object:Param:Format (262147), id Spa:Enum:ParamId:EnumFormat (3)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:mediaType (1), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Choice: type Spa:Enum:Choice:None, flags 00000000 20 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 1 (Spa:Enum:MediaType:audio)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:mediaSubtype (2), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Choice: type Spa:Enum:Choice:None, flags 00000000 20 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 1 (Spa:Enum:MediaSubtype:raw)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:Audio:format (65537), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Choice: type Spa:Enum:Choice:None, flags 00000000 24 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 283 (Spa:Enum:AudioFormat:F32LE)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 283 (Spa:Enum:AudioFormat:F32LE)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:Audio:rate (65539), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Choice: type Spa:Enum:Choice:None, flags 00000000 24 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Int 48000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Int 48000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:Audio:channels (65540), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Choice: type Spa:Enum:Choice:None, flags 00000000 24 4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Int 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Int 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Prop: key Spa:Pod:Object:Param:Format:Audio:position (65541), flags 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Array: child.size 4, child.type Spa:Id
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 3 (Spa:Enum:AudioChannel:FL)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:0 impl_port_set_param] pw: Id 4 (Spa:Enum:AudioChannel:FR)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:591 clear_buffers] pw: stream 0x7f03440329a0: clear buffers 0
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:311 onParamChanged(userdata:id:parameter:)] pipewire: Got param change
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1673 pw_stream_update_params] pw: stream 0x7f03440329a0: update params
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344049ff0: return output port 0: 0x7f034404c7e0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1294 node_port_info] pw: node 0x7f0344049ff0: output port 0 changed
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344050ad0: flags:00000019 change_mask:00000004 max_in:0 max_out:128
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 0 id:3 (Spa:Enum:ParamId:EnumFormat) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 1 id:1 (Spa:Enum:ParamId:PropInfo) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 2 id:2 (Spa:Enum:ParamId:Props) 00000006:00000006
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 3 id:4 (Spa:Enum:ParamId:Format) 00000004:00000007
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 4 id:10 (Spa:Enum:ParamId:EnumPortConfig) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 5 id:11 (Spa:Enum:ParamId:PortConfig) 00000006:00000006
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1006 node_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:1 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:3 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:1 (Spa:Enum:ParamId:PropInfo) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:2 (Spa:Enum:ParamId:Props) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:256 emit_params] pw: node 0x7f0344050ad0: emit 1 params
[05/Oct/2020:16:20:06 +1300] [WARNING] [PipewireOutput.swift:372 onParamChanged(userdata:id:parameter:)] pw_stream_update_params 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:254 negotiate_buffers] pw: audioadapter 0x7f03516de038: 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 5 (Spa:Enum:ParamId:Buffers) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:913 impl_node_port_enum_params] pw: audioconvert 0x7f03516de390: port 0.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:459 impl_node_port_enum_params] pw: 0x7f035191fe78: enum params port 0.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:311 negotiate_buffers] pw: 0x7f03516de038: buffers:2, blocks:1, size:65536, stride:8 align:16 0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:698 impl_node_port_use_buffers] pw: fmtconvert 0x7f035191fe78: use buffers 2 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:751 impl_node_port_use_buffers] pw: fmtconvert 0x7f035191fe78: buffer size 65536
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:591 clear_buffers] pw: stream 0x7f03440329a0: clear buffers 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:694 impl_port_use_buffers] pw: stream 0x7f03440329a0: got buffer id:0 datas:1, mapped size 65536
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:694 impl_port_use_buffers] pw: stream 0x7f03440329a0: got buffer id:1 datas:1, mapped size 65536
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:254 negotiate_buffers] pw: audioadapter 0x7f03516de038: 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:263 setup_convert] pw: setup convert n_links:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:769 impl_node_port_set_io] pw: fmtconvert 0x7f035191fe78: port 1:0 update io 1 0x7f03516de550
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:283 setup_convert] pw: negotiate 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:459 impl_node_port_enum_params] pw: 0x7f035191fe78: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:347 port_enum_formats] pw: fmtconvert 0x7f035191fe78: enum 0x7f0351920018 0 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:589 impl_node_port_enum_params] pw: 0x7f0351929168: enum params port 0.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:667 impl_node_port_set_param] pw: fmtconvert 0x7f035191fe78: set param 4 on port 1:0 0x7f03522e6388
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:619 port_set_format] pw: fmtconvert0x7f035191fe78: channels:2<>2 rate:48000<>48000 format:518<>283
[05/Oct/2020:16:20:06 +1300] [INFO] [fmtconvert.c:164 setup_convert] pw: fmtconvert 0x7f035191fe78: Spa:Enum:AudioFormat:F32LE/2@48000->Spa:Enum:AudioFormat:F32P/2@48000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:181 setup_convert] pw: fmtconvert 0x7f035191fe78: channel 0 -> 0 (FL -> FL)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:181 setup_convert] pw: fmtconvert 0x7f035191fe78: channel 1 -> 1 (FR -> FR)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:200 setup_convert] pw: fmtconvert 0x7f035191fe78: got converter features 0007cbdb:00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:643 port_set_format] pw: fmtconvert 0x7f035191fe78: set format on port 1:0 res:0 stride:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:768 port_set_format] pw: channelmix 0x7f0351929168: set format on port 0 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:286 setup_convert] pw: negotiate 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:408 impl_node_port_enum_params] pw: 0x7f035193eb28: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 0.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:555 port_set_format] pw: resample 0x7f035193eb28: set format on port 0 2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:627 port_set_format] pw: splitter 0x7f0351948e48: set format
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:675 port_set_format] pw: splitter 0x7f0351948e48: 0 4 2
[05/Oct/2020:16:20:06 +1300] [INFO] [splitter.c:570 setup_convert] pw: splitter 0x7f0351948e48: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/1@48000x2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:588 setup_convert] pw: splitter 0x7f0351948e48: got converter features 0007cbdb:00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:283 setup_convert] pw: negotiate 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:589 impl_node_port_enum_params] pw: 0x7f0351929168: enum params port 1.0 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:408 impl_node_port_enum_params] pw: 0x7f035193eb28: enum params port 0.0 0 3
[05/Oct/2020:16:20:06 +1300] [INFO] [channelmix.c:233 setup_convert] pw: channelmix 0x7f0351929168: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000 00000018:00000018
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:170 make_matrix] pw: src-mask:00000018 dst-mask:00000018
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:192 make_matrix] pw: unassigned 00000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:394 impl_channelmix_set_volume] pw: 0 0: 1.000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:394 impl_channelmix_set_volume] pw: 0 1: 0.000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:394 impl_channelmix_set_volume] pw: 1 0: 0.000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:394 impl_channelmix_set_volume] pw: 1 1: 1.000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix-ops.c:409 impl_channelmix_set_volume] pw: flags:0000000a
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1237 node_info] pw: node 0x7f0344050ad0: flags:00000019 change_mask:00000004 max_in:0 max_out:128
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 0 id:3 (Spa:Enum:ParamId:EnumFormat) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 1 id:1 (Spa:Enum:ParamId:PropInfo) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 2 id:2 (Spa:Enum:ParamId:Props) 00000006:00000007
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 3 id:4 (Spa:Enum:ParamId:Format) 00000007:00000007
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 4 id:10 (Spa:Enum:ParamId:EnumPortConfig) 00000002:00000002
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1257 node_info] pw: node 0x7f0344050ad0: param 5 id:11 (Spa:Enum:ParamId:PortConfig) 00000006:00000006
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1006 node_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 3 (Spa:Enum:ParamId:EnumFormat) start:1 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:0 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:4
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:516 impl_port_enum_params] pw: stream 0x7f03440329a0: param id 4 (Spa:Enum:ParamId:Format) start:3 num:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:10
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:11
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:1 (Spa:Enum:ParamId:PropInfo) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1762 pw_impl_node_for_each_param] pw: node 0x7f0344050ad0: params id:2 (Spa:Enum:ParamId:Props) index:0 max:4294967295
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioadapter.c:111 impl_node_enum_params] pw: audioadapter 0x7f03516de038: 0 id:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1017 node_event_param] pw: stream 0x7f03440329a0: control 65544 (Channel Volumes) changed 2:
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1020 node_event_param] pw: stream 0x7f03440329a0: value 0 1.000000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [stream.c:1020 node_event_param] pw: stream 0x7f03440329a0: value 1 1.000000
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:283 onControlInfo(userdata:id:controlInfo:)] Got control info
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:256 emit_params] pw: node 0x7f0344050ad0: emit 1 params
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:262 setup_convert] pw: channelmix 0x7f0351929168: got channelmix features 0007cbdb:00000008 flags:0000000a
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:768 port_set_format] pw: channelmix 0x7f0351929168: set format on port 0 0
[05/Oct/2020:16:20:06 +1300] [INFO] [resample.c:146 setup_convert] pw: resample 0x7f035193eb28: Spa:Enum:AudioFormat:F32P/2@48000->Spa:Enum:AudioFormat:F32P/2@48000
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample-native.c:385 resample_native_init] pw: native 0x7f035193faf0: q:4 in:1 out:1 n_taps:56 n_phases:256 features:0007cbdb:00010800
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:555 port_set_format] pw: resample 0x7f035193eb28: set format on port 0 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:286 setup_convert] pw: negotiate 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:406 setup_buffers] pw: audioconvert 0x7f03516de390: 0 3
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:589 impl_node_port_enum_params] pw: 0x7f0351929168: enum params port 0.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:459 impl_node_port_enum_params] pw: 0x7f035191fe78: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:351 negotiate_link_buffers] pw: 0x7f03516de390: buffers 2, blocks 2, size 32768, align 16 0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:698 impl_node_port_use_buffers] pw: fmtconvert 0x7f035191fe78: use buffers 2 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [fmtconvert.c:751 impl_node_port_use_buffers] pw: fmtconvert 0x7f035191fe78: buffer size 32768
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:824 impl_node_port_use_buffers] pw: channelmix 0x7f0351929168: use buffers 2 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:408 impl_node_port_enum_params] pw: 0x7f035193eb28: enum params port 0.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:589 impl_node_port_enum_params] pw: 0x7f0351929168: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:351 negotiate_link_buffers] pw: 0x7f03516de390: buffers 2, blocks 2, size 32768, align 16 0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [channelmix.c:824 impl_node_port_use_buffers] pw: channelmix 0x7f0351929168: use buffers 2 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:608 impl_node_port_use_buffers] pw: resample 0x7f035193eb28: use buffers 2 on port 0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:465 impl_node_port_enum_params] pw: 0x7f0351948e48: enum params port 0.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:408 impl_node_port_enum_params] pw: 0x7f035193eb28: enum params port 1.0 0 5
[05/Oct/2020:16:20:06 +1300] [DEBUG] [audioconvert.c:351 negotiate_link_buffers] pw: 0x7f03516de390: buffers 2, blocks 2, size 32768, align 16 0:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [resample.c:608 impl_node_port_use_buffers] pw: resample 0x7f035193eb28: use buffers 2 on port 1:0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:764 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: use buffers 2 on port 0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 0 data 0 flags:00000004 0x7f0350dbb0c0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 0 data 1 flags:00000004 0x7f0350dc30c0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 1 data 0 flags:00000004 0x7f0350dcb160
[05/Oct/2020:16:20:06 +1300] [DEBUG] [splitter.c:793 impl_node_port_use_buffers] pw: splitter 0x7f0351948e48: buffer 1 data 1 flags:00000004 0x7f0350dd3160
[05/Oct/2020:16:20:06 +1300] [DEBUG] [work-queue.c:200 pw_work_queue_add] pw: work-queue 0x7f0344051990: defer object 0x7f0344050ad0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [core.c:48 core_event_ping] pw: core 0x7f0344035630: object 2 ping 1073741856
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1816 pw_impl_node_find_port] pw: node 0x7f0344050ad0: return output port 1: 0x7f03440601d0
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-port.c:235 pw_impl_port_init_mix] pw: port 0x7f03440601d0: init mix n_mix:2 1.1 io:(nil): (Success)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:769 pw_mempool_find_tag] pw: mempool 0x7f0344036130: find tag 74:1:1:0:1 size:20
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:268 memblock_find_mapping] pw: mempool 0x7f0344036130: check 0x7f033c032270 offset:(0 <= 8) end:(4096 >= 16)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:272 memblock_find_mapping] pw: mempool 0x7f0344036130: found 0x7f033c030d60 id:4 fd:38 offs:8 size:8 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:389 pw_memblock_map] pw: mempool 0x7f0344036130: map:0x7f033c066e60 block:0x7f033c030d60 fd:38 ptr:0x7f0350e3f008 (8 8) mapping:0x7f033c032270 ref:2
[05/Oct/2020:16:20:06 +1300] [DEBUG] [mem.c:394 pw_memblock_map] pw: mempool 0x7f0344036130: tag:74:1:1:0:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:786 client_node_port_set_io] pw: port 0x7f03440601d0: set io:Spa:Enum:IO:Buffers new:0x7f0350e3f008 old:(nil)
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:201 activate_mix] pw: node 0x7f0351655088: mix 0x7f0351655240 activate
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [work-queue.c:83 process_work_queue] pw: work-queue 0x7f0344051990: n_queued:0 process work item 0x7f0344050ad0 seq:-1 res:0 id:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:1874 on_state_complete] pw: node 0x7f0344050ad0: state complete res:0 seq:1
[05/Oct/2020:16:20:06 +1300] [DEBUG] [impl-node.c:304 node_update_state] pw: node 0x7f0344050ad0: (node) suspended -> running ((null))
[05/Oct/2020:16:20:06 +1300] [INFO] [impl-node.c:311 node_update_state] pw: (node-17) suspended -> running
[05/Oct/2020:16:20:06 +1300] [DEBUG] [remote-node.c:1006 node_info_changed] pw: info changed 0x7f0351655088
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 2
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 1
[05/Oct/2020:16:20:06 +1300] [INFO] [PipewireOutput.swift:110 init()] 0
[05/Oct/2020:16:20:07 +1300] [DEBUG] [RealTimeMessageQueue.swift:323 processMainThreadMessages()] Got realtime message buffer underrun