Ticket #247: 96k

File 96k, 205.7 kB (added by sireasoning, 2 years ago)

ctrl-c at 96k

Line 
1
2 root@ubuntu-studio:/home/sczjd# jackd -R -P70 -dfirewire -r96000 -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: 5364:245746122180 engine.c:jack_get_fifo_fd:3572: /dev/shm/jack-0/default/jack-ack-fifo-5364-0
12 jack: 5364:245746122225 engine.c:jack_get_fifo_fd:3616: opened engine->fifo[0] == 7 (/dev/shm/jack-0/default/jack-ack-fifo-5364-0)
13 loading driver ..
14 jack: 5367:245746128433 engine.c:jack_server_thread:1480: acquiring graph read lock
15 jack: 5367:245746128489 engine.c:jack_server_thread:1517: release graph lock
16 jack: 5364:245746144943 clientengine.c:jack_client_by_name:346: acquiring graph read lock
17 jack: 5364:245746144962 clientengine.c:jack_client_by_name:357: release graph lock
18 jack: 5364:245746144978 clientengine.c:setup_client:634: acquiring graph write lock
19 jack: 5364:245746144985 clientengine.c:setup_client:641: release graph lock
20 jack: 5364:245746146565 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
21 jack: 5364:245746146580 engine.c:jack_deliver_event:2460: delivering event (type 2)
22 jack: 5364:245746146582 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
23 jack: 5364:245746146584 engine.c:jack_deliver_event:2637: event delivered
24 jack: 5364:245746146586 engine.c:jack_deliver_event_to_all:2423: release graph lock
25 jack: 5364:245746147681 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
26 jack: 5364:245746147691 engine.c:jack_deliver_event:2460: delivering event (type 2)
27 jack: 5364:245746147692 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
28 jack: 5364:245746147694 engine.c:jack_deliver_event:2637: event delivered
29 jack: 5364:245746147696 engine.c:jack_deliver_event_to_all:2423: release graph lock
30 jack: 5364:245746147697 engine.c:jack_driver_buffer_size:529: acquiring graph write lock
31 jack: 5364:245746147698 engine.c:jack_driver_buffer_size:534: release graph lock
32 jack: 5364:245746147700 engine.c:jack_deliver_event_to_all:2417: acquiring graph read lock
33 245746147724:  (ffado.cpp)[  92] ffado_streaming_init: libffado 2.999.0-1784 built Jan  5 2010 16:32:47
34 jack: 5364:245746147701 engine.c:jack_deliver_event:2460: delivering event (type 0)
35 jack: 5364:245746147703 engine.c:jack_deliver_event:2473: client firewire_pcm is still alive
36 jack: 5364:245746147708 engine.c:jack_deliver_event:2637: event delivered
37 jack: 5364:245746147710 engine.c:jack_deliver_event_to_all:2423: release graph lock
38 245746147864: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
39 245746147873: Debug (StreamProcessorManager.cpp)[1569] setVerboseLevel: Setting verbose level to 6...
40 245746147880: Debug (devicemanager.cpp)[1260] setVerboseLevel: Setting verbose level to 6...
41 245746147883: Debug (ffado.cpp)[ 119] ffado_streaming_init: Starting with realtime scheduling, base priority 70
42 245746147889: Debug (DeviceStringParser.cpp)[ 284] isValidString: isvalid? hw:0
43 245746147897: Debug (devicemanager.cpp)[ 233] addSpecString: Adding spec string hw:0
44 245746147903: Debug (DeviceStringParser.cpp)[ 253] parseString: parse: hw:0
45 245746147906: Debug (DeviceStringParser.cpp)[ 258] parseString:  left: hw:0
46 245746147910: Debug (DeviceStringParser.cpp)[  56] parse: parse: hw:0
47 245746147926: Debug (ffado.cpp)[ 148] ffado_streaming_init: setting slave mode to 0
48 245746147938: Debug (ffado.cpp)[ 154] ffado_streaming_init: setting snoop mode to 0
49 245746148062: Debug (Configuration.cpp)[  63] openFile: Could not open file: ~/.ffado/configuration
50 245746148771: Debug (devicemanager.cpp)[ 194] initialize: Found 1 firewire adapters (ports)
51 245746148797: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
52 245746148806: Debug (ieee1394service.cpp)[1567] setVerboseLevel: Setting verbose level to 6...
53 245746148816: Debug (ieee1394service.cpp)[ 422] setThreadParameters: Switching IsoManager to (rt=1, prio=70)
54 245746148823: Debug (IsoHandlerManager.cpp)[ 528] setThreadParameters: (0x12c3680) switch to: (rt=1, prio=70)...
55 245746148841: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
56 245746148856: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
57 245746148863: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
58 245746148874: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
59 245746148887: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
60 245746148893: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
61 245746148903: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
62 245746148915: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
63 245746148921: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
64 245746148924: Debug (ieee1394service.cpp)[ 428] setThreadParameters: Switching CycleTimerHelper to (rt=1, prio=1)
65 245746148927: Debug (CycleTimerHelper.cpp)[ 231] setThreadParameters: (0x12c3700) switch to: (rt=1, prio=1)...
66 245746148943: Debug (Watchdog.cpp)[ 200] start: (0x12c38f0) Starting watchdog...
67 245746148949: Debug (Watchdog.cpp)[ 201] start: Create hartbeat task/thread for 0x12c38f0...
68 245746148953: Debug (Watchdog.cpp)[ 215] start:  hartbeat task: 0x12c3a50, thread 0x12c3ac0...
69 245746148956: Debug (Watchdog.cpp)[ 217] start: Create check task/thread for 0x12c38f0...
70 245746148959: Debug (Watchdog.cpp)[ 231] start:  check task: 0x12c3b40, thread 0x12c3bb0...
71 245746160035: Debug (Watchdog.cpp)[ 249] start: (0x12c38f0) Watchdog running...
72 245746177321: Debug (ieee1394service.cpp)[ 331] initialize: This system supports the raw1394_read_cycle_timer call, using it.
73 245746177419: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.min_split_timeout_usecs
74 245746177437: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.min_split_timeout_usecs
75 245746177443: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.min_split_timeout_usecs' not found
76 245746177450: Debug (ieee1394service.cpp)[ 964] getSplitTimeoutUsecs: reading SPLIT_TIMEOUT on node 0x1...
77 245746177481: Debug (ieee1394service.cpp)[ 971] getSplitTimeoutUsecs:  READ HI: 0x01000000
78 245746177494: Debug (ieee1394service.cpp)[ 978] getSplitTimeoutUsecs:  READ LO: 0x00000000
79 245746177500: Debug (ieee1394service.cpp)[ 372] initialize: Minimum SPLIT_TIMEOUT: 1000000. Current: 1000000
80 245746177505: Debug (CycleTimerHelper.cpp)[ 116] Start: Start 0x12c3700...
81 245746177508: Debug (CycleTimerHelper.cpp)[ 149] initValues: (0x12c3700) Init values...
82 245746177511: Debug (CycleTimerHelper.cpp)[ 156] initValues: Read CTR...
83 245746177515: Debug (CycleTimerHelper.cpp)[ 167] initValues:  read : CTR:   228057314, local:  1262749300564904
84 245746177521: Debug (CycleTimerHelper.cpp)[ 173] initValues:   ctr   : 0x0D97E0E2   167504098 (006s 6526cy 0226ticks)
85 245746177525: Debug (CycleTimerHelper.cpp)[ 179] initValues: requesting DLL re-init...
86 245746179143: Debug (CycleTimerHelper.cpp)[ 308] initDLL:  (0x12c3700) First run
87 245746179154: Debug (CycleTimerHelper.cpp)[ 310] initDLL:   DLL bandwidth: 0.500000 Hz (rel: 0.100000)
88 245746179168: Debug (CycleTimerHelper.cpp)[ 313] initDLL:   usecs/update: 200000, ticks/update: 4915200, m_dll_e2: 4915200.000000
89 245746179173: Debug (CycleTimerHelper.cpp)[ 316] initDLL:   usecs current: 1262749300566530.000000, next: 1262749300766530.000000
90 245746179180: Debug (CycleTimerHelper.cpp)[ 319] initDLL:   ticks current: 167544032.000000, next: 172459232.000000
91 245746179184: Debug (CycleTimerHelper.cpp)[ 188] initValues: ready...
92 245746179195: Debug (Watchdog.cpp)[ 281] registerThread: (0x12c38f0) Adding thread 0x12c66a0
93 245746188112: Debug (CycleTimerHelper.cpp)[ 195] Init: Initialize 0x12c3700...
94 245746188148: Debug (ieee1394service.cpp)[1225] addBusResetHandler: Adding busreset handler (0x12c6850)
95 245746188207: Debug (CycleTimerHelper.cpp)[ 393] Execute: (0x12c3700) have to retry CTR read, diff unrealistic: diff: 167766707, max: +/- 3072 (try: 10) 0
96 245746188289: Debug (CycleTimerHelper.cpp)[ 308] initDLL:  (0x12c3700) First run
97 245746188294: Debug (CycleTimerHelper.cpp)[ 310] initDLL:   DLL bandwidth: 0.500000 Hz (rel: 0.100000)
98 245746188314: Debug (CycleTimerHelper.cpp)[ 313] initDLL:   usecs/update: 200000, ticks/update: 4915200, m_dll_e2: 4915200.000000
99 245746188320: Debug (CycleTimerHelper.cpp)[ 316] initDLL:   usecs current: 1262749300575678.000000, next: 1262749300775678.000000
100 245746188325: Debug (CycleTimerHelper.cpp)[ 319] initDLL:   ticks current: 167768818.000000, next: 172684018.000000
101 245746188114: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
102 245746189614: Debug (IsoHandlerManager.cpp)[ 569] init: Initializing ISO manager 0x12c3680...
103 245746189668: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
104 245746189685: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
105 245746189692: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
106 245746189703: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
107 245746189716: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
108 245746189722: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
109 245746189732: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
110 245746189745: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
111 245746189752: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
112 245746189763: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.isotask_activity_timeout_usecs
113 245746189776: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.isotask_activity_timeout_usecs
114 245746189783: Debug (Configuration.cpp)[ 268] getValueForSetting: path 'ieee1394.isomanager.isotask_activity_timeout_usecs' not found
115 245746189786: Debug (IsoHandlerManager.cpp)[ 590] init: Create iso thread for 0x12c3680 transmit...
116 245746189790: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
117 245746189798: Debug (Thread.h)[ 124] setVerboseLevel: (ISOXMT) Setting verbose level to 6...
118 245746189804: Debug (IsoHandlerManager.cpp)[ 609] init: Create iso thread for 0x12c3680 receive...
119 245746189807: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
120 245746189810: Debug (Thread.h)[ 124] setVerboseLevel: (ISORCV) Setting verbose level to 6...
121 245746189813: Debug (Watchdog.cpp)[ 281] registerThread: (0x12c38f0) Adding thread 0x12c6ab0
122 245746189827: Debug (Watchdog.cpp)[ 281] registerThread: (0x12c38f0) Adding thread 0x12c6cd0
123 245746189833: Debug (PosixThread.cpp)[ 101] Start: (ISOXMT) Create RT thread 0x12c6ab0 with priority 71
124 245746195273: Debug (PosixThread.cpp)[ 101] Start: (ISORCV) Create RT thread 0x12c6cd0 with priority 69
125 245746195273: Debug (PosixThread.cpp)[  78] ThreadHandler: (ISOXMT) ThreadHandler: start 0x12c6ab0
126 245746200995: Debug (PosixThread.cpp)[  78] ThreadHandler: (ISORCV) ThreadHandler: start 0x12c6cd0
127 245746200995: Debug (ieee1394service.cpp)[ 422] setThreadParameters: Switching IsoManager to (rt=1, prio=70)
128 245746201169: Debug (IsoHandlerManager.cpp)[ 528] setThreadParameters: (0x12c3680) switch to: (rt=1, prio=70)...
129 245746201227: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase
130 245746201251: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase
131 245746201260: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase' not found
132 245746201277: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_xmit
133 245746201296: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_xmit
134 245746201305: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_xmit' not found
135 245746201320: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.prio_increase_recv
136 245746201339: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.prio_increase_recv
137 245746201347: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.prio_increase_recv' not found
138 245746201352: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ISOXMT, 0x12c6ab0) Aquire realtime, prio 71
139 245746201361: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ISORCV, 0x12c6cd0) Aquire realtime, prio 69
140 245746201366: Debug (ieee1394service.cpp)[ 428] setThreadParameters: Switching CycleTimerHelper to (rt=1, prio=1)
141 245746201371: Debug (CycleTimerHelper.cpp)[ 231] setThreadParameters: (0x12c3700) switch to: (rt=1, prio=1)...
142 245746201376: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (CTRHLP, 0x12c66a0) Aquire realtime, prio 1
143 245746201383: Debug (ieee1394service.cpp)[1666] setThreadParameters: (0x12c60b0) switch to: (rt=1, prio=70)...
144 245746201387: Debug (PosixThread.cpp)[ 208] AcquireRealTime: (ARMRT, 0x12c60f0) Aquire realtime, prio 70
145 245746201409: Debug (ieee1394service.cpp)[1225] addBusResetHandler: Adding busreset handler (0x12c6da0)
146 245746201419: Debug (devicemanager.cpp)[ 358] discover: Starting discovery...
147 245746201437: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
148 245746201447: Debug (StreamProcessorManager.cpp)[1569] setVerboseLevel: Setting verbose level to 6...
149 245746201457: Debug (Thread.h)[ 124] setVerboseLevel: (ISOXMT) Setting verbose level to 6...
150 245746201464: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
151 245746201468: Debug (Thread.h)[ 124] setVerboseLevel: (ISORCV) Setting verbose level to 6...
152 245746201472: Debug (IsoHandlerManager.cpp)[ 445] setVerboseLevel: Setting verbose level to 6...
153 245746201475: Debug (IsoHandlerManager.cpp)[1143] setVerboseLevel: Setting verbose level to 6...
154 245746201480: Debug (ieee1394service.cpp)[1567] setVerboseLevel: Setting verbose level to 6...
155 245746201483: Debug (devicemanager.cpp)[1260] setVerboseLevel: Setting verbose level to 6...
156 245746201492: Debug (devicemanager.cpp)[ 385] discover: Probing node 0...
157 245746266886: Debug (devicemanager.cpp)[ 385] discover: Probing node 1...
158 245746266944: Debug (devicemanager.cpp)[ 388] discover: Skipping local node (1)...
159 245746266970: Debug (DeviceStringParser.cpp)[ 391] show: DeviceStringParser: 0x12c25c0
160 245746266980: Debug (DeviceStringParser.cpp)[ 218] show: string: hw:0
161 245746266984: Debug (DeviceStringParser.cpp)[ 221] show: type: eBusNode
162 245746266986: Debug (DeviceStringParser.cpp)[ 223] show:  Port: 0, Node: -1
163 245746266996: Debug (devicemanager.cpp)[ 560] discover: Probing node 0...
164 245746327630: Debug (configrom.cpp)[ 562] printConfigRomDebug: Config ROM
165 245746327644: Debug (configrom.cpp)[ 563] printConfigRomDebug:  Current Node Id:        0
166 245746327648: Debug (configrom.cpp)[ 564] printConfigRomDebug:  GUID:                   0x0001F20000015A02
167 245746327653: Debug (configrom.cpp)[ 565] printConfigRomDebug:  Vendor Name:           
168 245746327656: Debug (configrom.cpp)[ 566] printConfigRomDebug:  Model Name:             
169 245746327660: Debug (configrom.cpp)[ 567] printConfigRomDebug:  Node Vendor ID:         0x0001f2
170 245746327663: Debug (configrom.cpp)[ 568] printConfigRomDebug:  Model Id:               0x00102800
171 245746327666: Debug (configrom.cpp)[ 569] printConfigRomDebug:  Unit Specifier ID:      0x0001f2
172 245746327669: Debug (configrom.cpp)[ 570] printConfigRomDebug:  Unit version:           0x00000005
173 245746327672: Debug (configrom.cpp)[ 571] printConfigRomDebug:  ISO resource manager:   0
174 245746327675: Debug (configrom.cpp)[ 572] printConfigRomDebug:  Cycle master capable:   1
175 245746327678: Debug (configrom.cpp)[ 573] printConfigRomDebug:  Bus manager capable:    0
176 245746327681: Debug (configrom.cpp)[ 574] printConfigRomDebug:  Cycle clock accuracy:   0
177 245746327684: Debug (configrom.cpp)[ 576] printConfigRomDebug:  Max rec:                1 (max asy payload: 4 bytes)
178 245746327694: Debug (DeviceStringParser.cpp)[ 162] match: match 0x12da4b0 (0001f20000015a02)
179 245746327699: Debug (DeviceStringParser.cpp)[ 175] match: (eBusNode) device matches device string hw:0
180 245746327702: Debug (devicemanager.cpp)[1109] getDriverForDevice: Probing for supported device...
181 245746327706: Debug (devicemanager.cpp)[1032] getDriverForDeviceDo: Trying BeBoB...
182 245746327741: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
183 245746327844: Debug (devicemanager.cpp)[1039] getDriverForDeviceDo: Trying ECHO Audio FireWorks...
184 245746327859: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
185 245746327924: Debug (devicemanager.cpp)[1046] getDriverForDeviceDo: Trying Oxford FW90x...
186 245746327940: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
187 245746328072: Debug (devicemanager.cpp)[1061] getDriverForDeviceDo: Trying Generic AV/C...
188 245746328093: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
189 245746328158: Debug (devicemanager.cpp)[1068] getDriverForDeviceDo: Trying Motu...
190 245746328206: Debug (Element.cpp)[ 253] addElement: Adding Element ConfigRom to 0001f20000015a02
191 245746328227: Debug (Element.cpp)[ 253] addElement: Adding Element Generic to 0001f20000015a02
192 245746328242: Debug (Element.cpp)[ 253] addElement: Adding Element ClockSelect to Generic
193 245746328308: Debug (Element.cpp)[ 253] addElement: Adding Element SamplerateSelect to Generic
194 245746328323: Debug (Element.cpp)[ 253] addElement: Adding Element Nickname to Generic
195 245746328331: Debug (Element.cpp)[ 253] addElement: Adding Element StreamingStatus to Generic
196 245746328343: Debug (devicemanager.cpp)[1112] getDriverForDevice:  found supported device...
197 245746328348: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
198 245746328351: Debug (devicemanager.cpp)[ 620] discover: driver found for device 0
199 245746328354: Debug (ffadodevice.cpp)[ 216] setVerboseLevel: Setting verbose level to 6...
200 245746328357: Debug (Element.cpp)[ 129] setVerboseLevel: Setting verbose level to 6...
201 245746328361: Debug (motu_avdevice.cpp)[ 447] discover: found MOTU 896HD
202 245746328364: Debug (motu_mixer.cpp)[ 292] buildMixer: Building a MOTU mixer...
203 245746328367: Debug (motu_mixer.cpp)[ 357] destroyMixer: destroy mixer...
204 245746328370: Debug (motu_mixer.cpp)[ 360] destroyMixer: no mixer to destroy...
205 245746328379: Debug (Element.cpp)[ 253] addElement: Adding Element fader to Mixer
206 245746328386: Debug (Element.cpp)[ 253] addElement: Adding Element pan to Mixer
207 245746328393: Debug (Element.cpp)[ 253] addElement: Adding Element solo to Mixer
208 245746328400: Debug (Element.cpp)[ 253] addElement: Adding Element mute to Mixer
209 245746328486: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_fader to Mixer
210 245746328499: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_mute to Mixer
211 245746328507: Debug (Element.cpp)[ 253] addElement: Adding Element Mix1/Mix_dest to Mixer
212 245746328517: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_fader to Mixer
213 245746328529: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_mute to Mixer
214 245746328589: Debug (Element.cpp)[ 253] addElement: Adding Element Mix2/Mix_dest to Mixer
215 245746328603: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_fader to Mixer
216 245746328615: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_mute to Mixer
217 245746328624: Debug (Element.cpp)[ 253] addElement: Adding Element Mix3/Mix_dest to Mixer
218 245746328635: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_fader to Mixer
219 245746328644: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_mute to Mixer
220 245746328655: Debug (Element.cpp)[ 253] addElement: Adding Element Mix4/Mix_dest to Mixer
221 245746328669: Debug (Element.cpp)[ 253] addElement: Adding Element Mainout_fader to Mixer
222 245746328681: Debug (Element.cpp)[ 253] addElement: Adding Element Phones_fader to Mixer
223 245746328693: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Phones_src to Mixer
224 245746328705: Debug (Element.cpp)[ 253] addElement: Adding Element Control/OpticalIn_mode to Mixer
225 245746328714: Debug (Element.cpp)[ 253] addElement: Adding Element Control/OpticalOut_mode to Mixer
226 245746328726: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_peakhold_time to Mixer
227 245746328739: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_cliphold_time to Mixer
228 245746328751: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_aesebu_src to Mixer
229 245746328763: Debug (Element.cpp)[ 253] addElement: Adding Element Control/Meter_src to Mixer
230 245746328777: Debug (Element.cpp)[ 253] addElement: Adding Element Info/Model to Mixer
231 245746328789: Debug (Element.cpp)[ 253] addElement: Adding Element Info/IsStreaming to Mixer
232 245746328797: Debug (Element.cpp)[ 253] addElement: Adding Element Info/SampleRate to Mixer
233 245746328808: Debug (Element.cpp)[ 253] addElement: Adding Element Mixer to 0001f20000015a02
234 245746328817: Debug (Element.cpp)[ 253] addElement: Adding Element Control to 0001f20000015a02
235 245746328825: Debug (devicemanager.cpp)[ 630] discover: discovery successful
236 245746328828: Debug (devicemanager.cpp)[ 649] discover: No cached version of AVC model created
237 245746328837: Debug (Element.cpp)[ 253] addElement: Adding Element 0001f20000015a02 to DeviceManager
238 245746328842: Debug (devicemanager.cpp)[ 657] discover: discovery of node 0 on port 0 done...
239 245746328847: Debug (devicemanager.cpp)[ 560] discover: Probing node 1...
240 245746328850: Debug (devicemanager.cpp)[ 563] discover: Skipping local node (1)...
241 245746328854: Debug (devicemanager.cpp)[ 665] discover: Discovery finished...
242 245746328861: Debug (DeviceStringParser.cpp)[ 162] match: match 0x12da4b0 (0001f20000015a02)
243 245746328864: Debug (DeviceStringParser.cpp)[ 175] match: (eBusNode) device matches device string hw:0
244 245746328874: Debug (devicemanager.cpp)[ 684] discover: Mapping 0001f20000015a02 to position 0...
245 245746328909: Debug (ffadodevice.cpp)[ 176] setId: Set id to dev0...
246 245746328924: Debug (devicemanager.cpp)[1265] showDeviceInfo: ===== Device Manager =====
247 245746328931: Debug (Element.cpp)[ 121] show: Element DeviceManager
248 245746328935: Debug (devicemanager.cpp)[1273] showDeviceInfo: --- IEEE1394 Service  0 ---
249 245746328945: Debug (ieee1394service.cpp)[1582] show: Port:  0
250 245746328949: Debug (ieee1394service.cpp)[1583] show:  Name: ohci1394
251 245746328952: Debug (ieee1394service.cpp)[1585] show:  CycleTimerHelper: 0x12c3700, IsoManager: 0x12c3680, WatchDog: 0x12c38f0
252 245746328955: Debug (ieee1394service.cpp)[1590] show:  Time: 00171225109 (006s 7737cy 1045ticks)
253 Iso handler info:
254 Dumping IsoHandlerManager Stream handler information...
255  State: 2
256 245746328964: Debug (devicemanager.cpp)[1283] showDeviceInfo: --- Device  0 ---
257 245746328968: Debug (motu_avdevice.cpp)[ 854] showDevice: MOTU 896HD at node 0
258 245746328971: Debug (devicemanager.cpp)[1286] showDeviceInfo: Clock sync sources:
259 245746328989: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: Internal          , Id:  0, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: Internal sync
260 245746328994: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: ADAT              , Id:  1, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: ADAT optical
261 245746328999: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: SPDIF             , Id:  2, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: SPDIF/Toslink
262 245746329022: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: Erratic type      , Id:  3, Valid: 0, Active: 0, Locked 0, Slipping: 0, Description: SMPTE
263 245746329026: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: WordClock         , Id:  4, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: Wordclock
264 245746329031: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: ADAT              , Id:  5, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: ADAT 9-pin
265 245746329035: Debug (devicemanager.cpp)[1295] showDeviceInfo:  Type: AES               , Id:  7, Valid: 1, Active: 1, Locked 1, Slipping: 0, Description: AES/EBU
266 245746329045: Debug (devicemanager.cpp)[ 801] initStreaming: Locking device (0x12dbce0)
267 245746329048: Debug (devicemanager.cpp)[ 809] initStreaming: Setting samplerate to 96000 for (0x12dbce0)
268 245746368317: Debug (motu_avdevice.cpp)[ 866] prepare: Preparing MotuDevice...
269 245746385225: Debug (ieee1394service.cpp)[1266] allocateIsoChannelGeneric: Allocating ISO channel using generic method...
270 245746385372: Debug (ieee1394service.cpp)[1266] allocateIsoChannelGeneric: Allocating ISO channel using generic method...
271 245746385482: Debug (motu_avdevice.cpp)[ 911] prepare: recv channel = 0, send channel = 1
272 245746385547: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.recv_sp_dll_bw
273 245746385602: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.recv_sp_dll_bw
274 245746385613: Debug (Configuration.cpp)[ 289] getValueForSetting: path 'streaming.spm.recv_sp_dll_bw' not found
275 245746385630: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.xmit_sp_dll_bw
276 245746385650: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.xmit_sp_dll_bw
277 245746385658: Debug (Configuration.cpp)[ 289] getValueForSetting: path 'streaming.spm.xmit_sp_dll_bw' not found
278 245746385673: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
279 245746385747: Debug (Configuration.cpp)[ 359] getValueForDeviceSetting: device 1F2/102800 not found
280 245746385765: Debug (Configuration.cpp)[ 394] getDeviceSetting:   temporary has no device definitions
281 245746385828: Debug (Configuration.cpp)[ 359] getValueForDeviceSetting: device 1F2/102800 not found
282 245746385847: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
283 245746385856: Debug (IsoHandlerManager.cpp)[ 711] registerStream: Registering Receive stream 0x12e00e0
284 245746385862: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
285 245746385883: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.iso_receive_mode
286 245746385902: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.iso_receive_mode
287 245746385911: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.iso_receive_mode' not found
288 245746385926: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.bufferfill_mode_threshold
289 245746385945: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.bufferfill_mode_threshold
290 245746385953: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.bufferfill_mode_threshold' not found
291 245746385968: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_interrupts_per_period
292 245746385987: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_interrupts_per_period
293 245746385995: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_interrupts_per_period' not found
294 245746388027: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_nb_buffers_recv
295 245746388050: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_nb_buffers_recv
296 245746388059: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_nb_buffers_recv' not found
297 245746388075: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_packetsize_recv
298 245746388094: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_packetsize_recv
299 245746388103: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_packetsize_recv' not found
300 245746388108: Debug (IsoHandlerManager.cpp)[ 762] registerStream: Using bufferfill mode (auto) [86, 64]
301 245746388112: Debug (IsoHandlerManager.cpp)[ 808] registerStream:  creating IsoRecvHandler
302 245746388116: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
303 245746388119: Debug (IsoHandlerManager.cpp)[1426] registerStream: registering stream (0x12e00e0)
304 245746388122: Debug (IsoHandlerManager.cpp)[ 668] registerHandler: enter...
305 245746388125: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
306 245746388169: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
307 245746388199: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
308 245746388207: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
309 245746388211: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
310 245746388214: Debug (IsoHandlerManager.cpp)[ 887] registerStream:  registered stream (0x12e00e0) with handler (0x12dfda0)
311 245746388224: Debug (IsoHandlerManager.cpp)[ 891] registerStream:  1 streams, 1 handlers registered
312 245746388228: Debug (StreamProcessorManager.cpp)[ 226] registerProcessor: Registering processor (0x12e00e0)
313 245746388232: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
314 245746388237: Debug (PortManager.cpp)[ 236] addPortManagerUpdateHandler: Adding PortManagerUpdate handler (0x12dfd30)
315 245746388246: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
316 245746388251: Debug (motu_avdevice.cpp)[ 956] prepare: Adding ports to receive processor
317 245746388267: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Mix-L, type: 0, dir: 1
318 245746388280: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
319 245746388285: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
320 245746388292: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Mix-R, type: 0, dir: 1
321 245746388327: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
322 245746388332: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
323 245746388339: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog1, type: 0, dir: 1
324 245746388345: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
325 245746388348: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
326 245746388355: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog2, type: 0, dir: 1
327 245746388361: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
328 245746388365: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
329 245746388371: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog3, type: 0, dir: 1
330 245746388377: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
331 245746388387: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
332 245746388395: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog4, type: 0, dir: 1
333 245746388401: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
334 245746388404: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
335 245746388410: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog5, type: 0, dir: 1
336 245746388415: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
337 245746388418: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
338 245746388424: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog6, type: 0, dir: 1
339 245746388429: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
340 245746388433: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
341 245746388439: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog7, type: 0, dir: 1
342 245746388446: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
343 245746388450: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
344 245746388457: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_Analog8, type: 0, dir: 1
345 245746388469: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
346 245746388472: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
347 245746388479: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_unknown-1, type: 0, dir: 1
348 245746388485: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
349 245746388488: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
350 245746388494: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_unknown-2, type: 0, dir: 1
351 245746388501: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
352 245746388504: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
353 245746388511: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_AES/EBU1, type: 0, dir: 1
354 245746388517: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
355 245746388526: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
356 245746388533: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_AES/EBU2, type: 0, dir: 1
357 245746388540: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
358 245746388543: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
359 245746388550: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_cap_MIDI0, type: 1, dir: 1
360 245746388557: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dfd30)
361 245746388565: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
362 245746388576: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
363 245746388580: Debug (IsoHandlerManager.cpp)[ 711] registerStream: Registering Transmit stream 0x12e0a20
364 245746388585: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
365 245746388604: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_interrupts_per_period
366 245746388625: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_interrupts_per_period
367 245746388630: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_interrupts_per_period' not found
368 245746388645: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_nb_buffers_xmit
369 245746388659: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_nb_buffers_xmit
370 245746388664: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_nb_buffers_xmit' not found
371 245746388678: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.max_packetsize_xmit
372 245746388692: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.max_packetsize_xmit
373 245746388696: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.max_packetsize_xmit' not found
374 245746388710: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting ieee1394.isomanager.min_packetsize_xmit
375 245746388725: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting ieee1394.isomanager.min_packetsize_xmit
376 245746388729: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'ieee1394.isomanager.min_packetsize_xmit' not found
377 245746388733: Debug (IsoHandlerManager.cpp)[ 858] registerStream:  creating IsoXmitHandler
378 245746388736: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
379 245746388739: Debug (IsoHandlerManager.cpp)[1426] registerStream: registering stream (0x12e0a20)
380 245746388742: Debug (IsoHandlerManager.cpp)[ 668] registerHandler: enter...
381 245746388745: Debug (IsoHandlerManager.cpp)[1420] setVerboseLevel: Setting verbose level to 6...
382 245746388751: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
383 245746388754: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
384 245746388757: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
385 245746388760: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
386 245746388763: Debug (IsoHandlerManager.cpp)[ 887] registerStream:  registered stream (0x12e0a20) with handler (0x12e0040)
387 245746388769: Debug (IsoHandlerManager.cpp)[ 891] registerStream:  2 streams, 2 handlers registered
388 245746388772: Debug (StreamProcessorManager.cpp)[ 226] registerProcessor: Registering processor (0x12e0a20)
389 245746388776: Debug (StreamProcessor.cpp)[1931] setVerboseLevel: Setting verbose level to 6...
390 245746388780: Debug (PortManager.cpp)[ 236] addPortManagerUpdateHandler: Adding PortManagerUpdate handler (0x12dd120)
391 245746388785: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
392 245746388790: Debug (motu_avdevice.cpp)[1023] prepare: Adding ports to transmit processor
393 245746388798: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Phones-L, type: 0, dir: 0
394 245746388804: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
395 245746388807: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
396 245746388815: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Phones-R, type: 0, dir: 0
397 245746388821: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
398 245746388824: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
399 245746388832: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog1, type: 0, dir: 0
400 245746388838: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
401 245746388841: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
402 245746388848: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog2, type: 0, dir: 0
403 245746388853: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
404 245746388856: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
405 245746388863: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog3, type: 0, dir: 0
406 245746388869: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
407 245746388872: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
408 245746388880: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog4, type: 0, dir: 0
409 245746388885: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
410 245746388888: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
411 245746388896: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog5, type: 0, dir: 0
412 245746388901: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
413 245746388904: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
414 245746388911: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog6, type: 0, dir: 0
415 245746388916: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
416 245746388919: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
417 245746388926: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog7, type: 0, dir: 0
418 245746388933: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
419 245746388936: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
420 245746388944: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_Analog8, type: 0, dir: 0
421 245746388950: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
422 245746388953: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
423 245746388960: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_MainOut-L, type: 0, dir: 0
424 245746388966: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
425 245746388969: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
426 245746388976: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_MainOut-R, type: 0, dir: 0
427 245746388982: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
428 245746388985: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
429 245746388993: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_AES/EBU1, type: 0, dir: 0
430 245746389000: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
431 245746389003: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
432 245746389010: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_AES/EBU2, type: 0, dir: 0
433 245746389017: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
434 245746389020: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
435 245746389027: Debug (PortManager.cpp)[ 100] registerPort: Adding port dev0_pbk_MIDI0, type: 1, dir: 0
436 245746389034: Debug (PortManager.cpp)[ 283] callUpdateHandlers: Calling PortManagerUpdate handler (0x12dd120)
437 245746389037: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
438 245746389047: Debug (StreamProcessorManager.cpp)[ 308] setSyncSource: Setting sync source to (0x12e00e0)
439 245746389560: Debug (ffado.cpp)[ 189] ffado_streaming_prepare: Preparing...
440 245746389564: Debug (StreamProcessorManager.cpp)[ 315] prepare: Preparing...
441 245746389570: Debug (StreamProcessorManager.cpp)[ 349] prepare: Prepare Receive processors...
442 245746389575: Debug (StreamProcessorManager.cpp)[ 355] prepare:  note: could not set slaveMode option for (0x12e00e0)...
443 245746389578: Debug (StreamProcessor.cpp)[1066] prepare: Prepare SP (0x12e00e0)...
444 245746389582: Debug (StreamProcessor.cpp)[1070] prepare:  Allocate scratch buffer of 53248 quadlets
445 245746389587: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Mix-L
446 245746389590: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Mix-L
447 245746389593: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Mix-R
448 245746389596: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Mix-R
449 245746389599: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog1
450 245746389602: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog1
451 245746389606: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog2
452 245746389609: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog2
453 245746389612: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog3
454 245746389615: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog3
455 245746389618: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog4
456 245746389621: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog4
457 245746389624: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog5
458 245746389731: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog5
459 245746389737: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog6
460 245746389739: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog6
461 245746389743: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog7
462 245746389745: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog7
463 245746389749: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_Analog8
464 245746389751: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_Analog8
465 245746389754: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_unknown-1
466 245746389757: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_unknown-1
467 245746389761: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_unknown-2
468 245746389764: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_unknown-2
469 245746389767: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_AES/EBU1
470 245746389769: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_AES/EBU1
471 245746389773: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_AES/EBU2
472 245746389775: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_AES/EBU2
473 245746389779: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_cap_MIDI0
474 245746389782: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_cap_MIDI0
475 245746389785: Debug (PortManager.cpp)[ 202] initPorts: init ports
476 245746389788: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Mix-L
477 245746389791: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Mix-R
478 245746389794: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog1
479 245746389797: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog2
480 245746389800: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog3
481 245746389803: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog4
482 245746389805: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog5
483 245746389808: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog6
484 245746389811: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog7
485 245746389814: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_Analog8
486 245746389816: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_unknown-1
487 245746389819: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_unknown-2
488 245746389822: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_AES/EBU1
489 245746389825: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_AES/EBU2
490 245746389828: Debug (Port.cpp)[  61] init: Initialize port dev0_cap_MIDI0
491 245746389831: Debug (MotuReceiveStreamProcessor.cpp)[ 105] prepareChild: Preparing (0x12e00e0)...
492 245746389834: Debug (StreamProcessor.cpp)[1104] prepare: Prepared for:
493 245746389837: Debug (StreamProcessor.cpp)[1106] prepare:  Samplerate: 96000  [DLL Bandwidth: 0.100000 Hz]
494 245746389847: Debug (StreamProcessor.cpp)[1108] prepare:  PeriodSize: 1024, NbBuffers: 4
495 245746389850: Debug (StreamProcessor.cpp)[1110] prepare:  Port: 0, Channel: -1
496 245746389854: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Created => ePS_Stopped
497 245746389858: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_Created
498 245746389863: Debug (StreamProcessor.cpp)[1368] doStop: Initializing remote ticks/frame to 256.000000
499 245746389870: Debug (TimestampedBuffer.cpp)[ 140] setNominalRate:  nominal rate 0.000000e+00 => 2.560000e+02
500 245746389875: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.441406e-06 => 2.034505e-07
501 245746389881: Debug (TimestampedBuffer.cpp)[ 365] prepare: Preparing buffer (0x12dff10)
502 245746389883: Debug (TimestampedBuffer.cpp)[ 367] prepare:  Size=4097 events, events/frame=1, event size=52bytes
503 245746389887: Debug (TimestampedBuffer.cpp)[ 370] prepare:  update period 16
504 245746389889: Debug (TimestampedBuffer.cpp)[ 372] prepare:  nominal rate=256.000000
505 245746389893: Debug (TimestampedBuffer.cpp)[ 374] prepare:  wrapping at 3145728000.000
506 245746391141: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6b30) updating shadow vars...
507 245746391151: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6b30) Receive handler 0x12dfda0 skipped (disabled)
508 245746391155: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6b30) updated shadow vars...
509 245746391199: Debug (StreamProcessor.cpp)[1386] doStop: DLL info: nominal tpf: 256.000000, update period: 16, bandwidth: 2.034505e-07 1/ticks (5.000000e+00 Hz)
510 245746391210: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
511 245746391214: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
512 245746391218: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
513  StreamProcessor 0x12e00e0, Receive:
514   Port, Channel    : 0, -1
515   Packets, Dropped, Skipped : 0, 0, 0
516   Now                   : 00172756238 (007s 0235c 2318t)
517   Xrun?                 : False
518   State                 : ePS_Stopped
519   Buffer                : 0x12dff10
520   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
521   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
522    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
523     Head - Tail         : -3145728000.000 (-12288000.000000 frames)
524    DLL Rate             : 4096.000000 (256.000000)
525    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
526 245746391260: Debug (StreamProcessorManager.cpp)[ 363] prepare: Prepare Transmit processors...
527 245746391268: Debug (StreamProcessorManager.cpp)[ 368] prepare:  note: could not set slaveMode option for (0x12e0a20)...
528 245746391271: Debug (StreamProcessor.cpp)[1066] prepare: Prepare SP (0x12e0a20)...
529 245746391274: Debug (StreamProcessor.cpp)[1070] prepare:  Allocate scratch buffer of 53248 quadlets
530 245746391278: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Phones-L
531 245746391282: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Phones-L
532 245746391285: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Phones-R
533 245746391288: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Phones-R
534 245746391291: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog1
535 245746391294: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog1
536 245746391297: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog2
537 245746391300: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog2
538 245746391303: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog3
539 245746391306: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog3
540 245746391308: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog4
541 245746391311: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog4
542 245746391314: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog5
543 245746391317: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog5
544 245746391320: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog6
545 245746391323: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog6
546 245746391326: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog7
547 245746391329: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog7
548 245746391332: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_Analog8
549 245746391335: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_Analog8
550 245746391338: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_MainOut-L
551 245746391341: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_MainOut-L
552 245746391344: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_MainOut-R
553 245746391347: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_MainOut-R
554 245746391350: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_AES/EBU1
555 245746391353: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_AES/EBU1
556 245746391356: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_AES/EBU2
557 245746391359: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_AES/EBU2
558 245746391362: Debug (StreamProcessor.cpp)[1085] prepare: Setting up port dev0_pbk_MIDI0
559 245746391365: Debug (Port.cpp)[  92] setBufferSize: Setting buffersize to 1024 for port dev0_pbk_MIDI0
560 245746391368: Debug (PortManager.cpp)[ 202] initPorts: init ports
561 245746391371: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Phones-L
562 245746391374: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Phones-R
563 245746391377: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog1
564 245746391380: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog2
565 245746391383: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog3
566 245746391386: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog4
567 245746391389: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog5
568 245746391391: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog6
569 245746391394: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog7
570 245746391396: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_Analog8
571 245746391399: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_MainOut-L
572 245746391402: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_MainOut-R
573 245746391404: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_AES/EBU1
574 245746391407: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_AES/EBU2
575 245746391410: Debug (Port.cpp)[  61] init: Initialize port dev0_pbk_MIDI0
576 245746391413: Debug (MotuTransmitStreamProcessor.cpp)[ 516] prepareChild: Preparing (0x12e0a20)...
577 245746391416: Debug (StreamProcessor.cpp)[1104] prepare: Prepared for:
578 245746391419: Debug (StreamProcessor.cpp)[1106] prepare:  Samplerate: 96000  [DLL Bandwidth: 0.100000 Hz]
579 245746391423: Debug (StreamProcessor.cpp)[1108] prepare:  PeriodSize: 1024, NbBuffers: 4
580 245746391426: Debug (StreamProcessor.cpp)[1110] prepare:  Port: 0, Channel: -1
581 245746391429: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Created => ePS_Stopped
582 245746391432: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_Created
583 245746391436: Debug (StreamProcessor.cpp)[1368] doStop: Initializing remote ticks/frame to 256.000000
584 245746391440: Debug (TimestampedBuffer.cpp)[ 140] setNominalRate:  nominal rate 0.000000e+00 => 2.560000e+02
585 245746391445: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 3.814697e-08 => 2.034505e-07
586 245746391450: Debug (TimestampedBuffer.cpp)[ 365] prepare: Preparing buffer (0x12e1b20)
587 245746391453: Debug (TimestampedBuffer.cpp)[ 367] prepare:  Size=4097 events, events/frame=1, event size=52bytes
588 245746391456: Debug (TimestampedBuffer.cpp)[ 370] prepare:  update period 1024
589 245746391459: Debug (TimestampedBuffer.cpp)[ 372] prepare:  nominal rate=256.000000
590 245746391462: Debug (TimestampedBuffer.cpp)[ 374] prepare:  wrapping at 3145728000.000
591 245746391679: Debug (StreamProcessor.cpp)[1386] doStop: DLL info: nominal tpf: 256.000000, update period: 1024, bandwidth: 2.034505e-07 1/ticks (5.000000e+00 Hz)
592 245746391687: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
593 245746391690: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
594 245746391693: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
595  StreamProcessor 0x12e0a20, Transmit:
596   Port, Channel    : 0, -1
597   Packets, Dropped, Skipped : 0, 0, 0
598   Now                   : 00172767886 (007s 0239c 1678t)
599   Xrun?                 : False
600   State                 : ePS_Stopped
601   Buffer                : 0x12e1b20
602   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
603   TimestampedBuffer (0x12e1b20): 0000 frames, 0000 events
604    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
605     Head - Tail         : -3145728000.000 (-12288000.000000 frames)
606    DLL Rate             : 262144.000000 (256.000000)
607    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
608 245746391729: Debug (StreamProcessorManager.cpp)[ 387] prepare: setting activity timeout to 21333
609 245746391733: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
610 245746395513: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6910) updating shadow vars...
611 245746395519: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6910) Transmit handler 0x12e0040 skipped (disabled)
612 245746395522: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6910) updated shadow vars...
613 245746400632: Debug (ffado.cpp)[ 209] ffado_streaming_start: ------------- Start -------------
614 245746400648: Debug (devicemanager.cpp)[ 873] startStreamingOnDevice: Starting stream 0 of device 0x12dbce0
615 245746401169: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6b30) updating shadow vars...
616 245746401177: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6b30) Receive handler 0x12dfda0 skipped (disabled)
617 245746401180: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6b30) updated shadow vars...
618 245746405534: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6910) updating shadow vars...
619 245746405539: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6910) Transmit handler 0x12e0040 skipped (disabled)
620 245746405542: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6910) updated shadow vars...
621 245746405976: Debug (devicemanager.cpp)[ 873] startStreamingOnDevice: Starting stream 1 of device 0x12dbce0
622 245746413479: Debug (StreamProcessorManager.cpp)[ 908] start: Starting Processors...
623 245746413486: Debug (StreamProcessorManager.cpp)[ 398] startDryRunning: Putting StreamProcessor streams into dry-running state...
624 245746413490: Debug (StreamProcessorManager.cpp)[ 399] startDryRunning:  Schedule start dry-running...
625 245746413498: Debug (StreamProcessor.cpp)[1156] scheduleStartDryRunning: for Transmit SP (0x12e0a20)
626 245746413502: Debug (StreamProcessor.cpp)[1163] scheduleStartDryRunning:   Now                   : 00173303580 (007s 0413c 2844t)
627 245746413506: Debug (StreamProcessor.cpp)[1168] scheduleStartDryRunning:   Start at              : 00173917832 (007s 0613c 2696t)
628 245746413514: Debug (IsoHandlerManager.cpp)[1027] startHandlerForStream:  starting handler 0x12e0040 for stream 0x12e0a20
629 245746413518: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
630 245746413522: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
631 245746413528: Debug (StreamProcessor.cpp)[1156] scheduleStartDryRunning: for Receive SP (0x12e00e0)
632 245746413532: Debug (StreamProcessor.cpp)[1163] scheduleStartDryRunning:   Now                   : 00173304317 (007s 0414c 0509t)
633 245746413536: Debug (StreamProcessor.cpp)[1168] scheduleStartDryRunning:   Start at              : 00173918619 (007s 0614c 0411t)
634 245746413540: Debug (IsoHandlerManager.cpp)[1027] startHandlerForStream:  starting handler 0x12dfda0 for stream 0x12e00e0
635 245746413544: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
636 245746413547: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
637 245746413551: Debug (StreamProcessorManager.cpp)[ 432] startDryRunning:  Waiting for all SP's to be dry-running...
638 245746415557: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6910) updating shadow vars...
639 245746415563: Debug (IsoHandlerManager.cpp)[1859] updateState: (0x12e0040) handler needs state update from 0 => 1
640 245746415567: Debug (IsoHandlerManager.cpp)[1861] updateState: handler has to be enabled
641 245746415571: Debug (IsoHandlerManager.cpp)[1700] enable: start on cycle 0
642 245746415613: Debug (IsoHandlerManager.cpp)[1724] enable: Preparing iso handler (0x12e0040, client=0x12e0a20)
643   Handler type................: Transmit
644   Port, Channel...............:  0,  1
645   Buffer, MaxPacketSize, IRQ..:  128, 1040,   42
646   Speed ..................:  2
647   Min ISOXMT bufferfill : 7999
648   Last cycle, dropped.........:   -1,    0,    0
649 245746415887: Debug (IsoHandlerManager.cpp)[ 171] updateShadowMapHelper: (0x12c6910) Transmit handler 0x12e0040 added
650 245746415893: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6910) updated shadow vars...
651 245746415908: Debug (IsoHandlerManager.cpp)[1630] getPacket: Handler for Transmit SP 0x12e0040 is alive (cycle = 0)
652 245746415914: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_Stopped to ePS_WaitingForStream
653 245746415918: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Stopped => ePS_WaitingForStream
654 245746415922: Debug (StreamProcessor.cpp)[1430] doWaitForRunningStream: Enter from state: ePS_Stopped
655 245746415925: Debug (StreamProcessor.cpp)[1444] doWaitForRunningStream: State switch complete, dumping SP info...
656  StreamProcessor 0x12e0a20, Transmit:
657   Port, Channel    : 0, 1
658   Packets, Dropped, Skipped : 1, 0, 0
659   Now                   : 00173363342 (007s 0433c 1166t)
660   Xrun?                 : False
661   State                 : ePS_WaitingForStream
662   Buffer                : 0x12e1b20
663   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
664   TimestampedBuffer (0x12e1b20): 0000 frames, 0000 events
665    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
666     Head - Tail         : -3145728000.000 (-12288000.000000 frames)
667    DLL Rate             : 262144.000000 (256.000000)
668    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
669 245746421201: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6b30) updating shadow vars...
670 245746421210: Debug (IsoHandlerManager.cpp)[1859] updateState: (0x12dfda0) handler needs state update from 0 => 1
671 245746421214: Debug (IsoHandlerManager.cpp)[1861] updateState: handler has to be enabled
672 245746421217: Debug (IsoHandlerManager.cpp)[1700] enable: start on cycle 0
673 245746421250: Debug (IsoHandlerManager.cpp)[1724] enable: Preparing iso handler (0x12dfda0, client=0x12e00e0)
674   Handler type................: Receive
675   Port, Channel...............:  0,  0
676   Buffer, MaxPacketSize, IRQ..:   64, 1040,   32
677   Last cycle, dropped.........:   -1,    0,    0
678 245746421428: Debug (IsoHandlerManager.cpp)[ 171] updateShadowMapHelper: (0x12c6b30) Receive handler 0x12dfda0 added
679 245746421435: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6b30) updated shadow vars...
680 jack: 5364:245746389078 engine.c:do_request:1218: got a request of type 1
681 245747362748: Debug (MotuReceiveStreamProcessor.cpp)[ 163] processPacketHeader: Packet from MOTU: length = 840
682 245747362768: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_Stopped to ePS_WaitingForStream
683 245747362773: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Stopped => ePS_WaitingForStream
684 245747362777: Debug (StreamProcessor.cpp)[1430] doWaitForRunningStream: Enter from state: ePS_Stopped
685 245747362781: Debug (StreamProcessor.cpp)[1444] doWaitForRunningStream: State switch complete, dumping SP info...
686  StreamProcessor 0x12e00e0, Receive:
687   Port, Channel    : 0, 0
688   Packets, Dropped, Skipped : 1, 0, 0
689   Now                   : 00196629385 (008s 0006c 2953t)
690   Xrun?                 : False
691   State                 : ePS_WaitingForStream
692   Buffer                : 0x12dff10
693   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
694   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
695    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
696     Head - Tail         : -3145728000.000 (-12288000.000000 frames)
697    DLL Rate             : 4096.000000 (256.000000)
698    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
699 245747425738: Debug (StreamProcessor.cpp)[ 641] getPacket: Should update state to WaitingForStream to DryRunning
700 245747425755: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStream => ePS_DryRunning
701 245747425760: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStream
702 245747425764: Debug (StreamProcessor.cpp)[1471] doDryRunning: StreamProcessor 0x12e0a20 started dry-running
703 245747425768: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
704  StreamProcessor 0x12e0a20, Transmit:
705   Port, Channel    : 0, 1
706   Packets, Dropped, Skipped : 614, 0, 0
707   Now                   : 00198177084 (008s 0510c 2364t)
708   Xrun?                 : False
709   State                 : ePS_DryRunning
710   Buffer                : 0x12e1b20
711   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
712   TimestampedBuffer (0x12e1b20): 0000 frames, 0000 events
713    Timestamps           : head:          1.000, Tail: 3145728001.000, Next tail: 3145728001.000
714     Head - Tail         : -3145728000.000 (-12288000.000000 frames)
715    DLL Rate             : 262144.000000 (256.000000)
716    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
717 245747438760: Debug (StreamProcessor.cpp)[ 459] putPacket: Should update state to DryRunning due to good packet
718 245747438774: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStream => ePS_DryRunning
719 245747438778: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStream
720 245747438782: Debug (StreamProcessor.cpp)[1471] doDryRunning: StreamProcessor 0x12e00e0 started dry-running
721 245747438787: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
722  StreamProcessor 0x12e00e0, Receive:
723   Port, Channel    : 0, 0
724   Packets, Dropped, Skipped : 615, 0, 0
725   Now                   : 00198496946 (008s 0614c 2738t)
726   Xrun?                 : False
727   State                 : ePS_DryRunning
728   Buffer                : 0x12dff10
729   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
730   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
731    Timestamps           : head:  198500480.000, Tail:  198500480.000, Next tail:  198504576.000
732     Head - Tail         :          0.000 (0.000000 frames)
733    DLL Rate             : 4096.000000 (256.000000)
734    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
735 245747439138: Debug (StreamProcessorManager.cpp)[ 469] startDryRunning:  StreamProcessor streams dry-running...
736 245747439218: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.signal_delay_ticks
737 245747439243: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.signal_delay_ticks
738 245747439252: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.signal_delay_ticks' not found
739 245747439269: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.xmit_prebuffer_frames
740 245747439342: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.xmit_prebuffer_frames
741 245747439353: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.xmit_prebuffer_frames' not found
742 245747439370: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.sync_wait_time_msec
743 245747439390: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.sync_wait_time_msec
744 245747439399: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.sync_wait_time_msec' not found
745 245747439415: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.cycles_for_startup
746 245747439435: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.cycles_for_startup
747 245747439443: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.cycles_for_startup' not found
748 245747439536: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.prestart_cycles_for_xmit
749 245747439557: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.prestart_cycles_for_xmit
750 245747439567: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.prestart_cycles_for_xmit' not found
751 245747439583: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.prestart_cycles_for_recv
752 245747439602: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.prestart_cycles_for_recv
753 245747439611: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.prestart_cycles_for_recv' not found
754 245747439615: Debug (StreamProcessorManager.cpp)[ 497] syncStartAll: Finding minimal sync delay...
755 245747439621: Debug (StreamProcessorManager.cpp)[ 517] syncStartAll:  max_of_min_delay = 98304, max_packet_size_frames = 16...
756 245747439624: Debug (StreamProcessorManager.cpp)[ 531] syncStartAll: Waiting for sync...
757 245747699369: Debug (StreamProcessorManager.cpp)[ 558] syncStartAll: Propagate sync info...
758 245747699384: Debug (StreamProcessorManager.cpp)[ 569] syncStartAll:  sync source frame rate: 95997.687500 fps (256.006165 tpf)
759 245747699398: Debug (StreamProcessorManager.cpp)[ 579] syncStartAll:  sync at TS=00205152511 (008s 2781c 1279t)...
760 245747699403: Debug (StreamProcessorManager.cpp)[ 593] syncStartAll:   add 2400 frames (00000614414 ticks)...
761 245747699407: Debug (StreamProcessorManager.cpp)[ 599] syncStartAll:   => first sample at TS=00205766925 (008s 2981c 1293t)...
762 245747699411: Debug (StreamProcessorManager.cpp)[ 612] syncStartAll:   => xmit starts at  TS=00205705485 (008s 2961c 1293t)...
763 245747699414: Debug (StreamProcessorManager.cpp)[ 617] syncStartAll:   => recv starts at  TS=00205766925 (008s 2981c 1293t)...
764 245747699418: Debug (StreamProcessorManager.cpp)[ 626] syncStartAll:  sync delay: 98304 = 98304 + 0 ticks (000s 0032c 0000t) [383 frames]...
765 245747699422: Debug (StreamProcessorManager.cpp)[ 637] syncStartAll: The number of prebuffer frames (100) is not a multiple of the common block size (16), increased to 112...
766 245747699428: Debug (StreamProcessor.cpp)[ 197] setExtraBufferFrames: Setting extra buffer to 112 frames
767 245747699433: Debug (StreamProcessorManager.cpp)[ 666] syncStartAll:  transmit buffer tail 0205766925 => head TS 0205766925, fc=0...
768 245747699438: Debug (StreamProcessor.cpp)[ 197] setExtraBufferFrames: Setting extra buffer to 1024 frames
769 245747699441: Debug (StreamProcessor.cpp)[1202] scheduleStartRunning: for Receive SP (0x12e00e0)
770 245747699446: Debug (StreamProcessor.cpp)[1209] scheduleStartRunning:   Now                   : 00204901837 (008s 2699c 2509t)
771 245747699450: Debug (StreamProcessor.cpp)[1214] scheduleStartRunning:   Start at              : 00205766925 (008s 2981c 1293t)
772 245747699456: Debug (StreamProcessor.cpp)[1202] scheduleStartRunning: for Transmit SP (0x12e0a20)
773 245747699459: Debug (StreamProcessor.cpp)[1209] scheduleStartRunning:   Now                   : 00204902156 (008s 2699c 2828t)
774 245747699463: Debug (StreamProcessor.cpp)[1214] scheduleStartRunning:   Start at              : 00205705485 (008s 2961c 1293t)
775 245747699543: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_DryRunning to ePS_WaitingForStreamEnable
776 245747699551: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_WaitingForStreamEnable
777 245747699555: Debug (StreamProcessor.cpp)[1516] doWaitForStreamEnable: Enter from state: ePS_DryRunning
778 245747699909: Debug (StreamProcessor.cpp)[1555] doWaitForStreamEnable: State switch complete, dumping SP info...
779  StreamProcessor 0x12e00e0, Receive:
780   Port, Channel    : 0, 0
781   Packets, Dropped, Skipped : 2696, 0, 0
782   Now                   : 00204913656 (008s 2703c 2040t)
783   Xrun?                 : False
784   State                 : ePS_WaitingForStreamEnable
785   Buffer                : 0x12dff10
786   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
787   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
788    Timestamps           : head:  204890361.000, Tail:  204890361.000, Next tail:  204894457.099
789     Head - Tail         :          0.000 (0.000000 frames)
790    DLL Rate             : 4096.000000 (256.000000)
791    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
792 245747701771: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_DryRunning to ePS_WaitingForStreamEnable
793 245747701785: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_WaitingForStreamEnable
794 245747701789: Debug (StreamProcessor.cpp)[1516] doWaitForStreamEnable: Enter from state: ePS_DryRunning
795 245747701796: Debug (StreamProcessor.cpp)[1540] doWaitForStreamEnable: Prefill transmit SP 0x12e0a20 with 4208 frames (xmit prebuffer = 112)
796 245747702656: Debug (StreamProcessor.cpp)[1555] doWaitForStreamEnable: State switch complete, dumping SP info...
797  StreamProcessor 0x12e0a20, Transmit:
798   Port, Channel    : 0, 1
799   Packets, Dropped, Skipped : 2817, 0, 0
800   Now                   : 00204981158 (008s 2725c 1958t)
801   Xrun?                 : False
802   State                 : ePS_WaitingForStreamEnable
803   Buffer                : 0x12e1b20
804   Framerate             : Nominal: 96000, Sync: 95999.839783, Buffer 96000.000000
805   TimestampedBuffer (0x12e1b20): 4208 frames, 4208 events
806    Timestamps           : head:  205766925.000, Tail:  206844173.000, Next tail:  207106317.000
807     Head - Tail         :   -1077248.000 (-4208.000000 frames)
808    DLL Rate             : 262144.000000 (256.000000)
809    DLL Bandwidth        : 2.034505e-07 1/ticks (5.000000 Hz)
810 245747717785: Debug (StreamProcessor.cpp)[ 626] getPacket: Should update state to Running
811 245747717801: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamEnable => ePS_Running
812 245747717806: Debug (StreamProcessor.cpp)[1576] doRunning: Enter from state: ePS_WaitingForStreamEnable
813 245747717809: Debug (StreamProcessor.cpp)[1581] doRunning: StreamProcessor 0x12e0a20 started running
814 245747717814: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.034505e-07 => 4.069010e-09
815 245747717823: Debug (StreamProcessor.cpp)[1597] doRunning: State switch complete, dumping SP info...
816  StreamProcessor 0x12e0a20, Transmit:
817   Port, Channel    : 0, 1
818   Packets, Dropped, Skipped : 2962, 0, 0
819   Now                   : 00205353683 (008s 2846c 2771t)
820   Xrun?                 : False
821   State                 : ePS_Running
822   Buffer                : 0x12e1b20
823   Framerate             : Nominal: 96000, Sync: 95999.095925, Buffer 96000.000000
824   TimestampedBuffer (0x12e1b20): 4208 frames, 4208 events
825    Timestamps           : head:  205766925.000, Tail:  206844173.000, Next tail:  207106317.000
826     Head - Tail         :   -1077248.000 (-4208.000000 frames)
827    DLL Rate             : 262144.000000 (256.000000)
828    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
829 245747734816: Debug (StreamProcessor.cpp)[ 341] putPacket: Should update state to Running
830 245747734831: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamEnable => ePS_Running
831 245747734836: Debug (StreamProcessor.cpp)[1576] doRunning: Enter from state: ePS_WaitingForStreamEnable
832 245747734839: Debug (StreamProcessor.cpp)[1581] doRunning: StreamProcessor 0x12e00e0 started running
833 245747734844: Debug (TimestampedBuffer.cpp)[ 100] setBandwidth:  bandwidth 2.034505e-07 => 4.069010e-09
834 245747734856: Debug (StreamProcessor.cpp)[1597] doRunning: State switch complete, dumping SP info...
835  StreamProcessor 0x12e00e0, Receive:
836   Port, Channel    : 0, 0
837   Packets, Dropped, Skipped : 2982, 0, 0
838   Now                   : 00205772209 (008s 2983c 0433t)
839   Xrun?                 : False
840   State                 : ePS_Running
841   Buffer                : 0x12dff10
842   Framerate             : Nominal: 96000, Sync: 95998.855604, Buffer 95998.855604
843   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
844    Timestamps           : head:  205771017.000, Tail:  205771017.000, Next tail:  205775113.049
845     Head - Tail         :          0.000 (0.000000 frames)
846    DLL Rate             : 4096.048828 (256.003052)
847    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
848 245747734915: Debug (StreamProcessorManager.cpp)[ 749] syncStartAll:   initial time of transfer 0206033164, rate 256.003052...
849 245747734923: Debug (StreamProcessorManager.cpp)[ 768] syncStartAll:   preset transmit tail TS 0206848277, rate 256.003052...
850 245747734931: Debug (StreamProcessorManager.cpp)[ 779] syncStartAll:    => transmit head TS 0206156044, fc=2704...
851 245747734935: Debug (StreamProcessorManager.cpp)[ 795] alignReceivedStreams: Aligning received streams...
852 245747734980: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.align_tries
853 245747735017: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.align_tries
854 245747735027: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.align_tries' not found
855 245747735045: Debug (Configuration.cpp)[ 307] getSetting:   temporary has no setting streaming.spm.align_average_time_msec
856 245747735065: Debug (Configuration.cpp)[ 307] getSetting:   /usr/share/libffado/configuration has no setting streaming.spm.align_average_time_msec
857 245747735074: Debug (Configuration.cpp)[ 247] getValueForSetting: path 'streaming.spm.align_average_time_msec' not found
858 245747735079: Debug (StreamProcessorManager.cpp)[ 812] alignReceivedStreams:  averaging over 37 periods...
859 245747737826: Warning (StreamProcessor.cpp)[ 707] getPacket: Instantanous samplerate more than 1% off nominal. [Nom fs: 96000.000000, Instantanous fs: 48005.860090, diff: 47994.139910 (    0.499939)]
860 245747737844: Debug (StreamProcessor.cpp)[ 718] getPacket: cy 3106, rather large TSP difference TS=00206147853 => TS=00206156044 (8191, nom 4096)
861 245748133790: Debug (StreamProcessorManager.cpp)[ 847] alignReceivedStreams:  Average offsets:
862 245748133807: Debug (StreamProcessorManager.cpp)[ 858] alignReceivedStreams:    avg offset between SyncSP 0x12e00e0 and SP 0x12e00e0 is 0 ticks, 0 frames...
863 245748133813: Debug (StreamProcessorManager.cpp)[ 788] syncStartAll:  StreamProcessor streams running...
864 245748133817: Debug (StreamProcessorManager.cpp)[ 935] start:  Started...
865
866 ffado_streaming_wait
867 ============================================
868 Xruns: 0
869 ============================================
870 ----------------------------------------------------
871 Dumping StreamProcessorManager information...
872 Period count:     37
873 Data type: float
874  Receive processors...
875  StreamProcessor 0x12e00e0, Receive:
876   Port, Channel    : 0, 0
877   Packets, Dropped, Skipped : 6171, 0, 0
878   Now                   : 00215577466 (008s 6174c 2938t)
879   Xrun?                 : False
880   State                 : ePS_Running
881   Buffer                : 0x12dff10
882   Framerate             : Nominal: 96000, Sync: 95998.638172, Buffer 95998.638172
883   TimestampedBuffer (0x12dff10): 0384 frames, 0384 events
884    Timestamps           : head:  215470471.815, Tail:  215568777.205, Next tail:  215572873.263
885     Head - Tail         :     -98305.391 (-384.000780 frames)
886    DLL Rate             : 4096.049625 (256.003102)
887    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
888  Transmit processors...
889  StreamProcessor 0x12e0a20, Transmit:
890   Port, Channel    : 0, 1
891   Packets, Dropped, Skipped : 6280, 0, 0
892   Now                   : 00215579334 (008s 6175c 1734t)
893   Xrun?                 : False
894   State                 : ePS_Running
895   Buffer                : 0x12e1b20
896   Framerate             : Nominal: 96000, Sync: 95998.638172, Buffer 95998.809829
897   TimestampedBuffer (0x12e1b20): 2464 frames, 2464 events
898    Timestamps           : head:  215916930.429, Tail:  216547722.242, Next tail:  216809869.486
899     Head - Tail         :    -630791.812 (-2464.001071 frames)
900    DLL Rate             : 262147.132849 (256.003059)
901    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
902 ----------------------------------------------------
903 Port Information
904  Playback
905     0 (0x12dd0d0): [0x12e0a20] [ on] [  Audio] dev0_pbk_Phones-L
906     1 (0x12e1cc0): [0x12e0a20] [ on] [  Audio] dev0_pbk_Phones-R
907     2 (0x12e1d50): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog1
908     3 (0x12e1de0): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog2
909     4 (0x12e1e70): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog3
910     5 (0x12e1fa0): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog4
911     6 (0x12e2030): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog5
912     7 (0x12e20c0): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog6
913     8 (0x12e2150): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog7
914     9 (0x12e1f10): [0x12e0a20] [ on] [  Audio] dev0_pbk_Analog8
915    10 (0x12e1ec0): [0x12e0a20] [ on] [  Audio] dev0_pbk_MainOut-L
916    11 (0x12e22f0): [0x12e0a20] [ on] [  Audio] dev0_pbk_MainOut-R
917    12 (0x12e2380): [0x12e0a20] [ on] [  Audio] dev0_pbk_AES/EBU1
918    13 (0x12e2410): [0x12e0a20] [ on] [  Audio] dev0_pbk_AES/EBU2
919    14 (0x12e2460): [0x12e0a20] [ on] [   MIDI] dev0_pbk_MIDI0
920  Capture
921     0 (0x12c65e0): [0x12e00e0] [ on] [  Audio]  dev0_cap_Mix-L
922     1 (0x12daaa0): [0x12e00e0] [ on] [  Audio]  dev0_cap_Mix-R
923     2 (0x12daaf0): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog1
924     3 (0x12dab60): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog2
925     4 (0x12c6890): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog3
926     5 (0x12e05a0): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog4
927     6 (0x12e0630): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog5
928     7 (0x12e06c0): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog6
929     8 (0x12e0750): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog7
930     9 (0x12e0510): [0x12e00e0] [ on] [  Audio]  dev0_cap_Analog8
931    10 (0x12e04c0): [0x12e00e0] [ on] [  Audio]  dev0_cap_unknown-1
932    11 (0x12e0860): [0x12e00e0] [ on] [  Audio]  dev0_cap_unknown-2
933    12 (0x12e08f0): [0x12e00e0] [ on] [  Audio]  dev0_cap_AES/EBU1
934    13 (0x12e0980): [0x12e00e0] [ on] [  Audio]  dev0_cap_AES/EBU2
935    14 (0x12e09d0): [0x12e00e0] [ on] [   MIDI]  dev0_cap_MIDI0
936 ----------------------------------------------------
937
938 jack: 5378:245748144227 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
939 jack: 5378:245748144243 engine.c:jack_run_one_cycle:2150: release problem lock
940 jack: 5378:245748144246 engine.c:jack_run_one_cycle:2153: waiting for driver read
941
942 jack: 5378:245748144279 engine.c:jack_run_one_cycle:2159: run process
943
944 jack: 5378:245748144285 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
945 jack: 5378:245748144288 engine.c:jack_process_internal:580: invoking an internal client's callbacks
946 jack: 5378:245748144509 engine.c:jack_run_one_cycle:2211: release graph lock
947 jack: 5378:245748144517 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
948 jack: 5378:245748154897 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
949 jack: 5378:245748154911 engine.c:jack_run_one_cycle:2150: release problem lock
950 jack: 5378:245748154914 engine.c:jack_run_one_cycle:2153: waiting for driver read
951
952 jack: 5378:245748154946 engine.c:jack_run_one_cycle:2159: run process
953
954 jack: 5378:245748154949 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
955 jack: 5378:245748154952 engine.c:jack_process_internal:580: invoking an internal client's callbacks
956 jack: 5378:245748155160 engine.c:jack_run_one_cycle:2211: release graph lock
957 jack: 5378:245748155167 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
958 jack: 5378:245748165564 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
959 jack: 5378:245748165577 engine.c:jack_run_one_cycle:2150: release problem lock
960 jack: 5378:245748165579 engine.c:jack_run_one_cycle:2153: waiting for driver read
961
962 jack: 5378:245748165614 engine.c:jack_run_one_cycle:2159: run process
963
964 jack: 5378:245748165621 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
965 jack: 5378:245748165624 engine.c:jack_process_internal:580: invoking an internal client's callbacks
966 jack: 5378:245748165827 engine.c:jack_run_one_cycle:2211: release graph lock
967 jack: 5378:245748165832 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
968 jack: 5378:245748176226 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
969 jack: 5378:245748176236 engine.c:jack_run_one_cycle:2150: release problem lock
970 jack: 5378:245748176238 engine.c:jack_run_one_cycle:2153: waiting for driver read
971
972 245748176258: Debug (MotuReceiveStreamProcessor.cpp)[ 404] decodeMotuCtrlEvents: syncing device control status stream
973 jack: 5378:245748176279 engine.c:jack_run_one_cycle:2159: run process
974
975 jack: 5378:245748176282 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
976 jack: 5378:245748176284 engine.c:jack_process_internal:580: invoking an internal client's callbacks
977 jack: 5378:245748176502 engine.c:jack_run_one_cycle:2211: release graph lock
978 jack: 5378:245748176509 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
979 jack: 5378:245748186896 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
980 jack: 5378:245748186904 engine.c:jack_run_one_cycle:2150: release problem lock
981 245748186919: Debug (MotuReceiveStreamProcessor.cpp)[ 417] decodeMotuCtrlEvents: initialising device control status
982 jack: 5378:245748186907 engine.c:jack_run_one_cycle:2153: waiting for driver read
983
984 jack: 5378:245748186946 engine.c:jack_run_one_cycle:2159: run process
985
986 jack: 5378:245748186949 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
987 jack: 5378:245748186952 engine.c:jack_process_internal:580: invoking an internal client's callbacks
988 jack: 5378:245748187155 engine.c:jack_run_one_cycle:2211: release graph lock
989 jack: 5378:245748187161 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
990 jack: 5378:245748197569 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
991 jack: 5378:245748197582 engine.c:jack_run_one_cycle:2150: release problem lock
992 jack: 5378:245748197584 engine.c:jack_run_one_cycle:2153: waiting for driver read
993
994 jack: 5378:245748197621 engine.c:jack_run_one_cycle:2159: run process
995
996 jack: 5378:245748197628 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
997 jack: 5378:245748197631 engine.c:jack_process_internal:580: invoking an internal client's callbacks
998 jack: 5378:245748197831 engine.c:jack_run_one_cycle:2211: release graph lock
999 jack: 5378:245748197837 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1000 jack: 5378:245748208230 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1001 jack: 5378:245748208241 engine.c:jack_run_one_cycle:2150: release problem lock
1002 jack: 5378:245748208244 engine.c:jack_run_one_cycle:2153: waiting for driver read
1003
1004 jack: 5378:245748208276 engine.c:jack_run_one_cycle:2159: run process
1005
1006 jack: 5378:245748208282 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1007 jack: 5378:245748208285 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1008 jack: 5378:245748208500 engine.c:jack_run_one_cycle:2211: release graph lock
1009 jack: 5378:245748208507 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1010 245748218937: Debug (MotuReceiveStreamProcessor.cpp)[ 426] decodeMotuCtrlEvents: device control status valid: n_mixbuses=4, n_channels=12
1011 jack: 5378:245748218900 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1012 jack: 5378:245748218908 engine.c:jack_run_one_cycle:2150: release problem lock
1013 jack: 5378:245748218910 engine.c:jack_run_one_cycle:2153: waiting for driver read
1014
1015 jack: 5378:245748218953 engine.c:jack_run_one_cycle:2159: run process
1016
1017 jack: 5378:245748218956 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1018 jack: 5378:245748218958 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1019 jack: 5378:245748219163 engine.c:jack_run_one_cycle:2211: release graph lock
1020 jack: 5378:245748219166 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1021 jack: 5378:245748229569 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1022 jack: 5378:245748229580 engine.c:jack_run_one_cycle:2150: release problem lock
1023 jack: 5378:245748229582 engine.c:jack_run_one_cycle:2153: waiting for driver read
1024
1025 jack: 5378:245748229617 engine.c:jack_run_one_cycle:2159: run process
1026
1027 jack: 5378:245748229620 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1028 jack: 5378:245748229622 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1029 jack: 5378:245748229825 engine.c:jack_run_one_cycle:2211: release graph lock
1030 jack: 5378:245748229827 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1031 jack: 5378:245748240234 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1032 jack: 5378:245748240244 engine.c:jack_run_one_cycle:2150: release problem lock
1033 jack: 5378:245748240246 engine.c:jack_run_one_cycle:2153: waiting for driver read
1034
1035 jack: 5378:245748240277 engine.c:jack_run_one_cycle:2159: run process
1036
1037 jack: 5378:245748240280 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1038 jack: 5378:245748240282 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1039 jack: 5378:245748240506 engine.c:jack_run_one_cycle:2211: release graph lock
1040 jack: 5378:245748240510 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1041 jack: 5378:245748250903 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1042 jack: 5378:245748250913 engine.c:jack_run_one_cycle:2150: release problem lock
1043 jack: 5378:245748250916 engine.c:jack_run_one_cycle:2153: waiting for driver read
1044
1045 jack: 5378:245748250946 engine.c:jack_run_one_cycle:2159: run process
1046
1047 jack: 5378:245748250953 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1048 jack: 5378:245748250956 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1049 jack: 5378:245748251160 engine.c:jack_run_one_cycle:2211: release graph lock
1050 jack: 5378:245748251167 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1051 jack: 5378:245748261574 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1052 jack: 5378:245748261587 engine.c:jack_run_one_cycle:2150: release problem lock
1053 jack: 5378:245748261589 engine.c:jack_run_one_cycle:2153: waiting for driver read
1054
1055 jack: 5378:245748261623 engine.c:jack_run_one_cycle:2159: run process
1056
1057 jack: 5378:245748261626 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1058 jack: 5378:245748261629 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1059 jack: 5378:245748261825 engine.c:jack_run_one_cycle:2211: release graph lock
1060 jack: 5378:245748261828 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1061 jack: 5378:245748272239 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1062 jack: 5378:245748272248 engine.c:jack_run_one_cycle:2150: release problem lock
1063 jack: 5378:245748272251 engine.c:jack_run_one_cycle:2153: waiting for driver read
1064
1065 jack: 5378:245748272284 engine.c:jack_run_one_cycle:2159: run process
1066
1067 jack: 5378:245748272291 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1068 jack: 5378:245748272293 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1069 jack: 5378:245748272508 engine.c:jack_run_one_cycle:2211: release graph lock
1070 jack: 5378:245748272516 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1071 jack: 5378:245748282912 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1072 jack: 5378:245748282923 engine.c:jack_run_one_cycle:2150: release problem lock
1073 jack: 5378:245748282925 engine.c:jack_run_one_cycle:2153: waiting for driver read
1074
1075 jack: 5378:245748282955 engine.c:jack_run_one_cycle:2159: run process
1076
1077 jack: 5378:245748282958 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1078 jack: 5378:245748282960 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1079 jack: 5378:245748283165 engine.c:jack_run_one_cycle:2211: release graph lock
1080 jack: 5378:245748283172 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1081 jack: 5378:245748293578 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1082 jack: 5378:245748293591 engine.c:jack_run_one_cycle:2150: release problem lock
1083 jack: 5378:245748293593 engine.c:jack_run_one_cycle:2153: waiting for driver read
1084
1085 jack: 5378:245748293628 engine.c:jack_run_one_cycle:2159: run process
1086
1087 jack: 5378:245748293635 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1088 jack: 5378:245748293637 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1089 jack: 5378:245748293836 engine.c:jack_run_one_cycle:2211: release graph lock
1090 jack: 5378:245748293841 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1091 jack: 5378:245748304243 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1092 jack: 5378:245748304252 engine.c:jack_run_one_cycle:2150: release problem lock
1093 jack: 5378:245748304254 engine.c:jack_run_one_cycle:2153: waiting for driver read
1094
1095 jack: 5378:245748304284 engine.c:jack_run_one_cycle:2159: run process
1096
1097 jack: 5378:245748304291 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1098 jack: 5378:245748304294 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1099 jack: 5378:245748304508 engine.c:jack_run_one_cycle:2211: release graph lock
1100 jack: 5378:245748304516 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1101 jack: 5378:245748314916 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1102 jack: 5378:245748314928 engine.c:jack_run_one_cycle:2150: release problem lock
1103 jack: 5378:245748314930 engine.c:jack_run_one_cycle:2153: waiting for driver read
1104
1105 jack: 5378:245748314963 engine.c:jack_run_one_cycle:2159: run process
1106
1107 jack: 5378:245748314966 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1108 jack: 5378:245748314968 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1109 jack: 5378:245748315173 engine.c:jack_run_one_cycle:2211: release graph lock
1110 jack: 5378:245748315179 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1111 jack: 5378:245748325583 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1112 jack: 5378:245748325594 engine.c:jack_run_one_cycle:2150: release problem lock
1113 jack: 5378:245748325597 engine.c:jack_run_one_cycle:2153: waiting for driver read
1114
1115 jack: 5378:245748325630 engine.c:jack_run_one_cycle:2159: run process
1116
1117 jack: 5378:245748325637 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1118 jack: 5378:245748325639 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1119 jack: 5378:245748325841 engine.c:jack_run_one_cycle:2211: release graph lock
1120 jack: 5378:245748325847 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1121 jack: 5378:245748336248 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1122 jack: 5378:245748336256 engine.c:jack_run_one_cycle:2150: release problem lock
1123 jack: 5378:245748336259 engine.c:jack_run_one_cycle:2153: waiting for driver read
1124
1125 jack: 5378:245748336289 engine.c:jack_run_one_cycle:2159: run process
1126
1127 jack: 5378:245748336296 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1128 jack: 5378:245748336298 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1129 jack: 5378:245748336512 engine.c:jack_run_one_cycle:2211: release graph lock
1130 jack: 5378:245748336519 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1131 jack: 5378:245748346923 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1132 jack: 5378:245748346937 engine.c:jack_run_one_cycle:2150: release problem lock
1133 jack: 5378:245748346939 engine.c:jack_run_one_cycle:2153: waiting for driver read
1134
1135 jack: 5378:245748346973 engine.c:jack_run_one_cycle:2159: run process
1136
1137 jack: 5378:245748346976 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1138 jack: 5378:245748346979 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1139 jack: 5378:245748347184 engine.c:jack_run_one_cycle:2211: release graph lock
1140 jack: 5378:245748347190 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1141 jack: 5378:245748357586 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1142 jack: 5378:245748357597 engine.c:jack_run_one_cycle:2150: release problem lock
1143 jack: 5378:245748357599 engine.c:jack_run_one_cycle:2153: waiting for driver read
1144
1145 jack: 5378:245748357631 engine.c:jack_run_one_cycle:2159: run process
1146
1147 jack: 5378:245748357639 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1148 jack: 5378:245748357642 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1149 jack: 5378:245748357837 engine.c:jack_run_one_cycle:2211: release graph lock
1150 jack: 5378:245748357843 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1151 jack: 5378:245748368252 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1152 jack: 5378:245748368261 engine.c:jack_run_one_cycle:2150: release problem lock
1153 jack: 5378:245748368264 engine.c:jack_run_one_cycle:2153: waiting for driver read
1154
1155 jack: 5378:245748368293 engine.c:jack_run_one_cycle:2159: run process
1156
1157 jack: 5378:245748368301 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1158 jack: 5378:245748368303 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1159 jack: 5378:245748368517 engine.c:jack_run_one_cycle:2211: release graph lock
1160 jack: 5378:245748368525 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1161 jack: 5378:245748378926 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1162 jack: 5378:245748378939 engine.c:jack_run_one_cycle:2150: release problem lock
1163 jack: 5378:245748378942 engine.c:jack_run_one_cycle:2153: waiting for driver read
1164
1165 jack: 5378:245748378978 engine.c:jack_run_one_cycle:2159: run process
1166
1167 jack: 5378:245748378980 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1168 jack: 5378:245748378983 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1169 jack: 5378:245748379188 engine.c:jack_run_one_cycle:2211: release graph lock
1170 jack: 5378:245748379197 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1171 jack: 5378:245748389594 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1172 jack: 5378:245748389604 engine.c:jack_run_one_cycle:2150: release problem lock
1173 jack: 5378:245748389606 engine.c:jack_run_one_cycle:2153: waiting for driver read
1174
1175 jack: 5378:245748389638 engine.c:jack_run_one_cycle:2159: run process
1176
1177 jack: 5378:245748389645 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1178 jack: 5378:245748389648 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1179 jack: 5378:245748389844 engine.c:jack_run_one_cycle:2211: release graph lock
1180 jack: 5378:245748389850 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1181 jack: 5378:245748400261 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1182 jack: 5378:245748400270 engine.c:jack_run_one_cycle:2150: release problem lock
1183 jack: 5378:245748400272 engine.c:jack_run_one_cycle:2153: waiting for driver read
1184
1185 jack: 5378:245748400302 engine.c:jack_run_one_cycle:2159: run process
1186
1187 jack: 5378:245748400309 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1188 jack: 5378:245748400311 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1189 jack: 5378:245748400526 engine.c:jack_run_one_cycle:2211: release graph lock
1190 jack: 5378:245748400533 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1191 jack: 5378:245748410933 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1192 jack: 5378:245748410947 engine.c:jack_run_one_cycle:2150: release problem lock
1193 jack: 5378:245748410949 engine.c:jack_run_one_cycle:2153: waiting for driver read
1194
1195 jack: 5378:245748410983 engine.c:jack_run_one_cycle:2159: run process
1196
1197 jack: 5378:245748410986 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1198 jack: 5378:245748410988 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1199 jack: 5378:245748411194 engine.c:jack_run_one_cycle:2211: release graph lock
1200 jack: 5378:245748411201 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1201 jack: 5378:245748421595 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1202 jack: 5378:245748421604 engine.c:jack_run_one_cycle:2150: release problem lock
1203 jack: 5378:245748421607 engine.c:jack_run_one_cycle:2153: waiting for driver read
1204
1205 jack: 5378:245748421638 engine.c:jack_run_one_cycle:2159: run process
1206
1207 jack: 5378:245748421645 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1208 jack: 5378:245748421647 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1209 jack: 5378:245748421845 engine.c:jack_run_one_cycle:2211: release graph lock
1210 jack: 5378:245748421851 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1211 jack: 5378:245748432262 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1212 jack: 5378:245748432273 engine.c:jack_run_one_cycle:2150: release problem lock
1213 jack: 5378:245748432275 engine.c:jack_run_one_cycle:2153: waiting for driver read
1214
1215 jack: 5378:245748432307 engine.c:jack_run_one_cycle:2159: run process
1216
1217 jack: 5378:245748432309 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1218 jack: 5378:245748432312 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1219 jack: 5378:245748432524 engine.c:jack_run_one_cycle:2211: release graph lock
1220 jack: 5378:245748432528 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1221 jack: 5378:245748442936 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1222 jack: 5378:245748442949 engine.c:jack_run_one_cycle:2150: release problem lock
1223 jack: 5378:245748442951 engine.c:jack_run_one_cycle:2153: waiting for driver read
1224
1225 jack: 5378:245748442986 engine.c:jack_run_one_cycle:2159: run process
1226
1227 jack: 5378:245748442988 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1228 jack: 5378:245748442991 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1229 jack: 5378:245748443193 engine.c:jack_run_one_cycle:2211: release graph lock
1230 jack: 5378:245748443196 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1231 jack: 5378:245748453599 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1232 jack: 5378:245748453607 engine.c:jack_run_one_cycle:2150: release problem lock
1233 jack: 5378:245748453610 engine.c:jack_run_one_cycle:2153: waiting for driver read
1234
1235 jack: 5378:245748453638 engine.c:jack_run_one_cycle:2159: run process
1236
1237 jack: 5378:245748453641 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1238 jack: 5378:245748453643 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1239 jack: 5378:245748453839 engine.c:jack_run_one_cycle:2211: release graph lock
1240 jack: 5378:245748453842 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1241 245748464307: Warning (MotuReceiveStreamProcessor.cpp)[ 461] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel gain index 20 exceeded maximum 20
1242 jack: 5378:245748464271 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1243 jack: 5378:245748464280 engine.c:jack_run_one_cycle:2150: release problem lock
1244 jack: 5378:245748464283 engine.c:jack_run_one_cycle:2153: waiting for driver read
1245
1246 jack: 5378:245748464327 engine.c:jack_run_one_cycle:2159: run process
1247
1248 jack: 5378:245748464330 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1249 jack: 5378:245748464333 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1250 jack: 5378:245748464557 engine.c:jack_run_one_cycle:2211: release graph lock
1251 jack: 5378:245748464562 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1252 jack: 5378:245748474939 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1253 jack: 5378:245748474951 engine.c:jack_run_one_cycle:2150: release problem lock
1254 jack: 5378:245748474953 engine.c:jack_run_one_cycle:2153: waiting for driver read
1255
1256 jack: 5378:245748474987 engine.c:jack_run_one_cycle:2159: run process
1257
1258 jack: 5378:245748474994 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1259 jack: 5378:245748474997 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1260 jack: 5378:245748475201 engine.c:jack_run_one_cycle:2211: release graph lock
1261 jack: 5378:245748475207 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1262 jack: 5378:245748485605 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1263 jack: 5378:245748485614 engine.c:jack_run_one_cycle:2150: release problem lock
1264 jack: 5378:245748485616 engine.c:jack_run_one_cycle:2153: waiting for driver read
1265
1266 jack: 5378:245748485646 engine.c:jack_run_one_cycle:2159: run process
1267
1268 jack: 5378:245748485653 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1269 jack: 5378:245748485655 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1270 jack: 5378:245748485855 engine.c:jack_run_one_cycle:2211: release graph lock
1271 jack: 5378:245748485861 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1272 jack: 5378:245748496274 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1273 jack: 5378:245748496287 engine.c:jack_run_one_cycle:2150: release problem lock
1274 jack: 5378:245748496289 engine.c:jack_run_one_cycle:2153: waiting for driver read
1275
1276 jack: 5378:245748496323 engine.c:jack_run_one_cycle:2159: run process
1277
1278 jack: 5378:245748496326 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1279 jack: 5378:245748496328 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1280 jack: 5378:245748496543 engine.c:jack_run_one_cycle:2211: release graph lock
1281 jack: 5378:245748496548 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1282 jack: 5378:245748506942 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1283 jack: 5378:245748506952 engine.c:jack_run_one_cycle:2150: release problem lock
1284 jack: 5378:245748506954 engine.c:jack_run_one_cycle:2153: waiting for driver read
1285
1286 jack: 5378:245748506985 engine.c:jack_run_one_cycle:2159: run process
1287
1288 jack: 5378:245748506988 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1289 jack: 5378:245748506990 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1290 jack: 5378:245748507193 engine.c:jack_run_one_cycle:2211: release graph lock
1291 jack: 5378:245748507196 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1292 jack: 5378:245748517609 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1293 jack: 5378:245748517617 engine.c:jack_run_one_cycle:2150: release problem lock
1294 jack: 5378:245748517619 engine.c:jack_run_one_cycle:2153: waiting for driver read
1295
1296 jack: 5378:245748517648 engine.c:jack_run_one_cycle:2159: run process
1297
1298 jack: 5378:245748517651 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1299 jack: 5378:245748517653 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1300 jack: 5378:245748517847 engine.c:jack_run_one_cycle:2211: release graph lock
1301 jack: 5378:245748517850 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1302 jack: 5378:245748528282 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1303 jack: 5378:245748528293 engine.c:jack_run_one_cycle:2150: release problem lock
1304 jack: 5378:245748528295 engine.c:jack_run_one_cycle:2153: waiting for driver read
1305
1306 jack: 5378:245748528328 engine.c:jack_run_one_cycle:2159: run process
1307
1308 jack: 5378:245748528331 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1309 jack: 5378:245748528333 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1310 jack: 5378:245748528557 engine.c:jack_run_one_cycle:2211: release graph lock
1311 jack: 5378:245748528562 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1312 jack: 5378:245748538949 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1313 jack: 5378:245748538959 engine.c:jack_run_one_cycle:2150: release problem lock
1314 jack: 5378:245748538962 engine.c:jack_run_one_cycle:2153: waiting for driver read
1315
1316 jack: 5378:245748538994 engine.c:jack_run_one_cycle:2159: run process
1317
1318 jack: 5378:245748539001 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1319 jack: 5378:245748539004 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1320 jack: 5378:245748539207 engine.c:jack_run_one_cycle:2211: release graph lock
1321 jack: 5378:245748539213 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1322 jack: 5378:245748549614 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1323 jack: 5378:245748549623 engine.c:jack_run_one_cycle:2150: release problem lock
1324 jack: 5378:245748549625 engine.c:jack_run_one_cycle:2153: waiting for driver read
1325
1326 jack: 5378:245748549655 engine.c:jack_run_one_cycle:2159: run process
1327
1328 jack: 5378:245748549662 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1329 jack: 5378:245748549665 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1330 jack: 5378:245748549863 engine.c:jack_run_one_cycle:2211: release graph lock
1331 jack: 5378:245748549869 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1332 jack: 5378:245748560284 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1333 jack: 5378:245748560297 engine.c:jack_run_one_cycle:2150: release problem lock
1334 jack: 5378:245748560300 engine.c:jack_run_one_cycle:2153: waiting for driver read
1335
1336 jack: 5378:245748560334 engine.c:jack_run_one_cycle:2159: run process
1337
1338 jack: 5378:245748560337 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1339 jack: 5378:245748560339 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1340 jack: 5378:245748560555 engine.c:jack_run_one_cycle:2211: release graph lock
1341 jack: 5378:245748560559 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1342 jack: 5378:245748570954 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1343 jack: 5378:245748570963 engine.c:jack_run_one_cycle:2150: release problem lock
1344 jack: 5378:245748570965 engine.c:jack_run_one_cycle:2153: waiting for driver read
1345
1346 jack: 5378:245748570994 engine.c:jack_run_one_cycle:2159: run process
1347
1348 jack: 5378:245748570997 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1349 jack: 5378:245748570999 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1350 jack: 5378:245748571202 engine.c:jack_run_one_cycle:2211: release graph lock
1351 jack: 5378:245748571205 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1352 jack: 5378:245748581622 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1353 jack: 5378:245748581631 engine.c:jack_run_one_cycle:2150: release problem lock
1354 jack: 5378:245748581633 engine.c:jack_run_one_cycle:2153: waiting for driver read
1355
1356 jack: 5378:245748581666 engine.c:jack_run_one_cycle:2159: run process
1357
1358 jack: 5378:245748581669 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1359 jack: 5378:245748581671 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1360 jack: 5378:245748581867 engine.c:jack_run_one_cycle:2211: release graph lock
1361 jack: 5378:245748581870 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1362 jack: 5378:245748592289 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1363 jack: 5378:245748592300 engine.c:jack_run_one_cycle:2150: release problem lock
1364 jack: 5378:245748592302 engine.c:jack_run_one_cycle:2153: waiting for driver read
1365
1366 jack: 5378:245748592335 engine.c:jack_run_one_cycle:2159: run process
1367
1368 jack: 5378:245748592338 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1369 jack: 5378:245748592341 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1370 jack: 5378:245748592562 engine.c:jack_run_one_cycle:2211: release graph lock
1371 jack: 5378:245748592567 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1372 jack: 5378:245748602957 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1373 jack: 5378:245748602965 engine.c:jack_run_one_cycle:2150: release problem lock
1374 jack: 5378:245748602968 engine.c:jack_run_one_cycle:2153: waiting for driver read
1375
1376 jack: 5378:245748602997 engine.c:jack_run_one_cycle:2159: run process
1377
1378 jack: 5378:245748603004 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1379 jack: 5378:245748603007 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1380 jack: 5378:245748603208 engine.c:jack_run_one_cycle:2211: release graph lock
1381 jack: 5378:245748603214 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1382 jack: 5378:245748613637 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1383 jack: 5378:245748613651 engine.c:jack_run_one_cycle:2150: release problem lock
1384 jack: 5378:245748613653 engine.c:jack_run_one_cycle:2153: waiting for driver read
1385
1386 jack: 5378:245748613694 engine.c:jack_run_one_cycle:2159: run process
1387
1388 jack: 5378:245748613697 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1389 jack: 5378:245748613699 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1390 jack: 5378:245748613901 engine.c:jack_run_one_cycle:2211: release graph lock
1391 jack: 5378:245748613904 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1392 jack: 5378:245748624307 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1393 jack: 5378:245748624319 engine.c:jack_run_one_cycle:2150: release problem lock
1394 jack: 5378:245748624321 engine.c:jack_run_one_cycle:2153: waiting for driver read
1395
1396 jack: 5378:245748624363 engine.c:jack_run_one_cycle:2159: run process
1397
1398 jack: 5378:245748624366 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1399 jack: 5378:245748624368 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1400 jack: 5378:245748624596 engine.c:jack_run_one_cycle:2211: release graph lock
1401 jack: 5378:245748624601 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1402 jack: 5378:245748634964 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1403 jack: 5378:245748634975 engine.c:jack_run_one_cycle:2150: release problem lock
1404 jack: 5378:245748634977 engine.c:jack_run_one_cycle:2153: waiting for driver read
1405
1406 jack: 5378:245748635010 engine.c:jack_run_one_cycle:2159: run process
1407
1408 jack: 5378:245748635013 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1409 jack: 5378:245748635015 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1410 jack: 5378:245748635222 engine.c:jack_run_one_cycle:2211: release graph lock
1411 jack: 5378:245748635228 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1412 jack: 5378:245748645636 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1413 jack: 5378:245748645650 engine.c:jack_run_one_cycle:2150: release problem lock
1414 jack: 5378:245748645652 engine.c:jack_run_one_cycle:2153: waiting for driver read
1415
1416 jack: 5378:245748645688 engine.c:jack_run_one_cycle:2159: run process
1417
1418 jack: 5378:245748645691 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1419 jack: 5378:245748645694 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1420 jack: 5378:245748645892 engine.c:jack_run_one_cycle:2211: release graph lock
1421 jack: 5378:245748645895 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1422 jack: 5378:245748656299 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1423 jack: 5378:245748656309 engine.c:jack_run_one_cycle:2150: release problem lock
1424 jack: 5378:245748656311 engine.c:jack_run_one_cycle:2153: waiting for driver read
1425
1426 jack: 5378:245748656344 engine.c:jack_run_one_cycle:2159: run process
1427
1428 jack: 5378:245748656351 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1429 jack: 5378:245748656353 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1430 jack: 5378:245748656570 engine.c:jack_run_one_cycle:2211: release graph lock
1431 jack: 5378:245748656578 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1432 jack: 5378:245748666968 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1433 jack: 5378:245748666977 engine.c:jack_run_one_cycle:2150: release problem lock
1434 jack: 5378:245748666980 engine.c:jack_run_one_cycle:2153: waiting for driver read
1435
1436 jack: 5378:245748667017 engine.c:jack_run_one_cycle:2159: run process
1437
1438 jack: 5378:245748667021 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1439 jack: 5378:245748667023 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1440 jack: 5378:245748667224 engine.c:jack_run_one_cycle:2211: release graph lock
1441 jack: 5378:245748667227 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1442 jack: 5378:245748677639 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1443 jack: 5378:245748677652 engine.c:jack_run_one_cycle:2150: release problem lock
1444 jack: 5378:245748677654 engine.c:jack_run_one_cycle:2153: waiting for driver read
1445
1446 jack: 5378:245748677688 engine.c:jack_run_one_cycle:2159: run process
1447
1448 jack: 5378:245748677691 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1449 jack: 5378:245748677693 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1450 jack: 5378:245748677889 engine.c:jack_run_one_cycle:2211: release graph lock
1451 jack: 5378:245748677892 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1452 245748688326: Warning (MotuReceiveStreamProcessor.cpp)[ 471] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel pan index 20 exceeded maximum 20
1453 jack: 5378:245748688303 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1454 jack: 5378:245748688312 engine.c:jack_run_one_cycle:2150: release problem lock
1455 jack: 5378:245748688314 engine.c:jack_run_one_cycle:2153: waiting for driver read
1456
1457 jack: 5378:245748688362 engine.c:jack_run_one_cycle:2159: run process
1458
1459 jack: 5378:245748688365 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1460 jack: 5378:245748688368 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1461 jack: 5378:245748688591 engine.c:jack_run_one_cycle:2211: release graph lock
1462 jack: 5378:245748688596 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1463 jack: 5378:245748698972 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1464 jack: 5378:245748698980 engine.c:jack_run_one_cycle:2150: release problem lock
1465 jack: 5378:245748698982 engine.c:jack_run_one_cycle:2153: waiting for driver read
1466
1467 jack: 5378:245748699012 engine.c:jack_run_one_cycle:2159: run process
1468
1469 jack: 5378:245748699015 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1470 jack: 5378:245748699017 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1471 jack: 5378:245748699216 engine.c:jack_run_one_cycle:2211: release graph lock
1472 jack: 5378:245748699222 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1473 jack: 5378:245748709643 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1474 jack: 5378:245748709656 engine.c:jack_run_one_cycle:2150: release problem lock
1475 jack: 5378:245748709658 engine.c:jack_run_one_cycle:2153: waiting for driver read
1476
1477 jack: 5378:245748709693 engine.c:jack_run_one_cycle:2159: run process
1478
1479 jack: 5378:245748709696 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1480 jack: 5378:245748709699 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1481 jack: 5378:245748709896 engine.c:jack_run_one_cycle:2211: release graph lock
1482 jack: 5378:245748709899 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1483 jack: 5378:245748720307 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1484 jack: 5378:245748720315 engine.c:jack_run_one_cycle:2150: release problem lock
1485 jack: 5378:245748720317 engine.c:jack_run_one_cycle:2153: waiting for driver read
1486
1487 jack: 5378:245748720348 engine.c:jack_run_one_cycle:2159: run process
1488
1489 jack: 5378:245748720354 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1490 jack: 5378:245748720357 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1491 jack: 5378:245748720571 engine.c:jack_run_one_cycle:2211: release graph lock
1492 jack: 5378:245748720579 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1493 jack: 5378:245748730980 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1494 jack: 5378:245748730991 engine.c:jack_run_one_cycle:2150: release problem lock
1495 jack: 5378:245748730993 engine.c:jack_run_one_cycle:2153: waiting for driver read
1496
1497 jack: 5378:245748731025 engine.c:jack_run_one_cycle:2159: run process
1498
1499 jack: 5378:245748731028 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1500 jack: 5378:245748731030 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1501 jack: 5378:245748731232 engine.c:jack_run_one_cycle:2211: release graph lock
1502 jack: 5378:245748731239 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1503 jack: 5378:245748741648 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1504 jack: 5378:245748741661 engine.c:jack_run_one_cycle:2150: release problem lock
1505 jack: 5378:245748741663 engine.c:jack_run_one_cycle:2153: waiting for driver read
1506
1507 jack: 5378:245748741698 engine.c:jack_run_one_cycle:2159: run process
1508
1509 jack: 5378:245748741705 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1510 jack: 5378:245748741707 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1511 jack: 5378:245748741904 engine.c:jack_run_one_cycle:2211: release graph lock
1512 jack: 5378:245748741910 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1513 jack: 5378:245748752311 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1514 jack: 5378:245748752320 engine.c:jack_run_one_cycle:2150: release problem lock
1515 jack: 5378:245748752323 engine.c:jack_run_one_cycle:2153: waiting for driver read
1516
1517 jack: 5378:245748752353 engine.c:jack_run_one_cycle:2159: run process
1518
1519 jack: 5378:245748752360 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1520 jack: 5378:245748752363 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1521 jack: 5378:245748752576 engine.c:jack_run_one_cycle:2211: release graph lock
1522 jack: 5378:245748752584 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1523 jack: 5378:245748762987 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1524 jack: 5378:245748762999 engine.c:jack_run_one_cycle:2150: release problem lock
1525 jack: 5378:245748763001 engine.c:jack_run_one_cycle:2153: waiting for driver read
1526
1527 jack: 5378:245748763035 engine.c:jack_run_one_cycle:2159: run process
1528
1529 jack: 5378:245748763038 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1530 jack: 5378:245748763040 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1531 jack: 5378:245748763243 engine.c:jack_run_one_cycle:2211: release graph lock
1532 jack: 5378:245748763249 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1533 jack: 5378:245748773652 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1534 jack: 5378:245748773663 engine.c:jack_run_one_cycle:2150: release problem lock
1535 jack: 5378:245748773665 engine.c:jack_run_one_cycle:2153: waiting for driver read
1536
1537 jack: 5378:245748773697 engine.c:jack_run_one_cycle:2159: run process
1538
1539 jack: 5378:245748773704 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1540 jack: 5378:245748773706 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1541 jack: 5378:245748773903 engine.c:jack_run_one_cycle:2211: release graph lock
1542 jack: 5378:245748773909 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1543 jack: 5378:245748784318 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1544 jack: 5378:245748784327 engine.c:jack_run_one_cycle:2150: release problem lock
1545 jack: 5378:245748784330 engine.c:jack_run_one_cycle:2153: waiting for driver read
1546
1547 jack: 5378:245748784359 engine.c:jack_run_one_cycle:2159: run process
1548
1549 jack: 5378:245748784367 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1550 jack: 5378:245748784369 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1551 jack: 5378:245748784584 engine.c:jack_run_one_cycle:2211: release graph lock
1552 jack: 5378:245748784592 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1553 jack: 5378:245748794990 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1554 jack: 5378:245748795008 engine.c:jack_run_one_cycle:2150: release problem lock
1555 jack: 5378:245748795011 engine.c:jack_run_one_cycle:2153: waiting for driver read
1556
1557 jack: 5378:245748795047 engine.c:jack_run_one_cycle:2159: run process
1558
1559 jack: 5378:245748795049 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1560 jack: 5378:245748795052 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1561 jack: 5378:245748795248 engine.c:jack_run_one_cycle:2211: release graph lock
1562 jack: 5378:245748795251 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1563 jack: 5378:245748805655 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1564 jack: 5378:245748805665 engine.c:jack_run_one_cycle:2150: release problem lock
1565 jack: 5378:245748805668 engine.c:jack_run_one_cycle:2153: waiting for driver read
1566
1567 jack: 5378:245748805698 engine.c:jack_run_one_cycle:2159: run process
1568
1569 jack: 5378:245748805701 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1570 jack: 5378:245748805703 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1571 jack: 5378:245748805899 engine.c:jack_run_one_cycle:2211: release graph lock
1572 jack: 5378:245748805902 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1573 jack: 5378:245748816322 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1574 jack: 5378:245748816331 engine.c:jack_run_one_cycle:2150: release problem lock
1575 jack: 5378:245748816333 engine.c:jack_run_one_cycle:2153: waiting for driver read
1576
1577 jack: 5378:245748816363 engine.c:jack_run_one_cycle:2159: run process
1578
1579 jack: 5378:245748816366 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1580 jack: 5378:245748816368 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1581 jack: 5378:245748816589 engine.c:jack_run_one_cycle:2211: release graph lock
1582 jack: 5378:245748816594 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1583 jack: 5378:245748826996 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1584 jack: 5378:245748827011 engine.c:jack_run_one_cycle:2150: release problem lock
1585 jack: 5378:245748827013 engine.c:jack_run_one_cycle:2153: waiting for driver read
1586
1587 jack: 5378:245748827049 engine.c:jack_run_one_cycle:2159: run process
1588
1589 jack: 5378:245748827052 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1590 jack: 5378:245748827055 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1591 jack: 5378:245748827258 engine.c:jack_run_one_cycle:2211: release graph lock
1592 jack: 5378:245748827265 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1593 jack: 5378:245748837661 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1594 jack: 5378:245748837671 engine.c:jack_run_one_cycle:2150: release problem lock
1595 jack: 5378:245748837674 engine.c:jack_run_one_cycle:2153: waiting for driver read
1596
1597 jack: 5378:245748837706 engine.c:jack_run_one_cycle:2159: run process
1598
1599 jack: 5378:245748837714 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1600 jack: 5378:245748837716 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1601 jack: 5378:245748837914 engine.c:jack_run_one_cycle:2211: release graph lock
1602 jack: 5378:245748837920 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1603 jack: 5378:245748848327 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1604 jack: 5378:245748848337 engine.c:jack_run_one_cycle:2150: release problem lock
1605 jack: 5378:245748848339 engine.c:jack_run_one_cycle:2153: waiting for driver read
1606
1607 jack: 5378:245748848369 engine.c:jack_run_one_cycle:2159: run process
1608
1609 jack: 5378:245748848376 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1610 jack: 5378:245748848379 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1611 jack: 5378:245748848593 engine.c:jack_run_one_cycle:2211: release graph lock
1612 jack: 5378:245748848600 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1613 jack: 5378:245748859007 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1614 jack: 5378:245748859020 engine.c:jack_run_one_cycle:2150: release problem lock
1615 jack: 5378:245748859022 engine.c:jack_run_one_cycle:2153: waiting for driver read
1616
1617 jack: 5378:245748859057 engine.c:jack_run_one_cycle:2159: run process
1618
1619 jack: 5378:245748859060 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1620 jack: 5378:245748859063 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1621 jack: 5378:245748859260 engine.c:jack_run_one_cycle:2211: release graph lock
1622 jack: 5378:245748859263 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1623 jack: 5378:245748869665 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1624 jack: 5378:245748869673 engine.c:jack_run_one_cycle:2150: release problem lock
1625 jack: 5378:245748869675 engine.c:jack_run_one_cycle:2153: waiting for driver read
1626
1627 jack: 5378:245748869706 engine.c:jack_run_one_cycle:2159: run process
1628
1629 jack: 5378:245748869708 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1630 jack: 5378:245748869711 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1631 jack: 5378:245748869907 engine.c:jack_run_one_cycle:2211: release graph lock
1632 jack: 5378:245748869910 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1633 jack: 5378:245748880336 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1634 jack: 5378:245748880346 engine.c:jack_run_one_cycle:2150: release problem lock
1635 jack: 5378:245748880348 engine.c:jack_run_one_cycle:2153: waiting for driver read
1636
1637 jack: 5378:245748880379 engine.c:jack_run_one_cycle:2159: run process
1638
1639 jack: 5378:245748880381 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1640 jack: 5378:245748880384 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1641 jack: 5378:245748880609 engine.c:jack_run_one_cycle:2211: release graph lock
1642 jack: 5378:245748880614 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1643 jack: 5378:245748891005 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1644 jack: 5378:245748891016 engine.c:jack_run_one_cycle:2150: release problem lock
1645 jack: 5378:245748891019 engine.c:jack_run_one_cycle:2153: waiting for driver read
1646
1647 jack: 5378:245748891052 engine.c:jack_run_one_cycle:2159: run process
1648
1649 jack: 5378:245748891059 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1650 jack: 5378:245748891062 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1651 jack: 5378:245748891265 engine.c:jack_run_one_cycle:2211: release graph lock
1652 jack: 5378:245748891272 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1653 jack: 5378:245748901674 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1654 jack: 5378:245748901684 engine.c:jack_run_one_cycle:2150: release problem lock
1655 jack: 5378:245748901686 engine.c:jack_run_one_cycle:2153: waiting for driver read
1656
1657 jack: 5378:245748901719 engine.c:jack_run_one_cycle:2159: run process
1658
1659 jack: 5378:245748901726 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1660 jack: 5378:245748901728 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1661 jack: 5378:245748901924 engine.c:jack_run_one_cycle:2211: release graph lock
1662 jack: 5378:245748901930 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1663 245748912366: Warning (MotuReceiveStreamProcessor.cpp)[ 471] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel pan index 20 exceeded maximum 20
1664 245748912380: Warning (MotuReceiveStreamProcessor.cpp)[ 471] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel pan index 21 exceeded maximum 20
1665 jack: 5378:245748912341 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1666 jack: 5378:245748912351 engine.c:jack_run_one_cycle:2150: release problem lock
1667 jack: 5378:245748912353 engine.c:jack_run_one_cycle:2153: waiting for driver read
1668
1669 jack: 5378:245748912403 engine.c:jack_run_one_cycle:2159: run process
1670
1671 jack: 5378:245748912406 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1672 jack: 5378:245748912408 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1673 jack: 5378:245748912628 engine.c:jack_run_one_cycle:2211: release graph lock
1674 jack: 5378:245748912635 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1675 jack: 5378:245748923013 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1676 jack: 5378:245748923025 engine.c:jack_run_one_cycle:2150: release problem lock
1677 jack: 5378:245748923027 engine.c:jack_run_one_cycle:2153: waiting for driver read
1678
1679 jack: 5378:245748923060 engine.c:jack_run_one_cycle:2159: run process
1680
1681 jack: 5378:245748923062 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1682 jack: 5378:245748923065 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1683 jack: 5378:245748923260 engine.c:jack_run_one_cycle:2211: release graph lock
1684 jack: 5378:245748923263 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1685 jack: 5378:245748933674 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1686 jack: 5378:245748933683 engine.c:jack_run_one_cycle:2150: release problem lock
1687 jack: 5378:245748933685 engine.c:jack_run_one_cycle:2153: waiting for driver read
1688
1689 jack: 5378:245748933714 engine.c:jack_run_one_cycle:2159: run process
1690
1691 jack: 5378:245748933716 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1692 jack: 5378:245748933719 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1693 jack: 5378:245748933913 engine.c:jack_run_one_cycle:2211: release graph lock
1694 jack: 5378:245748933915 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1695 jack: 5378:245748944348 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1696 jack: 5378:245748944359 engine.c:jack_run_one_cycle:2150: release problem lock
1697 jack: 5378:245748944361 engine.c:jack_run_one_cycle:2153: waiting for driver read
1698
1699 jack: 5378:245748944397 engine.c:jack_run_one_cycle:2159: run process
1700
1701 jack: 5378:245748944399 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1702 jack: 5378:245748944402 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1703 jack: 5378:245748944627 engine.c:jack_run_one_cycle:2211: release graph lock
1704 jack: 5378:245748944632 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1705 jack: 5378:245748955014 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1706 jack: 5378:245748955024 engine.c:jack_run_one_cycle:2150: release problem lock
1707 jack: 5378:245748955026 engine.c:jack_run_one_cycle:2153: waiting for driver read
1708
1709 jack: 5378:245748955058 engine.c:jack_run_one_cycle:2159: run process
1710
1711 jack: 5378:245748955061 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1712 jack: 5378:245748955064 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1713 jack: 5378:245748955267 engine.c:jack_run_one_cycle:2211: release graph lock
1714 jack: 5378:245748955273 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1715 jack: 5378:245748965679 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1716 jack: 5378:245748965688 engine.c:jack_run_one_cycle:2150: release problem lock
1717 jack: 5378:245748965690 engine.c:jack_run_one_cycle:2153: waiting for driver read
1718
1719 jack: 5378:245748965719 engine.c:jack_run_one_cycle:2159: run process
1720
1721 jack: 5378:245748965726 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1722 jack: 5378:245748965729 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1723 jack: 5378:245748965929 engine.c:jack_run_one_cycle:2211: release graph lock
1724 jack: 5378:245748965934 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1725 jack: 5378:245748976349 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1726 jack: 5378:245748976360 engine.c:jack_run_one_cycle:2150: release problem lock
1727 jack: 5378:245748976363 engine.c:jack_run_one_cycle:2153: waiting for driver read
1728
1729 jack: 5378:245748976397 engine.c:jack_run_one_cycle:2159: run process
1730
1731 jack: 5378:245748976400 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1732 jack: 5378:245748976403 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1733 jack: 5378:245748976618 engine.c:jack_run_one_cycle:2211: release graph lock
1734 jack: 5378:245748976622 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1735 jack: 5378:245748987017 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1736 jack: 5378:245748987026 engine.c:jack_run_one_cycle:2150: release problem lock
1737 jack: 5378:245748987029 engine.c:jack_run_one_cycle:2153: waiting for driver read
1738
1739 jack: 5378:245748987059 engine.c:jack_run_one_cycle:2159: run process
1740
1741 jack: 5378:245748987062 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1742 jack: 5378:245748987064 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1743 jack: 5378:245748987260 engine.c:jack_run_one_cycle:2211: release graph lock
1744 jack: 5378:245748987263 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1745 jack: 5378:245748997684 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1746 jack: 5378:245748997691 engine.c:jack_run_one_cycle:2150: release problem lock
1747 jack: 5378:245748997694 engine.c:jack_run_one_cycle:2153: waiting for driver read
1748
1749 jack: 5378:245748997723 engine.c:jack_run_one_cycle:2159: run process
1750
1751 jack: 5378:245748997725 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1752 jack: 5378:245748997728 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1753 jack: 5378:245748997921 engine.c:jack_run_one_cycle:2211: release graph lock
1754 jack: 5378:245748997924 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1755 jack: 5378:245749008357 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1756 jack: 5378:245749008369 engine.c:jack_run_one_cycle:2150: release problem lock
1757 jack: 5378:245749008371 engine.c:jack_run_one_cycle:2153: waiting for driver read
1758
1759 jack: 5378:245749008408 engine.c:jack_run_one_cycle:2159: run process
1760
1761 jack: 5378:245749008411 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1762 jack: 5378:245749008413 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1763 jack: 5378:245749008642 engine.c:jack_run_one_cycle:2211: release graph lock
1764 jack: 5378:245749008647 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1765 jack: 5378:245749019022 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1766 jack: 5378:245749019031 engine.c:jack_run_one_cycle:2150: release problem lock
1767 jack: 5378:245749019033 engine.c:jack_run_one_cycle:2153: waiting for driver read
1768
1769 jack: 5378:245749019063 engine.c:jack_run_one_cycle:2159: run process
1770
1771 jack: 5378:245749019070 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1772 jack: 5378:245749019073 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1773 jack: 5378:245749019276 engine.c:jack_run_one_cycle:2211: release graph lock
1774 jack: 5378:245749019283 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1775 jack: 5378:245749029690 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1776 jack: 5378:245749029700 engine.c:jack_run_one_cycle:2150: release problem lock
1777 jack: 5378:245749029703 engine.c:jack_run_one_cycle:2153: waiting for driver read
1778
1779 jack: 5378:245749029733 engine.c:jack_run_one_cycle:2159: run process
1780
1781 jack: 5378:245749029735 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1782 jack: 5378:245749029738 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1783 jack: 5378:245749029933 engine.c:jack_run_one_cycle:2211: release graph lock
1784 jack: 5378:245749029936 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1785 jack: 5378:245749040359 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1786 jack: 5378:245749040371 engine.c:jack_run_one_cycle:2150: release problem lock
1787 jack: 5378:245749040373 engine.c:jack_run_one_cycle:2153: waiting for driver read
1788
1789 jack: 5378:245749040406 engine.c:jack_run_one_cycle:2159: run process
1790
1791 jack: 5378:245749040414 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1792 jack: 5378:245749040417 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1793 jack: 5378:245749040633 engine.c:jack_run_one_cycle:2211: release graph lock
1794 jack: 5378:245749040640 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1795 jack: 5378:245749051025 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1796 jack: 5378:245749051034 engine.c:jack_run_one_cycle:2150: release problem lock
1797 jack: 5378:245749051036 engine.c:jack_run_one_cycle:2153: waiting for driver read
1798
1799 jack: 5378:245749051067 engine.c:jack_run_one_cycle:2159: run process
1800
1801 jack: 5378:245749051070 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1802 jack: 5378:245749051072 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1803 jack: 5378:245749051267 engine.c:jack_run_one_cycle:2211: release graph lock
1804 jack: 5378:245749051270 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1805 jack: 5378:245749061700 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1806 jack: 5378:245749061710 engine.c:jack_run_one_cycle:2150: release problem lock
1807 jack: 5378:245749061712 engine.c:jack_run_one_cycle:2153: waiting for driver read
1808
1809 jack: 5378:245749061744 engine.c:jack_run_one_cycle:2159: run process
1810
1811 jack: 5378:245749061747 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1812 jack: 5378:245749061749 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1813 jack: 5378:245749061946 engine.c:jack_run_one_cycle:2211: release graph lock
1814 jack: 5378:245749061949 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1815 jack: 5378:245749072364 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1816 jack: 5378:245749072376 engine.c:jack_run_one_cycle:2150: release problem lock
1817 jack: 5378:245749072378 engine.c:jack_run_one_cycle:2153: waiting for driver read
1818
1819 jack: 5378:245749072411 engine.c:jack_run_one_cycle:2159: run process
1820
1821 jack: 5378:245749072419 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1822 jack: 5378:245749072421 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1823 jack: 5378:245749072638 engine.c:jack_run_one_cycle:2211: release graph lock
1824 jack: 5378:245749072645 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1825 jack: 5378:245749083031 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1826 jack: 5378:245749083039 engine.c:jack_run_one_cycle:2150: release problem lock
1827 jack: 5378:245749083041 engine.c:jack_run_one_cycle:2153: waiting for driver read
1828
1829 jack: 5378:245749083070 engine.c:jack_run_one_cycle:2159: run process
1830
1831 jack: 5378:245749083073 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1832 jack: 5378:245749083075 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1833 jack: 5378:245749083271 engine.c:jack_run_one_cycle:2211: release graph lock
1834 jack: 5378:245749083274 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1835 245749093746: Warning (MotuReceiveStreamProcessor.cpp)[ 471] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel pan index 20 exceeded maximum 20
1836 jack: 5378:245749093706 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1837 jack: 5378:245749093718 engine.c:jack_run_one_cycle:2150: release problem lock
1838 jack: 5378:245749093720 engine.c:jack_run_one_cycle:2153: waiting for driver read
1839
1840 jack: 5378:245749093768 engine.c:jack_run_one_cycle:2159: run process
1841
1842 jack: 5378:245749093771 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1843 jack: 5378:245749093773 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1844 jack: 5378:245749093998 engine.c:jack_run_one_cycle:2211: release graph lock
1845 jack: 5378:245749094003 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1846 jack: 5378:245749104369 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1847 jack: 5378:245749104380 engine.c:jack_run_one_cycle:2150: release problem lock
1848 jack: 5378:245749104382 engine.c:jack_run_one_cycle:2153: waiting for driver read
1849
1850 jack: 5378:245749104413 engine.c:jack_run_one_cycle:2159: run process
1851
1852 jack: 5378:245749104422 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1853 jack: 5378:245749104636 engine.c:jack_run_one_cycle:2211: release graph lock
1854 jack: 5378:245749104643 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1855 jack: 5378:245749115043 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1856 jack: 5378:245749115056 engine.c:jack_run_one_cycle:2150: release problem lock
1857 jack: 5378:245749115058 engine.c:jack_run_one_cycle:2153: waiting for driver read
1858
1859 jack: 5378:245749115096 engine.c:jack_run_one_cycle:2159: run process
1860
1861 jack: 5378:245749115100 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1862 jack: 5378:245749115102 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1863 jack: 5378:245749115310 engine.c:jack_run_one_cycle:2211: release graph lock
1864 jack: 5378:245749115317 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1865 jack: 5378:245749125711 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1866 jack: 5378:245749125725 engine.c:jack_run_one_cycle:2150: release problem lock
1867 jack: 5378:245749125727 engine.c:jack_run_one_cycle:2153: waiting for driver read
1868
1869 jack: 5378:245749125763 engine.c:jack_run_one_cycle:2159: run process
1870
1871 jack: 5378:245749125766 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1872 jack: 5378:245749125769 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1873 jack: 5378:245749125968 engine.c:jack_run_one_cycle:2211: release graph lock
1874 jack: 5378:245749125970 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1875 jack: 5378:245749136383 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1876 jack: 5378:245749136393 engine.c:jack_run_one_cycle:2150: release problem lock
1877 jack: 5378:245749136396 engine.c:jack_run_one_cycle:2153: waiting for driver read
1878
1879 jack: 5378:245749136427 engine.c:jack_run_one_cycle:2159: run process
1880
1881 jack: 5378:245749136429 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1882 jack: 5378:245749136432 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1883 jack: 5378:245749136656 engine.c:jack_run_one_cycle:2211: release graph lock
1884 jack: 5378:245749136661 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1885 jack: 5378:245749147043 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1886 jack: 5378:245749147053 engine.c:jack_run_one_cycle:2150: release problem lock
1887 jack: 5378:245749147055 engine.c:jack_run_one_cycle:2153: waiting for driver read
1888
1889 jack: 5378:245749147086 engine.c:jack_run_one_cycle:2159: run process
1890
1891 jack: 5378:245749147093 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1892 jack: 5378:245749147096 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1893 jack: 5378:245749147301 engine.c:jack_run_one_cycle:2211: release graph lock
1894 jack: 5378:245749147307 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1895 jack: 5378:245749157713 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1896 jack: 5378:245749157726 engine.c:jack_run_one_cycle:2150: release problem lock
1897 jack: 5378:245749157728 engine.c:jack_run_one_cycle:2153: waiting for driver read
1898
1899 jack: 5378:245749157763 engine.c:jack_run_one_cycle:2159: run process
1900
1901 jack: 5378:245749157766 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1902 jack: 5378:245749157769 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1903 jack: 5378:245749157967 engine.c:jack_run_one_cycle:2211: release graph lock
1904 jack: 5378:245749157970 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1905 jack: 5378:245749168378 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1906 jack: 5378:245749168387 engine.c:jack_run_one_cycle:2150: release problem lock
1907 jack: 5378:245749168389 engine.c:jack_run_one_cycle:2153: waiting for driver read
1908
1909 jack: 5378:245749168420 engine.c:jack_run_one_cycle:2159: run process
1910
1911 jack: 5378:245749168428 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1912 jack: 5378:245749168431 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1913 jack: 5378:245749168645 engine.c:jack_run_one_cycle:2211: release graph lock
1914 jack: 5378:245749168653 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1915 jack: 5378:245749179052 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1916 jack: 5378:245749179063 engine.c:jack_run_one_cycle:2150: release problem lock
1917 jack: 5378:245749179065 engine.c:jack_run_one_cycle:2153: waiting for driver read
1918
1919 jack: 5378:245749179097 engine.c:jack_run_one_cycle:2159: run process
1920
1921 jack: 5378:245749179100 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1922 jack: 5378:245749179102 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1923 jack: 5378:245749179306 engine.c:jack_run_one_cycle:2211: release graph lock
1924 jack: 5378:245749179310 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1925 jack: 5378:245749189721 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
1926 jack: 5378:245749189733 engine.c:jack_run_one_cycle:2150: release problem lock
1927 jack: 5378:245749189735 engine.c:jack_run_one_cycle:2153: waiting for driver read
1928
1929 245749189757: Warning (MotuReceiveStreamProcessor.cpp)[ 471] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel pan index 20 exceeded maximum 20
1930 jack: 5378:245749189784 engine.c:jack_run_one_cycle:2159: run process
1931
1932 jack: 5378:245749189787 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
1933 jack: 5378:245749189790 engine.c:jack_process_internal:580: invoking an internal client's callbacks
1934 jack: 5378:245749190009 engine.c:jack_run_one_cycle:2211: release graph lock
1935 jack: 5378:245749190016 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
1936
1937 ffado_streaming_wait
1938 ============================================
1939 Xruns: 0
1940 ============================================
1941 ----------------------------------------------------
1942 Dumping StreamProcessorManager information...
1943 Period count:    137
1944 Data type: float
1945  Receive processors...
1946  StreamProcessor 0x12e00e0, Receive:
1947   Port, Channel    : 0, 0
1948   Packets, Dropped, Skipped : 14708, 0, 0
1949   Now                   : 00241791410 (009s 6708c 0434t)
1950   Xrun?                 : False
1951   State                 : ePS_Running
1952   Buffer                : 0x12dff10
1953   Framerate             : Nominal: 96000, Sync: 95998.249086, Buffer 95998.249086
1954   TimestampedBuffer (0x12dff10): 0432 frames, 0432 events
1955    Timestamps           : head:  241685301.300, Tail:  241795895.315, Next tail:  241799991.390
1956     Head - Tail         :    -110594.016 (-432.001915 frames)
1957    DLL Rate             : 4096.056494 (256.003531)
1958    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
1959  Transmit processors...
1960  StreamProcessor 0x12e0a20, Transmit:
1961   Port, Channel    : 0, 1
1962   Packets, Dropped, Skipped : 14816, 0, 0
1963   Now                   : 00241792565 (009s 6708c 1589t)
1964   Xrun?                 : False
1965   State                 : ePS_Running
1966   Buffer                : 0x12e1b20
1967   Framerate             : Nominal: 96000, Sync: 95998.249086, Buffer 95998.489404
1968   TimestampedBuffer (0x12e1b20): 2464 frames, 2464 events
1969    Timestamps           : head:  242131692.016, Tail:  242762485.954, Next tail:  243024634.068
1970     Head - Tail         :    -630793.938 (-2464.007423 frames)
1971    DLL Rate             : 262147.340142 (256.003262)
1972    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
1973 ----------------------------------------------------
1974 Port Information
1975  Playback
1976     0 (0x12dd0d0): [0x12e0a20] [off] [  Audio] dev0_pbk_Phones-L
1977     1 (0x12e1cc0): [0x12e0a20] [off] [  Audio] dev0_pbk_Phones-R
1978     2 (0x12e1d50): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog1
1979     3 (0x12e1de0): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog2
1980     4 (0x12e1e70): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog3
1981     5 (0x12e1fa0): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog4
1982     6 (0x12e2030): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog5
1983     7 (0x12e20c0): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog6
1984     8 (0x12e2150): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog7
1985     9 (0x12e1f10): [0x12e0a20] [off] [  Audio] dev0_pbk_Analog8
1986    10 (0x12e1ec0): [0x12e0a20] [off] [  Audio] dev0_pbk_MainOut-L
1987    11 (0x12e22f0): [0x12e0a20] [off] [  Audio] dev0_pbk_MainOut-R
1988    12 (0x12e2380): [0x12e0a20] [off] [  Audio] dev0_pbk_AES/EBU1
1989    13 (0x12e2410): [0x12e0a20] [off] [  Audio] dev0_pbk_AES/EBU2
1990    14 (0x12e2460): [0x12e0a20] [off] [   MIDI] dev0_pbk_MIDI0
1991  Capture
1992     0 (0x12c65e0): [0x12e00e0] [off] [  Audio]  dev0_cap_Mix-L
1993     1 (0x12daaa0): [0x12e00e0] [off] [  Audio]  dev0_cap_Mix-R
1994     2 (0x12daaf0): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog1
1995     3 (0x12dab60): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog2
1996     4 (0x12c6890): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog3
1997     5 (0x12e05a0): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog4
1998     6 (0x12e0630): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog5
1999     7 (0x12e06c0): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog6
2000     8 (0x12e0750): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog7
2001     9 (0x12e0510): [0x12e00e0] [off] [  Audio]  dev0_cap_Analog8
2002    10 (0x12e04c0): [0x12e00e0] [off] [  Audio]  dev0_cap_unknown-1
2003    11 (0x12e0860): [0x12e00e0] [off] [  Audio]  dev0_cap_unknown-2
2004    12 (0x12e08f0): [0x12e00e0] [off] [  Audio]  dev0_cap_AES/EBU1
2005    13 (0x12e0980): [0x12e00e0] [off] [  Audio]  dev0_cap_AES/EBU2
2006    14 (0x12e09d0): [0x12e00e0] [off] [   MIDI]  dev0_cap_MIDI0
2007 ----------------------------------------------------
2008
2009 jack: 5378:245749200381 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2010 jack: 5378:245749200389 engine.c:jack_run_one_cycle:2150: release problem lock
2011 jack: 5378:245749200392 engine.c:jack_run_one_cycle:2153: waiting for driver read
2012
2013 jack: 5378:245749200421 engine.c:jack_run_one_cycle:2159: run process
2014
2015 jack: 5378:245749200424 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2016 jack: 5378:245749200426 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2017 jack: 5378:245749200652 engine.c:jack_run_one_cycle:2211: release graph lock
2018 jack: 5378:245749200657 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2019 jack: 5378:245749211055 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2020 jack: 5378:245749211066 engine.c:jack_run_one_cycle:2150: release problem lock
2021 jack: 5378:245749211069 engine.c:jack_run_one_cycle:2153: waiting for driver read
2022
2023 jack: 5378:245749211106 engine.c:jack_run_one_cycle:2159: run process
2024
2025 jack: 5378:245749211108 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2026 jack: 5378:245749211111 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2027 jack: 5378:245749211315 engine.c:jack_run_one_cycle:2211: release graph lock
2028 jack: 5378:245749211318 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2029 jack: 5378:245749221723 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2030 jack: 5378:245749221735 engine.c:jack_run_one_cycle:2150: release problem lock
2031 jack: 5378:245749221738 engine.c:jack_run_one_cycle:2153: waiting for driver read
2032
2033 jack: 5378:245749221773 engine.c:jack_run_one_cycle:2159: run process
2034
2035 jack: 5378:245749221781 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2036 jack: 5378:245749221783 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2037 jack: 5378:245749221984 engine.c:jack_run_one_cycle:2211: release graph lock
2038 jack: 5378:245749222007 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2039 jack: 5378:245749232386 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2040 jack: 5378:245749232395 engine.c:jack_run_one_cycle:2150: release problem lock
2041 jack: 5378:245749232397 engine.c:jack_run_one_cycle:2153: waiting for driver read
2042
2043 jack: 5378:245749232426 engine.c:jack_run_one_cycle:2159: run process
2044
2045 jack: 5378:245749232429 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2046 jack: 5378:245749232431 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2047 jack: 5378:245749232656 engine.c:jack_run_one_cycle:2211: release graph lock
2048 jack: 5378:245749232660 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2049 jack: 5378:245749243061 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2050 jack: 5378:245749243074 engine.c:jack_run_one_cycle:2150: release problem lock
2051 jack: 5378:245749243077 engine.c:jack_run_one_cycle:2153: waiting for driver read
2052
2053 jack: 5378:245749243112 engine.c:jack_run_one_cycle:2159: run process
2054
2055 jack: 5378:245749243115 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2056 jack: 5378:245749243117 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2057 jack: 5378:245749243320 engine.c:jack_run_one_cycle:2211: release graph lock
2058 jack: 5378:245749243324 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2059 jack: 5378:245749253725 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2060 jack: 5378:245749253734 engine.c:jack_run_one_cycle:2150: release problem lock
2061 jack: 5378:245749253737 engine.c:jack_run_one_cycle:2153: waiting for driver read
2062
2063 jack: 5378:245749253768 engine.c:jack_run_one_cycle:2159: run process
2064
2065 jack: 5378:245749253776 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2066 jack: 5378:245749253779 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2067 jack: 5378:245749253977 engine.c:jack_run_one_cycle:2211: release graph lock
2068 jack: 5378:245749253983 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2069 jack: 5378:245749264393 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2070 jack: 5378:245749264401 engine.c:jack_run_one_cycle:2150: release problem lock
2071 jack: 5378:245749264403 engine.c:jack_run_one_cycle:2153: waiting for driver read
2072
2073 jack: 5378:245749264433 engine.c:jack_run_one_cycle:2159: run process
2074
2075 jack: 5378:245749264440 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2076 jack: 5378:245749264442 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2077 jack: 5378:245749264657 engine.c:jack_run_one_cycle:2211: release graph lock
2078 jack: 5378:245749264665 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2079 jack: 5378:245749275065 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2080 jack: 5378:245749275076 engine.c:jack_run_one_cycle:2150: release problem lock
2081 jack: 5378:245749275078 engine.c:jack_run_one_cycle:2153: waiting for driver read
2082
2083 jack: 5378:245749275113 engine.c:jack_run_one_cycle:2159: run process
2084
2085 jack: 5378:245749275115 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2086 jack: 5378:245749275118 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2087 jack: 5378:245749275318 engine.c:jack_run_one_cycle:2211: release graph lock
2088 jack: 5378:245749275321 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2089 jack: 5378:245749285729 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2090 jack: 5378:245749285737 engine.c:jack_run_one_cycle:2150: release problem lock
2091 jack: 5378:245749285739 engine.c:jack_run_one_cycle:2153: waiting for driver read
2092
2093 jack: 5378:245749285770 engine.c:jack_run_one_cycle:2159: run process
2094
2095 jack: 5378:245749285773 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2096 jack: 5378:245749285775 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2097 jack: 5378:245749285970 engine.c:jack_run_one_cycle:2211: release graph lock
2098 jack: 5378:245749285973 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2099 jack: 5378:245749296399 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2100 jack: 5378:245749296407 engine.c:jack_run_one_cycle:2150: release problem lock
2101 jack: 5378:245749296409 engine.c:jack_run_one_cycle:2153: waiting for driver read
2102
2103 jack: 5378:245749296439 engine.c:jack_run_one_cycle:2159: run process
2104
2105 jack: 5378:245749296441 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2106 jack: 5378:245749296444 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2107 jack: 5378:245749296667 engine.c:jack_run_one_cycle:2211: release graph lock
2108 jack: 5378:245749296671 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2109 jack: 5378:245749307068 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2110 jack: 5378:245749307080 engine.c:jack_run_one_cycle:2150: release problem lock
2111 jack: 5378:245749307117 engine.c:jack_run_one_cycle:2159: run process
2112
2113 jack: 5378:245749307123 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2114 jack: 5378:245749307132 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2115 jack: 5378:245749307331 engine.c:jack_run_one_cycle:2211: release graph lock
2116 jack: 5378:245749307337 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2117 jack: 5378:245749317733 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2118 jack: 5378:245749317742 engine.c:jack_run_one_cycle:2150: release problem lock
2119 jack: 5378:245749317744 engine.c:jack_run_one_cycle:2153: waiting for driver read
2120
2121 jack: 5378:245749317777 engine.c:jack_run_one_cycle:2159: run process
2122
2123 jack: 5378:245749317784 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2124 jack: 5378:245749317787 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2125 jack: 5378:245749317983 engine.c:jack_run_one_cycle:2211: release graph lock
2126 jack: 5378:245749317989 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2127 jack: 5378:245749328403 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2128 jack: 5378:245749328413 engine.c:jack_run_one_cycle:2150: release problem lock
2129 jack: 5378:245749328415 engine.c:jack_run_one_cycle:2153: waiting for driver read
2130
2131 jack: 5378:245749328446 engine.c:jack_run_one_cycle:2159: run process
2132
2133 jack: 5378:245749328448 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2134 jack: 5378:245749328451 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2135 jack: 5378:245749328661 engine.c:jack_run_one_cycle:2211: release graph lock
2136 jack: 5378:245749328665 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2137 245749339109: Warning (MotuReceiveStreamProcessor.cpp)[ 481] decodeMotuCtrlEvents: MOTU cuemix value parser error: channel control index 20 exceeded maximum 20
2138 jack: 5378:245749339073 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2139 jack: 5378:245749339085 engine.c:jack_run_one_cycle:2150: release problem lock
2140 jack: 5378:245749339087 engine.c:jack_run_one_cycle:2153: waiting for driver read
2141
2142 jack: 5378:245749339133 engine.c:jack_run_one_cycle:2159: run process
2143
2144 jack: 5378:245749339136 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2145 jack: 5378:245749339139 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2146 jack: 5378:245749339336 engine.c:jack_run_one_cycle:2211: release graph lock
2147 jack: 5378:245749339338 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2148 jack: 5378:245749349738 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2149 jack: 5378:245749349746 engine.c:jack_run_one_cycle:2150: release problem lock
2150 jack: 5378:245749349749 engine.c:jack_run_one_cycle:2153: waiting for driver read
2151
2152 jack: 5378:245749349777 engine.c:jack_run_one_cycle:2159: run process
2153
2154 jack: 5378:245749349780 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2155 jack: 5378:245749349782 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2156 jack: 5378:245749349976 engine.c:jack_run_one_cycle:2211: release graph lock
2157 jack: 5378:245749349979 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2158 jack: 5378:245749360413 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2159 jack: 5378:245749360425 engine.c:jack_run_one_cycle:2150: release problem lock
2160 jack: 5378:245749360427 engine.c:jack_run_one_cycle:2153: waiting for driver read
2161
2162 jack: 5378:245749360460 engine.c:jack_run_one_cycle:2159: run process
2163
2164 jack: 5378:245749360463 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2165 jack: 5378:245749360465 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2166 jack: 5378:245749360691 engine.c:jack_run_one_cycle:2211: release graph lock
2167 jack: 5378:245749360696 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2168 jack: 5378:245749371078 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2169 jack: 5378:245749371088 engine.c:jack_run_one_cycle:2150: release problem lock
2170 jack: 5378:245749371091 engine.c:jack_run_one_cycle:2153: waiting for driver read
2171
2172 jack: 5378:245749371123 engine.c:jack_run_one_cycle:2159: run process
2173
2174 jack: 5378:245749371136 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2175 jack: 5378:245749371139 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2176 jack: 5378:245749371337 engine.c:jack_run_one_cycle:2211: release graph lock
2177 jack: 5378:245749371343 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2178 jack: 5378:245749381746 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2179 jack: 5378:245749381757 engine.c:jack_run_one_cycle:2150: release problem lock
2180 jack: 5378:245749381759 engine.c:jack_run_one_cycle:2153: waiting for driver read
2181
2182 jack: 5378:245749381793 engine.c:jack_run_one_cycle:2159: run process
2183
2184 jack: 5378:245749381801 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2185 jack: 5378:245749381803 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2186 jack: 5378:245749382001 engine.c:jack_run_one_cycle:2211: release graph lock
2187 jack: 5378:245749382007 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2188 jack: 5378:245749392417 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2189 jack: 5378:245749392431 engine.c:jack_run_one_cycle:2150: release problem lock
2190 jack: 5378:245749392433 engine.c:jack_run_one_cycle:2153: waiting for driver read
2191
2192 jack: 5378:245749392469 engine.c:jack_run_one_cycle:2159: run process
2193
2194 jack: 5378:245749392471 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2195 jack: 5378:245749392474 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2196 jack: 5378:245749392692 engine.c:jack_run_one_cycle:2211: release graph lock
2197 jack: 5378:245749392696 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2198 jack: 5378:245749403081 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2199 jack: 5378:245749403091 engine.c:jack_run_one_cycle:2150: release problem lock
2200 jack: 5378:245749403093 engine.c:jack_run_one_cycle:2153: waiting for driver read
2201
2202 jack: 5378:245749403124 engine.c:jack_run_one_cycle:2159: run process
2203
2204 jack: 5378:245749403127 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2205 jack: 5378:245749403129 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2206 jack: 5378:245749403325 engine.c:jack_run_one_cycle:2211: release graph lock
2207 jack: 5378:245749403327 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2208 ^Cjack main caught signal 2
2209 245749414215: Debug (ffado.cpp)[ 218] ffado_streaming_stop: ------------- Stop -------------
2210 jack: 5378:245749413749 engine.c:jack_run_one_cycle:2128: trying to acquire read lock
2211 245749414301: Debug (StreamProcessorManager.cpp)[ 940] stop: Stopping...
2212 245749414319: Debug (StreamProcessorManager.cpp)[ 942] stop:  scheduling stop for all SP's...
2213 245749414355: Debug (StreamProcessor.cpp)[1251] scheduleStopRunning: for Receive SP (0x12e00e0)
2214 jack: 5378:245749413759 engine.c:jack_run_one_cycle:2150: release problem lock
2215 jack: 5378:245749413761 engine.c:jack_run_one_cycle:2153: waiting for driver read
2216
2217 jack: 5378:245749413794 engine.c:jack_run_one_cycle:2159: run process
2218
2219 jack: 5378:245749413797 engine.c:jack_engine_process:843: considering client firewire_pcm for processing
2220 jack: 5378:245749413799 engine.c:jack_process_internal:580: invoking an internal client's callbacks
2221 jack: 5378:245749413995 engine.c:jack_run_one_cycle:2211: release graph lock
2222 jack: 5378:245749413998 engine.c:jack_run_one_cycle:2212: cycle finished, status = 0
2223 245749414468: Debug (StreamProcessor.cpp)[1258] scheduleStopRunning:   Now                   : 00247044572 (010s 0418c 0476t)
2224 245749414479: Debug (StreamProcessor.cpp)[1263] scheduleStopRunning:   Stop at              : 00253185033 (010s 2417c 0009t)
2225 245749414486: Debug (StreamProcessor.cpp)[1251] scheduleStopRunning: for Transmit SP (0x12e0a20)
2226 245749414490: Debug (StreamProcessor.cpp)[1258] scheduleStopRunning:   Now                   : 00247045112 (010s 0418c 1016t)
2227 245749414493: Debug (StreamProcessor.cpp)[1263] scheduleStopRunning:   Stop at              : 00253189014 (010s 2418c 0918t)
2228 245749415052: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_Running to ePS_WaitingForStreamDisable
2229 245749415062: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Running => ePS_WaitingForStreamDisable
2230 245749415067: Debug (StreamProcessor.cpp)[1617] doWaitForStreamDisable: Enter from state: ePS_Running
2231 245749415070: Debug (StreamProcessor.cpp)[1629] doWaitForStreamDisable: State switch complete, dumping SP info...
2232  StreamProcessor 0x12e00e0, Receive:
2233   Port, Channel    : 0, 0
2234   Packets, Dropped, Skipped : 16418, 0, 0
2235   Now                   : 00247059635 (010s 0423c 0179t)
2236   Xrun?                 : False
2237   State                 : ePS_WaitingForStreamDisable
2238   Buffer                : 0x12dff10
2239   Framerate             : Nominal: 96000, Sync: 95998.203311, Buffer 95998.203311
2240   TimestampedBuffer (0x12dff10): 0448 frames, 0448 events
2241    Timestamps           : head:  246928277.919, Tail:  247042968.068, Next tail:  247047064.144
2242     Head - Tail         :    -114690.148 (-448.002025 frames)
2243    DLL Rate             : 4096.058218 (256.003639)
2244    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2245 245749418059: Debug (StreamProcessor.cpp)[ 667] getPacket: Should update state from ePS_Running to ePS_WaitingForStreamDisable
2246 245749418071: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_Running => ePS_WaitingForStreamDisable
2247 245749418075: Debug (StreamProcessor.cpp)[1617] doWaitForStreamDisable: Enter from state: ePS_Running
2248 245749418079: Debug (StreamProcessor.cpp)[1629] doWaitForStreamDisable: State switch complete, dumping SP info...
2249  StreamProcessor 0x12e0a20, Transmit:
2250   Port, Channel    : 0, 1
2251   Packets, Dropped, Skipped : 16545, 0, 0
2252   Now                   : 00247133598 (010s 0447c 0414t)
2253   Xrun?                 : False
2254   State                 : ePS_WaitingForStreamDisable
2255   Buffer                : 0x12e1b20
2256   Framerate             : Nominal: 96000, Sync: 95998.203311, Buffer 95998.420741
2257   TimestampedBuffer (0x12e1b20): 2208 frames, 2208 events
2258    Timestamps           : head:  247440192.833, Tail:  248005450.145, Next tail:  248267598.460
2259     Head - Tail         :    -565257.312 (-2208.007571 frames)
2260    DLL Rate             : 262147.420001 (256.003340)
2261    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2262 245749650116: Debug (StreamProcessor.cpp)[ 595] getPacket: Should update state to DryRunning
2263 245749650134: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamDisable => ePS_DryRunning
2264 245749650139: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStreamDisable
2265 245749650143: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2266 245749650148: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
2267  StreamProcessor 0x12e0a20, Transmit:
2268   Port, Channel    : 0, 1
2269   Packets, Dropped, Skipped : 18419, 0, 0
2270   Now                   : 00252836732 (010s 2303c 1916t)
2271   Xrun?                 : False
2272   State                 : ePS_DryRunning
2273   Buffer                : 0x12e1b20
2274   Framerate             : Nominal: 96000, Sync: 95998.203311, Buffer 96000.000000
2275   TimestampedBuffer (0x12e1b20): 0000 frames, 0000 events
2276    Timestamps           : head:  248005450.145, Tail:  248005450.145, Next tail:  248267598.460
2277     Head - Tail         :          0.000 (0.000000 frames)
2278    DLL Rate             : 262144.000000 (256.000000)
2279    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2280 245749664676: Debug (StreamProcessor.cpp)[ 321] putPacket: Should update state to DryRunning
2281 245749664692: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_WaitingForStreamDisable => ePS_DryRunning
2282 245749664696: Debug (StreamProcessor.cpp)[1465] doDryRunning: Enter from state: ePS_WaitingForStreamDisable
2283 245749664700: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2284 245749664704: Debug (StreamProcessor.cpp)[1496] doDryRunning: State switch complete, dumping SP info...
2285  StreamProcessor 0x12e00e0, Receive:
2286   Port, Channel    : 0, 0
2287   Packets, Dropped, Skipped : 18418, 0, 0
2288   Now                   : 00253193798 (010s 2419c 2630t)
2289   Xrun?                 : False
2290   State                 : ePS_DryRunning
2291   Buffer                : 0x12dff10
2292   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
2293   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
2294    Timestamps           : head:  247047064.144, Tail:  247047064.144, Next tail:  247051160.221
2295     Head - Tail         :          0.000 (0.000000 frames)
2296    DLL Rate             : 4096.000000 (256.000000)
2297    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2298 245749664756: Warning (StreamProcessor.cpp)[ 389] putPacket: Instantanous samplerate more than 1% off nominal. [Nom fs: 96000.000000, Instantanous fs:    63.956155, diff: 95936.043845 (    0.999334)]
2299 245749664766: Debug (StreamProcessor.cpp)[ 401] putPacket: cy 2418 rather large TSP difference TS=00247047188 => TS=00253195400 (6148212, nom 4096)
2300 245749664773: Warning (TimestampedBuffer.cpp)[1053] incrementFrameCounter: (0x12dff10) difference rather large (+): diff=   6144239.779, max=      1536.000,  253195400.000,  247051160.221
2301 245749664785: Warning (TimestampedBuffer.cpp)[ 249] calculateRate: (0x12dff10) rate ( 312.87112) more that 10% off nominal (rate= 256.00000, diff=      5005.938, update_period=16)
2302 245749664989: Debug (StreamProcessor.cpp)[1226] scheduleStopDryRunning: for Receive SP (0x12e00e0)
2303 245749664997: Debug (StreamProcessor.cpp)[1233] scheduleStopDryRunning:   Now                   : 00253200727 (010s 2422c 0343t)
2304 245749665017: Debug (StreamProcessor.cpp)[1238] scheduleStopDryRunning:   Stop at              : 00259344481 (010s 4422c 0097t)
2305 245749665024: Debug (StreamProcessor.cpp)[1226] scheduleStopDryRunning: for Transmit SP (0x12e0a20)
2306 245749665027: Debug (StreamProcessor.cpp)[1233] scheduleStopDryRunning:   Now                   : 00253201464 (010s 2422c 1080t)
2307 245749665095: Debug (StreamProcessor.cpp)[1238] scheduleStopDryRunning:   Stop at              : 00259345391 (010s 4422c 1007t)
2308 245749665547: Debug (StreamProcessor.cpp)[ 477] putPacket: Should update state from ePS_DryRunning to ePS_Stopped
2309 245749665555: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_Stopped
2310 245749665560: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_DryRunning
2311 245749665564: Debug (IsoHandlerManager.cpp)[1059] stopHandlerForStream:  stopping handler 0x12dfda0 for stream 0x12e00e0
2312 245749665568: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
2313 245749665572: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
2314 245749665575: Debug (IsoHandlerManager.cpp)[1071] stopHandlerForStream:  requested disable for handler 0x12dfda0
2315 245749665578: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2316 245749665581: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
2317 245749665587: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
2318  StreamProcessor 0x12e00e0, Receive:
2319   Port, Channel    : 0, 0
2320   Packets, Dropped, Skipped : 18422, 0, 0
2321   Now                   : 00253215422 (010s 2426c 2750t)
2322   Xrun?                 : False
2323   State                 : ePS_Stopped
2324   Buffer                : 0x12dff10
2325   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
2326   TimestampedBuffer (0x12dff10): 0000 frames, 0000 events
2327    Timestamps           : head:  253199496.000, Tail:  253199496.000, Next tail:  253203592.000
2328     Head - Tail         :          0.000 (0.000000 frames)
2329    DLL Rate             : 4096.000000 (256.000000)
2330    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2331 245749665634: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6b30) updating shadow vars...
2332 245749665644: Debug (IsoHandlerManager.cpp)[1859] updateState: (0x12dfda0) handler needs state update from 1 => 0
2333 245749665648: Debug (IsoHandlerManager.cpp)[1864] updateState: handler has to be disabled
2334 245749665651: Debug (IsoHandlerManager.cpp)[1782] disable: (0x12dfda0, Receive) enter...
2335 245749665654: Debug (IsoHandlerManager.cpp)[1793] disable: (0x12dfda0, Receive) wake up handle...
2336 245749665660: Debug (IsoHandlerManager.cpp)[1805] disable: (0x12dfda0, Receive) stop...
2337 245749665734: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6b30) Receive handler 0x12dfda0 skipped (disabled)
2338 245749665744: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6b30) updated shadow vars...
2339 245749666043: Debug (StreamProcessor.cpp)[ 783] getPacket: Should update state from ePS_DryRunning to ePS_Stopped
2340 245749666052: Debug (StreamProcessor.cpp)[1650] updateState: Do state transition: ePS_DryRunning => ePS_Stopped
2341 245749666056: Debug (StreamProcessor.cpp)[1357] doStop: Enter from state: ePS_DryRunning
2342 245749666060: Debug (IsoHandlerManager.cpp)[1059] stopHandlerForStream:  stopping handler 0x12e0040 for stream 0x12e0a20
2343 245749666063: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
2344 245749666066: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
2345 245749666069: Debug (IsoHandlerManager.cpp)[1071] stopHandlerForStream:  requested disable for handler 0x12e0040
2346 245749666072: Debug (TimestampedBuffer.cpp)[ 343] clearBuffer: Clearing buffer
2347 245749666075: Debug (PortManager.cpp)[ 219] preparePorts: preparing ports
2348 245749666080: Debug (StreamProcessor.cpp)[1410] doStop: State switch complete, dumping SP info...
2349  StreamProcessor 0x12e0a20, Transmit:
2350   Port, Channel    : 0, 1
2351   Packets, Dropped, Skipped : 18529, 0, 0
2352   Now                   : 00253227536 (010s 2430c 2576t)
2353   Xrun?                 : False
2354   State                 : ePS_Stopped
2355   Buffer                : 0x12e1b20
2356   Framerate             : Nominal: 96000, Sync: 96000.000000, Buffer 96000.000000
2357   TimestampedBuffer (0x12e1b20): 0000 frames, 0000 events
2358    Timestamps           : head:  248005450.145, Tail:  248005450.145, Next tail:  248267598.460
2359     Head - Tail         :          0.000 (0.000000 frames)
2360    DLL Rate             : 262144.000000 (256.000000)
2361    DLL Bandwidth        : 4.069011e-09 1/ticks (0.100000 Hz)
2362 245749666199: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6910) updating shadow vars...
2363 245749666207: Debug (IsoHandlerManager.cpp)[1859] updateState: (0x12e0040) handler needs state update from 1 => 0
2364 245749666211: Debug (IsoHandlerManager.cpp)[1864] updateState: handler has to be disabled
2365 245749666214: Debug (IsoHandlerManager.cpp)[1782] disable: (0x12e0040, Transmit) enter...
2366 245749666217: Debug (IsoHandlerManager.cpp)[1793] disable: (0x12e0040, Transmit) wake up handle...
2367 245749666222: Debug (IsoHandlerManager.cpp)[1805] disable: (0x12e0040, Transmit) stop...
2368 245749666308: Debug (IsoHandlerManager.cpp)[ 174] updateShadowMapHelper: (0x12c6910) Transmit handler 0x12e0040 skipped (disabled)
2369 245749666317: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6910) updated shadow vars...
2370 245749666512: Debug (StreamProcessorManager.cpp)[1050] stop:  Stopped...
2371 245749666522: Debug (devicemanager.cpp)[ 968] stopStreamingOnDevice: Stopping stream 0 of device 0x12dbce0
2372 245749671551: Debug (devicemanager.cpp)[ 968] stopStreamingOnDevice: Stopping stream 1 of device 0x12dbce0
2373 245749679601: Debug (devicemanager.cpp)[ 854] finishStreaming: Unlocking device (0x12dbce0)
2374 245749679615: Debug (Configuration.cpp)[ 138] save: Not saving temporary config file: temporary
2375 245749679620: Debug (Configuration.cpp)[ 135] save: Not saving readonly config file: /usr/share/libffado/configuration
2376 245749679634: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element 0001f20000015a02 from DeviceManager
2377 245749679646: Debug (StreamProcessorManager.cpp)[ 253] unregisterProcessor: Unregistering processor (0x12e00e0)
2378 245749679651: Debug (StreamProcessorManager.cpp)[ 264] unregisterProcessor: unregistering sync source
2379 245749679656: Debug (PortManager.cpp)[ 268] getUpdateHandlerForPtr:  found
2380 245749679659: Debug (PortManager.cpp)[ 244] remPortManagerUpdateHandler: Removing PortManagerUpdate handler (0x12dfd30)
2381 245749679662: Debug (PortManager.cpp)[ 251] remPortManagerUpdateHandler:  found
2382 245749679667: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
2383 245749679678: Debug (IsoHandlerManager.cpp)[ 897] unregisterStream: Unregistering Receive stream 0x12e00e0
2384 245749679682: Debug (IsoHandlerManager.cpp)[1439] unregisterStream: unregistering stream (0x12e00e0)
2385 245749679685: Debug (IsoHandlerManager.cpp)[ 910] unregisterStream:  unregistered stream (0x12e00e0) from handler (0x12dfda0)...
2386 245749679689: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2387 245749679693: Debug (IsoHandlerManager.cpp)[ 945] pruneHandlers:  handler (0x12dfda0) not in use
2388 245749679701: Debug (IsoHandlerManager.cpp)[ 678] unregisterHandler: enter...
2389 245749679706: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
2390 245749679709: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
2391 245749679712: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
2392 245749679715: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
2393 245749679718: Debug (IsoHandlerManager.cpp)[ 956] pruneHandlers:  deleting handler (0x12dfda0)
2394 245749679724: Debug (IsoHandlerManager.cpp)[ 924] unregisterStream:  deleted stream (0x12e0a20) from list...
2395 jack: 5364:245749679318 engine.c:do_request:1218: got a request of type 2
2396 jack: 5364:245749679336 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2397 jack: 5364:245749679349 engine.c:jack_port_do_unregister:3903: release graph lock
2398 jack: 5364:245749679351 engine.c:do_request:1350: status of request: 0
2399 jack: 5364:245749679354 engine.c:do_request:1218: got a request of type 2
2400 jack: 5364:245749679356 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2401 jack: 5364:245749679359 engine.c:jack_port_do_unregister:3903: release graph lock
2402 jack: 5364:245749679361 engine.c:do_request:1350: status of request: 0
2403 jack: 5364:245749679363 engine.c:do_request:1218: got a request of type 2
2404 jack: 5364:245749679365 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2405 jack: 5364:245749679368 engine.c:jack_port_do_unregister:3903: release graph lock
2406 jack: 5364:245749679370 engine.c:do_request:1350: status of request: 0
2407 jack: 5364:245749679371 engine.c:do_request:1218: got a request of type 2
2408 jack: 5364:245749679374 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2409 jack: 5364:245749679376 engine.c:jack_port_do_unregister:3903: release graph lock
2410 jack: 5364:245749679378 engine.c:do_request:1350: status of request: 0
2411 jack: 5364:245749679381 engine.c:do_request:1218: got a request of type 2
2412 jack: 5364:245749679382 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2413 jack: 5364:245749679385 engine.c:jack_port_do_unregister:3903: release graph lock
2414 jack: 5364:245749679387 engine.c:do_request:1350: status of request: 0
2415 jack: 5364:245749679389 engine.c:do_request:1218: got a request of type 2
2416 jack: 5364:245749679391 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2417 jack: 5364:245749679394 engine.c:jack_port_do_unregister:3903: release graph lock
2418 jack: 5364:245749679396 engine.c:do_request:1350: status of request: 0
2419 jack: 5364:245749679398 engine.c:do_request:1218: got a request of type 2
2420 jack: 5364:245749679400 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2421 jack: 5364:245749679402 engine.c:jack_port_do_unregister:3903: release graph lock
2422 jack: 5364:245749679404 engine.c:do_request:1350: status of request: 0
2423 jack: 5364:245749679406 engine.c:do_request:1218: got a request of type 2
2424 jack: 5364:245749679408 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2425 jack: 5364:245749679411 engine.c:jack_port_do_unregister:3903: release graph lock
2426 jack: 5364:245749679413 engine.c:do_request:1350: status of request: 0
2427 jack: 5364:245749679415 engine.c:do_request:1218: got a request of type 2
2428 jack: 5364:245749679417 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2429 jack: 5364:245749679419 engine.c:jack_port_do_unregister:3903: release graph lock
2430 jack: 5364:245749679421 engine.c:do_request:1350: status of request: 0
2431 jack: 5364:245749679423 engine.c:do_request:1218: got a request of type 2
2432 jack: 5364:245749679425 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2433 jack: 5364:245749679428 engine.c:jack_port_do_unregister:3903: release graph lock
2434 jack: 5364:245749679430 engine.c:do_request:1350: status of request: 0
2435 jack: 5364:245749679432 engine.c:do_request:1218: got a request of type 2
2436 jack: 5364:245749679434 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2437 jack: 5364:245749679436 engine.c:jack_port_do_unregister:3903: release graph lock
2438 jack: 5364:245749679438 engine.c:do_request:1350: status of request: 0
2439 jack: 5364:245749679440 engine.c:do_request:1218: got a request of type 2
2440 245749680024: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Mix-L
2441 245749680033: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Mix-L
2442 245749680041: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Mix-R
2443 245749680065: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Mix-R
2444 245749680071: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog1
2445 245749680074: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog1
2446 245749680078: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog2
2447 245749680080: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog2
2448 245749680084: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog3
2449 245749680087: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog3
2450 245749680091: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog4
2451 245749680094: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog4
2452 245749680097: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog5
2453 245749680100: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog5
2454 245749680104: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog6
2455 245749680107: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog6
2456 245749680111: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog7
2457 245749680114: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog7
2458 245749680117: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_Analog8
2459 245749680120: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_Analog8
2460 245749680124: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_unknown-1
2461 245749680127: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_unknown-1
2462 245749680130: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_unknown-2
2463 jack: 5364:245749679442 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2464 jack: 5364:245749679445 engine.c:jack_port_do_unregister:3903: release graph lock
2465 jack: 5364:245749679447 engine.c:do_request:1350: status of request: 0
2466 jack: 5364:245749679449 engine.c:do_request:1218: got a request of type 2
2467 jack: 5364:245749679451 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2468 jack: 5364:245749679453 engine.c:jack_port_do_unregister:3903: release graph lock
2469 jack: 5364:245749679455 engine.c:do_request:1350: status of request: 0
2470 jack: 5364:245749679457 engine.c:do_request:1218: got a request of type 2
2471 jack: 5364:245749679459 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2472 jack: 5364:245749679461 engine.c:jack_port_do_unregister:3903: release graph lock
2473 jack: 5364:245749679463 engine.c:do_request:1350: status of request: 0
2474 jack: 5364:245749679465 engine.c:do_request:1218: got a request of type 2
2475 jack: 5364:245749679467 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2476 jack: 5364:245749679470 engine.c:jack_port_do_unregister:3903: release graph lock
2477 jack: 5364:245749679472 engine.c:do_request:1350: status of request: 0
2478 jack: 5364:245749679475 engine.c:do_request:1218: got a request of type 2
2479 jack: 5364:245749679478 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2480 jack: 5364:245749679480 engine.c:jack_port_do_unregister:3903: release graph lock
2481 jack: 5364:245749679482 engine.c:do_request:1350: status of request: 0
2482 jack: 5364:245749679484 engine.c:do_request:1218: got a request of type 2
2483 jack: 5364:245749679486 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2484 jack: 5364:245749679488 engine.c:jack_port_do_unregister:3903: release graph lock
2485 jack: 5364:245749679490 engine.c:do_request:1350: status of request: 0
2486 jack: 5364:245749679492 engine.c:do_request:1218: got a request of type 2
2487 jack: 5364:245749679494 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2488 jack: 5364:245749679496 engine.c:jack_port_do_unregister:3903: release graph lock
2489 jack: 5364:245749679498 engine.c:do_request:1350: status of request: 0
2490 jack: 5364:245749679500 engine.c:do_request:1218: got a request of type 2
2491 jack: 5364:245749679502 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2492 jack: 5364:245749679504 engine.c:jack_port_do_unregister:3903: release graph lock
2493 jack: 5364:245749679506 engine.c:do_request:1350: status of request: 0
2494 jack: 5364:245749679508 engine.c:do_request:1218: got a request of type 2
2495 jack: 5364:245749679510 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2496 jack: 5364:245749679512 engine.c:jack_port_do_unregister:3903: release graph lock
2497 jack: 5364:245749679514 engine.c:do_request:1350: status of request: 0
2498 jack: 5364:245749679516 engine.c:do_request:1218: got a request of type 2
2499 jack: 5364:245749679518 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2500 jack: 5364:245749679521 engine.c:jack_port_do_unregister:3903: release graph lock
2501 jack: 5364:245749679523 engine.c:do_request:1350: status of request: 0
2502 jack: 5364:245749679525 engine.c:do_request:1218: got a request of type 2
2503 jack: 5364:245749679527 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2504 jack: 5364:245749679529 engine.c:jack_port_do_unregister:3903: release graph lock
2505 jack: 5364:245749679531 engine.c:do_request:1350: status of request: 0
2506 jack: 5364:245749679533 engine.c:do_request:1218: got a request of type 2
2507 jack: 5364:245749679535 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2508 jack: 5364:245749679537 engine.c:jack_port_do_unregister:3903: release graph lock
2509 jack: 5364:245749679539 engine.c:do_request:1350: status of request: 0
2510 jack: 5364:245749679541 engine.c:do_request:1218: got a request of type 2
2511 jack: 5364:245749679543 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2512 jack: 5364:245749679545 engine.c:jack_port_do_unregister:3903: release graph lock
2513 jack: 5364:245749679547 engine.c:do_request:1350: status of request: 0
2514 jack: 5364:245749679549 engine.c:do_request:1218: got a request of type 2
2515 jack: 5364:245749679551 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2516 jack: 5364:245749679553 engine.c:jack_port_do_unregister:3903: release graph lock
2517 jack: 5364:245749679555 engine.c:do_request:1350: status of request: 0
2518 jack: 5364:245749679557 engine.c:do_request:1218: got a request of type 2
2519 jack: 5364:245749679559 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2520 jack: 5364:245749679561 engine.c:jack_port_do_unregister:3903: release graph lock
2521 jack: 5364:245749679563 engine.c:do_request:1350: status of request: 0
2522 jack: 5364:245749679565 engine.c:do_request:1218: got a request of type 2
2523 jack: 5364:245749679567 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2524 jack: 5364:245749679570 engine.c:jack_port_do_unregister:3903: release graph lock
2525 jack: 5364:245749679572 engine.c:do_request:1350: status of request: 0
2526 jack: 5364:245749679574 engine.c:do_request:1218: got a request of type 2
2527 jack: 5364:245749679576 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2528 jack: 5364:245749679578 engine.c:jack_port_do_unregister:3903: release graph lock
2529 jack: 5364:245749679580 engine.c:do_request:1350: status of request: 0
2530 jack: 5364:245749679582 engine.c:do_request:1218: got a request of type 2
2531 jack: 5364:245749679584 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2532 jack: 5364:245749679586 engine.c:jack_port_do_unregister:3903: release graph lock
2533 jack: 5364:245749679588 engine.c:do_request:1350: status of request: 0
2534 jack: 5364:245749679590 engine.c:do_request:1218: got a request of type 2
2535 jack: 5364:245749679592 engine.c:jack_port_do_unregister:3886: acquiring graph write lock
2536 jack: 5364:245749679594 engine.c:jack_port_do_unregister:3903: release graph lock
2537 jack: 5364:245749679596 engine.c:do_request:1350: status of request: 0
2538 245749680133: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_unknown-2
2539 245749680645: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_AES/EBU1
2540 245749680655: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_AES/EBU1
2541 245749680660: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_AES/EBU2
2542 245749680663: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_AES/EBU2
2543 245749680667: Debug (Port.cpp)[  49] ~Port: deleting port dev0_cap_MIDI0
2544 245749680670: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_cap_MIDI0
2545 245749680679: Debug (StreamProcessorManager.cpp)[ 253] unregisterProcessor: Unregistering processor (0x12e0a20)
2546 245749680683: Debug (PortManager.cpp)[ 268] getUpdateHandlerForPtr:  found
2547 245749680686: Debug (PortManager.cpp)[ 244] remPortManagerUpdateHandler: Removing PortManagerUpdate handler (0x12dd120)
2548 245749680690: Debug (PortManager.cpp)[ 251] remPortManagerUpdateHandler:  found
2549 245749680693: Debug (StreamProcessorManager.cpp)[1613] updateShadowLists: Updating port shadow lists...
2550 245749680696: Debug (IsoHandlerManager.cpp)[ 897] unregisterStream: Unregistering Transmit stream 0x12e0a20
2551 245749680700: Debug (IsoHandlerManager.cpp)[1439] unregisterStream: unregistering stream (0x12e0a20)
2552 245749680703: Debug (IsoHandlerManager.cpp)[ 910] unregisterStream:  unregistered stream (0x12e0a20) from handler (0x12e0040)...
2553 245749680706: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2554 245749680709: Debug (IsoHandlerManager.cpp)[ 945] pruneHandlers:  handler (0x12e0040) not in use
2555 245749680715: Debug (IsoHandlerManager.cpp)[ 678] unregisterHandler: enter...
2556 245749680718: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6910) enter
2557 245749680721: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6910) exit
2558 245749680724: Debug (IsoHandlerManager.cpp)[  88] requestShadowMapUpdate: (0x12c6b30) enter
2559 245749680726: Debug (IsoHandlerManager.cpp)[  93] requestShadowMapUpdate: (0x12c6b30) exit
2560 245749680729: Debug (IsoHandlerManager.cpp)[ 956] pruneHandlers:  deleting handler (0x12e0040)
2561 245749680733: Debug (IsoHandlerManager.cpp)[ 924] unregisterStream:  deleted stream (0x12e0a20) from list...
2562 245749681144: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Phones-L
2563 245749681151: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Phones-L
2564 245749681157: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Phones-R
2565 245749681160: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Phones-R
2566 245749681164: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog1
2567 245749681166: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog1
2568 245749681170: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog2
2569 245749681173: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog2
2570 245749681177: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog3
2571 245749681180: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog3
2572 245749681184: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog4
2573 245749681186: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog4
2574 245749681190: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog5
2575 245749681193: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog5
2576 245749681197: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog6
2577 245749681200: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog6
2578 245749681203: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog7
2579 245749681206: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog7
2580 245749681210: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_Analog8
2581 245749681212: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_Analog8
2582 245749681216: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_MainOut-L
2583 245749681219: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_MainOut-L
2584 245749681223: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_MainOut-R
2585 245749681226: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_MainOut-R
2586 245749681229: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_AES/EBU1
2587 245749681232: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_AES/EBU1
2588 245749681236: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_AES/EBU2
2589 245749681239: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_AES/EBU2
2590 245749681242: Debug (Port.cpp)[  49] ~Port: deleting port dev0_pbk_MIDI0
2591 245749681245: Debug (PortManager.cpp)[ 117] unregisterPort: unregistering port dev0_pbk_MIDI0
2592 245749681251: Debug (ieee1394service.cpp)[1445] freeIsoChannel: Freeing ISO channel 0...
2593 245749681255: Debug (ieee1394service.cpp)[1463] freeIsoChannel:  allocated using generic routine...
2594 245749681258: Debug (ieee1394service.cpp)[1464] freeIsoChannel:  freeing 857 bandwidth units...
2595 245749681301: Debug (ieee1394service.cpp)[1468] freeIsoChannel:  freeing channel 0...
2596 245749681333: Debug (ieee1394service.cpp)[1445] freeIsoChannel: Freeing ISO channel 1...
2597 245749681336: Debug (ieee1394service.cpp)[1463] freeIsoChannel:  allocated using generic routine...
2598 245749681339: Debug (ieee1394service.cpp)[1464] freeIsoChannel:  freeing 857 bandwidth units...
2599 245749681368: Debug (ieee1394service.cpp)[1468] freeIsoChannel:  freeing channel 1...
2600 245749681398: Debug (motu_mixer.cpp)[ 357] destroyMixer: destroy mixer...
2601 245749681406: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mixer from 0001f20000015a02
2602 245749681414: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element fader from Mixer
2603 245749681429: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element pan from Mixer
2604 245749681438: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element solo from Mixer
2605 245749681446: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element mute from Mixer
2606 245749681454: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_fader from Mixer
2607 245749681459: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_mute from Mixer
2608 245749681463: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix1/Mix_dest from Mixer
2609 245749681467: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_fader from Mixer
2610 245749681471: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_mute from Mixer
2611 245749681474: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix2/Mix_dest from Mixer
2612 245749681478: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_fader from Mixer
2613 245749681482: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_mute from Mixer
2614 245749681486: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix3/Mix_dest from Mixer
2615 245749681490: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_fader from Mixer
2616 245749681494: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_mute from Mixer
2617 245749681497: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mix4/Mix_dest from Mixer
2618 245749681501: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Mainout_fader from Mixer
2619 245749681505: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Phones_fader from Mixer
2620 245749681509: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Phones_src from Mixer
2621 245749681513: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/OpticalIn_mode from Mixer
2622 245749681517: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/OpticalOut_mode from Mixer
2623 245749681521: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_peakhold_time from Mixer
2624 245749681525: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_cliphold_time from Mixer
2625 245749681528: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_aesebu_src from Mixer
2626 245749681532: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control/Meter_src from Mixer
2627 245749681536: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/Model from Mixer
2628 245749681540: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/IsStreaming from Mixer
2629 245749681544: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Info/SampleRate from Mixer
2630 245749681552: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Control from 0001f20000015a02
2631 245749681560: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element ConfigRom from 0001f20000015a02
2632 245749681565: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Generic from 0001f20000015a02
2633 245749681569: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element ClockSelect from Generic
2634 245749681574: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element SamplerateSelect from Generic
2635 245749681578: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element Nickname from Generic
2636 245749681583: Debug (Element.cpp)[ 279] deleteElementNoLock: Deleting Element StreamingStatus from Generic
2637 245749681599: Debug (IsoHandlerManager.cpp)[1080] stopHandlers: enter...
2638 245749681602: Debug (IsoHandlerManager.cpp)[ 936] pruneHandlers: enter...
2639 245749681606: Debug (PosixThread.cpp)[ 190] Stop: (ISOXMT) Stop 0x12c6ab0 (thread: 0x7f9448d5f910)
2640 245749685763: Debug (IsoHandlerManager.cpp)[ 134] updateShadowMapHelper: (0x12c6b30) updating shadow vars...
2641 245749685770: Debug (IsoHandlerManager.cpp)[ 190] updateShadowMapHelper: (0x12c6b30) updated shadow vars...
2642 245749686333: Debug (PosixThread.cpp)[  90] ThreadHandler: (ISOXMT) ThreadHandler: exit 0x12c6ab0
2643 245749686356: Debug (PosixThread.cpp)[ 197] Stop: (ISOXMT) Stopped 0x12c6ab0 (thread: (nil))
2644 245749686365: Debug (PosixThread.cpp)[ 190] Stop: (ISORCV) Stop 0x12c6cd0 (thread: 0x7f944855e910)
2645 245749695779: Debug (PosixThread.cpp)[  90] ThreadHandler: (ISORCV) ThreadHandler: exit 0x12c6cd0
2646 245749695794: Debug (PosixThread.cpp)[ 197] Stop: (ISORCV) Stopped 0x12c6cd0 (thread: (nil))
2647 245749695805: Debug (PosixThread.cpp)[ 190] Stop: (CTRHLP) Stop 0x12c66a0 (thread: 0x7f9449560910)
2648 245749788316: Debug (PosixThread.cpp)[  90] ThreadHandler: (CTRHLP) ThreadHandler: exit 0x12c66a0
2649 245749788363: Debug (PosixThread.cpp)[ 197] Stop: (CTRHLP) Stopped 0x12c66a0 (thread: (nil))
2650 245749788378: Debug (ieee1394service.cpp)[1233] remBusResetHandler: Removing busreset handler (0x12c6850)
2651 245749788382: Debug (ieee1394service.cpp)[1240] remBusResetHandler:  found
2652 245749788399: Debug (PosixThread.cpp)[ 190] Stop: (BUSRST) Stop 0x12c39c0 (thread: 0x7f944ad63910)
2653 245749788416: Debug (PosixThread.cpp)[  90] ThreadHandler: (BUSRST) ThreadHandler: exit 0x12c39c0
2654 245749788438: Debug (PosixThread.cpp)[ 197] Stop: (BUSRST) Stopped 0x12c39c0 (thread: (nil))
2655 245749788450: Debug (PosixThread.cpp)[ 190] Stop: (ARMSTD) Stop 0x12c5fe0 (thread: 0x7f944a562910)
2656 245749788461: Debug (PosixThread.cpp)[  90] ThreadHandler: (ARMSTD) ThreadHandler: exit 0x12c5fe0
2657 245749792743: Debug (PosixThread.cpp)[ 197] Stop: (ARMSTD) Stopped 0x12c5fe0 (thread: (nil))
2658 245749792788: Debug (PosixThread.cpp)[ 190] Stop: (ARMRT) Stop 0x12c60f0 (thread: 0x7f9449d61910)
2659 245749793798: Debug (PosixThread.cpp)[  90] ThreadHandler: (ARMRT) ThreadHandler: exit 0x12c60f0
2660 245749797664: Debug (PosixThread.cpp)[ 197] Stop: (ARMRT) Stopped 0x12c60f0 (thread: (nil))
2661 245749797676: Debug (PosixThread.cpp)[ 174] Kill: (WDGCHK) Kill 0x12c3bb0 (thread: 0x7f944b564910)
2662 245749804388: Debug (PosixThread.cpp)[ 180] Kill: (WDGCHK) Killed 0x12c3bb0 (thread: 0x7f944b564910)
2663 245749804407: Debug (PosixThread.cpp)[ 174] Kill: (WDGHBT) Kill 0x12c3ac0 (thread: 0x7f944bd65910)
2664 245749808330: Debug (PosixThread.cpp)[ 180] Kill: (WDGHBT) Killed 0x12c3ac0 (thread: 0x7f944bd65910)
2665 no message buffer overruns
2666 ERROR: messagebuffer not initialized: jack: 5364:245749830681 ../libjack/client.c:default_jack_error_callback:210: WARNING: 2 message buffer overruns!root@ubuntu-studio:/home/sczjd#
2667
2668