Ticket #247: 192k

File 192k, 203.1 kB (added by sireasoning, 2 years ago)

ctrl-c at 192k

Line 
1 sczjd@ubuntu-studio:~$ sudo su
2 root@ubuntu-studio:/home/sczjd# jackd -R -P70 -dfirewire -r192000 -p1024 -n4 -v 6
3 jackd 0.118.0
4 Copyright 2001-2009 Paul Davis, Stephane Letz, Jack O'Quinn, Torben Hohn and others.
5 jackd comes with ABSOLUTELY NO WARRANTY
6 This is free software, and you are welcome to redistribute it
7 under certain conditions; see the file COPYING for details
8
9 no message buffer overruns
10 JACK compiled with System V SHM support.
11 jack: 5333:245620112423 engine.c:jack_get_fifo_fd:3572: /dev/shm/jack-0/default/jack-ack-fifo-5333-0
12 jack: 5333:245620112453 engine.c:jack_get_fifo_fd:3616: opened engine->fifo[0] == 7 (/dev/shm/jack-0/default/jack-ack-fifo-5333-0)
13 loading driver ..
14 jack: 5336:245620119225 engine.c:jack_server_thread:1480: acquiring graph read lock
15 jack: 5336:245620119277 engine.c:jack_server_thread:1517: release graph lock
16 jack: 5333:245620136309 clientengine.c:jack_client_by_name:346: acquiring graph read lock
17 jack: 5333:245620136325 clientengine.c:jack_client_by_name:357: release graph lock
18 jack: 5333:245620136338 clientengine.c:setup_client:634: acquiring graph write lock
19 jack: 5333:245620136345 clientengine.c:setup_client:641: release graph lock
20 jack: 5333:245620137691 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
21 jack: 5333:245620137703 engine.c:jack_deliver_event:2460: delivering event (type 2)
22 jack: 5333:245620137706 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
23 jack: 5333:245620137708 engine.c:jack_deliver_event:2637: event delivered
24 jack: 5333:245620137710 engine.c:jack_deliver_event_to_all:2423: release graph lock
25 245620138988:  (ffado.cpp)[  92] ffado_streaming_init: libffado 2.999.0-1784 built Jan  5 2010 16:32:47
26 jack: 5333:245620138949 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
27 jack: 5333:245620138958 engine.c:jack_deliver_event:2460: delivering event (type 2)
28 jack: 5333:245620138959 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
29 jack: 5333:245620138961 engine.c:jack_deliver_event:2637: event delivered
30 jack: 5333:245620138962 engine.c:jack_deliver_event_to_all:2423: release graph lock
31 jack: 5333:245620138964 engine.c:jack_driver_buffer_size:529: acquiring graph write lock
32 jack: 5333:245620138965 engine.c:jack_driver_buffer_size:534: release graph lock
33 jack: 5333:245620138966 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
34 jack: 5333:245620138968 engine.c:jack_deliver_event:2460: delivering event (type 0)
35 jack: 5333:245620138969 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
36 jack: 5333:245620138974 engine.c:jack_deliver_event:2637: event delivered
37 jack: 5333:245620138975 engine.c:jack_deliver_event_to_all:2423: release graph lock
38 245620139159: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
39 245620139169: Debug (StreamProcessorManager.cpp)[1569] setVerboseLevel: Setting verbose level to 6...
40 245620139176: Debug (devicemanager.cpp)[1260] setVerboseLevel: Setting verbose level to 6...
41 245620139179: Debug (ffado.cpp)[ 119] ffado_streaming_init: Starting with realtime scheduling, base priority 70
42 245620139184: Debug (DeviceStringParser.cpp)[ 284] isValidString: isvalid? hw:0
43 245620139198: Debug (devicemanager.cpp)[ 233] addSpecString: Adding spec string hw:0
44 245620139203: Debug (DeviceStringParser.cpp)[ 253] parseString: parse: hw:0
45 245620139206: Debug (DeviceStringParser.cpp)[ 258] parseString:  left: hw:0
46 245620139210: Debug (DeviceStringParser.cpp)[  56] parse: parse: hw:0
47 245620139225: Debug (ffado.cpp)[ 148] ffado_streaming_init: setting slave mode to 0
48 245620139237: Debug (ffado.cpp)[ 154] ffado_streaming_init: setting snoop mode to 0
49 245620139347: Debug (Configuration.cpp)[  63] openFile: Could not open file: ~/.ffado/configuration
50 245620140077: Debug (devicemanager.cpp)[ 194] initialize: Found 1 firewire adapters (ports)
51 245620140101: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
52 245620140108: Debug (ieee1394service.cpp)[1567] setVerboseLevel: Setting verbose level to 6...
53 245620140117: Debug (ieee1394service.cpp)[ 422] setThreadParameters: Switching IsoManager to (rt=1, prio=70)
54 245620140123: Debug (IsoHandlerManager.cpp)[ 528] setThreadParameters: (0xd47680) switch to: (rt=1, prio=70)...
55 245620140158: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
56 245620140193: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
57 245620140201: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
58 245620140212: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
59 245620140225: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
60 245620140231: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
61 245620140240: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
62 245620140252: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
63 245620140258: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
64 245620140261: Debug (ieee1394service.cpp)[ 428] setThreadParameters: Switching CycleTimerHelper to (rt=1, prio=1)
65 245620140264: Debug (CycleTimerHelper.cpp)[ 231] setThreadParameters: (0xd47700) switch to: (rt=1, prio=1)...
66 245620140283: Debug (Watchdog.cpp)[ 200] start: (0xd478f0) Starting watchdog...
67 245620140289: Debug (Watchdog.cpp)[ 201] start: Create hartbeat task/thread for 0xd478f0...
68 245620140294: Debug (Watchdog.cpp)[ 215] start:  hartbeat task: 0xd47a50, thread 0xd47ac0...
69 245620140296: Debug (Watchdog.cpp)[ 217] start: Create check task/thread for 0xd478f0...
70 245620140300: Debug (Watchdog.cpp)[ 231] start:  check task: 0xd47b40, thread 0xd47bb0...
71 245620153500: Debug (Watchdog.cpp)[ 249] start: (0xd478f0) Watchdog running...
72 245620172184: Debug (ieee1394service.cpp)[ 331] initialize: This system supports the raw1394_read_cycle_timer call, using it.
73 245620172332: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.min_split_timeout_usecs
74 245620172349: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.min_split_timeout_usecs
75 245620172356: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.min_split_timeout_usecs' not found
76 245620172363: Debug (ieee1394service.cpp)[ 964] getSplitTimeoutUsecs: reading SPLIT_TIMEOUT on node 0x1...
77 245620172395: Debug (ieee1394service.cpp)[ 971] getSplitTimeoutUsecs:  READ HI: 0x01000000
78 245620172408: Debug (ieee1394service.cpp)[ 978] getSplitTimeoutUsecs:  READ LO: 0x00000000
79 245620172414: Debug (ieee1394service.cpp)[ 372] initialize: Minimum SPLIT_TIMEOUT: 1000000. Current: 1000000
80 245620172419: Debug (CycleTimerHelper.cpp)[ 116] Start: Start 0xd47700...
81 245620172422: Debug (CycleTimerHelper.cpp)[ 149] initValues: (0xd47700) Init values...
82 245620172425: Debug (CycleTimerHelper.cpp)[ 156] initValues: Read CTR...
83 245620172430: Debug (CycleTimerHelper.cpp)[ 167] initValues:  read : CTR:   295563669, local:  1262749174559819
84 245620172433: Debug (CycleTimerHelper.cpp)[ 173] initValues:   ctr   : 0x119DF195   216954261 (008s 6623cy 0405ticks)
85 245620172436: Debug (CycleTimerHelper.cpp)[ 179] initValues: requesting DLL re-init...
86 245620174141: Debug (CycleTimerHelper.cpp)[ 308] initDLL:  (0xd47700) First run
87 245620174150: Debug (CycleTimerHelper.cpp)[ 310] initDLL:   DLL bandwidth: 0.500000 Hz (rel: 0.100000)
88 245620174161: Debug (CycleTimerHelper.cpp)[ 313] initDLL:   usecs/update: 200000, ticks/update: 4915200, m_dll_e2: 4915200.000000
89 245620174166: Debug (CycleTimerHelper.cpp)[ 316] initDLL:   usecs current: 1262749174561528.000000, next: 1262749174761528.000000
90 245620174171: Debug (CycleTimerHelper.cpp)[ 319] initDLL:   ticks current: 216996274.000000, next: 221911474.000000
91 245620174175: Debug (CycleTimerHelper.cpp)[ 188] initValues: ready...
92 245620174186: Debug (Watchdog.cpp)[ 281] registerThread: (0xd478f0) Adding thread 0xd4a6a0
93 245620183371: Debug (CycleTimerHelper.cpp)[ 195] Init: Initialize 0xd47700...
94 245620183410: Debug (ieee1394service.cpp)[1225] addBusResetHandler: Adding busreset handler (0xd4a850)
95 245620183465: Debug (CycleTimerHelper.cpp)[ 393] Execute: (0xd47700) have to retry CTR read, diff unrealistic: diff: 217225306, max: +/- 3072 (try: 10) 0
96 245620183546: Debug (CycleTimerHelper.cpp)[ 308] initDLL:  (0xd47700) First run
97 245620183553: Debug (CycleTimerHelper.cpp)[ 310] initDLL:   DLL bandwidth: 0.500000 Hz (rel: 0.100000)
98 245620183571: Debug (CycleTimerHelper.cpp)[ 313] initDLL:   usecs/update: 200000, ticks/update: 4915200, m_dll_e2: 4915200.000000
99 245620183576: Debug (CycleTimerHelper.cpp)[ 316] initDLL:   usecs current: 1262749174570934.000000, next: 1262749174770934.000000
100 245620183581: Debug (CycleTimerHelper.cpp)[ 319] initDLL:   ticks current: 217227403.000000, next: 222142603.000000
101 245620183373: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
102 245620184166: Debug (IsoHandlerManager.cpp)[ 569] init: Initializing ISO manager 0xd47680...
103 245620184219: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
104 245620184235: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
105 245620184242: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
106 245620184253: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
107 245620184266: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
108 245620184272: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
109 245620184282: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
110 245620184294: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
111 245620184300: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
112 245620184310: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.isotask_activity_timeout_usecs
113 245620184323: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.isotask_activity_timeout_usecs
114 245620184329: Debug (Configuration.cpp)[ 268] getValueForSetting: path 'ieee1394.isomanager.isotask_activity_timeout_usecs' not found
115 245620184332: Debug (IsoHandlerManager.cpp)[ 590] init: Create iso thread for 0xd47680 transmit...
116 245620184337: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
117 245620184345: Debug (Thread.h)[ 124] setVerboseLevel: (ISOXMT) Setting verbose level to 6...
118 245620184350: Debug (IsoHandlerManager.cpp)[ 609] init: Create iso thread for 0xd47680 receive...
119 245620184353: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
120 245620184356: Debug (Thread.h)[ 124] setVerboseLevel: (ISORCV) Setting verbose level to 6...
121 245620184360: Debug (Watchdog.cpp)[ 281] registerThread: (0xd478f0) Adding thread 0xd4aab0
122 245620184372: Debug (Watchdog.cpp)[ 281] registerThread: (0xd478f0) Adding thread 0xd4acd0
123 245620184379: Debug (PosixThread.cpp)[ 101] Start: (ISOXMT) Create RT thread 0xd4aab0 with priority 71
124 245620189615: Debug (PosixThread.cpp)[  78] ThreadHandler: (ISOXMT) ThreadHandler: start 0xd4aab0
125 245620189615: Debug (PosixThread.cpp)[ 101] Start: (ISORCV) Create RT thread 0xd4acd0 with priority 69
126 245620195530: Debug (PosixThread.cpp)[  78] ThreadHandler: (ISORCV) ThreadHandler: start 0xd4acd0
127 245620195530: Debug (ieee1394service.cpp)[ 422] setThreadParameters: Switching IsoManager to (rt=1, prio=70)
128 245620195705: Debug (IsoHandlerManager.cpp)[ 528] setThreadParameters: (0xd47680) switch to: (rt=1, prio=70)...
129 245620195771: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
130 245620195796: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
131 245620195806: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
132 245620195823: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
133 245620195842: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
134 245620195851: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
135 245620195867: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
136 245620195887: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
137 245620195895: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
138 245620195900: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ISOXMT, 0xd4aab0) Aquire realtime, prio 71
139 245620195910: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ISORCV, 0xd4acd0) Aquire realtime, prio 69
140 245620195915: Debug (ieee1394service.cpp)[ 428] setThreadParameters: Switching CycleTimerHelper to (rt=1, prio=1)
141 245620195921: Debug (CycleTimerHelper.cpp)[ 231] setThreadParameters: (0xd47700) switch to: (rt=1, prio=1)...
142 245620195926: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (CTRHLP, 0xd4a6a0) Aquire realtime, prio 1
143 245620195933: Debug (ieee1394service.cpp)[1666] setThreadParameters: (0xd4a0b0) switch to: (rt=1, prio=70)...
144 245620195943: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ARMRT, 0xd4a0f0) Aquire realtime, prio 70
145 245620195967: Debug (ieee1394service.cpp)[1225] addBusResetHandler: Adding busreset handler (0xd4ada0)
146 245620195978: Debug (devicemanager.cpp)[ 358] discover: Starting discovery...
147 245620195997: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
148 245620196008: Debug (StreamProcessorManager.cpp)[1569] setVerboseLevel: Setting verbose level to 6...
149 245620196046: Debug (Thread.h)[ 124] setVerboseLevel: (ISOXMT) Setting verbose level to 6...
150 245620196053: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
151 245620196057: Debug (Thread.h)[ 124] setVerboseLevel: (ISORCV) Setting verbose level to 6...
152 245620196061: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
153 245620196064: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
154 245620196069: Debug (ieee1394service.cpp)[1567] setVerboseLevel: Setting verbose level to 6...
155 245620196072: Debug (devicemanager.cpp)[1260] setVerboseLevel: Setting verbose level to 6...
156 245620196082: Debug (devicemanager.cpp)[ 385] discover: Probing node 0...
157 245620260299: Debug (devicemanager.cpp)[ 385] discover: Probing node 1...
158 245620260319: Debug (devicemanager.cpp)[ 388] discover: Skipping local node (1)...
159 245620260341: Debug (DeviceStringParser.cpp)[ 391] show: DeviceStringParser: 0xd465c0
160 245620260348: Debug (DeviceStringParser.cpp)[ 218] show: string: hw:0
161 245620260351: Debug (DeviceStringParser.cpp)[ 221] show: type: eBusNode
162 245620260353: Debug (DeviceStringParser.cpp)[ 223] show:  Port: 0, Node: -1
163 245620260363: Debug (devicemanager.cpp)[ 560] discover: Probing node 0...
164 245620321914: Debug (configrom.cpp)[ 562] printConfigRomDebug: Config ROM
165 245620321930: Debug (configrom.cpp)[ 563] printConfigRomDebug:  Current Node Id:        0
166 245620321934: Debug (configrom.cpp)[ 564] printConfigRomDebug:  GUID:                   0x0001F20000015A02
167 245620321939: Debug (configrom.cpp)[ 565] printConfigRomDebug:  Vendor Name:           
168 245620321942: Debug (configrom.cpp)[ 566] printConfigRomDebug:  Model Name:             
169 245620321945: Debug (configrom.cpp)[ 567] printConfigRomDebug:  Node Vendor ID:         0x0001f2
170 245620321948: Debug (configrom.cpp)[ 568] printConfigRomDebug:  Model Id:               0x00102800
171 245620321952: Debug (configrom.cpp)[ 569] printConfigRomDebug:  Unit Specifier ID:      0x0001f2
172 245620321955: Debug (configrom.cpp)[ 570] printConfigRomDebug:  Unit version:           0x00000005
173 245620321958: Debug (configrom.cpp)[ 571] printConfigRomDebug:  ISO resource manager:   0
174 245620321961: Debug (configrom.cpp)[ 572] printConfigRomDebug:  Cycle master capable:   1
175 245620321964: Debug (configrom.cpp)[ 573] printConfigRomDebug:  Bus manager capable:    0
176 245620321967: Debug (configrom.cpp)[ 574] printConfigRomDebug:  Cycle clock accuracy:   0
177 245620321970: Debug (configrom.cpp)[ 576] printConfigRomDebug:  Max rec:                1 (max asy payload: 4 bytes)
178 245620321980: Debug (DeviceStringParser.cpp)[ 162] match: match 0xd5e4b0 (0001f20000015a02)
179 245620321985: Debug (DeviceStringParser.cpp)[ 175] match: (eBusNode) device matches device string hw:0
180 245620321988: Debug (devicemanager.cpp)[1109] getDriverForDevice: Probing for supported device...
181 245620321992: Debug (devicemanager.cpp)[1032] getDriverForDeviceDo: Trying BeBoB...
182 245620322042: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
183 245620322177: Debug (devicemanager.cpp)[1039] getDriverForDeviceDo: Trying ECHO Audio FireWorks...
184 245620322233: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
185 245620322303: Debug (devicemanager.cpp)[1046] getDriverForDeviceDo: Trying Oxford FW90x...
186 245620322321: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
187 245620322385: Debug (devicemanager.cpp)[1061] getDriverForDeviceDo: Trying Generic AV/C...
188 245620322402: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
189 245620322465: Debug (devicemanager.cpp)[1068] getDriverForDeviceDo: Trying Motu...
190 245620322515: Debug (Element.cpp)[ 253] addElement: Adding Element ConfigRom to 0001f20000015a02
191 245620322535: Debug (Element.cpp)[ 253] addElement: Adding Element Generic to 0001f20000015a02
192 245620322549: Debug (Element.cpp)[ 253] addElement: Adding Element ClockSelect to Generic
193 245620322562: Debug (Element.cpp)[ 253] addElement: Adding Element SamplerateSelect to Generic
194 245620322575: Debug (Element.cpp)[ 253] addElement: Adding Element Nickname to Generic
195 245620322590: Debug (Element.cpp)[ 253] addElement: Adding Element StreamingStatus to Generic
196 245620322601: Debug (devicemanager.cpp)[1112] getDriverForDevice:  found supported device...
197 245620322606: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
198 245620322609: Debug (devicemanager.cpp)[ 620] discover: driver found for device 0
199 245620322612: Debug (ffadodevice.cpp)[ 216] setVerboseLevel: Setting verbose level to 6...
200 245620322615: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
201 245620322619: Debug (motu_avdevice.cpp)[ 447] discover: found MOTU 896HD
202 245620322622: Debug (motu_mixer.cpp)[ 292] buildMixer: Building a MOTU mixer...
203 245620322626: Debug (motu_mixer.cpp)[ 357] destroyMixer: destroy mixer...
204 245620322628: Debug (motu_mixer.cpp)[ 360] destroyMixer: no mixer to destroy...
205 245620322637: Debug (Element.cpp)[ 253] addElement: Adding Element fader to Mixer
206 245620322645: Debug (Element.cpp)[ 253] addElement: Adding Element pan to Mixer
207 245620322652: Debug (Element.cpp)[ 253] addElement: Adding Element solo to Mixer
208 245620322658: Debug (Element.cpp)[ 253] addElement: Adding Element mute to Mixer
209 245620322743: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_fader to Mixer
210 245620322757: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_mute to Mixer
211 245620322769: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_dest to Mixer
212 245620322779: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_fader to Mixer
213 245620322790: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_mute to Mixer
214 245620322798: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_dest to Mixer
215 245620322810: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_fader to Mixer
216 245620322819: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_mute to Mixer
217 245620322830: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_dest to Mixer
218 245620322842: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_fader to Mixer
219 245620322853: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_mute to Mixer
220 245620322862: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_dest to Mixer
221 245620322874: Debug (Element.cpp)[ 253] addElement: Adding Element Mainout_fader to Mixer
222 245620322885: Debug (Element.cpp)[ 253] addElement: Adding Element Phones_fader to Mixer
223 245620322897: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Phones_src to Mixer
224 245620322910: Debug (Element.cpp)[ 253] addElement: Adding Element Control/OpticalIn_mode to Mixer
225 245620322919: Debug (Element.cpp)[ 253] addElement: Adding Element Control/OpticalOut_mode to Mixer
226 245620322931: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_peakhold_time to Mixer
227 245620322944: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_cliphold_time to Mixer
228 245620322952: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_aesebu_src to Mixer
229 245620322965: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_src to Mixer
230 245620322977: Debug (Element.cpp)[ 253] addElement: Adding Element Info/Model to Mixer
231 245620322990: Debug (Element.cpp)[ 253] addElement: Adding Element Info/IsStreaming to Mixer
232 245620322998: Debug (Element.cpp)[ 253] addElement: Adding Element Info/SampleRate to Mixer
233 245620323010: Debug (Element.cpp)[ 253] addElement: Adding Element Mixer to 0001f20000015a02
234 245620323088: Debug (Element.cpp)[ 253] addElement: Adding Element Control to 0001f20000015a02
235 245620323123: Debug (devicemanager.cpp)[ 630] discover: discovery successful
236 245620323132: Debug (devicemanager.cpp)[ 649] discover: No cached version of AVC model created
237 245620323165: Debug (Element.cpp)[ 253] addElement: Adding Element 0001f20000015a02 to DeviceManager
238 245620323198: Debug (devicemanager.cpp)[ 657] discover: discovery of node 0 on port 0 done...
239 245620323208: Debug (devicemanager.cpp)[ 560] discover: Probing node 1...
240 245620323211: Debug (devicemanager.cpp)[ 563] discover: Skipping local node (1)...
241 245620323215: Debug (devicemanager.cpp)[ 665] discover: Discovery finished...
242 245620323223: Debug (DeviceStringParser.cpp)[ 162] match: match 0xd5e4b0 (0001f20000015a02)
243 245620323227: Debug (DeviceStringParser.cpp)[ 175] match: (eBusNode) device matches device string hw:0
244 245620323236: Debug (devicemanager.cpp)[ 684] discover: Mapping 0001f20000015a02 to position 0...
245 245620323258: Debug (ffadodevice.cpp)[ 176] setId: Set id to dev0...
246 245620323273: Debug (devicemanager.cpp)[1265] showDeviceInfo: ===== Device Manager =====
247 245620323281: Debug (Element.cpp)[ 121] show: Element DeviceManager
248 245620323284: Debug (devicemanager.cpp)[1273] showDeviceInfo: --- IEEE1394 Service  0 ---
249 245620323294: Debug (ieee1394service.cpp)[1582] show: Port:  0
250 245620323298: Debug (ieee1394service.cpp)[1583] show:  Name: ohci1394
251 245620323301: Debug (ieee1394service.cpp)[1585] show:  CycleTimerHelper: 0xd47700, IsoManager: 0xd47680, WatchDog: 0xd478f0
252 245620323304: Debug (ieee1394service.cpp)[1590] show:  Time: 00220661390 (008s 7829cy 2702ticks)
253 Iso handler info:
254 Dumping IsoHandlerManager Stream handler information...
255  State: 2
256 245620323314: Debug (devicemanager.cpp)[1283] showDeviceInfo: --- Device  0 ---
257 245620323317: Debug (motu_avdevice.cpp)[ 854] showDevice: MOTU 896HD at node 0
258 245620323321: Debug (devicemanager.cpp)[1286] showDeviceInfo: Clock sync sources:
259 245620323338: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: Internal          , Id:  0, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: Internal sync
260 245620323343: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: ADAT              , Id:  1, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: ADAT optical
261 245620323347: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: SPDIF             , Id:  2, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: SPDIF/Toslink
262 245620323351: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: Erratic type      , Id:  3, Valid: 0, Active: 0, Locked 0, Slipping: 0, Description: SMPTE
263 245620323355: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: WordClock         , Id:  4, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: Wordclock
264 245620323359: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: ADAT              , Id:  5, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: ADAT 9-pin
265 245620323363: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: AES               , Id:  7, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: AES/EBU
266 245620323372: Debug (devicemanager.cpp)[ 801] initStreaming: Locking device (0xd5fce0)
267 245620323376: Debug (devicemanager.cpp)[ 809] initStreaming: Setting samplerate to 192000 for (0xd5fce0)
268 245620372720: Debug (motu_avdevice.cpp)[ 866] prepare: Preparing MotuDevice...
269 245620389758: Debug (ieee1394service.cpp)[1266] allocateIsoChannelGeneric: Allocating ISO channel using generic method...
270 245620389875: Debug (ieee1394service.cpp)[1266] allocateIsoChannelGeneric: Allocating ISO channel using generic method...
271 245620389981: Debug (motu_avdevice.cpp)[ 911] prepare: recv channel = 0, send channel = 1
272 245620390056: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.recv_sp_dll_bw
273 245620390079: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.recv_sp_dll_bw
274 245620390088: Debug (Configuration.cpp)[ 289] getValueForSetting: path 'streaming.spm.recv_sp_dll_bw' not found
275 245620390105: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.xmit_sp_dll_bw
276 245620390124: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.xmit_sp_dll_bw
277 245620390145: Debug (Configuration.cpp)[ 289] getValueForSetting: path 'streaming.spm.xmit_sp_dll_bw' not found
278 245620390162: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
279 245620390237: Debug (Configuration.cpp)[ 359] getValueForDeviceSetting: device 1F2/102800 not found
280 245620390254: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
281 245620390318: Debug (Configuration.cpp)[ 359] getValueForDeviceSetting: device 1F2/102800 not found
282 245620390337: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
283 245620390346: Debug (IsoHandlerManager.cpp)[ 711] registerStream: Registering Receive stream 0xd640e0
284 245620390352: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
285 245620390431: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.iso_receive_mode
286 245620390453: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.iso_receive_mode
287 245620390462: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.iso_receive_mode' not found
288 245620390478: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.bufferfill_mode_threshold
289 245620390497: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.bufferfill_mode_threshold
290 245620390506: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.bufferfill_mode_threshold' not found
291 245620390522: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_interrupts_per_period
292 245620390541: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_interrupts_per_period
293 245620390550: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_interrupts_per_period' not found
294 245620390565: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_nb_buffers_recv
295 245620390585: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_nb_buffers_recv
296 245620390594: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_nb_buffers_recv' not found
297 245620390609: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_packetsize_recv
298 245620390628: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_packetsize_recv
299 245620390638: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_packetsize_recv' not found
300 245620390643: Debug (IsoHandlerManager.cpp)[ 758] registerStream: Using packet-per-buffer mode (auto) [43, 64]
301 245620390646: Debug (IsoHandlerManager.cpp)[ 808] registerStream:  creating IsoRecvHandler
302 245620390651: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
303 245620390654: Debug (IsoHandlerManager.cpp)[1426] registerStream: registering stream (0xd640e0)
304 245620390657: Debug (IsoHandlerManager.cpp)[ 668] registerHandler: enter...
305 245620390660: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
306 245620390667: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
307 245620390672: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
308 245620390674: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
309 245620390677: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
310 245620390680: Debug (IsoHandlerManager.cpp)[ 887] registerStream:  registered stream (0xd640e0) with handler (0xd63da0)
311 245620390689: Debug (IsoHandlerManager.cpp)[ 891] registerStream:  1 streams, 1 handlers registered
312 245620390692: Debug (StreamProcessorManager.cpp)[ 226] registerProcessor: Registering processor (0xd640e0)
313 245620390697: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
314 245620390701: Debug (PortManager.cpp)[ 236] addPortManagerUpdateHandler: Adding PortManagerUpdate handler (0xd63d30)
315 245620390711: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
316 245620390716: Debug (motu_avdevice.cpp)[ 956] prepare: Adding ports to receive processor
317 245620390731: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog1, type: 0, dir: 1
318 245620390741: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
319 245620390749: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
320 245620390759: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog2, type: 0, dir: 1
321 245620390765: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
322 245620390768: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
323 245620390775: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog3, type: 0, dir: 1
324 245620390780: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
325 245620390784: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
326 245620390790: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog4, type: 0, dir: 1
327 245620390795: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
328 245620390798: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
329 245620390804: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog5, type: 0, dir: 1
330 245620390809: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
331 245620390813: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
332 245620390819: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog6, type: 0, dir: 1
333 245620390825: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
334 245620390828: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
335 245620390833: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog7, type: 0, dir: 1
336 245620390838: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
337 245620390841: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
338 245620390847: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog8, type: 0, dir: 1
339 245620390852: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
340 245620390855: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
341 245620390861: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_MIDI0, type: 1, dir: 1
342 245620390868: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63d30)
343 245620390871: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
344 245620390881: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
345 245620390886: Debug (IsoHandlerManager.cpp)[ 711] registerStream: Registering Transmit stream 0xd647e0
346 245620390890: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
347 245620390908: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_interrupts_per_period
348 245620390928: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_interrupts_per_period
349 245620390937: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_interrupts_per_period' not found
350 245620390953: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_nb_buffers_xmit
351 245620390973: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_nb_buffers_xmit
352 245620390982: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_nb_buffers_xmit' not found
353 245620390997: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_packetsize_xmit
354 245620391042: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_packetsize_xmit
355 245620391076: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_packetsize_xmit' not found
356 245620391105: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_packetsize_xmit
357 245620391127: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_packetsize_xmit
358 245620391164: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_packetsize_xmit' not found
359 245620391173: Debug (IsoHandlerManager.cpp)[ 858] registerStream:  creating IsoXmitHandler
360 245620391177: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
361 245620391180: Debug (IsoHandlerManager.cpp)[1426] registerStream: registering stream (0xd647e0)
362 245620391183: Debug (IsoHandlerManager.cpp)[ 668] registerHandler: enter...
363 245620391186: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
364 245620391191: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
365 245620391194: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
366 245620391197: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
367 245620391200: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
368 245620391202: Debug (IsoHandlerManager.cpp)[ 887] registerStream:  registered stream (0xd647e0) with handler (0xd61010)
369 245620391208: Debug (IsoHandlerManager.cpp)[ 891] registerStream:  2 streams, 2 handlers registered
370 245620391211: Debug (StreamProcessorManager.cpp)[ 226] registerProcessor: Registering processor (0xd647e0)
371 245620391215: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
372 245620391219: Debug (PortManager.cpp)[ 236] addPortManagerUpdateHandler: Adding PortManagerUpdate handler (0xd63ed0)
373 245620391224: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
374 245620391229: Debug (motu_avdevice.cpp)[1023] prepare: Adding ports to transmit processor
375 245620391238: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog1, type: 0, dir: 0
376 245620391244: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
377 245620391248: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
378 245620391255: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog2, type: 0, dir: 0
379 245620391260: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
380 245620391263: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
381 245620391271: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog3, type: 0, dir: 0
382 245620391276: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
383 245620391280: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
384 245620391287: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog4, type: 0, dir: 0
385 245620391292: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
386 245620391295: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
387 245620391301: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog5, type: 0, dir: 0
388 245620391307: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
389 245620391310: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
390 245620391317: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog6, type: 0, dir: 0
391 245620391323: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
392 245620391326: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
393 245620391332: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog7, type: 0, dir: 0
394 245620391337: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
395 245620391340: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
396 245620391347: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog8, type: 0, dir: 0
397 245620391352: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
398 245620391355: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
399 245620391362: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_MIDI0, type: 1, dir: 0
400 245620391369: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0xd63ed0)
401 245620391372: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
402 245620391383: Debug (StreamProcessorManager.cpp)[ 308] setSyncSource: Setting sync source to (0xd640e0)
403 jack: 5333:245620391411 engine.c:do_request:1218: got a request of type 1
404 jack: 5333:245620391423 engine.c:jack_port_do_register:3764: acquiring graph write lock
405 jack: 5333:245620391443 engine.c:jack_port_do_register:3852: release graph lock
406 jack: 5333:245620391449 engine.c:do_request:1350: status of request: 0
407 jack: 5333:245620391458 engine.c:do_request:1218: got a request of type 1
408 jack: 5333:245620391460 engine.c:jack_port_do_register:3764: acquiring graph write lock
409 jack: 5333:245620391467 engine.c:jack_port_do_register:3852: release graph lock
410 jack: 5333:245620391469 engine.c:do_request:1350: status of request: 0
411 jack: 5333:245620391474 engine.c:do_request:1218: got a request of type 1
412 jack: 5333:245620391476 engine.c:jack_port_do_register:3764: acquiring graph write lock
413 jack: 5333:245620391481 engine.c:jack_port_do_register:3852: release graph lock
414 jack: 5333:245620391483 engine.c:do_request:1350: status of request: 0
415 jack: 5333:245620391488 engine.c:do_request:1218: got a request of type 1
416 jack: 5333:245620391490 engine.c:jack_port_do_register:3764: acquiring graph write lock
417 jack: 5333:245620391495 engine.c:jack_port_do_register:3852: release graph lock
418 jack: 5333:245620391497 engine.c:do_request:1350: status of request: 0
419 jack: 5333:245620391501 engine.c:do_request:1218: got a request of type 1
420 jack: 5333:245620391503 engine.c:jack_port_do_register:3764: acquiring graph write lock
421 jack: 5333:245620391509 engine.c:jack_port_do_register:3852: release graph lock
422 jack: 5333:245620391511 engine.c:do_request:1350: status of request: 0
423 jack: 5333:245620391515 engine.c:do_request:1218: got a request of type 1
424 jack: 5333:245620391517 engine.c:jack_port_do_register:3764: acquiring graph write lock
425 jack: 5333:245620391523 engine.c:jack_port_do_register:3852: release graph lock
426 jack: 5333:245620391525 engine.c:do_request:1350: status of request: 0
427 jack: 5333:245620391529 engine.c:do_request:1218: got a request of type 1
428 jack: 5333:245620391531 engine.c:jack_port_do_register:3764: acquiring graph write lock
429 jack: 5333:245620391536 engine.c:jack_port_do_register:3852: release graph lock
430 jack: 5333:245620391538 engine.c:do_request:1350: status of request: 0
431 jack: 5333:245620391543 engine.c:do_request:1218: got a request of type 1
432 jack: 5333:245620391545 engine.c:jack_port_do_register:3764: acquiring graph write lock
433 jack: 5333:245620391550 engine.c:jack_port_do_register:3852: release graph lock
434 jack: 5333:245620391552 engine.c:do_request:1350: status of request: 0
435 jack: 5333:245620391557 engine.c:do_request:1218: got a request of type 1
436 jack: 5333:245620391559 engine.c:jack_port_do_register:3764: acquiring graph write lock
437 jack: 5333:245620391565 engine.c:jack_port_do_register:3852: release graph lock
438 jack: 5333:245620391567 engine.c:do_request:1350: status of request: 0
439 jack: 5333:245620391575 engine.c:do_request:1218: got a request of type 1
440 jack: 5333:245620391577 engine.c:jack_port_do_register:3764: acquiring graph write lock
441 jack: 5333:245620391583 engine.c:jack_port_do_register:3852: release graph lock
442 jack: 5333:245620391585 engine.c:do_request:1350: status of request: 0
443 jack: 5333:245620391589 engine.c:do_request:1218: got a request of type 1
444 jack: 5333:245620391592 engine.c:jack_port_do_register:3764: acquiring graph write lock
445 jack: 5333:245620391597 engine.c:jack_port_do_register:3852: release graph lock
446 jack: 5333:245620391599 engine.c:do_request:1350: status of request: 0
447 jack: 5333:245620391603 engine.c:do_request:1218: got a request of type 1
448 jack: 5333:245620391606 engine.c:jack_port_do_register:3764: acquiring graph write lock
449 jack: 5333:245620391611 engine.c:jack_port_do_register:3852: release graph lock
450 jack: 5333:245620391613 engine.c:do_request:1350: status of request: 0
451 jack: 5333:245620391617 engine.c:do_request:1218: got a request of type 1
452 jack: 5333:245620391619 engine.c:jack_port_do_register:3764: acquiring graph write lock
453 jack: 5333:245620391625 engine.c:jack_port_do_register:3852: release graph lock
454 jack: 5333:245620391627 engine.c:do_request:1350: status of request: 0
455 245620391703: Debug (ffado.cpp)[ 189] ffado_streaming_prepare: Preparing...
456 245620391711: Debug (StreamProcessorManager.cpp)[ 315] prepare: Preparing...
457 245620391718: Debug (StreamProcessorManager.cpp)[ 349] prepare: Prepare Receive processors...
458 245620391749: Debug (StreamProcessorManager.cpp)[ 355] prepare:  note: could not set slaveMode option for (0xd640e0)...
459 245620391753: Debug (StreamProcessor.cpp)[1066] prepare: Prepare SP (0xd640e0)...
460 245620391757: Debug (StreamProcessor.cpp)[1070] prepare:  Allocate scratch buffer of 36864 quadlets
461 245620391761: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog1
462 245620391765: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog1
463 245620391768: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog2
464 245620391771: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog2
465 245620391775: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog3
466 245620391777: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog3
467 245620391780: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog4
468 245620391783: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog4
469 245620391786: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog5
470 245620391789: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog5
471 245620391792: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog6
472 245620391795: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog6
473 245620391798: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog7
474 245620391800: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog7
475 245620391803: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog8
476 245620391806: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog8
477 245620391809: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_MIDI0
478 245620391812: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_MIDI0
479 245620391815: Debug (PortManager.cpp)[ 202] initPorts: init ports
480 245620391818: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog1
481 245620391821: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog2
482 245620391824: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog3
483 245620391827: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog4
484 245620391829: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog5
485 245620391832: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog6
486 245620391835: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog7
487 245620391837: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog8
488 245620391840: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_MIDI0
489 245620391843: Debug (MotuReceiveStreamProcessor.cpp)[ 105] prepareChild: Preparing (0xd640e0)...
490 245620391846: Debug (StreamProcessor.cpp)[1104] prepare: Prepared for:
491 245620391849: Debug (StreamProcessor.cpp)[1106] prepare:  Samplerate: 192000  [DLL Bandwidth: 0.100000 Hz]
492 245620391859: Debug (StreamProcessor.cpp)[1108] prepare:  PeriodSize: 1024, NbBuffers: 4
493 245620391862: Debug (StreamProcessor.cpp)[1110] prepare:  Port: 0, Channel: -1
494 245620391865: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Created => ePS_Stopped
495 245620391869: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_Created
496 245620391873: Debug (StreamProcessor.cpp)[1368] doStop: Initializing remote ticks/frame to 128.000000
497 245620391880: Debug (TimestampedBuffer.cpp)[ 140] setNominalRate:  nominal rate 0.000000e+00 => 1.280000e+02
498 245620391885: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.441406e-06 => 2.034505e-07
499 245620391890: Debug (TimestampedBuffer.cpp)[ 365] prepare: Preparing buffer (0xd63f10)
500 245620391893: Debug (TimestampedBuffer.cpp)[ 367] prepare:  Size=4097 events, events/frame=1, event size=36bytes
501 245620391896: Debug (TimestampedBuffer.cpp)[ 370] prepare:  update period 32
502 245620391899: Debug (TimestampedBuffer.cpp)[ 372] prepare:  nominal rate=128.000000
503 245620391902: Debug (TimestampedBuffer.cpp)[ 374] prepare:  wrapping at 3145728000.000
504 245620392173: Debug (StreamProcessor.cpp)[1386] doStop: DLL info: nominal tpf: 128.000000, update period: 32, bandwidth: 2.034505e-07 1/ticks (5.000000e+00 Hz)
505 245620392182: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
506 245620392185: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
507 245620392190: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
508  StreamProcessor 0xd640e0, Receive:
509   Port, Channel    : 0, -1
510   Packets, Dropped, Skipped : 0, 0, 0
511   Now                   : 00222355257 (009s 0381c 0825t)
512   Xrun?                 : False
513   State                 : ePS_Stopped
514   Buffer                : 0xd63f10
515   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
516   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
517    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
518     Head - Tail         : -3145728000.000 (-24576000.000000 frames)
519    DLL Rate             : 4096.000000 (128.000000)
520    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
521 245620392230: Debug (StreamProcessorManager.cpp)[ 363] prepare: Prepare Transmit processors...
522 245620392235: Debug (StreamProcessorManager.cpp)[ 368] prepare:  note: could not set slaveMode option for (0xd647e0)...
523 245620392239: Debug (StreamProcessor.cpp)[1066] prepare: Prepare SP (0xd647e0)...
524 245620392242: Debug (StreamProcessor.cpp)[1070] prepare:  Allocate scratch buffer of 36864 quadlets
525 245620392246: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog1
526 245620392249: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog1
527 245620392252: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog2
528 245620392255: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog2
529 245620392258: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog3
530 245620392261: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog3
531 245620392264: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog4
532 245620392267: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog4
533 245620392270: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog5
534 245620392272: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog5
535 245620392275: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog6
536 245620392278: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog6
537 245620392281: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog7
538 245620392284: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog7
539 245620392287: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog8
540 245620392290: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog8
541 245620392293: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_MIDI0
542 245620392295: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_MIDI0
543 245620392298: Debug (PortManager.cpp)[ 202] initPorts: init ports
544 245620392301: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog1
545 245620392304: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog2
546 245620392306: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog3
547 245620392309: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog4
548 245620392312: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog5
549 245620392314: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog6
550 245620392317: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog7
551 245620392320: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog8
552 245620392322: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_MIDI0
553 245620392325: Debug (MotuTransmitStreamProcessor.cpp)[ 516] prepareChild: Preparing (0xd647e0)...
554 245620392328: Debug (StreamProcessor.cpp)[1104] prepare: Prepared for:
555 245620392331: Debug (StreamProcessor.cpp)[1106] prepare:  Samplerate: 192000  [DLL Bandwidth: 0.100000 Hz]
556 245620392335: Debug (StreamProcessor.cpp)[1108] prepare:  PeriodSize: 1024, NbBuffers: 4
557 245620392338: Debug (StreamProcessor.cpp)[1110] prepare:  Port: 0, Channel: -1
558 245620392341: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Created => ePS_Stopped
559 245620392344: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_Created
560 245620392347: Debug (StreamProcessor.cpp)[1368] doStop: Initializing remote ticks/frame to 128.000000
561 245620392352: Debug (TimestampedBuffer.cpp)[ 140] setNominalRate:  nominal rate 0.000000e+00 => 1.280000e+02
562 245620392357: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 7.629394e-08 => 2.034505e-07
563 245620392361: Debug (TimestampedBuffer.cpp)[ 365] prepare: Preparing buffer (0xd64010)
564 245620392364: Debug (TimestampedBuffer.cpp)[ 367] prepare:  Size=4097 events, events/frame=1, event size=36bytes
565 245620392367: Debug (TimestampedBuffer.cpp)[ 370] prepare:  update period 1024
566 245620392369: Debug (TimestampedBuffer.cpp)[ 372] prepare:  nominal rate=128.000000
567 245620392373: Debug (TimestampedBuffer.cpp)[ 374] prepare:  wrapping at 3145728000.000
568 245620392572: Debug (StreamProcessor.cpp)[1386] doStop: DLL info: nominal tpf: 128.000000, update period: 1024, bandwidth: 2.034505e-07 1/ticks (5.000000e+00 Hz)
569 245620392580: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
570 245620392583: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
571 245620392586: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
572  StreamProcessor 0xd647e0, Transmit:
573   Port, Channel    : 0, -1
574   Packets, Dropped, Skipped : 0, 0, 0
575   Now                   : 00222364964 (009s 0384c 1316t)
576   Xrun?                 : False
577   State                 : ePS_Stopped
578   Buffer                : 0xd64010
579   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
580   TimestampedBuffer (0xd64010): 0000 frames, 0000 events
581    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
582     Head - Tail         : -3145728000.000 (-24576000.000000 frames)
583    DLL Rate             : 131072.000000 (128.000000)
584    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
585 245620392622: Debug (StreamProcessorManager.cpp)[ 387] prepare: setting activity timeout to 10666
586 245620392626: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
587 245620395731: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4ab30) updating shadow vars...
588 245620395741: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0xd4ab30) Receive handler 0xd63da0 skipped (disabled)
589 245620395745: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4ab30) updated shadow vars...
590 245620396467: Debug (ffado.cpp)[ 209] ffado_streaming_start: ------------- Start -------------
591 245620396477: Debug (devicemanager.cpp)[ 873] startStreamingOnDevice: Starting stream 0 of device 0xd5fce0
592 245620399828: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4a910) updating shadow vars...
593 245620399838: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0xd4a910) Transmit handler 0xd61010 skipped (disabled)
594 245620399841: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4a910) updated shadow vars...
595 245620401756: Debug (devicemanager.cpp)[ 873] startStreamingOnDevice: Starting stream 1 of device 0xd5fce0
596 245620405758: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4ab30) updating shadow vars...
597 245620405765: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0xd4ab30) Receive handler 0xd63da0 skipped (disabled)
598 245620405768: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4ab30) updated shadow vars...
599 245620407509: Debug (StreamProcessorManager.cpp)[ 908] start: Starting Processors...
600 245620407514: Debug (StreamProcessorManager.cpp)[ 398] startDryRunning: Putting StreamProcessor streams into dry-running state...
601 245620407518: Debug (StreamProcessorManager.cpp)[ 399] startDryRunning:  Schedule start dry-running...
602 245620407526: Debug (StreamProcessor.cpp)[1156] scheduleStartDryRunning: for Transmit SP (0xd647e0)
603 245620407530: Debug (StreamProcessor.cpp)[1163] scheduleStartDryRunning:   Now                   : 00222732011 (009s 0503c 2795t)
604 245620407534: Debug (StreamProcessor.cpp)[1168] scheduleStartDryRunning:   Start at              : 00223346239 (009s 0703c 2623t)
605 245620407541: Debug (IsoHandlerManager.cpp)[1027] startHandlerForStream:  starting handler 0xd61010 for stream 0xd647e0
606 245620407546: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
607 245620407550: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
608 245620407556: Debug (StreamProcessor.cpp)[1156] scheduleStartDryRunning: for Receive SP (0xd640e0)
609 245620407560: Debug (StreamProcessor.cpp)[1163] scheduleStartDryRunning:   Now                   : 00222732724 (009s 0504c 0436t)
610 245620407564: Debug (StreamProcessor.cpp)[1168] scheduleStartDryRunning:   Start at              : 00223347025 (009s 0704c 0337t)
611 245620407568: Debug (IsoHandlerManager.cpp)[1027] startHandlerForStream:  starting handler 0xd63da0 for stream 0xd640e0
612 245620407571: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
613 245620407575: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
614 245620407578: Debug (StreamProcessorManager.cpp)[ 432] startDryRunning:  Waiting for all SP's to be dry-running...
615 245620409855: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4a910) updating shadow vars...
616 245620409863: Debug (IsoHandlerManager.cpp)[1859] updateState: (0xd61010) handler needs state update from 0 => 1
617 245620409868: Debug (IsoHandlerManager.cpp)[1861] updateState: handler has to be enabled
618 245620409871: Debug (IsoHandlerManager.cpp)[1700] enable: start on cycle 0
619 245620409912: Debug (IsoHandlerManager.cpp)[1724] enable: Preparing iso handler (0xd61010, client=0xd647e0)
620   Handler type................: Transmit
621   Port, Channel...............:  0,  1
622   Buffer, MaxPacketSize, IRQ..:  128, 1168,   21
623   Speed ..................:  2
624   Min ISOXMT bufferfill : 7999
625   Last cycle, dropped.........:   -1,    0,    0
626 245620410205: Debug (IsoHandlerManager.cpp)[ 171] updateShadowMapHelper: (0xd4a910) Transmit handler 0xd61010 added
627 245620410210: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4a910) updated shadow vars...
628 245620410227: Debug (IsoHandlerManager.cpp)[1630] getPacket: Handler for Transmit SP 0xd61010 is alive (cycle = 0)
629 245620410233: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_Stopped to ePS_WaitingForStream
630 245620410237: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Stopped => ePS_WaitingForStream
631 245620410241: Debug (StreamProcessor.cpp)[1430] doWaitForRunningStream: Enter from state: ePS_Stopped
632 245620410244: Debug (StreamProcessor.cpp)[1444] doWaitForRunningStream: State switch complete, dumping SP info...
633  StreamProcessor 0xd647e0, Transmit:
634   Port, Channel    : 0, 1
635   Packets, Dropped, Skipped : 1, 0, 0
636   Now                   : 00222798948 (009s 0525c 2148t)
637   Xrun?                 : False
638   State                 : ePS_WaitingForStream
639   Buffer                : 0xd64010
640   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
641   TimestampedBuffer (0xd64010): 0000 frames, 0000 events
642    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
643     Head - Tail         : -3145728000.000 (-24576000.000000 frames)
644    DLL Rate             : 131072.000000 (128.000000)
645    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
646 245620410681: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4a910) updating shadow vars...
647 245620410686: Debug (IsoHandlerManager.cpp)[ 171] updateShadowMapHelper: (0xd4a910) Transmit handler 0xd61010 added
648 245620410689: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4a910) updated shadow vars...
649 245620415780: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4ab30) updating shadow vars...
650 245620415788: Debug (IsoHandlerManager.cpp)[1859] updateState: (0xd63da0) handler needs state update from 0 => 1
651 245620415792: Debug (IsoHandlerManager.cpp)[1861] updateState: handler has to be enabled
652 245620415795: Debug (IsoHandlerManager.cpp)[1700] enable: start on cycle 0
653 245620415824: Debug (IsoHandlerManager.cpp)[1724] enable: Preparing iso handler (0xd63da0, client=0xd640e0)
654   Handler type................: Receive
655   Port, Channel...............:  0,  0
656   Buffer, MaxPacketSize, IRQ..:   64, 1168,   21
657   Last cycle, dropped.........:   -1,    0,    0
658 245620415999: Debug (IsoHandlerManager.cpp)[ 171] updateShadowMapHelper: (0xd4ab30) Receive handler 0xd63da0 added
659 245620416006: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4ab30) updated shadow vars...
660 jack: 5333:245620391631 engine.c:do_request:1218: got a request of type 1
661 jack: 5333:245620391633 engine.c:jack_port_do_register:3764: acquiring graph write lock
662 jack: 5333:245620391639 engine.c:jack_port_do_register:3852: release graph lock
663 jack: 5333:245620391641 engine.c:do_request:1350: status of request: 0
664 jack: 5333:245620391645 engine.c:do_request:1218: got a request of type 1
665 jack: 5333:245620391647 engine.c:jack_port_do_register:3764: acquiring graph write lock
666 jack: 5333:245620391653 engine.c:jack_port_do_register:3852: release graph lock
667 jack: 5333:245620391655 engine.c:do_request:1350: status of request: 0
668 jack: 5333:245620391659 engine.c:do_request:1218: got a request of type 1
669 jack: 5333:245620391661 engine.c:jack_port_do_register:3764: acquiring graph write lock
670 jack: 5333:245620391667 engine.c:jack_port_do_register:3852: release graph lock
671 jack: 5333:245620391669 engine.c:do_request:1350: status of request: 0
672 jack: 5333:245620391674 engine.c:do_request:1218: got a request of type 1
673 jack: 5333:245620391676 engine.c:jack_port_do_register:3764: acquiring graph write lock
674 jack: 5333:245620391682 engine.c:jack_port_do_register:3852: release graph lock
675 jack: 5333:245620391684 engine.c:do_request:1350: status of request: 0
676 jack: 5333:245620391688 engine.c:do_request:1218: got a request of type 1
677 jack: 5333:245620391690 engine.c:jack_port_do_register:3764: acquiring graph write lock
678 jack: 5333:245620391696 engine.c:jack_port_do_register:3852: release graph lock
679 jack: 5333:245620391698 engine.c:do_request:1350: status of request: 0
680 jack: 5333:245620395512 engine.c:do_request:1218: got a request of type 6
681 jack: 5333:245620395524 clientengine.c:jack_client_activate:819: acquiring graph write lock
682 jack: 5333:245620395533 engine.c:jack_get_fifo_fd:3572: /dev/shm/jack-0/default/jack-ack-fifo-5333-1
683 jack: 5333:245620395592 engine.c:jack_get_fifo_fd:3616: opened engine->fifo[1] == 13 (/dev/shm/jack-0/default/jack-ack-fifo-5333-1)
684 jack: 5333:245620395613 engine.c:jack_deliver_event:2460: delivering event (type 5)
685 jack: 5333:245620395616 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
686 jack: 5333:245620395618 engine.c:jack_deliver_event:2637: event delivered
687 jack: 5333:245620395621 clientengine.c:jack_client_activate:846: release graph lock
688 jack: 5333:245620395623 engine.c:do_request:1350: status of request: 0
689 245621344784: Debug (MotuReceiveStreamProcessor.cpp)[ 163] processPacketHeader: Packet from MOTU: length = 1160
690 245621344804: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_Stopped to ePS_WaitingForStream
691 245621344809: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Stopped => ePS_WaitingForStream
692 245621344814: Debug (StreamProcessor.cpp)[1430] doWaitForRunningStream: Enter from state: ePS_Stopped
693 245621344818: Debug (StreamProcessor.cpp)[1444] doWaitForRunningStream: State switch complete, dumping SP info...
694  StreamProcessor 0xd640e0, Receive:
695   Port, Channel    : 0, 0
696   Packets, Dropped, Skipped : 1, 0, 0
697   Now                   : 00245763136 (010s 0001c 0064t)
698   Xrun?                 : False
699   State                 : ePS_WaitingForStream
700   Buffer                : 0xd63f10
701   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
702   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
703    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
704     Head - Tail         : -3145728000.000 (-24576000.000000 frames)
705    DLL Rate             : 4096.000000 (128.000000)
706    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
707 245621419021: Debug (StreamProcessor.cpp)[ 641] getPacket: Should update state to WaitingForStream to DryRunning
708 245621419039: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStream => ePS_DryRunning
709 245621419043: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStream
710 245621419047: Debug (StreamProcessor.cpp)[1471] doDryRunning: StreamProcessor 0xd647e0 started dry-running
711 245621419052: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
712  StreamProcessor 0xd647e0, Transmit:
713   Port, Channel    : 0, 1
714   Packets, Dropped, Skipped : 704, 0, 0
715   Now                   : 00247587199 (010s 0594c 2431t)
716   Xrun?                 : False
717   State                 : ePS_DryRunning
718   Buffer                : 0xd64010
719   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
720   TimestampedBuffer (0xd64010): 0000 frames, 0000 events
721    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
722     Head - Tail         : -3145728000.000 (-24576000.000000 frames)
723    DLL Rate             : 131072.000000 (128.000000)
724    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
725 245621432808: Debug (StreamProcessor.cpp)[ 459] putPacket: Should update state to DryRunning due to good packet
726 245621432822: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStream => ePS_DryRunning
727 245621432826: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStream
728 245621432830: Debug (StreamProcessor.cpp)[1471] doDryRunning: StreamProcessor 0xd640e0 started dry-running
729 245621432834: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
730  StreamProcessor 0xd640e0, Receive:
731   Port, Channel    : 0, 0
732   Packets, Dropped, Skipped : 705, 0, 0
733   Now                   : 00247925836 (010s 0705c 0076t)
734   Xrun?                 : False
735   State                 : ePS_DryRunning
736   Buffer                : 0xd63f10
737   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
738   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
739    Timestamps           : head:  247930740.000, Tail:  247930740.000, Next tail:  247934836.000
740     Head - Tail         :          0.000 (0.000000 frames)
741    DLL Rate             : 4096.000000 (128.000000)
742    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
743 245621433119: Debug (StreamProcessorManager.cpp)[ 469] startDryRunning:  StreamProcessor streams dry-running...
744 245621433208: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.signal_delay_ticks
745 245621433232: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.signal_delay_ticks
746 245621433241: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.signal_delay_ticks' not found
747 245621433258: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.xmit_prebuffer_frames
748 245621433331: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.xmit_prebuffer_frames
749 245621433341: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.xmit_prebuffer_frames' not found
750 245621433358: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.sync_wait_time_msec
751 245621433378: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.sync_wait_time_msec
752 245621433387: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.sync_wait_time_msec' not found
753 245621433403: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.cycles_for_startup
754 245621433422: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.cycles_for_startup
755 245621433431: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.cycles_for_startup' not found
756 245621433447: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.prestart_cycles_for_xmit
757 245621433466: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.prestart_cycles_for_xmit
758 245621433475: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.prestart_cycles_for_xmit' not found
759 245621433491: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.prestart_cycles_for_recv
760 245621433510: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.prestart_cycles_for_recv
761 245621433519: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.prestart_cycles_for_recv' not found
762 245621433523: Debug (StreamProcessorManager.cpp)[ 497] syncStartAll: Finding minimal sync delay...
763 245621433528: Debug (StreamProcessorManager.cpp)[ 517] syncStartAll:  max_of_min_delay = 64512, max_packet_size_frames = 32...
764 245621433532: Debug (StreamProcessorManager.cpp)[ 531] syncStartAll: Waiting for sync...
765 245621729100: Debug (StreamProcessorManager.cpp)[ 558] syncStartAll: Propagate sync info...
766 245621729117: Debug (StreamProcessorManager.cpp)[ 569] syncStartAll:  sync source frame rate: 191995.656250 fps (128.002899 tpf)
767 245621729131: Debug (StreamProcessorManager.cpp)[ 579] syncStartAll:  sync at TS=00255336442 (010s 3117c 1018t)...
768 245621729183: Debug (StreamProcessorManager.cpp)[ 593] syncStartAll:   add 4800 frames (00000614413 ticks)...
769 245621729215: Debug (StreamProcessorManager.cpp)[ 599] syncStartAll:   => first sample at TS=00255950855 (010s 3317c 1031t)...
770 245621729226: Debug (StreamProcessorManager.cpp)[ 612] syncStartAll:   => xmit starts at  TS=00255889415 (010s 3297c 1031t)...
771 245621729231: Debug (StreamProcessorManager.cpp)[ 617] syncStartAll:   => recv starts at  TS=00255950855 (010s 3317c 1031t)...
772 245621729235: Debug (StreamProcessorManager.cpp)[ 626] syncStartAll:  sync delay: 64512 = 64512 + 0 ticks (000s 0021c 0000t) [503 frames]...
773 245621729239: Debug (StreamProcessorManager.cpp)[ 637] syncStartAll: The number of prebuffer frames (100) is not a multiple of the common block size (32), increased to 128...
774 245621729245: Debug (StreamProcessor.cpp)[ 197] setExtraBufferFrames: Setting extra buffer to 128 frames
775 245621729250: Debug (StreamProcessorManager.cpp)[ 666] syncStartAll:  transmit buffer tail 0255950855 => head TS 0255950855, fc=0...
776 245621729255: Debug (StreamProcessor.cpp)[ 197] setExtraBufferFrames: Setting extra buffer to 1024 frames
777 245621729259: Debug (StreamProcessor.cpp)[1202] scheduleStartRunning: for Receive SP (0xd640e0)
778 245621729264: Debug (StreamProcessor.cpp)[1209] scheduleStartRunning:   Now                   : 00255209717 (010s 3076c 0245t)
779 245621729268: Debug (StreamProcessor.cpp)[1214] scheduleStartRunning:   Start at              : 00255950855 (010s 3317c 1031t)
780 245621729274: Debug (StreamProcessor.cpp)[1202] scheduleStartRunning: for Transmit SP (0xd647e0)
781 245621729277: Debug (StreamProcessor.cpp)[1209] scheduleStartRunning:   Now                   : 00255210036 (010s 3076c 0564t)
782 245621729281: Debug (StreamProcessor.cpp)[1214] scheduleStartRunning:   Start at              : 00255889415 (010s 3297c 1031t)
783 245621730840: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_DryRunning to ePS_WaitingForStreamEnable
784 245621730850: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_WaitingForStreamEnable
785 245621730854: Debug (StreamProcessor.cpp)[1516] doWaitForStreamEnable: Enter from state: ePS_DryRunning
786 245621731069: Debug (StreamProcessor.cpp)[1555] doWaitForStreamEnable: State switch complete, dumping SP info...
787  StreamProcessor 0xd640e0, Receive:
788   Port, Channel    : 0, 0
789   Packets, Dropped, Skipped : 3075, 0, 0
790   Now                   : 00255254464 (010s 3090c 1984t)
791   Xrun?                 : False
792   State                 : ePS_WaitingForStreamEnable
793   Buffer                : 0xd63f10
794   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
795   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
796    Timestamps           : head:  255205368.000, Tail:  255205368.000, Next tail:  255209464.093
797     Head - Tail         :          0.000 (0.000000 frames)
798    DLL Rate             : 4096.000000 (128.000000)
799    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
800 245621731202: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_DryRunning to ePS_WaitingForStreamEnable
801 245621731207: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_WaitingForStreamEnable
802 245621731211: Debug (StreamProcessor.cpp)[1516] doWaitForStreamEnable: Enter from state: ePS_DryRunning
803 245621731217: Debug (StreamProcessor.cpp)[1540] doWaitForStreamEnable: Prefill transmit SP 0xd647e0 with 4224 frames (xmit prebuffer = 128)
804 245621731725: Debug (StreamProcessor.cpp)[1555] doWaitForStreamEnable: State switch complete, dumping SP info...
805  StreamProcessor 0xd647e0, Transmit:
806   Port, Channel    : 0, 1
807   Packets, Dropped, Skipped : 3201, 0, 0
808   Now                   : 00255270535 (010s 3095c 2695t)
809   Xrun?                 : False
810   State                 : ePS_WaitingForStreamEnable
811   Buffer                : 0xd64010
812   Framerate             : Nominal: 192000, Sync: 191999.679566, Buffer 192000.000000
813   TimestampedBuffer (0xd64010): 4224 frames, 4224 events
814    Timestamps           : head:  255950855.000, Tail:  256491527.000, Next tail:  256622599.000
815     Head - Tail         :    -540672.000 (-4224.000000 frames)
816    DLL Rate             : 131072.000000 (128.000000)
817    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
818 245621741707: Debug (StreamProcessor.cpp)[ 626] getPacket: Should update state to Running
819 245621741721: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamEnable => ePS_Running
820 245621741727: Debug (StreamProcessor.cpp)[1576] doRunning: Enter from state: ePS_WaitingForStreamEnable
821 245621741730: Debug (StreamProcessor.cpp)[1581] doRunning: StreamProcessor 0xd647e0 started running
822 245621741735: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.034505e-07 => 4.069010e-09
823 245621741742: Debug (StreamProcessor.cpp)[1597] doRunning: State switch complete, dumping SP info...
824  StreamProcessor 0xd647e0, Transmit:
825   Port, Channel    : 0, 1
826   Packets, Dropped, Skipped : 3298, 0, 0
827   Now                   : 00255516584 (010s 3175c 2984t)
828   Xrun?                 : False
829   State                 : ePS_Running
830   Buffer                : 0xd64010
831   Framerate             : Nominal: 192000, Sync: 191998.214738, Buffer 192000.000000
832   TimestampedBuffer (0xd64010): 4224 frames, 4224 events
833    Timestamps           : head:  255950855.000, Tail:  256491527.000, Next tail:  256622599.000
834     Head - Tail         :    -540672.000 (-4224.000000 frames)
835    DLL Rate             : 131072.000000 (128.000000)
836    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
837 245621760863: Debug (StreamProcessor.cpp)[ 341] putPacket: Should update state to Running
838 245621760879: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamEnable => ePS_Running
839 245621760884: Debug (StreamProcessor.cpp)[1576] doRunning: Enter from state: ePS_WaitingForStreamEnable
840 245621760888: Debug (StreamProcessor.cpp)[1581] doRunning: StreamProcessor 0xd640e0 started running
841 245621760893: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.034505e-07 => 4.069010e-09
842 245621760901: Debug (StreamProcessor.cpp)[1597] doRunning: State switch complete, dumping SP info...
843  StreamProcessor 0xd640e0, Receive:
844   Port, Channel    : 0, 0
845   Packets, Dropped, Skipped : 3318, 0, 0
846   Now                   : 00255987402 (010s 3329c 0714t)
847   Xrun?                 : False
848   State                 : ePS_Running
849   Buffer                : 0xd63f10
850   Framerate             : Nominal: 192000, Sync: 191997.825647, Buffer 191997.825647
851   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
852    Timestamps           : head:  255954950.000, Tail:  255954950.000, Next tail:  255959046.046
853     Head - Tail         :          0.000 (0.000000 frames)
854    DLL Rate             : 4096.046387 (128.001450)
855    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
856 245621761006: Debug (StreamProcessorManager.cpp)[ 749] syncStartAll:   initial time of transfer 0256086023, rate 128.001450...
857 245621761045: Debug (StreamProcessorManager.cpp)[ 768] syncStartAll:   preset transmit tail TS 0256495627, rate 128.001450...
858 245621761060: Debug (StreamProcessorManager.cpp)[ 779] syncStartAll:    => transmit head TS 0256393225, fc=800...
859 245621761065: Debug (StreamProcessorManager.cpp)[ 795] alignReceivedStreams: Aligning received streams...
860 245621761113: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.align_tries
861 245621761135: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.align_tries
862 245621761189: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.align_tries' not found
863 245621761218: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.align_average_time_msec
864 245621761239: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.align_average_time_msec
865 245621761248: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.align_average_time_msec' not found
866 245621761252: Debug (StreamProcessorManager.cpp)[ 812] alignReceivedStreams:  averaging over 75 periods...
867 245621763229: Warning (StreamProcessor.cpp)[ 707] getPacket: Instantanous samplerate more than 1% off nominal. [Nom fs: 192000.000000, Instantanous fs: 95976.568221, diff: 96023.431779 (    0.500122)]
868 245621763248: Debug (StreamProcessor.cpp)[ 718] getPacket: cy 3459, rather large TSP difference TS=00256385031 => TS=00256393225 (8194, nom 4096)
869 245622162452: Debug (StreamProcessorManager.cpp)[ 847] alignReceivedStreams:  Average offsets:
870 245622162578: Debug (StreamProcessorManager.cpp)[ 858] alignReceivedStreams:    avg offset between SyncSP 0xd640e0 and SP 0xd640e0 is 0 ticks, 0 frames...
871 245622162615: Debug (StreamProcessorManager.cpp)[ 788] syncStartAll:  StreamProcessor streams running...
872 245622162625: Debug (StreamProcessorManager.cpp)[ 935] start:  Started...
873
874 ffado_streaming_wait
875 ============================================
876 Xruns: 0
877 ============================================
878 ----------------------------------------------------
879 Dumping StreamProcessorManager information...
880 Period count:     75
881 Data type: float
882  Receive processors...
883  StreamProcessor 0xd640e0, Receive:
884   Port, Channel    : 0, 0
885   Packets, Dropped, Skipped : 6529, 0, 0
886   Now                   : 00265860335 (010s 6543c 0239t)
887   Xrun?                 : False
888   State                 : ePS_Running
889   Buffer                : 0xd63f10
890   Framerate             : Nominal: 192000, Sync: 191997.390783, Buffer 191997.390783
891   TimestampedBuffer (0xd63f10): 0288 frames, 0288 events
892    Timestamps           : head:  265785472.796, Tail:  265822337.296, Next tail:  265826433.352
893     Head - Tail         :     -36864.500 (-288.000588 frames)
894    DLL Rate             : 4096.047193 (128.001475)
895    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
896  Transmit processors...
897  StreamProcessor 0xd647e0, Transmit:
898   Port, Channel    : 0, 1
899   Packets, Dropped, Skipped : 6656, 0, 0
900   Now                   : 00265861465 (010s 6543c 1369t)
901   Xrun?                 : False
902   State                 : ePS_Running
903   Buffer                : 0xd64010
904   Framerate             : Nominal: 192000, Sync: 191997.390783, Buffer 191997.734097
905   TimestampedBuffer (0xd64010): 0864 frames, 0864 events
906    Timestamps           : head:  266215546.591, Tail:  266326139.895, Next tail:  266457213.436
907     Head - Tail         :    -110593.305 (-864.000384 frames)
908    DLL Rate             : 131073.488044 (128.001453)
909    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
910 ----------------------------------------------------
911 Port Information
912  Playback
913     0 (0xd63e80): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog1
914     1 (0xd65960): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog2
915     2 (0xd659f0): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog3
916     3 (0xd65a80): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog4
917     4 (0xd65b10): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog5
918     5 (0xd65c40): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog6
919     6 (0xd65cd0): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog7
920     7 (0xd65d60): [0xd647e0] [ on] [  Audio] dev0_pbk_Analog8
921     8 (0xd65db0): [0xd647e0] [ on] [   MIDI] dev0_pbk_MIDI0
922  Capture
923     0 (0xd4a5e0): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog1
924     1 (0xd5eaa0): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog2
925     2 (0xd5eaf0): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog3
926     3 (0xd5eb60): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog4
927     4 (0xd4a890): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog5
928     5 (0xd64620): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog6
929     6 (0xd646b0): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog7
930     7 (0xd64740): [0xd640e0] [ on] [  Audio]  dev0_cap_Analog8
931     8 (0xd64790): [0xd640e0] [ on] [   MIDI]  dev0_cap_MIDI0
932 ----------------------------------------------------
933
934 jack: 5347:245622167625 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
935 jack: 5347:245622167639 engine.c:jack_run_one_cycle:2150: release problem lock
936 jack: 5347:245622167642 engine.c:jack_run_one_cycle:2153: waiting for driver read
937 245622167659: Debug (MotuReceiveStreamProcessor.cpp)[ 404] decodeMotuCtrlEvents: syncing device control status stream
938 245622167678: Debug (MotuReceiveStreamProcessor.cpp)[ 417] decodeMotuCtrlEvents: initialising device control status
939
940 jack: 5347:245622167685 engine.c:jack_run_one_cycle:2159: run process
941
942 jack: 5347:245622167689 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
943 jack: 5347:245622167691 engine.c:jack_process_internal:580: invoking an internal client's callbacks
944 jack: 5347:245622167822 engine.c:jack_run_one_cycle:2211: release graph lock
945 jack: 5347:245622167828 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
946 jack: 5347:245622172960 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
947 jack: 5347:245622172968 engine.c:jack_run_one_cycle:2150: release problem lock
948 jack: 5347:245622172970 engine.c:jack_run_one_cycle:2153: waiting for driver read
949
950 jack: 5347:245622172997 engine.c:jack_run_one_cycle:2159: run process
951
952 jack: 5347:245622172999 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
953 jack: 5347:245622173002 engine.c:jack_process_internal:580: invoking an internal client's callbacks
954 jack: 5347:245622173133 engine.c:jack_run_one_cycle:2211: release graph lock
955 jack: 5347:245622173137 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
956 jack: 5347:245622178294 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
957 jack: 5347:245622178305 engine.c:jack_run_one_cycle:2150: release problem lock
958 jack: 5347:245622178307 engine.c:jack_run_one_cycle:2153: waiting for driver read
959
960 jack: 5347:245622178340 engine.c:jack_run_one_cycle:2159: run process
961
962 jack: 5347:245622178342 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
963 jack: 5347:245622178345 engine.c:jack_process_internal:580: invoking an internal client's callbacks
964 jack: 5347:245622178473 engine.c:jack_run_one_cycle:2211: release graph lock
965 jack: 5347:245622178476 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
966 jack: 5347:245622183627 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
967 jack: 5347:245622183635 engine.c:jack_run_one_cycle:2150: release problem lock
968 jack: 5347:245622183637 engine.c:jack_run_one_cycle:2153: waiting for driver read
969
970 jack: 5347:245622183666 engine.c:jack_run_one_cycle:2159: run process
971
972 jack: 5347:245622183668 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
973 jack: 5347:245622183670 engine.c:jack_process_internal:580: invoking an internal client's callbacks
974 jack: 5347:245622183797 engine.c:jack_run_one_cycle:2211: release graph lock
975 jack: 5347:245622183800 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
976 jack: 5347:245622188960 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
977 jack: 5347:245622188969 engine.c:jack_run_one_cycle:2150: release problem lock
978 jack: 5347:245622188971 engine.c:jack_run_one_cycle:2153: waiting for driver read
979
980 jack: 5347:245622188999 engine.c:jack_run_one_cycle:2159: run process
981
982 jack: 5347:245622189002 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
983 jack: 5347:245622189004 engine.c:jack_process_internal:580: invoking an internal client's callbacks
984 jack: 5347:245622189136 engine.c:jack_run_one_cycle:2211: release graph lock
985 jack: 5347:245622189139 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
986 jack: 5347:245622194297 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
987 jack: 5347:245622194307 engine.c:jack_run_one_cycle:2150: release problem lock
988 jack: 5347:245622194309 engine.c:jack_run_one_cycle:2153: waiting for driver read
989
990 jack: 5347:245622194337 engine.c:jack_run_one_cycle:2159: run process
991
992 jack: 5347:245622194339 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
993 jack: 5347:245622194342 engine.c:jack_process_internal:580: invoking an internal client's callbacks
994 jack: 5347:245622194470 engine.c:jack_run_one_cycle:2211: release graph lock
995 jack: 5347:245622194473 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
996 jack: 5347:245622199626 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
997 jack: 5347:245622199634 engine.c:jack_run_one_cycle:2150: release problem lock
998 jack: 5347:245622199636 engine.c:jack_run_one_cycle:2153: waiting for driver read
999
1000 jack: 5347:245622199663 engine.c:jack_run_one_cycle:2159: run process
1001
1002 jack: 5347:245622199666 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1003 jack: 5347:245622199668 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1004 jack: 5347:245622199793 engine.c:jack_run_one_cycle:2211: release graph lock
1005 jack: 5347:245622199796 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1006 jack: 5347:245622204959 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1007 jack: 5347:245622204967 engine.c:jack_run_one_cycle:2150: release problem lock
1008 jack: 5347:245622204970 engine.c:jack_run_one_cycle:2153: waiting for driver read
1009
1010 jack: 5347:245622204996 engine.c:jack_run_one_cycle:2159: run process
1011
1012 jack: 5347:245622204999 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1013 jack: 5347:245622205001 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1014 jack: 5347:245622205133 engine.c:jack_run_one_cycle:2211: release graph lock
1015 jack: 5347:245622205136 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1016 jack: 5347:245622210291 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1017 jack: 5347:245622210298 engine.c:jack_run_one_cycle:2150: release problem lock
1018 jack: 5347:245622210300 engine.c:jack_run_one_cycle:2153: waiting for driver read
1019
1020 jack: 5347:245622210325 engine.c:jack_run_one_cycle:2159: run process
1021
1022 jack: 5347:245622210328 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1023 jack: 5347:245622210330 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1024 jack: 5347:245622210454 engine.c:jack_run_one_cycle:2211: release graph lock
1025 jack: 5347:245622210457 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1026 245622215656: Debug (MotuReceiveStreamProcessor.cpp)[ 426] decodeMotuCtrlEvents: device control status valid: n_mixbuses=4, n_channels=11
1027 jack: 5347:245622215626 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1028 jack: 5347:245622215633 engine.c:jack_run_one_cycle:2150: release problem lock
1029 jack: 5347:245622215636 engine.c:jack_run_one_cycle:2153: waiting for driver read
1030
1031 jack: 5347:245622215675 engine.c:jack_run_one_cycle:2159: run process
1032
1033 jack: 5347:245622215678 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1034 jack: 5347:245622215680 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1035 jack: 5347:245622215805 engine.c:jack_run_one_cycle:2211: release graph lock
1036 jack: 5347:245622215808 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1037 jack: 5347:245622220963 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1038 jack: 5347:245622220971 engine.c:jack_run_one_cycle:2150: release problem lock
1039 jack: 5347:245622220973 engine.c:jack_run_one_cycle:2153: waiting for driver read
1040
1041 jack: 5347:245622221001 engine.c:jack_run_one_cycle:2159: run process
1042
1043 jack: 5347:245622221003 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1044 jack: 5347:245622221005 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1045 jack: 5347:245622221137 engine.c:jack_run_one_cycle:2211: release graph lock
1046 jack: 5347:245622221141 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1047 jack: 5347:245622226301 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1048 jack: 5347:245622226311 engine.c:jack_run_one_cycle:2150: release problem lock
1049 jack: 5347:245622226314 engine.c:jack_run_one_cycle:2153: waiting for driver read
1050
1051 jack: 5347:245622226344 engine.c:jack_run_one_cycle:2159: run process
1052
1053 jack: 5347:245622226347 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1054 jack: 5347:245622226349 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1055 jack: 5347:245622226476 engine.c:jack_run_one_cycle:2211: release graph lock
1056 jack: 5347:245622226479 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1057 jack: 5347:245622231631 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1058 jack: 5347:245622231639 engine.c:jack_run_one_cycle:2150: release problem lock
1059 jack: 5347:245622231641 engine.c:jack_run_one_cycle:2153: waiting for driver read
1060
1061 jack: 5347:245622231668 engine.c:jack_run_one_cycle:2159: run process
1062
1063 jack: 5347:245622231670 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1064 jack: 5347:245622231673 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1065 jack: 5347:245622231798 engine.c:jack_run_one_cycle:2211: release graph lock
1066 jack: 5347:245622231801 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1067 jack: 5347:245622236965 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1068 jack: 5347:245622236973 engine.c:jack_run_one_cycle:2150: release problem lock
1069 jack: 5347:245622236975 engine.c:jack_run_one_cycle:2153: waiting for driver read
1070
1071 jack: 5347:245622237002 engine.c:jack_run_one_cycle:2159: run process
1072
1073 jack: 5347:245622237004 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1074 jack: 5347:245622237006 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1075 jack: 5347:245622237138 engine.c:jack_run_one_cycle:2211: release graph lock
1076 jack: 5347:245622237141 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1077 jack: 5347:245622242296 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1078 jack: 5347:245622242303 engine.c:jack_run_one_cycle:2150: release problem lock
1079 jack: 5347:245622242306 engine.c:jack_run_one_cycle:2153: waiting for driver read
1080
1081 jack: 5347:245622242331 engine.c:jack_run_one_cycle:2159: run process
1082
1083 jack: 5347:245622242333 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1084 jack: 5347:245622242335 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1085 jack: 5347:245622242459 engine.c:jack_run_one_cycle:2211: release graph lock
1086 jack: 5347:245622242462 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1087 jack: 5347:245622247632 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1088 jack: 5347:245622247639 engine.c:jack_run_one_cycle:2150: release problem lock
1089 jack: 5347:245622247641 engine.c:jack_run_one_cycle:2153: waiting for driver read
1090
1091 jack: 5347:245622247668 engine.c:jack_run_one_cycle:2159: run process
1092
1093 jack: 5347:245622247670 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1094 jack: 5347:245622247673 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1095 jack: 5347:245622247798 engine.c:jack_run_one_cycle:2211: release graph lock
1096 jack: 5347:245622247801 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1097 jack: 5347:245622252968 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1098 jack: 5347:245622252977 engine.c:jack_run_one_cycle:2150: release problem lock
1099 jack: 5347:245622252979 engine.c:jack_run_one_cycle:2153: waiting for driver read
1100
1101 jack: 5347:245622253006 engine.c:jack_run_one_cycle:2159: run process
1102
1103 jack: 5347:245622253009 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1104 jack: 5347:245622253011 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1105 jack: 5347:245622253143 engine.c:jack_run_one_cycle:2211: release graph lock
1106 jack: 5347:245622253146 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1107 jack: 5347:245622258307 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1108 jack: 5347:245622258317 engine.c:jack_run_one_cycle:2150: release problem lock
1109 jack: 5347:245622258320 engine.c:jack_run_one_cycle:2153: waiting for driver read
1110
1111 jack: 5347:245622258351 engine.c:jack_run_one_cycle:2159: run process
1112
1113 jack: 5347:245622258353 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1114 jack: 5347:245622258356 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1115 jack: 5347:245622258483 engine.c:jack_run_one_cycle:2211: release graph lock
1116 jack: 5347:245622258486 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1117 jack: 5347:245622263635 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1118 jack: 5347:245622263643 engine.c:jack_run_one_cycle:2150: release problem lock
1119 jack: 5347:245622263645 engine.c:jack_run_one_cycle:2153: waiting for driver read
1120
1121 jack: 5347:245622263671 engine.c:jack_run_one_cycle:2159: run process
1122
1123 jack: 5347:245622263674 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1124 jack: 5347:245622263676 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1125 jack: 5347:245622263801 engine.c:jack_run_one_cycle:2211: release graph lock
1126 jack: 5347:245622263804 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1127 jack: 5347:245622268968 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1128 jack: 5347:245622268976 engine.c:jack_run_one_cycle:2150: release problem lock
1129 jack: 5347:245622268978 engine.c:jack_run_one_cycle:2153: waiting for driver read
1130
1131 jack: 5347:245622269005 engine.c:jack_run_one_cycle:2159: run process
1132
1133 jack: 5347:245622269007 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1134 jack: 5347:245622269009 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1135 jack: 5347:245622269143 engine.c:jack_run_one_cycle:2211: release graph lock
1136 jack: 5347:245622269146 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1137 jack: 5347:245622274301 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1138 jack: 5347:245622274308 engine.c:jack_run_one_cycle:2150: release problem lock
1139 jack: 5347:245622274310 engine.c:jack_run_one_cycle:2153: waiting for driver read
1140
1141 jack: 5347:245622274336 engine.c:jack_run_one_cycle:2159: run process
1142
1143 jack: 5347:245622274338 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1144 jack: 5347:245622274340 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1145 jack: 5347:245622274464 engine.c:jack_run_one_cycle:2211: release graph lock
1146 jack: 5347:245622274467 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1147 jack: 5347:245622279636 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1148 jack: 5347:245622279643 engine.c:jack_run_one_cycle:2150: release problem lock
1149 jack: 5347:245622279646 engine.c:jack_run_one_cycle:2153: waiting for driver read
1150
1151 jack: 5347:245622279671 engine.c:jack_run_one_cycle:2159: run process
1152
1153 jack: 5347:245622279674 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1154 jack: 5347:245622279676 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1155 jack: 5347:245622279801 engine.c:jack_run_one_cycle:2211: release graph lock
1156 jack: 5347:245622279804 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1157 jack: 5347:245622284977 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1158 jack: 5347:245622284987 engine.c:jack_run_one_cycle:2150: release problem lock
1159 jack: 5347:245622284989 engine.c:jack_run_one_cycle:2153: waiting for driver read
1160
1161 jack: 5347:245622285018 engine.c:jack_run_one_cycle:2159: run process
1162
1163 jack: 5347:245622285021 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1164 jack: 5347:245622285023 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1165 jack: 5347:245622285157 engine.c:jack_run_one_cycle:2211: release graph lock
1166 jack: 5347:245622285160 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1167 jack: 5347:245622290307 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1168 jack: 5347:245622290315 engine.c:jack_run_one_cycle:2150: release problem lock
1169 jack: 5347:245622290317 engine.c:jack_run_one_cycle:2153: waiting for driver read
1170
1171 jack: 5347:245622290345 engine.c:jack_run_one_cycle:2159: run process
1172
1173 jack: 5347:245622290348 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1174 jack: 5347:245622290350 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1175 jack: 5347:245622290476 engine.c:jack_run_one_cycle:2211: release graph lock
1176 jack: 5347:245622290479 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1177 jack: 5347:245622295637 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1178 jack: 5347:245622295644 engine.c:jack_run_one_cycle:2150: release problem lock
1179 jack: 5347:245622295647 engine.c:jack_run_one_cycle:2153: waiting for driver read
1180
1181 jack: 5347:245622295674 engine.c:jack_run_one_cycle:2159: run process
1182
1183 jack: 5347:245622295676 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1184 jack: 5347:245622295679 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1185 jack: 5347:245622295803 engine.c:jack_run_one_cycle:2211: release graph lock
1186 jack: 5347:245622295806 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1187 jack: 5347:245622300973 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1188 jack: 5347:245622300981 engine.c:jack_run_one_cycle:2150: release problem lock
1189 jack: 5347:245622300983 engine.c:jack_run_one_cycle:2153: waiting for driver read
1190
1191 jack: 5347:245622301009 engine.c:jack_run_one_cycle:2159: run process
1192
1193 jack: 5347:245622301012 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1194 jack: 5347:245622301014 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1195 jack: 5347:245622301144 engine.c:jack_run_one_cycle:2211: release graph lock
1196 jack: 5347:245622301147 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1197 jack: 5347:245622306306 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1198 jack: 5347:245622306313 engine.c:jack_run_one_cycle:2150: release problem lock
1199 jack: 5347:245622306315 engine.c:jack_run_one_cycle:2153: waiting for driver read
1200
1201 jack: 5347:245622306341 engine.c:jack_run_one_cycle:2159: run process
1202
1203 jack: 5347:245622306343 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1204 jack: 5347:245622306346 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1205 jack: 5347:245622306470 engine.c:jack_run_one_cycle:2211: release graph lock
1206 jack: 5347:245622306473 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1207 jack: 5347:245622311647 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1208 jack: 5347:245622311656 engine.c:jack_run_one_cycle:2150: release problem lock
1209 jack: 5347:245622311658 engine.c:jack_run_one_cycle:2153: waiting for driver read
1210
1211 jack: 5347:245622311685 engine.c:jack_run_one_cycle:2159: run process
1212
1213 jack: 5347:245622311687 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1214 jack: 5347:245622311690 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1215 jack: 5347:245622311816 engine.c:jack_run_one_cycle:2211: release graph lock
1216 jack: 5347:245622311819 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1217 jack: 5347:245622316979 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1218 jack: 5347:245622316989 engine.c:jack_run_one_cycle:2150: release problem lock
1219 jack: 5347:245622316991 engine.c:jack_run_one_cycle:2153: waiting for driver read
1220
1221 jack: 5347:245622317019 engine.c:jack_run_one_cycle:2159: run process
1222
1223 jack: 5347:245622317021 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1224 jack: 5347:245622317024 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1225 jack: 5347:245622317156 engine.c:jack_run_one_cycle:2211: release graph lock
1226 jack: 5347:245622317159 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1227 jack: 5347:245622322310 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1228 jack: 5347:245622322319 engine.c:jack_run_one_cycle:2150: release problem lock
1229 jack: 5347:245622322321 engine.c:jack_run_one_cycle:2153: waiting for driver read
1230
1231 jack: 5347:245622322349 engine.c:jack_run_one_cycle:2159: run process
1232
1233 jack: 5347:245622322351 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1234 jack: 5347:245622322354 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1235 jack: 5347:245622322478 engine.c:jack_run_one_cycle:2211: release graph lock
1236 jack: 5347:245622322481 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1237 jack: 5347:245622327647 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1238 jack: 5347:245622327654 engine.c:jack_run_one_cycle:2150: release problem lock
1239 jack: 5347:245622327657 engine.c:jack_run_one_cycle:2153: waiting for driver read
1240
1241 jack: 5347:245622327682 engine.c:jack_run_one_cycle:2159: run process
1242
1243 jack: 5347:245622327685 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1244 jack: 5347:245622327687 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1245 jack: 5347:245622327812 engine.c:jack_run_one_cycle:2211: release graph lock
1246 jack: 5347:245622327815 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1247 jack: 5347:245622332978 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1248 jack: 5347:245622332986 engine.c:jack_run_one_cycle:2150: release problem lock
1249 jack: 5347:245622332988 engine.c:jack_run_one_cycle:2153: waiting for driver read
1250
1251 jack: 5347:245622333016 engine.c:jack_run_one_cycle:2159: run process
1252
1253 jack: 5347:245622333019 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1254 jack: 5347:245622333021 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1255 jack: 5347:245622333153 engine.c:jack_run_one_cycle:2211: release graph lock
1256 jack: 5347:245622333157 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1257 jack: 5347:245622338310 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1258 jack: 5347:245622338317 engine.c:jack_run_one_cycle:2150: release problem lock
1259 jack: 5347:245622338319 engine.c:jack_run_one_cycle:2153: waiting for driver read
1260
1261 jack: 5347:245622338344 engine.c:jack_run_one_cycle:2159: run process
1262
1263 jack: 5347:245622338347 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1264 jack: 5347:245622338349 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1265 jack: 5347:245622338473 engine.c:jack_run_one_cycle:2211: release graph lock
1266 jack: 5347:245622338476 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1267 jack: 5347:245622343652 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1268 jack: 5347:245622343662 engine.c:jack_run_one_cycle:2150: release problem lock
1269 jack: 5347:245622343664 engine.c:jack_run_one_cycle:2153: waiting for driver read
1270
1271 jack: 5347:245622343692 engine.c:jack_run_one_cycle:2159: run process
1272
1273 jack: 5347:245622343695 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1274 jack: 5347:245622343698 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1275 jack: 5347:245622343824 engine.c:jack_run_one_cycle:2211: release graph lock
1276 jack: 5347:245622343827 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1277 jack: 5347:245622348984 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1278 jack: 5347:245622348992 engine.c:jack_run_one_cycle:2150: release problem lock
1279 jack: 5347:245622348994 engine.c:jack_run_one_cycle:2153: waiting for driver read
1280
1281 jack: 5347:245622349023 engine.c:jack_run_one_cycle:2159: run process
1282
1283 jack: 5347:245622349025 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1284 jack: 5347:245622349028 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1285 jack: 5347:245622349160 engine.c:jack_run_one_cycle:2211: release graph lock
1286 jack: 5347:245622349163 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1287 jack: 5347:245622354314 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1288 jack: 5347:245622354321 engine.c:jack_run_one_cycle:2150: release problem lock
1289 jack: 5347:245622354324 engine.c:jack_run_one_cycle:2153: waiting for driver read
1290
1291 jack: 5347:245622354350 engine.c:jack_run_one_cycle:2159: run process
1292
1293 jack: 5347:245622354352 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1294 jack: 5347:245622354355 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1295 jack: 5347:245622354480 engine.c:jack_run_one_cycle:2211: release graph lock
1296 jack: 5347:245622354483 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1297 jack: 5347:245622359650 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1298 jack: 5347:245622359657 engine.c:jack_run_one_cycle:2150: release problem lock
1299 jack: 5347:245622359660 engine.c:jack_run_one_cycle:2153: waiting for driver read
1300
1301 jack: 5347:245622359685 engine.c:jack_run_one_cycle:2159: run process
1302
1303 jack: 5347:245622359687 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1304 jack: 5347:245622359689 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1305 jack: 5347:245622359814 engine.c:jack_run_one_cycle:2211: release graph lock
1306 jack: 5347:245622359817 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1307 jack: 5347:245622364983 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1308 jack: 5347:245622364990 engine.c:jack_run_one_cycle:2150: release problem lock
1309 jack: 5347:245622364992 engine.c:jack_run_one_cycle:2153: waiting for driver read
1310
1311 jack: 5347:245622365018 engine.c:jack_run_one_cycle:2159: run process
1312
1313 jack: 5347:245622365021 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1314 jack: 5347:245622365023 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1315 jack: 5347:245622365153 engine.c:jack_run_one_cycle:2211: release graph lock
1316 jack: 5347:245622365157 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1317 jack: 5347:245622370320 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1318 jack: 5347:245622370328 engine.c:jack_run_one_cycle:2150: release problem lock
1319 jack: 5347:245622370330 engine.c:jack_run_one_cycle:2153: waiting for driver read
1320
1321 jack: 5347:245622370358 engine.c:jack_run_one_cycle:2159: run process
1322
1323 jack: 5347:245622370360 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1324 jack: 5347:245622370363 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1325 jack: 5347:245622370488 engine.c:jack_run_one_cycle:2211: release graph lock
1326 jack: 5347:245622370491 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1327 jack: 5347:245622375656 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1328 jack: 5347:245622375669 engine.c:jack_run_one_cycle:2150: release problem lock
1329 jack: 5347:245622375671 engine.c:jack_run_one_cycle:2153: waiting for driver read
1330
1331 jack: 5347:245622375702 engine.c:jack_run_one_cycle:2159: run process
1332
1333 jack: 5347:245622375705 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1334 jack: 5347:245622375708 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1335 jack: 5347:245622375835 engine.c:jack_run_one_cycle:2211: release graph lock
1336 jack: 5347:245622375838 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1337 jack: 5347:245622380989 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1338 jack: 5347:245622381000 engine.c:jack_run_one_cycle:2150: release problem lock
1339 jack: 5347:245622381002 engine.c:jack_run_one_cycle:2153: waiting for driver read
1340
1341 jack: 5347:245622381031 engine.c:jack_run_one_cycle:2159: run process
1342
1343 jack: 5347:245622381033 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1344 jack: 5347:245622381036 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1345 jack: 5347:245622381169 engine.c:jack_run_one_cycle:2211: release graph lock
1346 jack: 5347:245622381172 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1347 jack: 5347:245622386319 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1348 jack: 5347:245622386326 engine.c:jack_run_one_cycle:2150: release problem lock
1349 jack: 5347:245622386328 engine.c:jack_run_one_cycle:2153: waiting for driver read
1350
1351 jack: 5347:245622386353 engine.c:jack_run_one_cycle:2159: run process
1352
1353 jack: 5347:245622386356 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1354 jack: 5347:245622386358 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1355 jack: 5347:245622386482 engine.c:jack_run_one_cycle:2211: release graph lock
1356 jack: 5347:245622386485 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1357 jack: 5347:245622391653 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1358 jack: 5347:245622391661 engine.c:jack_run_one_cycle:2150: release problem lock
1359 jack: 5347:245622391663 engine.c:jack_run_one_cycle:2153: waiting for driver read
1360
1361 jack: 5347:245622391689 engine.c:jack_run_one_cycle:2159: run process
1362
1363 jack: 5347:245622391691 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1364 jack: 5347:245622391693 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1365 jack: 5347:245622391819 engine.c:jack_run_one_cycle:2211: release graph lock
1366 jack: 5347:245622391821 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1367 jack: 5347:245622396987 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1368 jack: 5347:245622396994 engine.c:jack_run_one_cycle:2150: release problem lock
1369 jack: 5347:245622396996 engine.c:jack_run_one_cycle:2153: waiting for driver read
1370
1371 jack: 5347:245622397022 engine.c:jack_run_one_cycle:2159: run process
1372
1373 jack: 5347:245622397024 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1374 jack: 5347:245622397027 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1375 jack: 5347:245622397157 engine.c:jack_run_one_cycle:2211: release graph lock
1376 jack: 5347:245622397160 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1377 jack: 5347:245622402326 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1378 jack: 5347:245622402334 engine.c:jack_run_one_cycle:2150: release problem lock
1379 jack: 5347:245622402336 engine.c:jack_run_one_cycle:2153: waiting for driver read
1380
1381 jack: 5347:245622402365 engine.c:jack_run_one_cycle:2159: run process
1382
1383 jack: 5347:245622402368 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1384 jack: 5347:245622402370 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1385 jack: 5347:245622402496 engine.c:jack_run_one_cycle:2211: release graph lock
1386 jack: 5347:245622402499 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1387 jack: 5347:245622407659 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1388 jack: 5347:245622407668 engine.c:jack_run_one_cycle:2150: release problem lock
1389 jack: 5347:245622407670 engine.c:jack_run_one_cycle:2153: waiting for driver read
1390
1391 jack: 5347:245622407700 engine.c:jack_run_one_cycle:2159: run process
1392
1393 jack: 5347:245622407703 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1394 jack: 5347:245622407705 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1395 jack: 5347:245622407832 engine.c:jack_run_one_cycle:2211: release graph lock
1396 jack: 5347:245622407835 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1397 jack: 5347:245622412992 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1398 jack: 5347:245622413002 engine.c:jack_run_one_cycle:2150: release problem lock
1399 jack: 5347:245622413004 engine.c:jack_run_one_cycle:2153: waiting for driver read
1400
1401 jack: 5347:245622413032 engine.c:jack_run_one_cycle:2159: run process
1402
1403 jack: 5347:245622413035 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1404 jack: 5347:245622413037 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1405 jack: 5347:245622413169 engine.c:jack_run_one_cycle:2211: release graph lock
1406 jack: 5347:245622413172 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1407 jack: 5347:245622418323 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1408 jack: 5347:245622418330 engine.c:jack_run_one_cycle:2150: release problem lock
1409 jack: 5347:245622418332 engine.c:jack_run_one_cycle:2153: waiting for driver read
1410
1411 jack: 5347:245622418358 engine.c:jack_run_one_cycle:2159: run process
1412
1413 jack: 5347:245622418360 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1414 jack: 5347:245622418362 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1415 jack: 5347:245622418486 engine.c:jack_run_one_cycle:2211: release graph lock
1416 jack: 5347:245622418489 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1417 jack: 5347:245622423659 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1418 jack: 5347:245622423666 engine.c:jack_run_one_cycle:2150: release problem lock
1419 jack: 5347:245622423668 engine.c:jack_run_one_cycle:2153: waiting for driver read
1420
1421 jack: 5347:245622423694 engine.c:jack_run_one_cycle:2159: run process
1422
1423 jack: 5347:245622423696 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1424 jack: 5347:245622423698 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1425 jack: 5347:245622423823 engine.c:jack_run_one_cycle:2211: release graph lock
1426 jack: 5347:245622423826 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1427 jack: 5347:245622428992 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1428 jack: 5347:245622428999 engine.c:jack_run_one_cycle:2150: release problem lock
1429 jack: 5347:245622429001 engine.c:jack_run_one_cycle:2153: waiting for driver read
1430
1431 jack: 5347:245622429026 engine.c:jack_run_one_cycle:2159: run process
1432
1433 jack: 5347:245622429029 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1434 jack: 5347:245622429031 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1435 jack: 5347:245622429162 engine.c:jack_run_one_cycle:2211: release graph lock
1436 jack: 5347:245622429166 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1437 jack: 5347:245622434332 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1438 jack: 5347:245622434343 engine.c:jack_run_one_cycle:2150: release problem lock
1439 jack: 5347:245622434345 engine.c:jack_run_one_cycle:2153: waiting for driver read
1440
1441 jack: 5347:245622434374 engine.c:jack_run_one_cycle:2159: run process
1442
1443 jack: 5347:245622434377 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1444 jack: 5347:245622434379 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1445 jack: 5347:245622434506 engine.c:jack_run_one_cycle:2211: release graph lock
1446 jack: 5347:245622434509 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1447 jack: 5347:245622439662 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1448 jack: 5347:245622439671 engine.c:jack_run_one_cycle:2150: release problem lock
1449 jack: 5347:245622439673 engine.c:jack_run_one_cycle:2153: waiting for driver read
1450
1451 jack: 5347:245622439700 engine.c:jack_run_one_cycle:2159: run process
1452
1453 jack: 5347:245622439703 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1454 jack: 5347:245622439705 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1455 jack: 5347:245622439830 engine.c:jack_run_one_cycle:2211: release graph lock
1456 jack: 5347:245622439834 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1457 jack: 5347:245622444995 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1458 jack: 5347:245622445003 engine.c:jack_run_one_cycle:2150: release problem lock
1459 jack: 5347:245622445005 engine.c:jack_run_one_cycle:2153: waiting for driver read
1460
1461 jack: 5347:245622445031 engine.c:jack_run_one_cycle:2159: run process
1462
1463 jack: 5347:245622445034 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1464 jack: 5347:245622445036 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1465 jack: 5347:245622445168 engine.c:jack_run_one_cycle:2211: release graph lock
1466 jack: 5347:245622445172 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1467 jack: 5347:245622450327 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1468 jack: 5347:245622450333 engine.c:jack_run_one_cycle:2150: release problem lock
1469 jack: 5347:245622450336 engine.c:jack_run_one_cycle:2153: waiting for driver read
1470
1471 jack: 5347:245622450361 engine.c:jack_run_one_cycle:2159: run process
1472
1473 jack: 5347:245622450364 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1474 jack: 5347:245622450366 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1475 jack: 5347:245622450490 engine.c:jack_run_one_cycle:2211: release graph lock
1476 jack: 5347:245622450493 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1477 jack: 5347:245622455662 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1478 jack: 5347:245622455669 engine.c:jack_run_one_cycle:2150: release problem lock
1479 jack: 5347:245622455672 engine.c:jack_run_one_cycle:2153: waiting for driver read
1480
1481 jack: 5347:245622455697 engine.c:jack_run_one_cycle:2159: run process
1482
1483 jack: 5347:245622455700 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1484 jack: 5347:245622455702 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1485 jack: 5347:245622455826 engine.c:jack_run_one_cycle:2211: release graph lock
1486 jack: 5347:245622455829 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1487 jack: 5347:245622461000 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1488 jack: 5347:245622461008 engine.c:jack_run_one_cycle:2150: release problem lock
1489 jack: 5347:245622461010 engine.c:jack_run_one_cycle:2153: waiting for driver read
1490
1491 jack: 5347:245622461037 engine.c:jack_run_one_cycle:2159: run process
1492
1493 jack: 5347:245622461039 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1494 jack: 5347:245622461042 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1495 jack: 5347:245622461177 engine.c:jack_run_one_cycle:2211: release graph lock
1496 jack: 5347:245622461180 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1497 jack: 5347:245622466336 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1498 jack: 5347:245622466347 engine.c:jack_run_one_cycle:2150: release problem lock
1499 jack: 5347:245622466349 engine.c:jack_run_one_cycle:2153: waiting for driver read
1500
1501 jack: 5347:245622466380 engine.c:jack_run_one_cycle:2159: run process
1502
1503 jack: 5347:245622466382 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1504 jack: 5347:245622466385 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1505 jack: 5347:245622466512 engine.c:jack_run_one_cycle:2211: release graph lock
1506 jack: 5347:245622466515 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1507 jack: 5347:245622471665 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1508 jack: 5347:245622471673 engine.c:jack_run_one_cycle:2150: release problem lock
1509 jack: 5347:245622471675 engine.c:jack_run_one_cycle:2153: waiting for driver read
1510
1511 jack: 5347:245622471702 engine.c:jack_run_one_cycle:2159: run process
1512
1513 jack: 5347:245622471705 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1514 jack: 5347:245622471707 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1515 jack: 5347:245622471831 engine.c:jack_run_one_cycle:2211: release graph lock
1516 jack: 5347:245622471834 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1517 jack: 5347:245622477000 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1518 jack: 5347:245622477008 engine.c:jack_run_one_cycle:2150: release problem lock
1519 jack: 5347:245622477010 engine.c:jack_run_one_cycle:2153: waiting for driver read
1520
1521 jack: 5347:245622477036 engine.c:jack_run_one_cycle:2159: run process
1522
1523 jack: 5347:245622477039 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1524 jack: 5347:245622477041 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1525 jack: 5347:245622477172 engine.c:jack_run_one_cycle:2211: release graph lock
1526 jack: 5347:245622477176 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1527 jack: 5347:245622482332 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1528 jack: 5347:245622482339 engine.c:jack_run_one_cycle:2150: release problem lock
1529 jack: 5347:245622482341 engine.c:jack_run_one_cycle:2153: waiting for driver read
1530
1531 jack: 5347:245622482366 engine.c:jack_run_one_cycle:2159: run process
1532
1533 jack: 5347:245622482369 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1534 jack: 5347:245622482371 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1535 jack: 5347:245622482496 engine.c:jack_run_one_cycle:2211: release graph lock
1536 jack: 5347:245622482499 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1537 jack: 5347:245622487667 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1538 jack: 5347:245622487675 engine.c:jack_run_one_cycle:2150: release problem lock
1539 jack: 5347:245622487677 engine.c:jack_run_one_cycle:2153: waiting for driver read
1540
1541 jack: 5347:245622487705 engine.c:jack_run_one_cycle:2159: run process
1542
1543 jack: 5347:245622487707 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1544 jack: 5347:245622487709 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1545 jack: 5347:245622487834 engine.c:jack_run_one_cycle:2211: release graph lock
1546 jack: 5347:245622487837 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1547 jack: 5347:245622493007 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1548 jack: 5347:245622493016 engine.c:jack_run_one_cycle:2150: release problem lock
1549 jack: 5347:245622493018 engine.c:jack_run_one_cycle:2153: waiting for driver read
1550
1551 jack: 5347:245622493046 engine.c:jack_run_one_cycle:2159: run process
1552
1553 jack: 5347:245622493048 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1554 jack: 5347:245622493051 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1555 jack: 5347:245622493183 engine.c:jack_run_one_cycle:2211: release graph lock
1556 jack: 5347:245622493186 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1557 jack: 5347:245622498339 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1558 jack: 5347:245622498348 engine.c:jack_run_one_cycle:2150: release problem lock
1559 jack: 5347:245622498350 engine.c:jack_run_one_cycle:2153: waiting for driver read
1560
1561 jack: 5347:245622498379 engine.c:jack_run_one_cycle:2159: run process
1562
1563 jack: 5347:245622498382 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1564 jack: 5347:245622498384 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1565 jack: 5347:245622498511 engine.c:jack_run_one_cycle:2211: release graph lock
1566 jack: 5347:245622498514 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1567 jack: 5347:245622503669 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1568 jack: 5347:245622503676 engine.c:jack_run_one_cycle:2150: release problem lock
1569 jack: 5347:245622503678 engine.c:jack_run_one_cycle:2153: waiting for driver read
1570
1571 jack: 5347:245622503704 engine.c:jack_run_one_cycle:2159: run process
1572
1573 jack: 5347:245622503706 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1574 jack: 5347:245622503708 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1575 jack: 5347:245622503833 engine.c:jack_run_one_cycle:2211: release graph lock
1576 jack: 5347:245622503836 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1577 jack: 5347:245622509005 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1578 jack: 5347:245622509012 engine.c:jack_run_one_cycle:2150: release problem lock
1579 jack: 5347:245622509014 engine.c:jack_run_one_cycle:2153: waiting for driver read
1580
1581 jack: 5347:245622509040 engine.c:jack_run_one_cycle:2159: run process
1582
1583 jack: 5347:245622509042 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1584 jack: 5347:245622509045 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1585 jack: 5347:245622509176 engine.c:jack_run_one_cycle:2211: release graph lock
1586 jack: 5347:245622509179 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1587 jack: 5347:245622514339 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1588 jack: 5347:245622514347 engine.c:jack_run_one_cycle:2150: release problem lock
1589 jack: 5347:245622514349 engine.c:jack_run_one_cycle:2153: waiting for driver read
1590
1591 jack: 5347:245622514376 engine.c:jack_run_one_cycle:2159: run process
1592
1593 jack: 5347:245622514378 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1594 jack: 5347:245622514381 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1595 jack: 5347:245622514505 engine.c:jack_run_one_cycle:2211: release graph lock
1596 jack: 5347:245622514508 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1597 jack: 5347:245622519674 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1598 jack: 5347:245622519681 engine.c:jack_run_one_cycle:2150: release problem lock
1599 jack: 5347:245622519684 engine.c:jack_run_one_cycle:2153: waiting for driver read
1600
1601 jack: 5347:245622519709 engine.c:jack_run_one_cycle:2159: run process
1602
1603 jack: 5347:245622519712 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1604 jack: 5347:245622519714 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1605 jack: 5347:245622519839 engine.c:jack_run_one_cycle:2211: release graph lock
1606 jack: 5347:245622519842 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1607 jack: 5347:245622525013 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1608 jack: 5347:245622525022 engine.c:jack_run_one_cycle:2150: release problem lock
1609 jack: 5347:245622525025 engine.c:jack_run_one_cycle:2153: waiting for driver read
1610
1611 jack: 5347:245622525057 engine.c:jack_run_one_cycle:2159: run process
1612
1613 jack: 5347:245622525059 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1614 jack: 5347:245622525062 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1615 jack: 5347:245622525195 engine.c:jack_run_one_cycle:2211: release graph lock
1616 jack: 5347:245622525198 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1617 jack: 5347:245622530342 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1618 jack: 5347:245622530350 engine.c:jack_run_one_cycle:2150: release problem lock
1619 jack: 5347:245622530352 engine.c:jack_run_one_cycle:2153: waiting for driver read
1620
1621 jack: 5347:245622530378 engine.c:jack_run_one_cycle:2159: run process
1622
1623 jack: 5347:245622530381 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1624 jack: 5347:245622530383 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1625 jack: 5347:245622530507 engine.c:jack_run_one_cycle:2211: release graph lock
1626 jack: 5347:245622530510 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1627 jack: 5347:245622535678 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1628 jack: 5347:245622535686 engine.c:jack_run_one_cycle:2150: release problem lock
1629 jack: 5347:245622535688 engine.c:jack_run_one_cycle:2153: waiting for driver read
1630
1631 jack: 5347:245622535715 engine.c:jack_run_one_cycle:2159: run process
1632
1633 jack: 5347:245622535717 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1634 jack: 5347:245622535720 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1635 jack: 5347:245622535845 engine.c:jack_run_one_cycle:2211: release graph lock
1636 jack: 5347:245622535848 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1637 jack: 5347:245622541009 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1638 jack: 5347:245622541016 engine.c:jack_run_one_cycle:2150: release problem lock
1639 jack: 5347:245622541019 engine.c:jack_run_one_cycle:2153: waiting for driver read
1640
1641 jack: 5347:245622541044 engine.c:jack_run_one_cycle:2159: run process
1642
1643 jack: 5347:245622541046 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1644 jack: 5347:245622541048 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1645 jack: 5347:245622541178 engine.c:jack_run_one_cycle:2211: release graph lock
1646 jack: 5347:245622541182 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1647 jack: 5347:245622546343 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1648 jack: 5347:245622546350 engine.c:jack_run_one_cycle:2150: release problem lock
1649 jack: 5347:245622546352 engine.c:jack_run_one_cycle:2153: waiting for driver read
1650
1651 jack: 5347:245622546378 engine.c:jack_run_one_cycle:2159: run process
1652
1653 jack: 5347:245622546381 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1654 jack: 5347:245622546383 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1655 jack: 5347:245622546508 engine.c:jack_run_one_cycle:2211: release graph lock
1656 jack: 5347:245622546511 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1657 jack: 5347:245622551680 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1658 jack: 5347:245622551688 engine.c:jack_run_one_cycle:2150: release problem lock
1659 jack: 5347:245622551690 engine.c:jack_run_one_cycle:2153: waiting for driver read
1660
1661 jack: 5347:245622551717 engine.c:jack_run_one_cycle:2159: run process
1662
1663 jack: 5347:245622551719 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1664 jack: 5347:245622551721 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1665 jack: 5347:245622551847 engine.c:jack_run_one_cycle:2211: release graph lock
1666 jack: 5347:245622551850 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1667 jack: 5347:245622557019 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1668 jack: 5347:245622557029 engine.c:jack_run_one_cycle:2150: release problem lock
1669 jack: 5347:245622557031 engine.c:jack_run_one_cycle:2153: waiting for driver read
1670
1671 jack: 5347:245622557061 engine.c:jack_run_one_cycle:2159: run process
1672
1673 jack: 5347:245622557063 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1674 jack: 5347:245622557065 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1675 jack: 5347:245622557198 engine.c:jack_run_one_cycle:2211: release graph lock
1676 jack: 5347:245622557201 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1677 jack: 5347:245622562346 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1678 jack: 5347:245622562354 engine.c:jack_run_one_cycle:2150: release problem lock
1679 jack: 5347:245622562356 engine.c:jack_run_one_cycle:2153: waiting for driver read
1680
1681 jack: 5347:245622562384 engine.c:jack_run_one_cycle:2159: run process
1682
1683 jack: 5347:245622562387 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1684 jack: 5347:245622562389 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1685 jack: 5347:245622562514 engine.c:jack_run_one_cycle:2211: release graph lock
1686 jack: 5347:245622562517 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1687 jack: 5347:245622567680 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1688 jack: 5347:245622567687 engine.c:jack_run_one_cycle:2150: release problem lock
1689 jack: 5347:245622567690 engine.c:jack_run_one_cycle:2153: waiting for driver read
1690
1691 jack: 5347:245622567716 engine.c:jack_run_one_cycle:2159: run process
1692
1693 jack: 5347:245622567719 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1694 jack: 5347:245622567721 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1695 jack: 5347:245622567846 engine.c:jack_run_one_cycle:2211: release graph lock
1696 jack: 5347:245622567848 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1697 jack: 5347:245622573014 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1698 jack: 5347:245622573021 engine.c:jack_run_one_cycle:2150: release problem lock
1699 jack: 5347:245622573023 engine.c:jack_run_one_cycle:2153: waiting for driver read
1700
1701 jack: 5347:245622573048 engine.c:jack_run_one_cycle:2159: run process
1702
1703 jack: 5347:245622573051 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1704 jack: 5347:245622573053 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1705 jack: 5347:245622573184 engine.c:jack_run_one_cycle:2211: release graph lock
1706 jack: 5347:245622573187 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1707 jack: 5347:245622578348 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1708 jack: 5347:245622578355 engine.c:jack_run_one_cycle:2150: release problem lock
1709 jack: 5347:245622578358 engine.c:jack_run_one_cycle:2153: waiting for driver read
1710
1711 jack: 5347:245622578383 engine.c:jack_run_one_cycle:2159: run process
1712
1713 jack: 5347:245622578385 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1714 jack: 5347:245622578388 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1715 jack: 5347:245622578512 engine.c:jack_run_one_cycle:2211: release graph lock
1716 jack: 5347:245622578515 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1717 jack: 5347:245622583688 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1718 jack: 5347:245622583697 engine.c:jack_run_one_cycle:2150: release problem lock
1719 jack: 5347:245622583700 engine.c:jack_run_one_cycle:2153: waiting for driver read
1720
1721 jack: 5347:245622583728 engine.c:jack_run_one_cycle:2159: run process
1722
1723 jack: 5347:245622583730 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1724 jack: 5347:245622583733 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1725 jack: 5347:245622583859 engine.c:jack_run_one_cycle:2211: release graph lock
1726 jack: 5347:245622583862 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1727 jack: 5347:245622589021 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1728 jack: 5347:245622589029 engine.c:jack_run_one_cycle:2150: release problem lock
1729 jack: 5347:245622589031 engine.c:jack_run_one_cycle:2153: waiting for driver read
1730
1731 jack: 5347:245622589059 engine.c:jack_run_one_cycle:2159: run process
1732
1733 jack: 5347:245622589062 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1734 jack: 5347:245622589064 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1735 jack: 5347:245622589197 engine.c:jack_run_one_cycle:2211: release graph lock
1736 jack: 5347:245622589200 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1737 jack: 5347:245622594349 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1738 jack: 5347:245622594356 engine.c:jack_run_one_cycle:2150: release problem lock
1739 jack: 5347:245622594359 engine.c:jack_run_one_cycle:2153: waiting for driver read
1740
1741 jack: 5347:245622594384 engine.c:jack_run_one_cycle:2159: run process
1742
1743 jack: 5347:245622594387 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1744 jack: 5347:245622594389 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1745 jack: 5347:245622594513 engine.c:jack_run_one_cycle:2211: release graph lock
1746 jack: 5347:245622594516 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1747 jack: 5347:245622599685 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1748 jack: 5347:245622599692 engine.c:jack_run_one_cycle:2150: release problem lock
1749 jack: 5347:245622599694 engine.c:jack_run_one_cycle:2153: waiting for driver read
1750
1751 jack: 5347:245622599722 engine.c:jack_run_one_cycle:2159: run process
1752
1753 jack: 5347:245622599724 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1754 jack: 5347:245622599727 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1755 jack: 5347:245622599852 engine.c:jack_run_one_cycle:2211: release graph lock
1756 jack: 5347:245622599855 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1757 jack: 5347:245622605018 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1758 jack: 5347:245622605025 engine.c:jack_run_one_cycle:2150: release problem lock
1759 jack: 5347:245622605027 engine.c:jack_run_one_cycle:2153: waiting for driver read
1760
1761 jack: 5347:245622605053 engine.c:jack_run_one_cycle:2159: run process
1762
1763 jack: 5347:245622605055 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1764 jack: 5347:245622605057 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1765 jack: 5347:245622605186 engine.c:jack_run_one_cycle:2211: release graph lock
1766 jack: 5347:245622605190 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1767 jack: 5347:245622610356 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1768 jack: 5347:245622610365 engine.c:jack_run_one_cycle:2150: release problem lock
1769 jack: 5347:245622610367 engine.c:jack_run_one_cycle:2153: waiting for driver read
1770
1771 jack: 5347:245622610395 engine.c:jack_run_one_cycle:2159: run process
1772
1773 jack: 5347:245622610397 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1774 jack: 5347:245622610399 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1775 jack: 5347:245622610527 engine.c:jack_run_one_cycle:2211: release graph lock
1776 jack: 5347:245622610530 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1777 jack: 5347:245622615701 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1778 jack: 5347:245622615714 engine.c:jack_run_one_cycle:2150: release problem lock
1779 jack: 5347:245622615716 engine.c:jack_run_one_cycle:2153: waiting for driver read
1780
1781 jack: 5347:245622615752 engine.c:jack_run_one_cycle:2159: run process
1782
1783 jack: 5347:245622615754 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1784 jack: 5347:245622615757 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1785 jack: 5347:245622615886 engine.c:jack_run_one_cycle:2211: release graph lock
1786 jack: 5347:245622615889 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1787 jack: 5347:245622621035 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1788 jack: 5347:245622621046 engine.c:jack_run_one_cycle:2150: release problem lock
1789 jack: 5347:245622621048 engine.c:jack_run_one_cycle:2153: waiting for driver read
1790
1791 jack: 5347:245622621080 engine.c:jack_run_one_cycle:2159: run process
1792
1793 jack: 5347:245622621082 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1794 jack: 5347:245622621085 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1795 jack: 5347:245622621220 engine.c:jack_run_one_cycle:2211: release graph lock
1796 jack: 5347:245622621224 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1797 jack: 5347:245622626356 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1798 jack: 5347:245622626364 engine.c:jack_run_one_cycle:2150: release problem lock
1799 jack: 5347:245622626366 engine.c:jack_run_one_cycle:2153: waiting for driver read
1800
1801 jack: 5347:245622626393 engine.c:jack_run_one_cycle:2159: run process
1802
1803 jack: 5347:245622626396 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1804 jack: 5347:245622626398 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1805 jack: 5347:245622626524 engine.c:jack_run_one_cycle:2211: release graph lock
1806 jack: 5347:245622626527 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1807 jack: 5347:245622631691 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1808 jack: 5347:245622631700 engine.c:jack_run_one_cycle:2150: release problem lock
1809 jack: 5347:245622631702 engine.c:jack_run_one_cycle:2153: waiting for driver read
1810
1811 jack: 5347:245622631729 engine.c:jack_run_one_cycle:2159: run process
1812
1813 jack: 5347:245622631732 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1814 jack: 5347:245622631734 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1815 jack: 5347:245622631861 engine.c:jack_run_one_cycle:2211: release graph lock
1816 jack: 5347:245622631864 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1817 jack: 5347:245622637026 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1818 jack: 5347:245622637033 engine.c:jack_run_one_cycle:2150: release problem lock
1819 jack: 5347:245622637036 engine.c:jack_run_one_cycle:2153: waiting for driver read
1820
1821 jack: 5347:245622637064 engine.c:jack_run_one_cycle:2159: run process
1822
1823 jack: 5347:245622637066 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1824 jack: 5347:245622637069 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1825 jack: 5347:245622637203 engine.c:jack_run_one_cycle:2211: release graph lock
1826 jack: 5347:245622637206 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1827 jack: 5347:245622642365 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1828 jack: 5347:245622642376 engine.c:jack_run_one_cycle:2150: release problem lock
1829 jack: 5347:245622642378 engine.c:jack_run_one_cycle:2153: waiting for driver read
1830
1831 jack: 5347:245622642408 engine.c:jack_run_one_cycle:2159: run process
1832
1833 jack: 5347:245622642411 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1834 jack: 5347:245622642413 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1835 jack: 5347:245622642540 engine.c:jack_run_one_cycle:2211: release graph lock
1836 jack: 5347:245622642543 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1837 jack: 5347:245622647700 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1838 jack: 5347:245622647708 engine.c:jack_run_one_cycle:2150: release problem lock
1839 jack: 5347:245622647711 engine.c:jack_run_one_cycle:2153: waiting for driver read
1840
1841 jack: 5347:245622647738 engine.c:jack_run_one_cycle:2159: run process
1842
1843 jack: 5347:245622647740 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1844 jack: 5347:245622647743 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1845 jack: 5347:245622647870 engine.c:jack_run_one_cycle:2211: release graph lock
1846 jack: 5347:245622647873 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1847 jack: 5347:245622653029 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1848 jack: 5347:245622653037 engine.c:jack_run_one_cycle:2150: release problem lock
1849 jack: 5347:245622653039 engine.c:jack_run_one_cycle:2153: waiting for driver read
1850
1851 jack: 5347:245622653067 engine.c:jack_run_one_cycle:2159: run process
1852
1853 jack: 5347:245622653069 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1854 jack: 5347:245622653071 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1855 jack: 5347:245622653204 engine.c:jack_run_one_cycle:2211: release graph lock
1856 jack: 5347:245622653207 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1857 jack: 5347:245622658359 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1858 jack: 5347:245622658366 engine.c:jack_run_one_cycle:2150: release problem lock
1859 jack: 5347:245622658368 engine.c:jack_run_one_cycle:2153: waiting for driver read
1860
1861 jack: 5347:245622658394 engine.c:jack_run_one_cycle:2159: run process
1862
1863 jack: 5347:245622658396 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1864 jack: 5347:245622658398 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1865 jack: 5347:245622658522 engine.c:jack_run_one_cycle:2211: release graph lock
1866 jack: 5347:245622658525 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1867 jack: 5347:245622663696 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1868 jack: 5347:245622663703 engine.c:jack_run_one_cycle:2150: release problem lock
1869 jack: 5347:245622663705 engine.c:jack_run_one_cycle:2153: waiting for driver read
1870
1871 jack: 5347:245622663732 engine.c:jack_run_one_cycle:2159: run process
1872
1873 jack: 5347:245622663734 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1874 jack: 5347:245622663737 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1875 jack: 5347:245622663863 engine.c:jack_run_one_cycle:2211: release graph lock
1876 jack: 5347:245622663866 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1877 jack: 5347:245622669031 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1878 jack: 5347:245622669038 engine.c:jack_run_one_cycle:2150: release problem lock
1879 jack: 5347:245622669041 engine.c:jack_run_one_cycle:2153: waiting for driver read
1880
1881 jack: 5347:245622669066 engine.c:jack_run_one_cycle:2159: run process
1882
1883 jack: 5347:245622669069 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1884 jack: 5347:245622669071 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1885 jack: 5347:245622669204 engine.c:jack_run_one_cycle:2211: release graph lock
1886 jack: 5347:245622669207 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1887 jack: 5347:245622674368 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1888 jack: 5347:245622674378 engine.c:jack_run_one_cycle:2150: release problem lock
1889 jack: 5347:245622674380 engine.c:jack_run_one_cycle:2153: waiting for driver read
1890
1891 jack: 5347:245622674412 engine.c:jack_run_one_cycle:2159: run process
1892
1893 jack: 5347:245622674414 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1894 jack: 5347:245622674417 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1895 jack: 5347:245622674545 engine.c:jack_run_one_cycle:2211: release graph lock
1896 jack: 5347:245622674548 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1897 jack: 5347:245622679698 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1898 jack: 5347:245622679706 engine.c:jack_run_one_cycle:2150: release problem lock
1899 jack: 5347:245622679708 engine.c:jack_run_one_cycle:2153: waiting for driver read
1900
1901 jack: 5347:245622679735 engine.c:jack_run_one_cycle:2159: run process
1902
1903 jack: 5347:245622679737 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1904 jack: 5347:245622679740 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1905 jack: 5347:245622679866 engine.c:jack_run_one_cycle:2211: release graph lock
1906 jack: 5347:245622679869 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1907 jack: 5347:245622685033 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1908 jack: 5347:245622685041 engine.c:jack_run_one_cycle:2150: release problem lock
1909 jack: 5347:245622685043 engine.c:jack_run_one_cycle:2153: waiting for driver read
1910
1911 jack: 5347:245622685070 engine.c:jack_run_one_cycle:2159: run process
1912
1913 jack: 5347:245622685072 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1914 jack: 5347:245622685075 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1915 jack: 5347:245622685207 engine.c:jack_run_one_cycle:2211: release graph lock
1916 jack: 5347:245622685210 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1917 jack: 5347:245622690364 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1918 jack: 5347:245622690371 engine.c:jack_run_one_cycle:2150: release problem lock
1919 jack: 5347:245622690373 engine.c:jack_run_one_cycle:2153: waiting for driver read
1920
1921 jack: 5347:245622690399 engine.c:jack_run_one_cycle:2159: run process
1922
1923 jack: 5347:245622690401 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1924 jack: 5347:245622690403 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1925 jack: 5347:245622690531 engine.c:jack_run_one_cycle:2211: release graph lock
1926 jack: 5347:245622690534 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1927
1928 ffado_streaming_wait
1929 ============================================
1930 Xruns: 0
1931 ============================================
1932 ----------------------------------------------------
1933 Dumping StreamProcessorManager information...
1934 Period count:    175
1935 Data type: float
1936  Receive processors...
1937  StreamProcessor 0xd640e0, Receive:
1938   Port, Channel    : 0, 0
1939   Packets, Dropped, Skipped : 10801, 0, 0
1940   Now                   : 00278962585 (011s 2808c 0409t)
1941   Xrun?                 : False
1942   State                 : ePS_Running
1943   Buffer                : 0xd63f10
1944   Framerate             : Nominal: 192000, Sync: 191996.933032, Buffer 191996.933032
1945   TimestampedBuffer (0xd63f10): 0416 frames, 0416 events
1946    Timestamps           : head:  278892866.929, Tail:  278946115.780, Next tail:  278950211.846
1947     Head - Tail         :     -53248.852 (-416.001566 frames)
1948    DLL Rate             : 4096.050087 (128.001565)
1949    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
1950  Transmit processors...
1951  StreamProcessor 0xd647e0, Transmit:
1952   Port, Channel    : 0, 1
1953   Packets, Dropped, Skipped : 10922, 0, 0
1954   Now                   : 00278963863 (011s 2808c 1687t)
1955   Xrun?                 : False
1956   State                 : ePS_Running
1957   Buffer                : 0xd64010
1958   Framerate             : Nominal: 192000, Sync: 191996.933032, Buffer 191997.482333
1959   TimestampedBuffer (0xd64010): 0896 frames, 0896 events
1960    Timestamps           : head:  279318812.933, Tail:  279433502.433, Next tail:  279564576.157
1961     Head - Tail         :    -114689.500 (-896.001340 frames)
1962    DLL Rate             : 131073.518220 (128.001483)
1963    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
1964 ----------------------------------------------------
1965 Port Information
1966  Playback
1967     0 (0xd63e80): [0xd647e0] [off] [  Audio] dev0_pbk_Analog1
1968     1 (0xd65960): [0xd647e0] [off] [  Audio] dev0_pbk_Analog2
1969     2 (0xd659f0): [0xd647e0] [off] [  Audio] dev0_pbk_Analog3
1970     3 (0xd65a80): [0xd647e0] [off] [  Audio] dev0_pbk_Analog4
1971     4 (0xd65b10): [0xd647e0] [off] [  Audio] dev0_pbk_Analog5
1972     5 (0xd65c40): [0xd647e0] [off] [  Audio] dev0_pbk_Analog6
1973     6 (0xd65cd0): [0xd647e0] [off] [  Audio] dev0_pbk_Analog7
1974     7 (0xd65d60): [0xd647e0] [off] [  Audio] dev0_pbk_Analog8
1975     8 (0xd65db0): [0xd647e0] [off] [   MIDI] dev0_pbk_MIDI0
1976  Capture
1977     0 (0xd4a5e0): [0xd640e0] [off] [  Audio]  dev0_cap_Analog1
1978     1 (0xd5eaa0): [0xd640e0] [off] [  Audio]  dev0_cap_Analog2
1979     2 (0xd5eaf0): [0xd640e0] [off] [  Audio]  dev0_cap_Analog3
1980     3 (0xd5eb60): [0xd640e0] [off] [  Audio]  dev0_cap_Analog4
1981     4 (0xd4a890): [0xd640e0] [off] [  Audio]  dev0_cap_Analog5
1982     5 (0xd64620): [0xd640e0] [off] [  Audio]  dev0_cap_Analog6
1983     6 (0xd646b0): [0xd640e0] [off] [  Audio]  dev0_cap_Analog7
1984     7 (0xd64740): [0xd640e0] [off] [  Audio] jack: 5347:245622695700 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1985  dev0_cap_Analog8
1986     8 (0xd64790): [0xd640e0] [off] [   MIDI]  dev0_cap_MIDI0
1987 ----------------------------------------------------
1988
1989 jack: 5347:245622695707 engine.c:jack_run_one_cycle:2150: release problem lock
1990 jack: 5347:245622695709 engine.c:jack_run_one_cycle:2153: waiting for driver read
1991
1992 jack: 5347:245622695734 engine.c:jack_run_one_cycle:2159: run process
1993
1994 jack: 5347:245622695737 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1995 jack: 5347:245622695739 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1996 jack: 5347:245622695863 engine.c:jack_run_one_cycle:2211: release graph lock
1997 jack: 5347:245622695866 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1998 jack: 5347:245622701038 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1999 jack: 5347:245622701047 engine.c:jack_run_one_cycle:2150: release problem lock
2000 jack: 5347:245622701049 engine.c:jack_run_one_cycle:2153: waiting for driver read
2001
2002 jack: 5347:245622701077 engine.c:jack_run_one_cycle:2159: run process
2003
2004 jack: 5347:245622701079 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2005 jack: 5347:245622701081 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2006 jack: 5347:245622701215 engine.c:jack_run_one_cycle:2211: release graph lock
2007 jack: 5347:245622701218 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2008 jack: 5347:245622706376 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2009 jack: 5347:245622706387 engine.c:jack_run_one_cycle:2150: release problem lock
2010 jack: 5347:245622706389 engine.c:jack_run_one_cycle:2153: waiting for driver read
2011
2012 jack: 5347:245622706420 engine.c:jack_run_one_cycle:2159: run process
2013
2014 jack: 5347:245622706423 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2015 jack: 5347:245622706425 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2016 jack: 5347:245622706554 engine.c:jack_run_one_cycle:2211: release graph lock
2017 jack: 5347:245622706557 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2018 jack: 5347:245622711707 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2019 jack: 5347:245622711716 engine.c:jack_run_one_cycle:2150: release problem lock
2020 jack: 5347:245622711718 engine.c:jack_run_one_cycle:2153: waiting for driver read
2021
2022 jack: 5347:245622711748 engine.c:jack_run_one_cycle:2159: run process
2023
2024 jack: 5347:245622711750 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2025 jack: 5347:245622711753 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2026 jack: 5347:245622711880 engine.c:jack_run_one_cycle:2211: release graph lock
2027 jack: 5347:245622711883 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2028 jack: 5347:245622717037 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2029 jack: 5347:245622717045 engine.c:jack_run_one_cycle:2150: release problem lock
2030 jack: 5347:245622717047 engine.c:jack_run_one_cycle:2153: waiting for driver read
2031
2032 jack: 5347:245622717074 engine.c:jack_run_one_cycle:2159: run process
2033
2034 jack: 5347:245622717076 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2035 jack: 5347:245622717078 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2036 jack: 5347:245622717209 engine.c:jack_run_one_cycle:2211: release graph lock
2037 jack: 5347:245622717213 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2038 jack: 5347:245622722372 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2039 jack: 5347:245622722379 engine.c:jack_run_one_cycle:2150: release problem lock
2040 jack: 5347:245622722381 engine.c:jack_run_one_cycle:2153: waiting for driver read
2041
2042 jack: 5347:245622722407 engine.c:jack_run_one_cycle:2159: run process
2043
2044 jack: 5347:245622722409 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2045 jack: 5347:245622722412 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2046 jack: 5347:245622722536 engine.c:jack_run_one_cycle:2211: release graph lock
2047 jack: 5347:245622722539 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2048 jack: 5347:245622727708 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2049 jack: 5347:245622727715 engine.c:jack_run_one_cycle:2150: release problem lock
2050 jack: 5347:245622727717 engine.c:jack_run_one_cycle:2153: waiting for driver read
2051
2052 jack: 5347:245622727743 engine.c:jack_run_one_cycle:2159: run process
2053
2054 jack: 5347:245622727745 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2055 jack: 5347:245622727748 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2056 jack: 5347:245622727873 engine.c:jack_run_one_cycle:2211: release graph lock
2057 jack: 5347:245622727876 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2058 jack: 5347:245622733043 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2059 jack: 5347:245622733053 engine.c:jack_run_one_cycle:2150: release problem lock
2060 jack: 5347:245622733056 engine.c:jack_run_one_cycle:2153: waiting for driver read
2061
2062 jack: 5347:245622733085 engine.c:jack_run_one_cycle:2159: run process
2063
2064 jack: 5347:245622733087 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2065 jack: 5347:245622733090 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2066 jack: 5347:245622733223 engine.c:jack_run_one_cycle:2211: release graph lock
2067 jack: 5347:245622733227 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2068 jack: 5347:245622738376 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2069 jack: 5347:245622738384 engine.c:jack_run_one_cycle:2150: release problem lock
2070 jack: 5347:245622738386 engine.c:jack_run_one_cycle:2153: waiting for driver read
2071
2072 jack: 5347:245622738415 engine.c:jack_run_one_cycle:2159: run process
2073
2074 jack: 5347:245622738418 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2075 jack: 5347:245622738420 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2076 jack: 5347:245622738545 engine.c:jack_run_one_cycle:2211: release graph lock
2077 jack: 5347:245622738548 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2078 jack: 5347:245622743709 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2079 jack: 5347:245622743717 engine.c:jack_run_one_cycle:2150: release problem lock
2080 jack: 5347:245622743719 engine.c:jack_run_one_cycle:2153: waiting for driver read
2081
2082 jack: 5347:245622743745 engine.c:jack_run_one_cycle:2159: run process
2083
2084 jack: 5347:245622743747 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2085 jack: 5347:245622743749 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2086 jack: 5347:245622743875 engine.c:jack_run_one_cycle:2211: release graph lock
2087 jack: 5347:245622743878 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2088 jack: 5347:245622749041 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2089 jack: 5347:245622749048 engine.c:jack_run_one_cycle:2150: release problem lock
2090 jack: 5347:245622749051 engine.c:jack_run_one_cycle:2153: waiting for driver read
2091
2092 jack: 5347:245622749078 engine.c:jack_run_one_cycle:2159: run process
2093
2094 jack: 5347:245622749081 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2095 jack: 5347:245622749083 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2096 jack: 5347:245622749214 engine.c:jack_run_one_cycle:2211: release graph lock
2097 jack: 5347:245622749218 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2098 jack: 5347:245622754376 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2099 jack: 5347:245622754384 engine.c:jack_run_one_cycle:2150: release problem lock
2100 jack: 5347:245622754386 engine.c:jack_run_one_cycle:2153: waiting for driver read
2101
2102 jack: 5347:245622754413 engine.c:jack_run_one_cycle:2159: run process
2103
2104 jack: 5347:245622754415 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2105 jack: 5347:245622754417 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2106 jack: 5347:245622754543 engine.c:jack_run_one_cycle:2211: release graph lock
2107 jack: 5347:245622754546 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2108 jack: 5347:245622759713 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2109 jack: 5347:245622759722 engine.c:jack_run_one_cycle:2150: release problem lock
2110 jack: 5347:245622759724 engine.c:jack_run_one_cycle:2153: waiting for driver read
2111
2112 jack: 5347:245622759752 engine.c:jack_run_one_cycle:2159: run process
2113
2114 jack: 5347:245622759755 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2115 jack: 5347:245622759757 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2116 jack: 5347:245622759881 engine.c:jack_run_one_cycle:2211: release graph lock
2117 jack: 5347:245622759884 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2118 jack: 5347:245622765051 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2119 jack: 5347:245622765061 engine.c:jack_run_one_cycle:2150: release problem lock
2120 jack: 5347:245622765063 engine.c:jack_run_one_cycle:2153: waiting for driver read
2121
2122 jack: 5347:245622765093 engine.c:jack_run_one_cycle:2159: run process
2123
2124 jack: 5347:245622765095 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2125 jack: 5347:245622765098 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2126 jack: 5347:245622765230 engine.c:jack_run_one_cycle:2211: release graph lock
2127 jack: 5347:245622765233 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2128 jack: 5347:245622770378 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2129 jack: 5347:245622770386 engine.c:jack_run_one_cycle:2150: release problem lock
2130 jack: 5347:245622770388 engine.c:jack_run_one_cycle:2153: waiting for driver read
2131
2132 jack: 5347:245622770414 engine.c:jack_run_one_cycle:2159: run process
2133
2134 jack: 5347:245622770416 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2135 jack: 5347:245622770419 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2136 jack: 5347:245622770544 engine.c:jack_run_one_cycle:2211: release graph lock
2137 jack: 5347:245622770547 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2138 jack: 5347:245622775712 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2139 jack: 5347:245622775720 engine.c:jack_run_one_cycle:2150: release problem lock
2140 jack: 5347:245622775722 engine.c:jack_run_one_cycle:2153: waiting for driver read
2141
2142 jack: 5347:245622775748 engine.c:jack_run_one_cycle:2159: run process
2143
2144 jack: 5347:245622775750 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2145 jack: 5347:245622775752 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2146 jack: 5347:245622775877 engine.c:jack_run_one_cycle:2211: release graph lock
2147 jack: 5347:245622775880 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2148 jack: 5347:245622781045 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2149 jack: 5347:245622781052 engine.c:jack_run_one_cycle:2150: release problem lock
2150 jack: 5347:245622781055 engine.c:jack_run_one_cycle:2153: waiting for driver read
2151
2152 jack: 5347:245622781080 engine.c:jack_run_one_cycle:2159: run process
2153
2154 jack: 5347:245622781083 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2155 jack: 5347:245622781085 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2156 jack: 5347:245622781215 engine.c:jack_run_one_cycle:2211: release graph lock
2157 jack: 5347:245622781218 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2158 jack: 5347:245622786382 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2159 jack: 5347:245622786390 engine.c:jack_run_one_cycle:2150: release problem lock
2160 jack: 5347:245622786392 engine.c:jack_run_one_cycle:2153: waiting for driver read
2161
2162 jack: 5347:245622786419 engine.c:jack_run_one_cycle:2159: run process
2163
2164 jack: 5347:245622786421 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2165 jack: 5347:245622786423 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2166 jack: 5347:245622786548 engine.c:jack_run_one_cycle:2211: release graph lock
2167 jack: 5347:245622786551 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2168 jack: 5347:245622791719 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2169 jack: 5347:245622791728 engine.c:jack_run_one_cycle:2150: release problem lock
2170 jack: 5347:245622791730 engine.c:jack_run_one_cycle:2153: waiting for driver read
2171
2172 jack: 5347:245622791759 engine.c:jack_run_one_cycle:2159: run process
2173
2174 jack: 5347:245622791761 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2175 jack: 5347:245622791764 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2176 jack: 5347:245622791891 engine.c:jack_run_one_cycle:2211: release graph lock
2177 jack: 5347:245622791894 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2178 jack: 5347:245622797053 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2179 jack: 5347:245622797062 engine.c:jack_run_one_cycle:2150: release problem lock
2180 jack: 5347:245622797064 engine.c:jack_run_one_cycle:2153: waiting for driver read
2181
2182 jack: 5347:245622797092 engine.c:jack_run_one_cycle:2159: run process
2183
2184 jack: 5347:245622797095 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2185 jack: 5347:245622797097 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2186 jack: 5347:245622797229 engine.c:jack_run_one_cycle:2211: release graph lock
2187 jack: 5347:245622797232 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2188 jack: 5347:245622802382 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2189 jack: 5347:245622802389 engine.c:jack_run_one_cycle:2150: release problem lock
2190 jack: 5347:245622802391 engine.c:jack_run_one_cycle:2153: waiting for driver read
2191
2192 jack: 5347:245622802417 engine.c:jack_run_one_cycle:2159: run process
2193
2194 jack: 5347:245622802419 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2195 jack: 5347:245622802422 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2196 jack: 5347:245622802549 engine.c:jack_run_one_cycle:2211: release graph lock
2197 jack: 5347:245622802552 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2198 jack: 5347:245622807718 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2199 jack: 5347:245622807725 engine.c:jack_run_one_cycle:2150: release problem lock
2200 jack: 5347:245622807728 engine.c:jack_run_one_cycle:2153: waiting for driver read
2201
2202 jack: 5347:245622807754 engine.c:jack_run_one_cycle:2159: run process
2203
2204 jack: 5347:245622807756 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2205 jack: 5347:245622807759 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2206 jack: 5347:245622807884 engine.c:jack_run_one_cycle:2211: release graph lock
2207 jack: 5347:245622807887 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2208 jack: 5347:245622813051 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2209 jack: 5347:245622813057 engine.c:jack_run_one_cycle:2150: release problem lock
2210 jack: 5347:245622813060 engine.c:jack_run_one_cycle:2153: waiting for driver read
2211
2212 jack: 5347:245622813085 engine.c:jack_run_one_cycle:2159: run process
2213
2214 jack: 5347:245622813087 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2215 jack: 5347:245622813089 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2216 jack: 5347:245622813220 engine.c:jack_run_one_cycle:2211: release graph lock
2217 jack: 5347:245622813223 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2218 jack: 5347:245622818387 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2219 jack: 5347:245622818395 engine.c:jack_run_one_cycle:2150: release problem lock
2220 jack: 5347:245622818397 engine.c:jack_run_one_cycle:2153: waiting for driver read
2221
2222 jack: 5347:245622818425 engine.c:jack_run_one_cycle:2159: run process
2223
2224 jack: 5347:245622818427 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2225 jack: 5347:245622818430 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2226 jack: 5347:245622818556 engine.c:jack_run_one_cycle:2211: release graph lock
2227 jack: 5347:245622818559 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2228 jack: 5347:245622823725 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2229 jack: 5347:245622823735 engine.c:jack_run_one_cycle:2150: release problem lock
2230 jack: 5347:245622823737 engine.c:jack_run_one_cycle:2153: waiting for driver read
2231
2232 jack: 5347:245622823766 engine.c:jack_run_one_cycle:2159: run process
2233
2234 jack: 5347:245622823768 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2235 jack: 5347:245622823770 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2236 jack: 5347:245622823897 engine.c:jack_run_one_cycle:2211: release graph lock
2237 jack: 5347:245622823900 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2238 jack: 5347:245622829057 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2239 jack: 5347:245622829065 engine.c:jack_run_one_cycle:2150: release problem lock
2240 jack: 5347:245622829067 engine.c:jack_run_one_cycle:2153: waiting for driver read
2241
2242 jack: 5347:245622829097 engine.c:jack_run_one_cycle:2159: run process
2243
2244 jack: 5347:245622829099 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2245 jack: 5347:245622829101 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2246 jack: 5347:245622829234 engine.c:jack_run_one_cycle:2211: release graph lock
2247 jack: 5347:245622829237 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2248 jack: 5347:245622834387 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2249 jack: 5347:245622834394 engine.c:jack_run_one_cycle:2150: release problem lock
2250 jack: 5347:245622834397 engine.c:jack_run_one_cycle:2153: waiting for driver read
2251
2252 jack: 5347:245622834422 engine.c:jack_run_one_cycle:2159: run process
2253
2254 jack: 5347:245622834425 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2255 jack: 5347:245622834427 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2256 jack: 5347:245622834554 engine.c:jack_run_one_cycle:2211: release graph lock
2257 jack: 5347:245622834557 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2258 jack: 5347:245622839722 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2259 jack: 5347:245622839730 engine.c:jack_run_one_cycle:2150: release problem lock
2260 jack: 5347:245622839732 engine.c:jack_run_one_cycle:2153: waiting for driver read
2261
2262 jack: 5347:245622839758 engine.c:jack_run_one_cycle:2159: run process
2263
2264 jack: 5347:245622839761 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2265 jack: 5347:245622839763 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2266 jack: 5347:245622839888 engine.c:jack_run_one_cycle:2211: release graph lock
2267 jack: 5347:245622839891 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2268 jack: 5347:245622845058 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2269 jack: 5347:245622845065 engine.c:jack_run_one_cycle:2150: release problem lock
2270 jack: 5347:245622845067 engine.c:jack_run_one_cycle:2153: waiting for driver read
2271
2272 jack: 5347:245622845094 engine.c:jack_run_one_cycle:2159: run process
2273
2274 jack: 5347:245622845096 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2275 jack: 5347:245622845098 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2276 jack: 5347:245622845231 engine.c:jack_run_one_cycle:2211: release graph lock
2277 jack: 5347:245622845235 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2278 jack: 5347:245622850398 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2279 jack: 5347:245622850407 engine.c:jack_run_one_cycle:2150: release problem lock
2280 jack: 5347:245622850410 engine.c:jack_run_one_cycle:2153: waiting for driver read
2281
2282 jack: 5347:245622850439 engine.c:jack_run_one_cycle:2159: run process
2283
2284 jack: 5347:245622850441 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2285 jack: 5347:245622850443 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2286 jack: 5347:245622850570 engine.c:jack_run_one_cycle:2211: release graph lock
2287 jack: 5347:245622850573 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2288 ^Cjack main caught signal 2
2289 jack: 5347:245622855732 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2290 jack: 5347:245622855741 engine.c:jack_run_one_cycle:2150: release problem lock
2291 jack: 5347:245622855743 engine.c:jack_run_one_cycle:2153: waiting for driver read
2292
2293 jack: 5347:245622855772 engine.c:jack_run_one_cycle:2159: run process
2294
2295 jack: 5347:245622855775 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2296 jack: 5347:245622855777 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2297 jack: 5347:245622855904 engine.c:jack_run_one_cycle:2211: release graph lock
2298 jack: 5347:245622855907 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2299 245622856448: Debug (ffado.cpp)[ 218] ffado_streaming_stop: ------------- Stop -------------
2300 245622856494: Debug (StreamProcessorManager.cpp)[ 940] stop: Stopping...
2301 245622856505: Debug (StreamProcessorManager.cpp)[ 942] stop:  scheduling stop for all SP's...
2302 245622856511: Debug (StreamProcessor.cpp)[1251] scheduleStopRunning: for Receive SP (0xd640e0)
2303 245622856515: Debug (StreamProcessor.cpp)[1258] scheduleStopRunning:   Now                   : 00282909335 (011s 4092c 2711t)
2304 245622856520: Debug (StreamProcessor.cpp)[1263] scheduleStopRunning:   Stop at              : 00289053212 (011s 6092c 2588t)
2305 245622856526: Debug (StreamProcessor.cpp)[1251] scheduleStopRunning: for Transmit SP (0xd647e0)
2306 245622856529: Debug (StreamProcessor.cpp)[1258] scheduleStopRunning:   Now                   : 00282909679 (011s 4092c 3055t)
2307 245622856533: Debug (StreamProcessor.cpp)[1263] scheduleStopRunning:   Stop at              : 00289053605 (011s 6092c 2981t)
2308 245622856731: Debug (StreamProcessor.cpp)[ 667] getPacket: Should update state from ePS_Running to ePS_WaitingForStreamDisable
2309 245622856742: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Running => ePS_WaitingForStreamDisable
2310 245622856746: Debug (StreamProcessor.cpp)[1617] doWaitForStreamDisable: Enter from state: ePS_Running
2311 245622856749: Debug (StreamProcessor.cpp)[1629] doWaitForStreamDisable: State switch complete, dumping SP info...
2312  StreamProcessor 0xd647e0, Transmit:
2313   Port, Channel    : 0, 1
2314   Packets, Dropped, Skipped : 12203, 0, 0
2315   Now                   : 00282915379 (011s 4094c 2611t)
2316   Xrun?                 : False
2317   State                 : ePS_WaitingForStreamDisable
2318   Buffer                : 0xd64010
2319   Framerate             : Nominal: 192000, Sync: 191996.841483, Buffer 191997.367895
2320   TimestampedBuffer (0xd64010): 0896 frames, 0896 events
2321    Timestamps           : head:  283251025.639, Tail:  283365715.210, Next tail:  283496789.004
2322     Head - Tail         :    -114689.570 (-896.001775 frames)
2323    DLL Rate             : 131073.534964 (128.001499)
2324    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2325 245622857023: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_Running to ePS_WaitingForStreamDisable
2326 245622857033: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Running => ePS_WaitingForStreamDisable
2327 245622857037: Debug (StreamProcessor.cpp)[1617] doWaitForStreamDisable: Enter from state: ePS_Running
2328 245622857040: Debug (StreamProcessor.cpp)[1629] doWaitForStreamDisable: State switch complete, dumping SP info...
2329  StreamProcessor 0xd640e0, Receive:
2330   Port, Channel    : 0, 0
2331   Packets, Dropped, Skipped : 12083, 0, 0
2332   Now                   : 00282922432 (011s 4097c 0448t)
2333   Xrun?                 : False
2334   State                 : ePS_WaitingForStreamDisable
2335   Buffer                : 0xd63f10
2336   Framerate             : Nominal: 192000, Sync: 191996.841483, Buffer 191996.841483
2337   TimestampedBuffer (0xd63f10): 0416 frames, 0416 events
2338    Timestamps           : head:  282825090.682, Tail:  282878339.557, Next tail:  282882435.625
2339     Head - Tail         :     -53248.875 (-416.001635 frames)
2340    DLL Rate             : 4096.051210 (128.001600)
2341    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2342 245623091433: Debug (StreamProcessor.cpp)[ 595] getPacket: Should update state to DryRunning
2343 245623091450: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamDisable => ePS_DryRunning
2344 245623091454: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStreamDisable
2345 245623091459: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2346 245623091463: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
2347  StreamProcessor 0xd647e0, Transmit:
2348   Port, Channel    : 0, 1
2349   Packets, Dropped, Skipped : 14093, 0, 0
2350   Now                   : 00288682944 (011s 5972c 0960t)
2351   Xrun?                 : False
2352   State                 : ePS_DryRunning
2353   Buffer                : 0xd64010
2354   Framerate             : Nominal: 192000, Sync: 191996.841483, Buffer 192000.000000
2355   TimestampedBuffer (0xd64010): 0000 frames, 0000 events
2356    Timestamps           : head:  283365715.210, Tail:  283365715.210, Next tail:  283496789.004
2357     Head - Tail         :          0.000 (0.000000 frames)
2358    DLL Rate             : 131072.000000 (128.000000)
2359    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2360 245623107028: Debug (StreamProcessor.cpp)[ 321] putPacket: Should update state to DryRunning
2361 245623107041: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamDisable => ePS_DryRunning
2362 245623107045: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStreamDisable
2363 245623107049: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2364 245623107053: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
2365  StreamProcessor 0xd640e0, Receive:
2366   Port, Channel    : 0, 0
2367   Packets, Dropped, Skipped : 14093, 0, 0
2368   Now                   : 00289065957 (011s 6096c 3045t)
2369   Xrun?                 : False
2370   State                 : ePS_DryRunning
2371   Buffer                : 0xd63f10
2372   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
2373   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
2374    Timestamps           : head:  282882435.625, Tail:  282882435.625, Next tail:  282886531.692
2375     Head - Tail         :          0.000 (0.000000 frames)
2376    DLL Rate             : 4096.000000 (128.000000)
2377    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2378 245623107098: Warning (StreamProcessor.cpp)[ 389] putPacket: Instantanous samplerate more than 1% off nominal. [Nom fs: 192000.000000, Instantanous fs:   127.234254, diff: 191872.765746 (    0.999337)]
2379 245623107108: Debug (StreamProcessor.cpp)[ 401] putPacket: cy 6094 rather large TSP difference TS=00282882546 => TS=00289063523 (6180977, nom 4096)
2380 245623107115: Warning (TimestampedBuffer.cpp)[1053] incrementFrameCounter: (0xd63f10) difference rather large (+): diff=   6176991.308, max=      1536.000,  289063523.000,  282886531.692
2381 245623107126: Warning (TimestampedBuffer.cpp)[ 249] calculateRate: (0xd63f10) rate ( 156.58714) more that 10% off nominal (rate= 128.00000, diff=      5010.788, update_period=32)
2382 245623107424: Debug (StreamProcessor.cpp)[1226] scheduleStopDryRunning: for Receive SP (0xd640e0)
2383 245623107433: Debug (StreamProcessor.cpp)[1233] scheduleStopDryRunning:   Now                   : 00289075049 (011s 6099c 2921t)
2384 245623107437: Debug (StreamProcessor.cpp)[1238] scheduleStopDryRunning:   Stop at              : 00295217280 (012s 0099c 1152t)
2385 245623107443: Debug (StreamProcessor.cpp)[1226] scheduleStopDryRunning: for Transmit SP (0xd647e0)
2386 245623107446: Debug (StreamProcessor.cpp)[1233] scheduleStopDryRunning:   Now                   : 00289075369 (011s 6100c 0169t)
2387 245623107450: Debug (StreamProcessor.cpp)[1238] scheduleStopDryRunning:   Stop at              : 00295219295 (012s 0100c 0095t)
2388 245623109027: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_DryRunning to ePS_Stopped
2389 245623109036: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_Stopped
2390 245623109040: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_DryRunning
2391 245623109044: Debug (IsoHandlerManager.cpp)[1059] stopHandlerForStream:  stopping handler 0xd63da0 for stream 0xd640e0
2392 245623109049: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
2393 245623109052: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
2394 245623109055: Debug (IsoHandlerManager.cpp)[1071] stopHandlerForStream:  requested disable for handler 0xd63da0
2395 245623109058: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2396 245623109061: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
2397 245623109070: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
2398  StreamProcessor 0xd640e0, Receive:
2399   Port, Channel    : 0, 0
2400   Packets, Dropped, Skipped : 14099, 0, 0
2401   Now                   : 00289115471 (011s 6113c 0335t)
2402   Xrun?                 : False
2403   State                 : ePS_Stopped
2404   Buffer                : 0xd63f10
2405   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
2406   TimestampedBuffer (0xd63f10): 0000 frames, 0000 events
2407    Timestamps           : head:  289071715.000, Tail:  289071715.000, Next tail:  289075811.000
2408     Head - Tail         :          0.000 (0.000000 frames)
2409    DLL Rate             : 4096.000000 (128.000000)
2410    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2411 245623109129: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4ab30) updating shadow vars...
2412 245623109134: Debug (IsoHandlerManager.cpp)[1859] updateState: (0xd63da0) handler needs state update from 1 => 0
2413 245623109138: Debug (IsoHandlerManager.cpp)[1864] updateState: handler has to be disabled
2414 245623109141: Debug (IsoHandlerManager.cpp)[1782] disable: (0xd63da0, Receive) enter...
2415 245623109144: Debug (IsoHandlerManager.cpp)[1793] disable: (0xd63da0, Receive) wake up handle...
2416 245623109150: Debug (IsoHandlerManager.cpp)[1805] disable: (0xd63da0, Receive) stop...
2417 245623109234: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0xd4ab30) Receive handler 0xd63da0 skipped (disabled)
2418 245623109244: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4ab30) updated shadow vars...
2419 245623109996: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_DryRunning to ePS_Stopped
2420 245623110013: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_Stopped
2421 245623110017: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_DryRunning
2422 245623110021: Debug (IsoHandlerManager.cpp)[1059] stopHandlerForStream:  stopping handler 0xd61010 for stream 0xd647e0
2423 245623110025: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
2424 245623110028: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
2425 245623110031: Debug (IsoHandlerManager.cpp)[1071] stopHandlerForStream:  requested disable for handler 0xd61010
2426 245623110034: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2427 245623110037: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
2428 245623110041: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
2429  StreamProcessor 0xd647e0, Transmit:
2430   Port, Channel    : 0, 1
2431   Packets, Dropped, Skipped : 14230, 0, 0
2432   Now                   : 00289139331 (011s 6120c 2691t)
2433   Xrun?                 : False
2434   State                 : ePS_Stopped
2435   Buffer                : 0xd64010
2436   Framerate             : Nominal: 192000, Sync: 192000.000000, Buffer 192000.000000
2437   TimestampedBuffer (0xd64010): 0000 frames, 0000 events
2438    Timestamps           : head:  283365715.210, Tail:  283365715.210, Next tail:  283496789.004
2439     Head - Tail         :          0.000 (0.000000 frames)
2440    DLL Rate             : 131072.000000 (128.000000)
2441    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2442 245623110127: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4a910) updating shadow vars...
2443 245623110132: Debug (IsoHandlerManager.cpp)[1859] updateState: (0xd61010) handler needs state update from 1 => 0
2444 245623110135: Debug (IsoHandlerManager.cpp)[1864] updateState: handler has to be disabled
2445 245623110138: Debug (IsoHandlerManager.cpp)[1782] disable: (0xd61010, Transmit) enter...
2446 245623110141: Debug (IsoHandlerManager.cpp)[1793] disable: (0xd61010, Transmit) wake up handle...
2447 245623110146: Debug (IsoHandlerManager.cpp)[1805] disable: (0xd61010, Transmit) stop...
2448 245623110268: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0xd4a910) Transmit handler 0xd61010 skipped (disabled)
2449 245623110278: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4a910) updated shadow vars...
2450 245623110472: Debug (StreamProcessorManager.cpp)[1050] stop:  Stopped...
2451 245623110483: Debug (devicemanager.cpp)[ 968] stopStreamingOnDevice: Stopping stream 0 of device 0xd5fce0
2452 245623116305: Debug (devicemanager.cpp)[ 968] stopStreamingOnDevice: Stopping stream 1 of device 0xd5fce0
2453 245623122831: Debug (devicemanager.cpp)[ 854] finishStreaming: Unlocking device (0xd5fce0)
2454 245623122847: Debug (Configuration.cpp)[ 138] save: Not saving temporary config file: temporary
2455 245623122852: Debug (Configuration.cpp)[ 135] save: Not saving readonly config file: /usr/share/libffado/configuration
2456 245623122867: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element 0001f20000015a02 from DeviceManager
2457 245623122880: Debug (StreamProcessorManager.cpp)[ 253] unregisterProcessor: Unregistering processor (0xd640e0)
2458 245623122894: Debug (StreamProcessorManager.cpp)[ 264] unregisterProcessor: unregistering sync source
2459 245623122900: Debug (PortManager.cpp)[ 268] getUpdateHandlerForPtr:  found
2460 245623122904: Debug (PortManager.cpp)[ 244] remPortManagerUpdateHandler: Removing PortManagerUpdate handler (0xd63d30)
2461 245623122907: Debug (PortManager.cpp)[ 251] remPortManagerUpdateHandler:  found
2462 245623122912: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
2463 245623122923: Debug (IsoHandlerManager.cpp)[ 897] unregisterStream: Unregistering Receive stream 0xd640e0
2464 245623122929: Debug (IsoHandlerManager.cpp)[1439] unregisterStream: unregistering stream (0xd640e0)
2465 245623122932: Debug (IsoHandlerManager.cpp)[ 910] unregisterStream:  unregistered stream (0xd640e0) from handler (0xd63da0)...
2466 245623122935: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2467 245623122940: Debug (IsoHandlerManager.cpp)[ 945] pruneHandlers:  handler (0xd63da0) not in use
2468 245623122949: Debug (IsoHandlerManager.cpp)[ 678] unregisterHandler: enter...
2469 245623122954: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
2470 245623122957: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
2471 245623122960: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
2472 245623122963: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
2473 245623122966: Debug (IsoHandlerManager.cpp)[ 956] pruneHandlers:  deleting handler (0xd63da0)
2474 245623122972: Debug (IsoHandlerManager.cpp)[ 924] unregisterStream:  deleted stream (0xd647e0) from list...
2475 245623123022: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog1
2476 245623123032: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog1
2477 245623123039: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog2
2478 245623123042: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog2
2479 245623123046: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog3
2480 245623123049: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog3
2481 245623123053: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog4
2482 245623123055: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog4
2483 245623123059: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog5
2484 245623123062: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog5
2485 245623123066: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog6
2486 245623123068: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog6
2487 245623123072: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog7
2488 245623123075: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog7
2489 245623123079: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog8
2490 245623123081: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog8
2491 245623123085: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_MIDI0
2492 245623123088: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_MIDI0
2493 245623123097: Debug (StreamProcessorManager.cpp)[ 253] unregisterProcessor: Unregistering processor (0xd647e0)
2494 245623123101: Debug (PortManager.cpp)[ 268] getUpdateHandlerForPtr:  found
2495 245623123103: Debug (PortManager.cpp)[ 244] remPortManagerUpdateHandler: Removing PortManagerUpdate handler (0xd63ed0)
2496 jack: 5333:245623122655 engine.c:do_request:1218: got a request of type 2
2497 245623123106: Debug (PortManager.cpp)[ 251] remPortManagerUpdateHandler:  found
2498 245623123109: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
2499 245623123112: Debug (IsoHandlerManager.cpp)[ 897] unregisterStream: Unregistering Transmit stream 0xd647e0
2500 245623123116: Debug (IsoHandlerManager.cpp)[1439] unregisterStream: unregistering stream (0xd647e0)
2501 245623123119: Debug (IsoHandlerManager.cpp)[ 910] unregisterStream:  unregistered stream (0xd647e0) from handler (0xd61010)...
2502 245623123122: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2503 245623123125: Debug (IsoHandlerManager.cpp)[ 945] pruneHandlers:  handler (0xd61010) not in use
2504 245623123130: Debug (IsoHandlerManager.cpp)[ 678] unregisterHandler: enter...
2505 245623123133: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4a910) enter
2506 245623123187: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4a910) exit
2507 jack: 5333:245623122670 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2508 jack: 5333:245623122680 engine.c:jack_port_do_unregister:3903: release graph lock
2509 jack: 5333:245623122683 engine.c:do_request:1350: status of request: 0
2510 jack: 5333:245623122685 engine.c:do_request:1218: got a request of type 2
2511 jack: 5333:245623122687 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2512 jack: 5333:245623122690 engine.c:jack_port_do_unregister:3903: release graph lock
2513 jack: 5333:245623122692 engine.c:do_request:1350: status of request: 0
2514 jack: 5333:245623122695 engine.c:do_request:1218: got a request of type 2
2515 jack: 5333:245623122696 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2516 jack: 5333:245623122699 engine.c:jack_port_do_unregister:3903: release graph lock
2517 jack: 5333:245623122701 engine.c:do_request:1350: status of request: 0
2518 jack: 5333:245623122703 engine.c:do_request:1218: got a request of type 2
2519 jack: 5333:245623122705 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2520 jack: 5333:245623122708 engine.c:jack_port_do_unregister:3903: release graph lock
2521 jack: 5333:245623122710 engine.c:do_request:1350: status of request: 0
2522 jack: 5333:245623122712 engine.c:do_request:1218: got a request of type 2
2523 jack: 5333:245623122714 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2524 jack: 5333:245623122717 engine.c:jack_port_do_unregister:3903: release graph lock
2525 jack: 5333:245623122718 engine.c:do_request:1350: status of request: 0
2526 jack: 5333:245623122721 engine.c:do_request:1218: got a request of type 2
2527 jack: 5333:245623122723 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2528 jack: 5333:245623122725 engine.c:jack_port_do_unregister:3903: release graph lock
2529 jack: 5333:245623122727 engine.c:do_request:1350: status of request: 0
2530 jack: 5333:245623122729 engine.c:do_request:1218: got a request of type 2
2531 jack: 5333:245623122731 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2532 jack: 5333:245623122733 engine.c:jack_port_do_unregister:3903: release graph lock
2533 jack: 5333:245623122735 engine.c:do_request:1350: status of request: 0
2534 jack: 5333:245623122737 engine.c:do_request:1218: got a request of type 2
2535 jack: 5333:245623122739 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2536 jack: 5333:245623122742 engine.c:jack_port_do_unregister:3903: release graph lock
2537 jack: 5333:245623122744 engine.c:do_request:1350: status of request: 0
2538 jack: 5333:245623122746 engine.c:do_request:1218: got a request of type 2
2539 jack: 5333:245623122747 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2540 jack: 5333:245623122750 engine.c:jack_port_do_unregister:3903: release graph lock
2541 jack: 5333:245623122752 engine.c:do_request:1350: status of request: 0
2542 jack: 5333:245623122755 engine.c:do_request:1218: got a request of type 2
2543 jack: 5333:245623122757 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2544 jack: 5333:245623122759 engine.c:jack_port_do_unregister:3903: release graph lock
2545 jack: 5333:245623122761 engine.c:do_request:1350: status of request: 0
2546 jack: 5333:245623122763 engine.c:do_request:1218: got a request of type 2
2547 jack: 5333:245623122765 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2548 jack: 5333:245623122767 engine.c:jack_port_do_unregister:3903: release graph lock
2549 jack: 5333:245623122769 engine.c:do_request:1350: status of request: 0
2550 jack: 5333:245623122771 engine.c:do_request:1218: got a request of type 2
2551 jack: 5333:245623122773 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2552 jack: 5333:245623122776 engine.c:jack_port_do_unregister:3903: release graph lock
2553 jack: 5333:245623122778 engine.c:do_request:1350: status of request: 0
2554 jack: 5333:245623122779 engine.c:do_request:1218: got a request of type 2
2555 jack: 5333:245623122781 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2556 jack: 5333:245623122784 engine.c:jack_port_do_unregister:3903: release graph lock
2557 jack: 5333:245623122786 engine.c:do_request:1350: status of request: 0
2558 jack: 5333:245623122787 engine.c:do_request:1218: got a request of type 2
2559 jack: 5333:245623122789 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2560 jack: 5333:245623122792 engine.c:jack_port_do_unregister:3903: release graph lock
2561 jack: 5333:245623122794 engine.c:do_request:1350: status of request: 0
2562 jack: 5333:245623122796 engine.c:do_request:1218: got a request of type 2
2563 jack: 5333:245623122798 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2564 jack: 5333:245623122800 engine.c:jack_port_do_unregister:3903: release graph lock
2565 jack: 5333:245623122802 engine.c:do_request:1350: status of request: 0
2566 jack: 5333:245623122804 engine.c:do_request:1218: got a request of type 2
2567 jack: 5333:245623122806 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2568 jack: 5333:245623122808 engine.c:jack_port_do_unregister:3903: release graph lock
2569 jack: 5333:245623122810 engine.c:do_request:1350: status of request: 0
2570 jack: 5333:245623122812 engine.c:do_request:1218: got a request of type 2
2571 jack: 5333:245623122814 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2572 jack: 5333:245623122816 engine.c:jack_port_do_unregister:3903: release graph lock
2573 jack: 5333:245623122818 engine.c:do_request:1350: status of request: 0
2574 jack: 5333:245623122820 engine.c:do_request:1218: got a request of type 2
2575 jack: 5333:245623122822 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2576 jack: 5333:245623122824 engine.c:jack_port_do_unregister:3903: release graph lock
2577 jack: 5333:245623122826 engine.c:do_request:1350: status of request: 0
2578 245623124148: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0xd4ab30) enter
2579 245623124157: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0xd4ab30) exit
2580 245623124161: Debug (IsoHandlerManager.cpp)[ 956] pruneHandlers:  deleting handler (0xd61010)
2581 245623124166: Debug (IsoHandlerManager.cpp)[ 924] unregisterStream:  deleted stream (0xd647e0) from list...
2582 245623125149: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog1
2583 245623125158: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog1
2584 245623125165: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog2
2585 245623125168: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog2
2586 245623125172: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog3
2587 245623125175: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog3
2588 245623125178: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog4
2589 245623125181: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog4
2590 245623125185: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog5
2591 245623125188: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog5
2592 245623125191: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog6
2593 245623125194: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog6
2594 245623125198: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog7
2595 245623125201: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog7
2596 245623125204: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog8
2597 245623125207: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog8
2598 245623125211: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_MIDI0
2599 245623125214: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_MIDI0
2600 245623125220: Debug (ieee1394service.cpp)[1445] freeIsoChannel: Freeing ISO channel 0...
2601 245623125225: Debug (ieee1394service.cpp)[1463] freeIsoChannel:  allocated using generic routine...
2602 245623125227: Debug (ieee1394service.cpp)[1464] freeIsoChannel:  freeing 1177 bandwidth units...
2603 245623125271: Debug (ieee1394service.cpp)[1468] freeIsoChannel:  freeing channel 0...
2604 245623125307: Debug (ieee1394service.cpp)[1445] freeIsoChannel: Freeing ISO channel 1...
2605 245623125315: Debug (ieee1394service.cpp)[1463] freeIsoChannel:  allocated using generic routine...
2606 245623125318: Debug (ieee1394service.cpp)[1464] freeIsoChannel:  freeing 1177 bandwidth units...
2607 245623125348: Debug (ieee1394service.cpp)[1468] freeIsoChannel:  freeing channel 1...
2608 245623125382: Debug (motu_mixer.cpp)[ 357] destroyMixer: destroy mixer...
2609 245623125394: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mixer from 0001f20000015a02
2610 245623125407: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element fader from Mixer
2611 245623125423: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element pan from Mixer
2612 245623125436: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element solo from Mixer
2613 245623125449: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element mute from Mixer
2614 245623125461: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_fader from Mixer
2615 245623125471: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_mute from Mixer
2616 245623125475: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_dest from Mixer
2617 245623125480: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_fader from Mixer
2618 245623125484: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_mute from Mixer
2619 245623125488: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_dest from Mixer
2620 245623125492: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_fader from Mixer
2621 245623125497: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_mute from Mixer
2622 245623125500: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_dest from Mixer
2623 245623125504: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_fader from Mixer
2624 245623125508: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_mute from Mixer
2625 245623125512: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_dest from Mixer
2626 245623125516: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mainout_fader from Mixer
2627 245623125520: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Phones_fader from Mixer
2628 245623125523: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Phones_src from Mixer
2629 245623125528: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/OpticalIn_mode from Mixer
2630 245623125532: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/OpticalOut_mode from Mixer
2631 245623125536: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_peakhold_time from Mixer
2632 245623125540: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_cliphold_time from Mixer
2633 245623125544: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_aesebu_src from Mixer
2634 245623125547: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_src from Mixer
2635 245623125551: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/Model from Mixer
2636 245623125555: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/IsStreaming from Mixer
2637 245623125559: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/SampleRate from Mixer
2638 245623125568: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control from 0001f20000015a02
2639 245623125575: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element ConfigRom from 0001f20000015a02
2640 245623125580: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Generic from 0001f20000015a02
2641 245623125585: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element ClockSelect from Generic
2642 245623125589: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element SamplerateSelect from Generic
2643 245623125594: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Nickname from Generic
2644 245623125598: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element StreamingStatus from Generic
2645 245623125614: Debug (IsoHandlerManager.cpp)[1080] stopHandlers: enter...
2646 245623125617: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2647 245623125622: Debug (PosixThread.cpp)[ 190] Stop: (ISOXMT) Stop 0xd4aab0 (thread: 0x7facc3dce910)
2648 245623129265: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0xd4ab30) updating shadow vars...
2649 245623129271: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0xd4ab30) updated shadow vars...
2650 245623130294: Debug (PosixThread.cpp)[  90] ThreadHandler: (ISOXMT) ThreadHandler: exit 0xd4aab0
2651 245623130316: Debug (PosixThread.cpp)[ 197] Stop: (ISOXMT) Stopped 0xd4aab0 (thread: (nil))
2652 245623130325: Debug (PosixThread.cpp)[ 190] Stop: (ISORCV) Stop 0xd4acd0 (thread: 0x7facc35cd910)
2653 245623139282: Debug (PosixThread.cpp)[  90] ThreadHandler: (ISORCV) ThreadHandler: exit 0xd4acd0
2654 245623139300: Debug (PosixThread.cpp)[ 197] Stop: (ISORCV) Stopped 0xd4acd0 (thread: (nil))
2655 245623139313: Debug (PosixThread.cpp)[ 190] Stop: (CTRHLP) Stop 0xd4a6a0 (thread: 0x7facc45cf910)
2656 245623183567: Debug (PosixThread.cpp)[  90] ThreadHandler: (CTRHLP) ThreadHandler: exit 0xd4a6a0
2657 245623184143: Debug (PosixThread.cpp)[ 197] Stop: (CTRHLP) Stopped 0xd4a6a0 (thread: (nil))
2658 245623184183: Debug (ieee1394service.cpp)[1233] remBusResetHandler: Removing busreset handler (0xd4a850)
2659 245623184193: Debug (ieee1394service.cpp)[1240] remBusResetHandler:  found
2660 245623184209: Debug (PosixThread.cpp)[ 190] Stop: (BUSRST) Stop 0xd479c0 (thread: 0x7facc5dd2910)
2661 245623184225: Debug (PosixThread.cpp)[  90] ThreadHandler: (BUSRST) ThreadHandler: exit 0xd479c0
2662 245623184249: Debug (PosixThread.cpp)[ 197] Stop: (BUSRST) Stopped 0xd479c0 (thread: (nil))
2663 245623184262: Debug (PosixThread.cpp)[ 190] Stop: (ARMSTD) Stop 0xd49fe0 (thread: 0x7facc55d1910)
2664 245623184273: Debug (PosixThread.cpp)[  90] ThreadHandler: (ARMSTD) ThreadHandler: exit 0xd49fe0
2665 245623188833: Debug (PosixThread.cpp)[ 197] Stop: (ARMSTD) Stopped 0xd49fe0 (thread: (nil))
2666 245623189179: Debug (PosixThread.cpp)[ 190] Stop: (ARMRT) Stop 0xd4a0f0 (thread: 0x7facc4dd0910)
2667 245623189918: Debug (PosixThread.cpp)[  90] ThreadHandler: (ARMRT) ThreadHandler: exit 0xd4a0f0
2668 245623193927: Debug (PosixThread.cpp)[ 197] Stop: (ARMRT) Stopped 0xd4a0f0 (thread: (nil))
2669 245623193942: Debug (PosixThread.cpp)[ 174] Kill: (WDGCHK) Kill 0xd47bb0 (thread: 0x7facc65d3910)
2670 245623197963: Debug (PosixThread.cpp)[ 180] Kill: (WDGCHK) Killed 0xd47bb0 (thread: 0x7facc65d3910)
2671 245623197979: Debug (PosixThread.cpp)[ 174] Kill: (WDGHBT) Kill 0xd47ac0 (thread: 0x7facc6dd4910)
2672 245623202159: Debug (PosixThread.cpp)[ 180] Kill: (WDGHBT) Killed 0xd47ac0 (thread: 0x7facc6dd4910)
2673 no message buffer overruns
2674 root@ubuntu-studio:/home/sczjd#
2675
2676