On Fri, Mar 06, 2015 at 09:07:40PM +0100, Alexandre Ratchov wrote: > Yes we have modifications. Back around 2008, audio used to be very > unsable on MP systems and sndiod used to run with lower priority. > So using large buffers (around 500ms) was the only way to get > stable audio. > > Nowadays, this is not necessary, but buffer sizes are still big > because nobody tryed to reduce them. Maybe it's time now. Properly > written software could probably work with 50ms buffers. > > Still I'm talking about 500ms. Not the 1-2s you mentioned, which I > need to understand. > > Could you do the following: in one window, kill sndiod and start a > new one as follows: > > sudo pkill sndiod > SNDIO_DEBUG=4 sndiod -ddd 2>/tmp/log > > in another window: > > mplayer /foo/bar.mp3 > > after few seconds, push the right arrow key to skip forward, wait > few seconds, press q, kill sndiod and send me the /tmp/log file. > > When you hit the right arrow key, mplayer is supposed to take 500ms > to react, but on your setup it takes 1-2s, right? > > The file is huge, so please send it off-list.
I attached the file -- Regards Henrique Lengler snd0 pst=cfg.default: rec=0:1 play=0:1 vol=23170 dup listen(/tmp/aucat-1000/aucat0|ini): created sock(sock|ini): created sock,rmsg,widl: AUTH message sio_sun_setpar: 0: trying pars = 48000/16/6 sio_sun_setpar: bpf = (4, 4) sio_sun_setpar: 0: trying round = 600 -> (576, 576) sio_sun_setpar: blocksize ok sio_sun_setpar: 0: trying pars = 48000/16/6 sio_sun_setpar: bpf = (4, 4) sio_sun_setpar: 0: trying round = 960 -> (960, 960) sio_sun_setpar: blocksize ok sock,rmsg,widl: HELLO message sock,rmsg,widl: hello from <mplayer>, mode = 1, ver 7 sock,rmsg,widl: using snd0 pst=cfg.default, mode = 1 mplayer0: overwritten slot 0 snd0 pst=cfg: device requested sio(rsnd/0|ini): created snd0 pst=ini: 48000Hz, s16le, play 0:1, rec 0:1, 9 blocks of 960 frames mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: SETPAR message mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: playback channels 0:1 -> 0:1 mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: 44100Hz sample rate, 882 frame blocks mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: 11466 frame buffer mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: GETPAR message mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: GETPAR message mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: START message mplayer0 vol=127,pst=ini,mmc=off: playing s32le -> s16le mplayer0 vol=127,pst=ini,mmc=off: allocated 11466/19404 fr buffers mplayer0 vol=127,pst=sta,mmc=off: 44100Hz, s32le, play 0:1, 13 blocks of 882 frames mplayer0 vol=127,pst=sta,mmc=off,rmsg,widl: building SETVOL message, vol = 127 snd0 pst=ini: device started snd0 pst=run: started mplayer0 vol=127,pst=run,mmc=off: attached at -7938, delta = 0 cmap: nch = 2, ostart = 0, onext = 0, istart = 0, inext = 0 dec: s32le, 2 channels resamp: 882/960 mplayer0 vol=127,pst=run,mmc=off: set weight: 23170/23170 000012083: sio_revents: revents = 0x4, took 1537ns 000035969: sio_revents: revents = 0x4, took 1257ns 000047213: sio_revents: revents = 0x4, took 1257ns 000057410: sio_revents: revents = 0x4, took 1257ns 000067746: sio_revents: revents = 0x4, took 1258ns 000078223: sio_revents: revents = 0x4, took 1187ns 000095893: sio_revents: revents = 0x4, took 1257ns 000106788: sio_revents: revents = 0x4, took 1187ns 000117194: sio_revents: revents = 0x4, took 1187ns 00000849340: clk +0 +0, wr +8 +0 rd: +0 +0 00020857890: clk +1 +0, wr +9 +0 rd: +0 +0 020856284: sio_revents: revents = 0x1, took 4330ns 020903706: sio_revents: revents = 0x0, took 1327ns 020928570: sio_revents: revents = 0x0, took 1327ns 00040880828: clk +2 +0, wr +10 +0 rd: +1 +0 040879291: sio_revents: revents = 0x1, took 5238ns 040912396: sio_revents: revents = 0x0, took 1257ns 040932370: sio_revents: revents = 0x0, took 1258ns 00060857879: clk +3 +0, wr +11 +0 rd: +2 +0 060856343: sio_revents: revents = 0x1, took 5098ns 060889308: sio_revents: revents = 0x0, took 1257ns 060908304: sio_revents: revents = 0x0, took 1258ns 00080882842: clk +4 +0, wr +12 +0 rd: +3 +0 080881515: sio_revents: revents = 0x1, took 3422ns 080911756: sio_revents: revents = 0x0, took 1257ns 080928937: sio_revents: revents = 0x0, took 1257ns 00100857728: clk +5 +0, wr +13 +0 rd: +4 +0 100856401: sio_revents: revents = 0x1, took 3492ns 100886852: sio_revents: revents = 0x0, took 1327ns 100904312: sio_revents: revents = 0x0, took 1327ns 00120890024: clk +6 +0, wr +14 +0 rd: +5 +0 120888697: sio_revents: revents = 0x1, took 3353ns 120918799: sio_revents: revents = 0x0, took 1187ns 120935770: sio_revents: revents = 0x0, took 1257ns 00140857647: clk +7 +0, wr +15 +0 rd: +6 +0 140856250: sio_revents: revents = 0x1, took 4051ns 140888238: sio_revents: revents = 0x0, took 1187ns 140905838: sio_revents: revents = 0x0, took 1257ns 00160902165: clk +8 +0, wr +16 +0 rd: +7 +0 160900908: sio_revents: revents = 0x1, took 3353ns 160931219: sio_revents: revents = 0x0, took 1258ns 160948610: sio_revents: revents = 0x0, took 1257ns 00180857636: clk +9 +0, wr +17 +0 rd: +8 +0 180856309: sio_revents: revents = 0x1, took 3632ns 180887319: sio_revents: revents = 0x0, took 1187ns 180915674: sio_revents: revents = 0x0, took 1327ns 00200909977: clk +10 +0, wr +18 +0 rd: +9 +0 200908300: sio_revents: revents = 0x1, took 5657ns 200942173: sio_revents: revents = 0x0, took 1188ns 208599222: sio_revents: revents = 0x0, took 1537ns 208614587: sio_revents: revents = 0x0, took 1258ns 208627997: sio_revents: revents = 0x0, took 1257ns 208640708: sio_revents: revents = 0x0, took 1187ns 00220858044: clk +11 +0, wr +19 +0 rd: +10 +0 220856507: sio_revents: revents = 0x1, took 5448ns 220890101: sio_revents: revents = 0x0, took 1257ns 238503165: sio_revents: revents = 0x0, took 1257ns 238516714: sio_revents: revents = 0x0, took 1257ns 238529774: sio_revents: revents = 0x0, took 1258ns 238542276: sio_revents: revents = 0x0, took 1257ns 00240921140: clk +12 +0, wr +20 +0 rd: +11 +0 240919673: sio_revents: revents = 0x1, took 5169ns 240953057: sio_revents: revents = 0x0, took 1327ns 00260858522: clk +13 +0, wr +21 +0 rd: +12 +0 260857195: sio_revents: revents = 0x1, took 3282ns 260887296: sio_revents: revents = 0x0, took 1257ns 268596237: sio_revents: revents = 0x0, took 1397ns 268610974: sio_revents: revents = 0x0, took 1257ns 268624034: sio_revents: revents = 0x0, took 1327ns 268636606: sio_revents: revents = 0x0, took 1257ns 268649317: sio_revents: revents = 0x0, took 1327ns 268661818: sio_revents: revents = 0x0, took 1188ns 268674390: sio_revents: revents = 0x0, took 1257ns 268686891: sio_revents: revents = 0x0, took 1257ns 00280929999: clk +14 +0, wr +22 +0 rd: +13 +0 280928392: sio_revents: revents = 0x1, took 5448ns 280961986: sio_revents: revents = 0x0, took 1257ns 298546345: sio_revents: revents = 0x0, took 1606ns 298561920: sio_revents: revents = 0x0, took 1257ns 298575050: sio_revents: revents = 0x0, took 1257ns 298587551: sio_revents: revents = 0x0, took 1258ns 00300858580: clk +15 +0, wr +23 +0 rd: +14 +0 300856974: sio_revents: revents = 0x1, took 5517ns 300890777: sio_revents: revents = 0x0, took 1257ns 00320937460: clk +16 +0, wr +24 +0 rd: +15 +0 320936134: sio_revents: revents = 0x1, took 3492ns 320966584: sio_revents: revents = 0x0, took 1257ns 328550649: sio_revents: revents = 0x0, took 1397ns 328564827: sio_revents: revents = 0x0, took 1257ns 328577818: sio_revents: revents = 0x0, took 1257ns 328590459: sio_revents: revents = 0x0, took 1257ns 328603240: sio_revents: revents = 0x0, took 1257ns 328615671: sio_revents: revents = 0x0, took 1258ns 328628452: sio_revents: revents = 0x0, took 1188ns 328640884: sio_revents: revents = 0x0, took 1257ns 00340858918: clk +17 +0, wr +25 +0 rd: +16 +0 340857382: sio_revents: revents = 0x1, took 5168ns 340890487: sio_revents: revents = 0x0, took 1187ns 358540776: sio_revents: revents = 0x0, took 1397ns 358555163: sio_revents: revents = 0x0, took 1257ns 358568153: sio_revents: revents = 0x0, took 1258ns 358580795: sio_revents: revents = 0x0, took 1257ns 00360945900: clk +18 +0, wr +26 +0 rd: +17 +0 360944433: sio_revents: revents = 0x1, took 5169ns 360977817: sio_revents: revents = 0x0, took 1188ns 00380857650: clk +19 +0, wr +27 +0 rd: +18 +0 380856393: sio_revents: revents = 0x1, took 3282ns 380886355: sio_revents: revents = 0x0, took 1187ns 388549690: sio_revents: revents = 0x0, took 1327ns 388563937: sio_revents: revents = 0x0, took 1257ns 388576928: sio_revents: revents = 0x0, took 1257ns 388589359: sio_revents: revents = 0x0, took 1258ns 388602001: sio_revents: revents = 0x0, took 1327ns 388614502: sio_revents: revents = 0x0, took 1257ns 388627144: sio_revents: revents = 0x0, took 1257ns 388639645: sio_revents: revents = 0x0, took 1257ns 00400953502: clk +20 +0, wr +28 +0 rd: +19 +0 400952035: sio_revents: revents = 0x1, took 5238ns 400985210: sio_revents: revents = 0x0, took 1257ns 418542051: sio_revents: revents = 0x0, took 1467ns 418556648: sio_revents: revents = 0x0, took 1257ns 418569638: sio_revents: revents = 0x0, took 1258ns 418582210: sio_revents: revents = 0x0, took 1257ns 00420858756: clk +21 +0, wr +29 +0 rd: +20 +0 420857290: sio_revents: revents = 0x1, took 6425ns 420892001: sio_revents: revents = 0x0, took 1257ns 00440959427: clk +22 +0, wr +30 +0 rd: +21 +0 440958100: sio_revents: revents = 0x1, took 3352ns 440988271: sio_revents: revents = 0x0, took 1188ns 448580228: sio_revents: revents = 0x0, took 1397ns 448594686: sio_revents: revents = 0x0, took 1257ns 448607746: sio_revents: revents = 0x0, took 1257ns 448620248: sio_revents: revents = 0x0, took 1257ns 448633029: sio_revents: revents = 0x0, took 1257ns 448645530: sio_revents: revents = 0x0, took 1187ns 448658032: sio_revents: revents = 0x0, took 1257ns 448670603: sio_revents: revents = 0x0, took 1257ns 00460858326: clk +23 +0, wr +31 +0 rd: +22 +0 460856720: sio_revents: revents = 0x1, took 5238ns 460890034: sio_revents: revents = 0x0, took 1257ns 478502888: sio_revents: revents = 0x0, took 1327ns 478516298: sio_revents: revents = 0x0, took 1257ns 478529498: sio_revents: revents = 0x0, took 1257ns 478541930: sio_revents: revents = 0x0, took 1257ns 00480964305: clk +24 +0, wr +32 +0 rd: +23 +0 480962768: sio_revents: revents = 0x1, took 5099ns 480995803: sio_revents: revents = 0x0, took 1187ns 00500857616: clk +25 +0, wr +33 +0 rd: +24 +0 500856359: sio_revents: revents = 0x1, took 3283ns 500886531: sio_revents: revents = 0x0, took 1187ns 508591631: sio_revents: revents = 0x0, took 1397ns 508606577: sio_revents: revents = 0x0, took 1257ns 508619567: sio_revents: revents = 0x0, took 1257ns 508632139: sio_revents: revents = 0x0, took 1257ns 508644850: sio_revents: revents = 0x0, took 1257ns 508657351: sio_revents: revents = 0x0, took 1258ns 508669993: sio_revents: revents = 0x0, took 1257ns 508682424: sio_revents: revents = 0x0, took 1258ns 00520968763: clk +26 +0, wr +34 +0 rd: +25 +0 520967227: sio_revents: revents = 0x1, took 5378ns 521001309: sio_revents: revents = 0x0, took 1188ns 538545649: sio_revents: revents = 0x0, took 1397ns 538560037: sio_revents: revents = 0x0, took 1257ns 538574284: sio_revents: revents = 0x0, took 1257ns 538586786: sio_revents: revents = 0x0, took 1257ns 00540859561: clk +27 +0, wr +35 +0 rd: +26 +0 540858024: sio_revents: revents = 0x1, took 5169ns 540891409: sio_revents: revents = 0x0, took 1257ns 00560982022: clk +28 +0, wr +36 +0 rd: +27 +0 560980485: sio_revents: revents = 0x1, took 4820ns 561012822: sio_revents: revents = 0x0, took 1187ns 568548836: sio_revents: revents = 0x0, took 1397ns 568563433: sio_revents: revents = 0x0, took 1257ns 568576493: sio_revents: revents = 0x0, took 1257ns 568588995: sio_revents: revents = 0x0, took 1257ns 568601706: sio_revents: revents = 0x0, took 1187ns 568614138: sio_revents: revents = 0x0, took 1257ns 568626779: sio_revents: revents = 0x0, took 1257ns 568639211: sio_revents: revents = 0x0, took 1257ns 00580863810: clk +29 +0, wr +37 +0 rd: +28 +0 580862274: sio_revents: revents = 0x1, took 5168ns 580895378: sio_revents: revents = 0x0, took 1188ns 598542106: sio_revents: revents = 0x0, took 1396ns 598556353: sio_revents: revents = 0x0, took 1257ns 598569414: sio_revents: revents = 0x0, took 1257ns 598581915: sio_revents: revents = 0x0, took 1257ns 00600994582: clk +30 +0, wr +38 +0 rd: +29 +0 600992976: sio_revents: revents = 0x1, took 5238ns 601026360: sio_revents: revents = 0x0, took 1187ns 00620875462: clk +31 +0, wr +39 +0 rd: +30 +0 620874135: sio_revents: revents = 0x1, took 3423ns 620904446: sio_revents: revents = 0x0, took 1188ns 628586150: sio_revents: revents = 0x0, took 1396ns 628600467: sio_revents: revents = 0x0, took 1257ns 628613457: sio_revents: revents = 0x0, took 1188ns 628625959: sio_revents: revents = 0x0, took 1257ns 628638600: sio_revents: revents = 0x0, took 1258ns 628651102: sio_revents: revents = 0x0, took 1257ns 628663743: sio_revents: revents = 0x0, took 1257ns 628676175: sio_revents: revents = 0x0, took 1257ns 00640858450: clk +32 +0, wr +40 +0 rd: +31 +0 640856914: sio_revents: revents = 0x1, took 5168ns 640891346: sio_revents: revents = 0x0, took 1187ns 658503292: sio_revents: revents = 0x0, took 1327ns 658516701: sio_revents: revents = 0x0, took 1258ns 658529622: sio_revents: revents = 0x0, took 1257ns 658542194: sio_revents: revents = 0x0, took 1257ns 00660881178: clk +33 +0, wr +41 +0 rd: +32 +0 660879642: sio_revents: revents = 0x1, took 5028ns 660912746: sio_revents: revents = 0x0, took 1258ns 00680858788: clk +34 +0, wr +42 +0 rd: +33 +0 680857462: sio_revents: revents = 0x1, took 3352ns 680887493: sio_revents: revents = 0x0, took 1187ns 688581768: sio_revents: revents = 0x0, took 1397ns 688596295: sio_revents: revents = 0x0, took 1257ns 688609145: sio_revents: revents = 0x0, took 1258ns 688621717: sio_revents: revents = 0x0, took 1257ns 688634358: sio_revents: revents = 0x0, took 1257ns 688646930: sio_revents: revents = 0x0, took 1187ns 688659501: sio_revents: revents = 0x0, took 1327ns 688671933: sio_revents: revents = 0x0, took 1257ns 00700887523: clk +35 +0, wr +43 +0 rd: +34 +0 700885916: sio_revents: revents = 0x1, took 5448ns 700919231: sio_revents: revents = 0x0, took 1187ns 718543678: sio_revents: revents = 0x0, took 1537ns 718558904: sio_revents: revents = 0x0, took 1257ns 718571894: sio_revents: revents = 0x0, took 1258ns 718584396: sio_revents: revents = 0x0, took 1257ns 00720859825: clk +36 +0, wr +44 +0 rd: +35 +0 720858288: sio_revents: revents = 0x1, took 5308ns 720891882: sio_revents: revents = 0x0, took 1257ns 00740891981: clk +37 +0, wr +45 +0 rd: +36 +0 740890654: sio_revents: revents = 0x1, took 3353ns 740920616: sio_revents: revents = 0x0, took 1257ns 748550706: sio_revents: revents = 0x0, took 1328ns 748565024: sio_revents: revents = 0x0, took 1257ns 748578084: sio_revents: revents = 0x0, took 1257ns 748590586: sio_revents: revents = 0x0, took 1327ns 748603507: sio_revents: revents = 0x0, took 1257ns 748615938: sio_revents: revents = 0x0, took 1257ns 748628580: sio_revents: revents = 0x0, took 1257ns 748641081: sio_revents: revents = 0x0, took 1257ns 00760858557: clk +38 +0, wr +46 +0 rd: +37 +0 760857020: sio_revents: revents = 0x1, took 5238ns 760890334: sio_revents: revents = 0x0, took 1188ns 778541811: sio_revents: revents = 0x0, took 1397ns 778556268: sio_revents: revents = 0x0, took 1327ns 778569189: sio_revents: revents = 0x0, took 1257ns 778581690: sio_revents: revents = 0x0, took 1257ns 00780892878: clk +39 +0, wr +47 +0 rd: +38 +0 780891342: sio_revents: revents = 0x1, took 5168ns 780924586: sio_revents: revents = 0x0, took 1327ns 00800857917: clk +40 +0, wr +48 +0 rd: +39 +0 800856660: sio_revents: revents = 0x1, took 3282ns 800886622: sio_revents: revents = 0x0, took 1257ns 808581175: sio_revents: revents = 0x0, took 1397ns 808640820: sio_revents: revents = 0x0, took 1537ns 808656185: sio_revents: revents = 0x0, took 1257ns 808668896: sio_revents: revents = 0x0, took 1257ns 808681607: sio_revents: revents = 0x0, took 1257ns 808694039: sio_revents: revents = 0x0, took 1257ns 808706680: sio_revents: revents = 0x0, took 1257ns 808719252: sio_revents: revents = 0x0, took 1187ns 00820896359: clk +41 +0, wr +49 +0 rd: +40 +0 820894822: sio_revents: revents = 0x1, took 5378ns 820928276: sio_revents: revents = 0x0, took 1257ns 838504254: sio_revents: revents = 0x0, took 1327ns 838517734: sio_revents: revents = 0x0, took 1187ns 838530724: sio_revents: revents = 0x0, took 1257ns 838543505: sio_revents: revents = 0x0, took 1257ns 00840859791: clk +42 +0, wr +50 +0 rd: +41 +0 840858255: sio_revents: revents = 0x1, took 5098ns 840891499: sio_revents: revents = 0x0, took 1257ns 00860899630: clk +43 +0, wr +51 +0 rd: +42 +0 860898164: sio_revents: revents = 0x1, took 4050ns 860929802: sio_revents: revents = 0x0, took 1257ns 868586711: sio_revents: revents = 0x0, took 1467ns 868601238: sio_revents: revents = 0x0, took 1257ns 868614508: sio_revents: revents = 0x0, took 1257ns 868627149: sio_revents: revents = 0x0, took 1257ns 868639860: sio_revents: revents = 0x0, took 1257ns 868668705: sio_revents: revents = 0x0, took 1257ns 868681486: sio_revents: revents = 0x0, took 1257ns 868694057: sio_revents: revents = 0x0, took 1257ns 00880858802: clk +44 +0, wr +52 +0 rd: +43 +0 880857266: sio_revents: revents = 0x1, took 5378ns 880890650: sio_revents: revents = 0x0, took 1187ns 898546457: sio_revents: revents = 0x0, took 1466ns 898561403: sio_revents: revents = 0x0, took 1257ns 898574393: sio_revents: revents = 0x0, took 1257ns 898586965: sio_revents: revents = 0x0, took 1257ns 00900899549: clk +45 +0, wr +53 +0 rd: +44 +0 900898013: sio_revents: revents = 0x1, took 5238ns 900931467: sio_revents: revents = 0x0, took 1327ns 00920858372: clk +46 +0, wr +54 +0 rd: +45 +0 920857045: sio_revents: revents = 0x1, took 3562ns 920887636: sio_revents: revents = 0x0, took 1187ns 928553135: sio_revents: revents = 0x0, took 1328ns 928638272: sio_revents: revents = 0x0, took 1537ns 928662717: sio_revents: revents = 0x0, took 1257ns 928817485: sio_revents: revents = 0x0, took 1606ns 928907790: sio_revents: revents = 0x0, took 1746ns 928999282: sio_revents: revents = 0x0, took 1466ns 929090494: sio_revents: revents = 0x0, took 1537ns 929115428: sio_revents: revents = 0x0, took 1397ns 00940902960: clk +47 +0, wr +55 +0 rd: +46 +0 940901354: sio_revents: revents = 0x1, took 5727ns 940935716: sio_revents: revents = 0x0, took 1257ns 958545008: sio_revents: revents = 0x0, took 1467ns 958559675: sio_revents: revents = 0x0, took 1257ns 958572875: sio_revents: revents = 0x0, took 1257ns 958585446: sio_revents: revents = 0x0, took 1188ns 00960859269: clk +48 +0, wr +56 +0 rd: +47 +0 960857662: sio_revents: revents = 0x1, took 5518ns 960891396: sio_revents: revents = 0x0, took 1257ns 00980906581: clk +49 +0, wr +57 +0 rd: +48 +0 980905254: sio_revents: revents = 0x1, took 3282ns 980935286: sio_revents: revents = 0x0, took 1257ns 988549522: sio_revents: revents = 0x0, took 1397ns 988564119: sio_revents: revents = 0x0, took 1257ns 988580182: sio_revents: revents = 0x0, took 1257ns 988592893: sio_revents: revents = 0x0, took 1258ns 988605674: sio_revents: revents = 0x0, took 1258ns 988618176: sio_revents: revents = 0x0, took 1257ns 988630957: sio_revents: revents = 0x0, took 1257ns 988643319: sio_revents: revents = 0x0, took 1257ns 01000858629: clk +50 +0, wr +58 +0 rd: +49 +0 1000857093: sio_revents: revents = 0x1, took 5377ns 1000890407: sio_revents: revents = 0x0, took 1187ns 1018545585: sio_revents: revents = 0x0, took 1467ns 1018560182: sio_revents: revents = 0x0, took 1257ns 1018573312: sio_revents: revents = 0x0, took 1257ns 1018585814: sio_revents: revents = 0x0, took 1257ns 01020914671: clk +51 +0, wr +59 +0 rd: +50 +0 1020913135: sio_revents: revents = 0x1, took 5308ns 1020946589: sio_revents: revents = 0x0, took 1257ns 01040858409: clk +52 +0, wr +60 +0 rd: +51 +0 1040857081: sio_revents: revents = 0x1, took 3493ns 1040887532: sio_revents: revents = 0x0, took 1257ns 1048592493: sio_revents: revents = 0x0, took 1396ns 1048607020: sio_revents: revents = 0x0, took 1257ns 1048620150: sio_revents: revents = 0x0, took 1257ns 1048632721: sio_revents: revents = 0x0, took 1257ns 1048645432: sio_revents: revents = 0x0, took 1327ns 1048658074: sio_revents: revents = 0x0, took 1187ns 1048670715: sio_revents: revents = 0x0, took 1257ns 1048683216: sio_revents: revents = 0x0, took 1258ns 01060917104: clk +53 +0, wr +61 +0 rd: +52 +0 1060915568: sio_revents: revents = 0x1, took 5308ns 1060948813: sio_revents: revents = 0x0, took 1326ns 1078507330: sio_revents: revents = 0x0, took 1537ns 1078522486: sio_revents: revents = 0x0, took 1257ns 1078535616: sio_revents: revents = 0x0, took 1257ns 1078548117: sio_revents: revents = 0x0, took 1258ns 01080859305: clk +54 +0, wr +62 +0 rd: +53 +0 1080857699: sio_revents: revents = 0x1, took 5378ns 1080891363: sio_revents: revents = 0x0, took 1257ns 01100923309: clk +55 +0, wr +63 +0 rd: +54 +0 1100921982: sio_revents: revents = 0x1, took 3353ns 1100953690: sio_revents: revents = 0x0, took 1188ns 1108582523: sio_revents: revents = 0x0, took 1397ns 1108597260: sio_revents: revents = 0x0, took 1257ns 1108610390: sio_revents: revents = 0x0, took 1327ns 1108623101: sio_revents: revents = 0x0, took 1257ns 1108635812: sio_revents: revents = 0x0, took 1257ns 1108648384: sio_revents: revents = 0x0, took 1257ns 1108661095: sio_revents: revents = 0x0, took 1257ns 1108673527: sio_revents: revents = 0x0, took 1257ns 01120858805: clk +56 +0, wr +64 +0 rd: +55 +0 1120857199: sio_revents: revents = 0x1, took 5447ns 1120890443: sio_revents: revents = 0x0, took 1257ns 1138545202: sio_revents: revents = 0x0, took 1467ns 1138559729: sio_revents: revents = 0x0, took 1188ns 1138572790: sio_revents: revents = 0x0, took 1257ns 1138585291: sio_revents: revents = 0x0, took 1257ns 01140918060: clk +57 +0, wr +65 +0 rd: +56 +0 1140916523: sio_revents: revents = 0x1, took 5239ns 1140949977: sio_revents: revents = 0x0, took 1258ns 01160858654: clk +58 +0, wr +66 +0 rd: +57 +0 1160857327: sio_revents: revents = 0x1, took 3283ns 1160887988: sio_revents: revents = 0x0, took 1257ns 1168551253: sio_revents: revents = 0x0, took 1397ns 1168565640: sio_revents: revents = 0x0, took 1257ns 1168578840: sio_revents: revents = 0x0, took 1257ns 1168591411: sio_revents: revents = 0x0, took 1188ns 1168604123: sio_revents: revents = 0x0, took 1257ns 1168616624: sio_revents: revents = 0x0, took 1257ns 1168629265: sio_revents: revents = 0x0, took 1258ns 1168641697: sio_revents: revents = 0x0, took 1257ns 01180911344: clk +59 +0, wr +67 +0 rd: +58 +0 1180909738: sio_revents: revents = 0x1, took 5447ns 1180943890: sio_revents: revents = 0x0, took 1187ns 1198543824: sio_revents: revents = 0x0, took 1396ns 1198558211: sio_revents: revents = 0x0, took 1257ns 1198571271: sio_revents: revents = 0x0, took 1257ns 1198583773: sio_revents: revents = 0x0, took 1257ns 01200860110: clk +60 +0, wr +68 +0 rd: +59 +0 1200858573: sio_revents: revents = 0x1, took 5169ns 1200893005: sio_revents: revents = 0x0, took 1257ns 01220912311: clk +61 +0, wr +69 +0 rd: +60 +0 1220910774: sio_revents: revents = 0x1, took 4121ns 1220942203: sio_revents: revents = 0x0, took 1257ns 1228585633: sio_revents: revents = 0x0, took 1397ns 1228600299: sio_revents: revents = 0x0, took 1258ns 1228613499: sio_revents: revents = 0x0, took 1257ns 1228626071: sio_revents: revents = 0x0, took 1257ns 1228638782: sio_revents: revents = 0x0, took 1257ns 1228651284: sio_revents: revents = 0x0, took 1257ns 1228663925: sio_revents: revents = 0x0, took 1257ns 1228676426: sio_revents: revents = 0x0, took 1258ns 01240859610: clk +62 +0, wr +70 +0 rd: +61 +0 1240858003: sio_revents: revents = 0x1, took 5378ns 1240891388: sio_revents: revents = 0x0, took 1326ns 1258504591: sio_revents: revents = 0x0, took 1257ns 1258517931: sio_revents: revents = 0x0, took 1257ns 1258530921: sio_revents: revents = 0x0, took 1257ns 1258543423: sio_revents: revents = 0x0, took 1257ns 01260916071: clk +63 +0, wr +71 +0 rd: +62 +0 1260914465: sio_revents: revents = 0x1, took 5238ns 1260947779: sio_revents: revents = 0x0, took 1257ns 01280859319: clk +64 +0, wr +72 +0 rd: +63 +0 1280858062: sio_revents: revents = 0x1, took 3283ns 1280888233: sio_revents: revents = 0x0, took 1118ns 1288584603: sio_revents: revents = 0x0, took 1467ns 1288599340: sio_revents: revents = 0x0, took 1257ns 1288612610: sio_revents: revents = 0x0, took 1257ns 1288625321: sio_revents: revents = 0x0, took 1257ns 1288638032: sio_revents: revents = 0x0, took 1187ns 1288650464: sio_revents: revents = 0x0, took 1257ns 1288663175: sio_revents: revents = 0x0, took 1187ns 1288675606: sio_revents: revents = 0x0, took 1258ns 01300909984: clk +65 +0, wr +73 +0 rd: +64 +0 1300908377: sio_revents: revents = 0x1, took 5448ns 1300941761: sio_revents: revents = 0x0, took 1258ns 1318544698: sio_revents: revents = 0x0, took 1397ns 1318559295: sio_revents: revents = 0x0, took 1257ns 1318572285: sio_revents: revents = 0x0, took 1258ns 1318586324: sio_revents: revents = 0x0, took 1257ns 01320860426: clk +66 +0, wr +74 +0 rd: +65 +0 1320858819: sio_revents: revents = 0x1, took 5378ns 1320892483: sio_revents: revents = 0x0, took 1257ns 01340905223: clk +67 +0, wr +75 +0 rd: +66 +0 1340903687: sio_revents: revents = 0x1, took 4958ns 1340936372: sio_revents: revents = 0x0, took 1257ns 1348555079: sio_revents: revents = 0x0, took 1396ns 1348569675: sio_revents: revents = 0x0, took 1258ns 1348582805: sio_revents: revents = 0x0, took 1258ns 1348595377: sio_revents: revents = 0x0, took 1257ns 1348608088: sio_revents: revents = 0x0, took 1257ns 1348620660: sio_revents: revents = 0x0, took 1257ns 1348633161: sio_revents: revents = 0x0, took 1257ns 1348645593: sio_revents: revents = 0x0, took 1257ns 01360859297: clk +68 +0, wr +76 +0 rd: +67 +0 1360857691: sio_revents: revents = 0x1, took 5307ns 1360891284: sio_revents: revents = 0x0, took 1188ns 1378543669: sio_revents: revents = 0x0, took 1327ns 1378557986: sio_revents: revents = 0x0, took 1327ns 1378571046: sio_revents: revents = 0x0, took 1257ns 1378583478: sio_revents: revents = 0x0, took 1257ns 01380896342: clk +69 +0, wr +77 +0 rd: +68 +0 1380894736: sio_revents: revents = 0x1, took 5238ns 1380928190: sio_revents: revents = 0x0, took 1257ns 01400858797: clk +70 +0, wr +78 +0 rd: +69 +0 1400857470: sio_revents: revents = 0x1, took 3282ns 1400887502: sio_revents: revents = 0x0, took 1187ns 1408555167: sio_revents: revents = 0x0, took 1396ns 1408569624: sio_revents: revents = 0x0, took 1257ns 1408582614: sio_revents: revents = 0x0, took 1257ns 1408595116: sio_revents: revents = 0x0, took 1257ns 1408607687: sio_revents: revents = 0x0, took 1257ns 1408620119: sio_revents: revents = 0x0, took 1257ns 1408632690: sio_revents: revents = 0x0, took 1327ns 1408645192: sio_revents: revents = 0x0, took 1257ns 01420893956: clk +71 +0, wr +79 +0 rd: +70 +0 1420892350: sio_revents: revents = 0x1, took 5308ns 1420925595: sio_revents: revents = 0x0, took 1257ns 1438544735: sio_revents: revents = 0x0, took 1396ns 1438559191: sio_revents: revents = 0x0, took 1258ns 1438572252: sio_revents: revents = 0x0, took 1187ns 1438584753: sio_revents: revents = 0x0, took 1257ns 01440859484: clk +72 +0, wr +80 +0 rd: +71 +0 1440857947: sio_revents: revents = 0x1, took 5239ns 1440891401: sio_revents: revents = 0x0, took 1258ns 01460897717: clk +73 +0, wr +81 +0 rd: +72 +0 1460896460: sio_revents: revents = 0x1, took 3282ns 1460926840: sio_revents: revents = 0x0, took 1188ns 1468585286: sio_revents: revents = 0x0, took 1397ns 1468599674: sio_revents: revents = 0x0, took 1257ns 1468612804: sio_revents: revents = 0x0, took 1257ns 1468625375: sio_revents: revents = 0x0, took 1257ns 1468638156: sio_revents: revents = 0x0, took 1257ns 1468650658: sio_revents: revents = 0x0, took 1257ns 1468663369: sio_revents: revents = 0x0, took 1257ns 1468675940: sio_revents: revents = 0x0, took 1257ns 01480859194: clk +74 +0, wr +82 +0 rd: +73 +0 1480857587: sio_revents: revents = 0x1, took 5308ns 1480890901: sio_revents: revents = 0x0, took 1258ns 1498545591: sio_revents: revents = 0x0, took 1327ns 1498559908: sio_revents: revents = 0x0, took 1257ns 1498572899: sio_revents: revents = 0x0, took 1257ns 1498585330: sio_revents: revents = 0x0, took 1327ns 01500904480: clk +75 +0, wr +83 +0 rd: +74 +0 1500902874: sio_revents: revents = 0x1, took 5238ns 1500936328: sio_revents: revents = 0x0, took 1187ns 01520859043: clk +76 +0, wr +84 +0 rd: +75 +0 1520857716: sio_revents: revents = 0x1, took 3631ns 1520888655: sio_revents: revents = 0x0, took 1188ns 1528552409: sio_revents: revents = 0x0, took 1397ns 1528566727: sio_revents: revents = 0x0, took 1257ns 1528579787: sio_revents: revents = 0x0, took 1257ns 1528592289: sio_revents: revents = 0x0, took 1257ns 1528605139: sio_revents: revents = 0x0, took 1258ns 1528617571: sio_revents: revents = 0x0, took 1257ns 1528630213: sio_revents: revents = 0x0, took 1257ns 1528644111: sio_revents: revents = 0x0, took 1257ns 01540910335: clk +77 +0, wr +85 +0 rd: +76 +0 1540908729: sio_revents: revents = 0x1, took 5448ns 1540942113: sio_revents: revents = 0x0, took 1187ns 1558546028: sio_revents: revents = 0x0, took 1327ns 1558560345: sio_revents: revents = 0x0, took 1257ns 1558573336: sio_revents: revents = 0x0, took 1257ns 1558585837: sio_revents: revents = 0x0, took 1257ns 01560860638: clk +78 +0, wr +86 +0 rd: +77 +0 1560859031: sio_revents: revents = 0x1, took 5308ns 1560892555: sio_revents: revents = 0x0, took 1257ns 01580915982: clk +79 +0, wr +87 +0 rd: +78 +0 1580914724: sio_revents: revents = 0x1, took 3283ns 1580944826: sio_revents: revents = 0x0, took 1187ns 1588558853: sio_revents: revents = 0x0, took 1397ns 1588618427: sio_revents: revents = 0x0, took 1467ns 1588633793: sio_revents: revents = 0x0, took 1257ns 1588646504: sio_revents: revents = 0x0, took 1257ns 1588659215: sio_revents: revents = 0x0, took 1257ns 1588671716: sio_revents: revents = 0x0, took 1258ns 1588684358: sio_revents: revents = 0x0, took 1257ns 1588696720: sio_revents: revents = 0x0, took 1257ns 01600860487: clk +80 +0, wr +88 +0 rd: +79 +0 1600858811: sio_revents: revents = 0x1, took 5727ns 1600892824: sio_revents: revents = 0x0, took 1257ns 1618546325: sio_revents: revents = 0x0, took 1467ns 1618560852: sio_revents: revents = 0x0, took 1257ns 1618574052: sio_revents: revents = 0x0, took 1258ns 1618586484: sio_revents: revents = 0x0, took 1257ns 01620926237: clk +81 +0, wr +89 +0 rd: +80 +0 1620924631: sio_revents: revents = 0x1, took 5447ns 1620958364: sio_revents: revents = 0x0, took 1257ns 01640858869: clk +82 +0, wr +90 +0 rd: +81 +0 1640857543: sio_revents: revents = 0x1, took 3422ns 1640887923: sio_revents: revents = 0x0, took 1188ns 1648588274: sio_revents: revents = 0x0, took 1397ns 1648602731: sio_revents: revents = 0x0, took 1257ns 1648615861: sio_revents: revents = 0x0, took 1257ns 1648628433: sio_revents: revents = 0x0, took 1257ns 1648642471: sio_revents: revents = 0x0, took 1257ns 1648655042: sio_revents: revents = 0x0, took 1257ns 1648667684: sio_revents: revents = 0x0, took 1257ns 1648680115: sio_revents: revents = 0x0, took 1258ns 01660931045: clk +83 +0, wr +91 +0 rd: +82 +0 1660929508: sio_revents: revents = 0x1, took 5308ns 1660962753: sio_revents: revents = 0x0, took 1257ns 1678507093: sio_revents: revents = 0x0, took 1257ns 1678520362: sio_revents: revents = 0x0, took 1328ns 1678533423: sio_revents: revents = 0x0, took 1327ns 1678545924: sio_revents: revents = 0x0, took 1258ns 01680860185: clk +84 +0, wr +92 +0 rd: +83 +0 1680858579: sio_revents: revents = 0x1, took 5238ns 1680891963: sio_revents: revents = 0x0, took 1257ns 01700932361: clk +85 +0, wr +93 +0 rd: +84 +0 1700931104: sio_revents: revents = 0x1, took 3212ns 1700961065: sio_revents: revents = 0x0, took 1258ns 1708605543: sio_revents: revents = 0x0, took 1467ns 1708620419: sio_revents: revents = 0x0, took 1257ns 1708633480: sio_revents: revents = 0x0, took 1257ns 1708646121: sio_revents: revents = 0x0, took 1187ns 1708658692: sio_revents: revents = 0x0, took 1257ns 1708671194: sio_revents: revents = 0x0, took 1257ns 1708683835: sio_revents: revents = 0x0, took 1257ns 1708696197: sio_revents: revents = 0x0, took 1257ns 01720859336: clk +86 +0, wr +94 +0 rd: +85 +0 1720857730: sio_revents: revents = 0x1, took 5308ns 1720890974: sio_revents: revents = 0x0, took 1257ns 1738548247: sio_revents: revents = 0x0, took 1537ns 1738563333: sio_revents: revents = 0x0, took 1257ns 1738576463: sio_revents: revents = 0x0, took 1257ns 1738588965: sio_revents: revents = 0x0, took 1257ns 01740931651: clk +87 +0, wr +95 +0 rd: +86 +0 1740930045: sio_revents: revents = 0x1, took 5447ns 1740963778: sio_revents: revents = 0x0, took 1257ns 01760859115: clk +88 +0, wr +96 +0 rd: +87 +0 1760857788: sio_revents: revents = 0x1, took 3283ns 1760888518: sio_revents: revents = 0x0, took 1188ns 1768554158: sio_revents: revents = 0x0, took 1327ns 1768585936: sio_revents: revents = 0x0, took 1257ns 1768599206: sio_revents: revents = 0x0, took 1257ns 1768611777: sio_revents: revents = 0x0, took 1327ns 1768624558: sio_revents: revents = 0x0, took 1187ns 1768637060: sio_revents: revents = 0x0, took 1187ns 1768649841: sio_revents: revents = 0x0, took 1257ns 1768662622: sio_revents: revents = 0x0, took 1187ns 01780931430: clk +89 +0, wr +97 +0 rd: +88 +0 1780929824: sio_revents: revents = 0x1, took 5517ns 1780963208: sio_revents: revents = 0x0, took 1257ns 1798545472: sio_revents: revents = 0x0, took 1327ns 1798559720: sio_revents: revents = 0x0, took 1257ns 1798572780: sio_revents: revents = 0x0, took 1257ns 1798585212: sio_revents: revents = 0x0, took 1187ns 01800860082: clk +90 +0, wr +98 +0 rd: +89 +0 1800858545: sio_revents: revents = 0x1, took 5308ns 1800892069: sio_revents: revents = 0x0, took 1188ns 01820937286: clk +91 +0, wr +99 +0 rd: +90 +0 1820935959: sio_revents: revents = 0x1, took 3282ns 1820966200: sio_revents: revents = 0x0, took 1188ns 1828584557: sio_revents: revents = 0x0, took 1397ns 1828598945: sio_revents: revents = 0x0, took 1257ns 1828611935: sio_revents: revents = 0x0, took 1257ns 1828624437: sio_revents: revents = 0x0, took 1257ns 1828637148: sio_revents: revents = 0x0, took 1257ns 1828649649: sio_revents: revents = 0x0, took 1187ns 1828662151: sio_revents: revents = 0x0, took 1257ns 1828674583: sio_revents: revents = 0x0, took 1257ns 01840860420: clk +92 +0, wr +100 +0 rd: +91 +0 1840858813: sio_revents: revents = 0x1, took 5448ns 1840892128: sio_revents: revents = 0x0, took 1257ns 1858509871: sio_revents: revents = 0x0, took 1537ns 1858524957: sio_revents: revents = 0x0, took 1187ns 1858537877: sio_revents: revents = 0x0, took 1327ns 1858550449: sio_revents: revents = 0x0, took 1257ns 01860945865: clk +93 +0, wr +101 +0 rd: +92 +0 1860944189: sio_revents: revents = 0x1, took 5308ns 1860977783: sio_revents: revents = 0x0, took 1257ns 01880859082: clk +94 +0, wr +102 +0 rd: +93 +0 1880857755: sio_revents: revents = 0x1, took 5657ns 1880890301: sio_revents: revents = 0x0, took 1257ns 1888601267: sio_revents: revents = 0x0, took 1467ns 1888619496: sio_revents: revents = 0x0, took 1257ns 1888632836: sio_revents: revents = 0x0, took 1257ns 1888645337: sio_revents: revents = 0x0, took 1257ns 1888658048: sio_revents: revents = 0x0, took 1188ns 1888670550: sio_revents: revents = 0x0, took 1257ns 1888683261: sio_revents: revents = 0x0, took 1187ns 1888695763: sio_revents: revents = 0x0, took 1257ns 01900955771: clk +95 +0, wr +103 +0 rd: +94 +0 1900954165: sio_revents: revents = 0x1, took 5448ns 1900987549: sio_revents: revents = 0x0, took 1257ns 1918549559: sio_revents: revents = 0x0, took 1397ns 1918564086: sio_revents: revents = 0x0, took 1257ns 1918577146: sio_revents: revents = 0x0, took 1257ns 1918589718: sio_revents: revents = 0x0, took 1257ns 01920860048: clk +96 +0, wr +104 +0 rd: +95 +0 1920858442: sio_revents: revents = 0x1, took 5308ns 1920891966: sio_revents: revents = 0x0, took 1327ns 01940965119: clk +97 +0, wr +105 +0 rd: +96 +0 1940963792: sio_revents: revents = 0x1, took 3282ns 1940993894: sio_revents: revents = 0x0, took 1187ns 1948552397: sio_revents: revents = 0x0, took 1466ns 1948567063: sio_revents: revents = 0x0, took 1257ns 1948580124: sio_revents: revents = 0x0, took 1257ns 1948592695: sio_revents: revents = 0x0, took 1257ns 1948605336: sio_revents: revents = 0x0, took 1257ns 1948617838: sio_revents: revents = 0x0, took 1257ns 1948630479: sio_revents: revents = 0x0, took 1257ns 1948642981: sio_revents: revents = 0x0, took 1257ns 01960859618: clk +98 +0, wr +106 +0 rd: +97 +0 1960858082: sio_revents: revents = 0x1, took 5238ns 1960891186: sio_revents: revents = 0x0, took 1258ns 1978584428: sio_revents: revents = 0x0, took 1467ns 1978599374: sio_revents: revents = 0x0, took 1257ns 1978612504: sio_revents: revents = 0x0, took 1257ns 1978625006: sio_revents: revents = 0x0, took 1257ns 01980978936: clk +99 +0, wr +107 +0 rd: +98 +0 1980977330: sio_revents: revents = 0x1, took 5378ns 1981010924: sio_revents: revents = 0x0, took 1257ns 02000859328: clk +100 +0, wr +108 +0 rd: +99 +0 2000858001: sio_revents: revents = 0x1, took 3352ns 2000888172: sio_revents: revents = 0x0, took 1257ns 2008553532: sio_revents: revents = 0x0, took 1467ns 2008567850: sio_revents: revents = 0x0, took 1257ns 2008580980: sio_revents: revents = 0x0, took 1327ns 2008593482: sio_revents: revents = 0x0, took 1257ns 2008606193: sio_revents: revents = 0x0, took 1257ns 2008618694: sio_revents: revents = 0x0, took 1257ns 2008631266: sio_revents: revents = 0x0, took 1257ns 2008643697: sio_revents: revents = 0x0, took 1257ns 02020988773: clk +101 +0, wr +109 +0 rd: +100 +0 2020987236: sio_revents: revents = 0x1, took 5239ns 2021020481: sio_revents: revents = 0x0, took 1257ns 2038544706: sio_revents: revents = 0x0, took 1397ns 2038558954: sio_revents: revents = 0x0, took 1327ns 2038572154: sio_revents: revents = 0x0, took 1257ns 2038584726: sio_revents: revents = 0x0, took 1257ns 02040868745: clk +102 +0, wr +110 +0 rd: +101 +0 2040867208: sio_revents: revents = 0x1, took 5169ns 2040900593: sio_revents: revents = 0x0, took 1257ns 02060999028: clk +103 +0, wr +111 +0 rd: +102 +0 2060997701: sio_revents: revents = 0x1, took 3423ns 2061027943: sio_revents: revents = 0x0, took 1257ns 2068583233: sio_revents: revents = 0x0, took 1327ns 2068597760: sio_revents: revents = 0x0, took 1257ns 2068610890: sio_revents: revents = 0x0, took 1257ns 2068623392: sio_revents: revents = 0x0, took 1257ns 2068636103: sio_revents: revents = 0x0, took 1257ns 2068648535: sio_revents: revents = 0x0, took 1257ns 2068661246: sio_revents: revents = 0x0, took 1257ns 2068673747: sio_revents: revents = 0x0, took 1258ns 02080882353: clk +104 +0, wr +112 +0 rd: +103 +0 2080880816: sio_revents: revents = 0x1, took 5308ns 2080914270: sio_revents: revents = 0x0, took 1258ns 2098544934: sio_revents: revents = 0x0, took 1397ns 2098560509: sio_revents: revents = 0x0, took 1257ns 2098573639: sio_revents: revents = 0x0, took 1257ns 2098586071: sio_revents: revents = 0x0, took 1257ns 02100861150: clk +105 +0, wr +113 +0 rd: +104 +0 2100859614: sio_revents: revents = 0x1, took 5168ns 2100892858: sio_revents: revents = 0x0, took 1258ns 02120885065: clk +106 +0, wr +114 +0 rd: +105 +0 2120883459: sio_revents: revents = 0x1, took 4889ns 2120915935: sio_revents: revents = 0x0, took 1188ns 2128554407: sio_revents: revents = 0x0, took 1396ns 2128568934: sio_revents: revents = 0x0, took 1327ns 2128582134: sio_revents: revents = 0x0, took 1257ns 2128594635: sio_revents: revents = 0x0, took 1257ns 2128607416: sio_revents: revents = 0x0, took 1257ns 2128619918: sio_revents: revents = 0x0, took 1257ns 2128632559: sio_revents: revents = 0x0, took 1257ns 2128644921: sio_revents: revents = 0x0, took 1257ns 02140859882: clk +107 +0, wr +115 +0 rd: +106 +0 2140858276: sio_revents: revents = 0x1, took 5238ns 2140891450: sio_revents: revents = 0x0, took 1258ns 2158549352: sio_revents: revents = 0x0, took 1397ns 2158563949: sio_revents: revents = 0x0, took 1257ns 2158576940: sio_revents: revents = 0x0, took 1257ns 2158589441: sio_revents: revents = 0x0, took 1257ns 02160899232: clk +108 +0, wr +116 +0 rd: +107 +0 2160897765: sio_revents: revents = 0x1, took 5239ns 2160931848: sio_revents: revents = 0x0, took 1257ns 02180859452: clk +109 +0, wr +117 +0 rd: +108 +0 2180858195: sio_revents: revents = 0x1, took 3282ns 2180888157: sio_revents: revents = 0x0, took 1257ns 2188551561: sio_revents: revents = 0x0, took 1397ns 2188566018: sio_revents: revents = 0x0, took 1258ns 2188578939: sio_revents: revents = 0x0, took 1257ns 2188591441: sio_revents: revents = 0x0, took 1257ns 2188604152: sio_revents: revents = 0x0, took 1257ns 2188616654: sio_revents: revents = 0x0, took 1257ns 2188629295: sio_revents: revents = 0x0, took 1257ns 2188641726: sio_revents: revents = 0x0, took 1258ns 02200915354: clk +110 +0, wr +118 +0 rd: +109 +0 2200913818: sio_revents: revents = 0x1, took 6355ns 2200948249: sio_revents: revents = 0x0, took 1258ns 2218545878: sio_revents: revents = 0x0, took 1467ns 2218560196: sio_revents: revents = 0x0, took 1257ns 2218573256: sio_revents: revents = 0x0, took 1257ns 2218585828: sio_revents: revents = 0x0, took 1257ns 02220860349: clk +111 +0, wr +119 +0 rd: +110 +0 2220858812: sio_revents: revents = 0x1, took 5308ns 2220892336: sio_revents: revents = 0x0, took 1187ns 02240928124: clk +112 +0, wr +120 +0 rd: +111 +0 2240926727: sio_revents: revents = 0x1, took 4051ns 2240958016: sio_revents: revents = 0x0, took 1257ns 2248589503: sio_revents: revents = 0x0, took 1327ns 2248604100: sio_revents: revents = 0x0, took 1257ns 2248617091: sio_revents: revents = 0x0, took 1257ns 2248629592: sio_revents: revents = 0x0, took 1257ns 2248642303: sio_revents: revents = 0x0, took 1257ns 2248654805: sio_revents: revents = 0x0, took 1327ns 2248667446: sio_revents: revents = 0x0, took 1257ns 2248679878: sio_revents: revents = 0x0, took 1257ns 02260861176: clk +113 +0, wr +121 +0 rd: +112 +0 2260859639: sio_revents: revents = 0x1, took 5378ns 2260893163: sio_revents: revents = 0x0, took 1257ns 2278507973: sio_revents: revents = 0x0, took 1327ns 2278521522: sio_revents: revents = 0x0, took 1257ns 2278534512: sio_revents: revents = 0x0, took 1258ns 2278547433: sio_revents: revents = 0x0, took 1257ns 02280945503: clk +114 +0, wr +122 +0 rd: +113 +0 2280943967: sio_revents: revents = 0x1, took 5098ns 2280977351: sio_revents: revents = 0x0, took 1257ns 02300859768: clk +115 +0, wr +123 +0 rd: +114 +0 2300858441: sio_revents: revents = 0x1, took 3212ns 2300888542: sio_revents: revents = 0x0, took 1187ns 2308586937: sio_revents: revents = 0x0, took 1397ns 2308601464: sio_revents: revents = 0x0, took 1258ns 2308614664: sio_revents: revents = 0x0, took 1257ns 2308627236: sio_revents: revents = 0x0, took 1257ns 2308640087: sio_revents: revents = 0x0, took 1187ns 2308652449: sio_revents: revents = 0x0, took 1257ns 2308666417: sio_revents: revents = 0x0, took 1257ns 2308678988: sio_revents: revents = 0x0, took 1257ns 02320955549: clk +116 +0, wr +124 +0 rd: +115 +0 2320954013: sio_revents: revents = 0x1, took 5168ns 2320987187: sio_revents: revents = 0x0, took 1258ns 2338545984: sio_revents: revents = 0x0, took 1467ns 2338560302: sio_revents: revents = 0x0, took 1257ns 2338573362: sio_revents: revents = 0x0, took 1257ns 2338585934: sio_revents: revents = 0x0, took 1257ns 02340860944: clk +117 +0, wr +125 +0 rd: +116 +0 2340859477: sio_revents: revents = 0x1, took 5308ns 2340893071: sio_revents: revents = 0x0, took 1257ns 02360969018: clk +118 +0, wr +126 +0 rd: +117 +0 2360967760: sio_revents: revents = 0x1, took 3353ns 2360998560: sio_revents: revents = 0x0, took 1258ns 2368556016: sio_revents: revents = 0x0, took 1327ns 2368615241: sio_revents: revents = 0x0, took 1467ns 2368630606: sio_revents: revents = 0x0, took 1257ns 2368643387: sio_revents: revents = 0x0, took 1257ns 2368656029: sio_revents: revents = 0x0, took 1327ns 2368668740: sio_revents: revents = 0x0, took 1257ns 2368681311: sio_revents: revents = 0x0, took 1257ns 2368693882: sio_revents: revents = 0x0, took 1258ns 02380860025: clk +119 +0, wr +127 +0 rd: +118 +0 2380858488: sio_revents: revents = 0x1, took 5308ns 2380891733: sio_revents: revents = 0x0, took 1257ns 2398556898: sio_revents: revents = 0x0, took 1467ns 2398571495: sio_revents: revents = 0x0, took 1187ns 2398584555: sio_revents: revents = 0x0, took 1257ns 2398597126: sio_revents: revents = 0x0, took 1258ns 02400981578: clk +120 +0, wr +128 +0 rd: +119 +0 2400980041: sio_revents: revents = 0x1, took 5308ns 2401013705: sio_revents: revents = 0x0, took 1187ns 02420862598: clk +121 +0, wr +129 +0 rd: +120 +0 2420861340: sio_revents: revents = 0x1, took 3353ns 2420891791: sio_revents: revents = 0x0, took 1257ns 2428585298: sio_revents: revents = 0x0, took 1396ns 2428599825: sio_revents: revents = 0x0, took 1257ns 2428614491: sio_revents: revents = 0x0, took 1257ns 2428626993: sio_revents: revents = 0x0, took 1257ns 2428639774: sio_revents: revents = 0x0, took 1187ns 2428652205: sio_revents: revents = 0x0, took 1258ns 2428665056: sio_revents: revents = 0x0, took 1257ns 2428677558: sio_revents: revents = 0x0, took 1257ns 02440993021: clk +122 +0, wr +130 +0 rd: +121 +0 2440991554: sio_revents: revents = 0x1, took 5238ns 2441024728: sio_revents: revents = 0x0, took 1188ns 2458507887: sio_revents: revents = 0x0, took 1397ns 2458521507: sio_revents: revents = 0x0, took 1257ns 2458534427: sio_revents: revents = 0x0, took 1188ns 2458546859: sio_revents: revents = 0x0, took 1257ns 02460876624: clk +123 +0, wr +131 +0 rd: +122 +0 2460875088: sio_revents: revents = 0x1, took 5168ns 2460908472: sio_revents: revents = 0x0, took 1257ns 02480859822: clk +124 +0, wr +132 +0 rd: +123 +0 2480858565: sio_revents: revents = 0x1, took 3282ns 2480888597: sio_revents: revents = 0x0, took 1187ns 2488589855: sio_revents: revents = 0x0, took 1397ns 2488604243: sio_revents: revents = 0x0, took 1257ns 2488617233: sio_revents: revents = 0x0, took 1257ns 2488629735: sio_revents: revents = 0x0, took 1257ns 2488642446: sio_revents: revents = 0x0, took 1187ns 2488654878: sio_revents: revents = 0x0, took 1257ns 2488667309: sio_revents: revents = 0x0, took 1257ns 2488679811: sio_revents: revents = 0x0, took 1187ns 02500883178: clk +125 +0, wr +133 +0 rd: +124 +0 2500881712: sio_revents: revents = 0x1, took 5238ns 2500914816: sio_revents: revents = 0x0, took 1258ns 2518547925: sio_revents: revents = 0x0, took 1397ns 2518562382: sio_revents: revents = 0x0, took 1257ns 2518575442: sio_revents: revents = 0x0, took 1327ns 2518587944: sio_revents: revents = 0x0, took 1257ns 02520861557: clk +126 +0, wr +134 +0 rd: +125 +0 2520860020: sio_revents: revents = 0x1, took 5099ns 2520893335: sio_revents: revents = 0x0, took 1257ns 02540886450: clk +127 +0, wr +135 +0 rd: +126 +0 2540885193: sio_revents: revents = 0x1, took 3212ns 2540916412: sio_revents: revents = 0x0, took 1257ns 2548551181: sio_revents: revents = 0x0, took 1327ns 2548565429: sio_revents: revents = 0x0, took 1257ns 2548578350: sio_revents: revents = 0x0, took 1257ns 2548590921: sio_revents: revents = 0x0, took 1257ns 2548603492: sio_revents: revents = 0x0, took 1258ns 2548615994: sio_revents: revents = 0x0, took 1257ns 2548628635: sio_revents: revents = 0x0, took 1258ns 2548641067: sio_revents: revents = 0x0, took 1257ns 02560861127: clk +128 +0, wr +136 +0 rd: +127 +0 2560859590: sio_revents: revents = 0x1, took 5169ns 2560892625: sio_revents: revents = 0x0, took 1257ns 2578547524: sio_revents: revents = 0x0, took 1397ns 2578561841: sio_revents: revents = 0x0, took 1188ns 2578574832: sio_revents: revents = 0x0, took 1187ns 2578587263: sio_revents: revents = 0x0, took 1258ns 02580892235: clk +129 +0, wr +137 +0 rd: +128 +0 2580890699: sio_revents: revents = 0x1, took 5098ns 2580924083: sio_revents: revents = 0x0, took 1187ns 02600860557: clk +130 +0, wr +138 +0 rd: +129 +0 2600859230: sio_revents: revents = 0x1, took 3282ns 2600889192: sio_revents: revents = 0x0, took 1327ns 2608554971: sio_revents: revents = 0x0, took 1397ns 2608569289: sio_revents: revents = 0x0, took 1257ns 2608582209: sio_revents: revents = 0x0, took 1257ns 2608594711: sio_revents: revents = 0x0, took 1257ns 2608607422: sio_revents: revents = 0x0, took 1257ns 2608619923: sio_revents: revents = 0x0, took 1258ns 2608632565: sio_revents: revents = 0x0, took 1257ns 2608644996: sio_revents: revents = 0x0, took 1257ns 02620889989: clk +131 +0, wr +139 +0 rd: +130 +0 2620888523: sio_revents: revents = 0x1, took 5098ns 2620921558: sio_revents: revents = 0x0, took 1257ns 2638549986: sio_revents: revents = 0x0, took 1537ns 2638565561: sio_revents: revents = 0x0, took 1257ns 2638578691: sio_revents: revents = 0x0, took 1257ns 2638591193: sio_revents: revents = 0x0, took 1187ns 02640861663: clk +132 +0, wr +140 +0 rd: +131 +0 2640860127: sio_revents: revents = 0x1, took 5377ns 2640905873: sio_revents: revents = 0x0, took 1257ns 02660883413: clk +133 +0, wr +141 +0 rd: +132 +0 2660882156: sio_revents: revents = 0x1, took 3422ns 2660912537: sio_revents: revents = 0x0, took 1187ns 2668588583: sio_revents: revents = 0x0, took 1397ns 2668603110: sio_revents: revents = 0x0, took 1257ns 2668616240: sio_revents: revents = 0x0, took 1257ns 2668628742: sio_revents: revents = 0x0, took 1327ns 2668641523: sio_revents: revents = 0x0, took 1257ns 2668654024: sio_revents: revents = 0x0, took 1257ns 2668667084: sio_revents: revents = 0x0, took 1257ns 2668679586: sio_revents: revents = 0x0, took 1257ns 02680860255: clk +134 +0, wr +142 +0 rd: +133 +0 2680858719: sio_revents: revents = 0x1, took 5307ns 2680891963: sio_revents: revents = 0x0, took 1257ns 2698508938: sio_revents: revents = 0x0, took 1327ns 2698522417: sio_revents: revents = 0x0, took 1258ns 2698535408: sio_revents: revents = 0x0, took 1257ns 2698547979: sio_revents: revents = 0x0, took 1257ns 02700879910: clk +135 +0, wr +143 +0 rd: +134 +0 2700878303: sio_revents: revents = 0x1, took 5169ns 2700911757: sio_revents: revents = 0x0, took 1258ns 02720860104: clk +136 +0, wr +144 +0 rd: +135 +0 2720858707: sio_revents: revents = 0x1, took 3842ns 2720889717: sio_revents: revents = 0x0, took 1257ns 2728588601: sio_revents: revents = 0x0, took 1397ns 2728603128: sio_revents: revents = 0x0, took 1257ns 2728616258: sio_revents: revents = 0x0, took 1257ns 2728628829: sio_revents: revents = 0x0, took 1258ns 2728641541: sio_revents: revents = 0x0, took 1257ns 2728654112: sio_revents: revents = 0x0, took 1257ns 2728666823: sio_revents: revents = 0x0, took 1257ns 2728679255: sio_revents: revents = 0x0, took 1257ns 02740876756: clk +137 +0, wr +145 +0 rd: +136 +0 2740875150: sio_revents: revents = 0x1, took 5377ns 2740908394: sio_revents: revents = 0x0, took 1187ns 2758548207: sio_revents: revents = 0x0, took 1536ns 2758563293: sio_revents: revents = 0x0, took 1257ns 2758576213: sio_revents: revents = 0x0, took 1257ns 2758591090: sio_revents: revents = 0x0, took 1257ns 02760860931: clk +138 +0, wr +146 +0 rd: +137 +0 2760859395: sio_revents: revents = 0x1, took 5308ns 2760892988: sio_revents: revents = 0x0, took 1327ns 02780876815: clk +139 +0, wr +147 +0 rd: +138 +0 2780875418: sio_revents: revents = 0x1, took 3562ns 2780905869: sio_revents: revents = 0x0, took 1257ns 2788555235: sio_revents: revents = 0x0, took 1327ns 2788569483: sio_revents: revents = 0x0, took 1257ns 2788582613: sio_revents: revents = 0x0, took 1257ns 2788595184: sio_revents: revents = 0x0, took 1257ns 2788607965: sio_revents: revents = 0x0, took 1257ns 2788620467: sio_revents: revents = 0x0, took 1257ns 2788633178: sio_revents: revents = 0x0, took 1257ns 2788645610: sio_revents: revents = 0x0, took 1257ns 02800860361: clk +140 +0, wr +148 +0 rd: +139 +0 2800858825: sio_revents: revents = 0x1, took 5238ns 2800891999: sio_revents: revents = 0x0, took 1258ns 2818547876: sio_revents: revents = 0x0, took 1397ns 2818562124: sio_revents: revents = 0x0, took 1257ns 2818575323: sio_revents: revents = 0x0, took 1258ns 2818587895: sio_revents: revents = 0x0, took 1257ns 02820878759: clk +141 +0, wr +149 +0 rd: +140 +0 2820877152: sio_revents: revents = 0x1, took 5308ns 2820910606: sio_revents: revents = 0x0, took 1258ns 02840860071: clk +142 +0, wr +150 +0 rd: +141 +0 2840858813: sio_revents: revents = 0x1, took 3283ns 2840888845: sio_revents: revents = 0x0, took 1188ns 2848586682: sio_revents: revents = 0x0, took 1466ns 2848601209: sio_revents: revents = 0x0, took 1257ns 2848614339: sio_revents: revents = 0x0, took 1257ns 2848626910: sio_revents: revents = 0x0, took 1257ns 2848639761: sio_revents: revents = 0x0, took 1187ns 2848652333: sio_revents: revents = 0x0, took 1257ns 2848664974: sio_revents: revents = 0x0, took 1187ns 2848677406: sio_revents: revents = 0x0, took 1257ns 02860879446: clk +143 +0, wr +151 +0 rd: +142 +0 2860877840: sio_revents: revents = 0x1, took 5308ns 2860912551: sio_revents: revents = 0x0, took 1257ns 2878508853: sio_revents: revents = 0x0, took 1397ns 2878522402: sio_revents: revents = 0x0, took 1257ns 2878535253: sio_revents: revents = 0x0, took 1257ns 2878547824: sio_revents: revents = 0x0, took 1257ns 02880861875: clk +144 +0, wr +152 +0 rd: +143 +0 2880860269: sio_revents: revents = 0x1, took 5029ns 2880893374: sio_revents: revents = 0x0, took 1187ns 02900886629: clk +145 +0, wr +153 +0 rd: +144 +0 2900884952: sio_revents: revents = 0x1, took 5169ns 2900917708: sio_revents: revents = 0x0, took 1257ns 2908598224: sio_revents: revents = 0x0, took 1397ns 2908612821: sio_revents: revents = 0x0, took 1257ns 2908626021: sio_revents: revents = 0x0, took 1257ns 2908638592: sio_revents: revents = 0x0, took 1257ns 2908651303: sio_revents: revents = 0x0, took 1257ns 2908663805: sio_revents: revents = 0x0, took 1257ns 2908676446: sio_revents: revents = 0x0, took 1257ns 2908688878: sio_revents: revents = 0x0, took 1257ns 02920860467: clk +146 +0, wr +154 +0 rd: +145 +0 2920858931: sio_revents: revents = 0x1, took 5378ns 2920892315: sio_revents: revents = 0x0, took 1187ns 2938546934: sio_revents: revents = 0x0, took 1327ns 2938561252: sio_revents: revents = 0x0, took 1257ns 2938574172: sio_revents: revents = 0x0, took 1328ns 2938586744: sio_revents: revents = 0x0, took 1257ns 02940896605: clk +147 +0, wr +155 +0 rd: +146 +0 2940895068: sio_revents: revents = 0x1, took 5168ns 2940928382: sio_revents: revents = 0x0, took 1258ns 02960860037: clk +148 +0, wr +156 +0 rd: +147 +0 2960858710: sio_revents: revents = 0x1, took 3283ns 2960889371: sio_revents: revents = 0x0, took 1187ns 2968554382: sio_revents: revents = 0x0, took 1466ns 2968568909: sio_revents: revents = 0x0, took 1187ns 2968581899: sio_revents: revents = 0x0, took 1257ns 2968594610: sio_revents: revents = 0x0, took 1188ns 2968607251: sio_revents: revents = 0x0, took 1257ns 2968619823: sio_revents: revents = 0x0, took 1187ns 2968632464: sio_revents: revents = 0x0, took 1187ns 2968646432: sio_revents: revents = 0x0, took 1188ns 02980900086: clk +149 +0, wr +157 +0 rd: +148 +0 2980898549: sio_revents: revents = 0x1, took 5168ns 2980931793: sio_revents: revents = 0x0, took 1258ns 2998550584: sio_revents: revents = 0x0, took 1467ns 2998564902: sio_revents: revents = 0x0, took 1257ns 2998577892: sio_revents: revents = 0x0, took 1257ns 2998590394: sio_revents: revents = 0x0, took 1187ns 03000861842: clk +150 +0, wr +158 +0 rd: +149 +0 3000860305: sio_revents: revents = 0x1, took 5238ns 3000893550: sio_revents: revents = 0x0, took 1257ns 03020903078: clk +151 +0, wr +159 +0 rd: +150 +0 3020901821: sio_revents: revents = 0x1, took 3212ns 3020931782: sio_revents: revents = 0x0, took 1258ns 3028554539: sio_revents: revents = 0x0, took 1467ns 3028568996: sio_revents: revents = 0x0, took 1258ns 3028581987: sio_revents: revents = 0x0, took 1257ns 3028594558: sio_revents: revents = 0x0, took 1258ns 3028607200: sio_revents: revents = 0x0, took 1257ns 3028619701: sio_revents: revents = 0x0, took 1257ns 3028632343: sio_revents: revents = 0x0, took 1257ns 3028644844: sio_revents: revents = 0x0, took 1257ns 03040860644: clk +152 +0, wr +160 +0 rd: +151 +0 3040859107: sio_revents: revents = 0x1, took 5168ns 3040892421: sio_revents: revents = 0x0, took 1257ns 3058547110: sio_revents: revents = 0x0, took 1397ns 3058561358: sio_revents: revents = 0x0, took 1257ns 3058574209: sio_revents: revents = 0x0, took 1257ns 3058586710: sio_revents: revents = 0x0, took 1258ns 03060909771: clk +153 +0, wr +161 +0 rd: +152 +0 3060908235: sio_revents: revents = 0x1, took 5238ns 3060941689: sio_revents: revents = 0x0, took 1187ns 03080860213: clk +154 +0, wr +162 +0 rd: +153 +0 3080858956: sio_revents: revents = 0x1, took 3283ns 3080889128: sio_revents: revents = 0x0, took 1187ns 3088588361: sio_revents: revents = 0x0, took 1397ns 3088602818: sio_revents: revents = 0x0, took 1257ns 3088615948: sio_revents: revents = 0x0, took 1257ns 3088628519: sio_revents: revents = 0x0, took 1258ns 3088642837: sio_revents: revents = 0x0, took 1257ns 3088655408: sio_revents: revents = 0x0, took 1257ns 3088668050: sio_revents: revents = 0x0, took 1257ns 3088680481: sio_revents: revents = 0x0, took 1257ns 03100911995: clk +155 +0, wr +163 +0 rd: +154 +0 3100910458: sio_revents: revents = 0x1, took 5169ns 3100943703: sio_revents: revents = 0x0, took 1257ns 3118548805: sio_revents: revents = 0x0, took 1397ns 3118563122: sio_revents: revents = 0x0, took 1257ns 3118576113: sio_revents: revents = 0x0, took 1257ns 3118588684: sio_revents: revents = 0x0, took 1257ns 03120861389: clk +156 +0, wr +164 +0 rd: +155 +0 3120859853: sio_revents: revents = 0x1, took 5238ns 3120893446: sio_revents: revents = 0x0, took 1258ns 03140910796: clk +157 +0, wr +165 +0 rd: +156 +0 3140909469: sio_revents: revents = 0x1, took 3353ns 3140939571: sio_revents: revents = 0x0, took 1257ns 3148554855: sio_revents: revents = 0x0, took 1397ns 3148614430: sio_revents: revents = 0x0, took 1536ns 3148629795: sio_revents: revents = 0x0, took 1257ns 3148642506: sio_revents: revents = 0x0, took 1257ns 3148655217: sio_revents: revents = 0x0, took 1187ns 3148667719: sio_revents: revents = 0x0, took 1257ns 3148680360: sio_revents: revents = 0x0, took 1257ns 3148692862: sio_revents: revents = 0x0, took 1257ns 03160860889: clk +158 +0, wr +166 +0 rd: +157 +0 3160859353: sio_revents: revents = 0x1, took 5238ns 3160892946: sio_revents: revents = 0x0, took 1188ns 3178547705: sio_revents: revents = 0x0, took 1467ns 3178562372: sio_revents: revents = 0x0, took 1257ns 3178575293: sio_revents: revents = 0x0, took 1257ns 3178587794: sio_revents: revents = 0x0, took 1257ns 03180911693: clk +159 +0, wr +167 +0 rd: +158 +0 3180910157: sio_revents: revents = 0x1, took 5168ns 3180943541: sio_revents: revents = 0x0, took 1257ns 03200860669: clk +160 +0, wr +168 +0 rd: +159 +0 3200859272: sio_revents: revents = 0x1, took 4819ns 3200891888: sio_revents: revents = 0x0, took 1257ns 3208557038: sio_revents: revents = 0x0, took 1397ns 3208571635: sio_revents: revents = 0x0, took 1327ns 3208584975: sio_revents: revents = 0x0, took 1257ns 3208597616: sio_revents: revents = 0x0, took 1257ns 3208610397: sio_revents: revents = 0x0, took 1257ns 3208622968: sio_revents: revents = 0x0, took 1258ns 3208635680: sio_revents: revents = 0x0, took 1257ns 3208648181: sio_revents: revents = 0x0, took 1257ns 3214659700: sio_revents: revents = 0x0, took 1536ns mplayer0 vol=127,pst=run,mmc=off,rmsg,widl: STOP message mplayer0 vol=127,pst=run,mmc=off: stopping 03220909307: clk +161 +0, wr +169 +0 rd: +160 +0 3220907980: sio_revents: revents = 0x1, took 3772ns 03240860588: clk +162 +0, wr +170 +0 rd: +161 +0 3240859330: sio_revents: revents = 0x1, took 3283ns 03260911671: clk +163 +0, wr +171 +0 rd: +162 +0 3260910204: sio_revents: revents = 0x1, took 4121ns 03280861414: clk +164 +0, wr +172 +0 rd: +163 +0 3280860087: sio_revents: revents = 0x1, took 3423ns 03300911730: clk +165 +0, wr +173 +0 rd: +164 +0 3300910333: sio_revents: revents = 0x1, took 3282ns 03320860775: clk +166 +0, wr +174 +0 rd: +165 +0 3320859308: sio_revents: revents = 0x1, took 5029ns 03340919820: clk +167 +0, wr +175 +0 rd: +166 +0 3340918563: sio_revents: revents = 0x1, took 3213ns 03360860554: clk +168 +0, wr +176 +0 rd: +167 +0 3360859297: sio_revents: revents = 0x1, took 3143ns 03380927422: clk +169 +0, wr +177 +0 rd: +168 +0 3380926094: sio_revents: revents = 0x1, took 3213ns 03400860473: clk +170 +0, wr +178 +0 rd: +169 +0 3400859146: sio_revents: revents = 0x1, took 3352ns 03420928248: clk +171 +0, wr +179 +0 rd: +170 +0 3420926712: sio_revents: revents = 0x1, took 4679ns 03440860532: clk +172 +0, wr +180 +0 rd: +171 +0 3440859275: sio_revents: revents = 0x1, took 3212ns 03460926002: clk +173 +0, wr +181 +0 rd: +172 +0 3460924745: sio_revents: revents = 0x1, took 3213ns 03480860590: clk +174 +0, wr +182 +0 rd: +173 +0 3480859263: sio_revents: revents = 0x1, took 4610ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: stopped 3480878400: sio_revents: revents = 0x0, took 1187ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: building STOP message 3480893276: sio_revents: revents = 0x0, took 1257ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: GETPAR message 3480902984: sio_revents: revents = 0x0, took 1257ns 3481502851: sio_revents: revents = 0x0, took 1397ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: START message mplayer0 vol=127,pst=ini,mmc=off: playing s32le -> s16le mplayer0 vol=127,pst=ini,mmc=off: allocated 11466/19404 fr buffers mplayer0 vol=127,pst=sta,mmc=off: 44100Hz, s32le, play 0:1, 13 blocks of 882 frames 3481515492: sio_revents: revents = 0x0, took 1257ns 3481534419: sio_revents: revents = 0x0, took 1257ns mplayer0 vol=127,pst=run,mmc=off: attached at -7938, delta = 0 cmap: nch = 2, ostart = 0, onext = 0, istart = 0, inext = 0 dec: s32le, 2 channels resamp: 882/960 mplayer0 vol=127,pst=run,mmc=off: set weight: 23170/23170 03500924385: clk +175 +0, wr +183 +0 rd: +174 +0 3500922988: sio_revents: revents = 0x1, took 3911ns 3500954626: sio_revents: revents = 0x0, took 1257ns 3500972435: sio_revents: revents = 0x0, took 1258ns 03520861417: clk +176 +0, wr +184 +0 rd: +175 +0 3520860090: sio_revents: revents = 0x1, took 3492ns 3520890471: sio_revents: revents = 0x0, took 1188ns 3520907582: sio_revents: revents = 0x0, took 1257ns 03540919205: clk +177 +0, wr +185 +0 rd: +176 +0 3540917809: sio_revents: revents = 0x1, took 3771ns 3540948958: sio_revents: revents = 0x0, took 1257ns 3540966628: sio_revents: revents = 0x0, took 1257ns 03560861406: clk +178 +0, wr +186 +0 rd: +177 +0 3560860149: sio_revents: revents = 0x1, took 3282ns 3560890250: sio_revents: revents = 0x0, took 1188ns 3560907292: sio_revents: revents = 0x0, took 1257ns 03580911582: clk +179 +0, wr +187 +0 rd: +178 +0 3580910254: sio_revents: revents = 0x1, took 3353ns 3580940216: sio_revents: revents = 0x0, took 1257ns 3580958445: sio_revents: revents = 0x0, took 1257ns 03600860906: clk +180 +0, wr +188 +0 rd: +179 +0 3600859649: sio_revents: revents = 0x1, took 3282ns 3600889960: sio_revents: revents = 0x0, took 1187ns 3600907071: sio_revents: revents = 0x0, took 1257ns 3601219820: sio_revents: revents = 0x0, took 1397ns 3601234278: sio_revents: revents = 0x0, took 1257ns 03620915132: clk +181 +0, wr +189 +0 rd: +180 +0 3620913526: sio_revents: revents = 0x1, took 4539ns 3620946002: sio_revents: revents = 0x0, took 1327ns 3620964789: sio_revents: revents = 0x0, took 1257ns 03640860895: clk +182 +0, wr +190 +0 rd: +181 +0 3640859638: sio_revents: revents = 0x1, took 3422ns 3640890019: sio_revents: revents = 0x0, took 1257ns 3640907339: sio_revents: revents = 0x0, took 1257ns 03660914283: clk +183 +0, wr +191 +0 rd: +182 +0 3660913026: sio_revents: revents = 0x1, took 3352ns 3660943058: sio_revents: revents = 0x0, took 1257ns 3660964429: sio_revents: revents = 0x0, took 1257ns 03680861512: clk +184 +0, wr +192 +0 rd: +183 +0 3680859906: sio_revents: revents = 0x1, took 6006ns 3680894128: sio_revents: revents = 0x0, took 1257ns 3688550409: sio_revents: revents = 0x0, took 1397ns 3688565285: sio_revents: revents = 0x0, took 1257ns 3688578485: sio_revents: revents = 0x0, took 1257ns 3688591266: sio_revents: revents = 0x0, took 1187ns 03700912107: clk +185 +0, wr +193 +0 rd: +184 +0 3700910361: sio_revents: revents = 0x1, took 5447ns 3700943675: sio_revents: revents = 0x0, took 1187ns 3718511132: sio_revents: revents = 0x0, took 1327ns 3718524891: sio_revents: revents = 0x0, took 1257ns 3718537882: sio_revents: revents = 0x0, took 1257ns 3718550383: sio_revents: revents = 0x0, took 1257ns 03720862758: clk +186 +0, wr +194 +0 rd: +185 +0 3720861152: sio_revents: revents = 0x1, took 5168ns 3720894466: sio_revents: revents = 0x0, took 1187ns 03740911467: clk +187 +0, wr +195 +0 rd: +186 +0 3740910000: sio_revents: revents = 0x1, took 3911ns 3740941010: sio_revents: revents = 0x0, took 1187ns 3748592961: sio_revents: revents = 0x0, took 1466ns 3748607557: sio_revents: revents = 0x0, took 1257ns 3748620757: sio_revents: revents = 0x0, took 1258ns 3748633399: sio_revents: revents = 0x0, took 1257ns 3748646180: sio_revents: revents = 0x0, took 1257ns 3748658821: sio_revents: revents = 0x0, took 1187ns 3748671532: sio_revents: revents = 0x0, took 1187ns 3748684034: sio_revents: revents = 0x0, took 1187ns 03760861350: clk +188 +0, wr +196 +0 rd: +187 +0 3760859674: sio_revents: revents = 0x1, took 5448ns 3760893058: sio_revents: revents = 0x0, took 1257ns 3778551659: sio_revents: revents = 0x0, took 1536ns 3778566744: sio_revents: revents = 0x0, took 1257ns 3778579804: sio_revents: revents = 0x0, took 1258ns 3778592376: sio_revents: revents = 0x0, took 1257ns 03780913900: clk +189 +0, wr +197 +0 rd: +188 +0 3780912294: sio_revents: revents = 0x1, took 5378ns 3780946516: sio_revents: revents = 0x0, took 1257ns 03800860850: clk +190 +0, wr +198 +0 rd: +189 +0 3800859523: sio_revents: revents = 0x1, took 3422ns 3800889904: sio_revents: revents = 0x0, took 1187ns 3808556731: sio_revents: revents = 0x0, took 1467ns 3808571258: sio_revents: revents = 0x0, took 1257ns 3808584249: sio_revents: revents = 0x0, took 1257ns 3808596820: sio_revents: revents = 0x0, took 1187ns 3808609531: sio_revents: revents = 0x0, took 1257ns 3808622033: sio_revents: revents = 0x0, took 1257ns 3808634604: sio_revents: revents = 0x0, took 1257ns 3808647036: sio_revents: revents = 0x0, took 1257ns 03820912702: clk +191 +0, wr +199 +0 rd: +190 +0 3820911165: sio_revents: revents = 0x1, took 5238ns 3820944410: sio_revents: revents = 0x0, took 1187ns 3838552375: sio_revents: revents = 0x0, took 1397ns 3838566762: sio_revents: revents = 0x0, took 1258ns 3838579753: sio_revents: revents = 0x0, took 1257ns 3838592324: sio_revents: revents = 0x0, took 1257ns 03840862655: clk +192 +0, wr +200 +0 rd: +191 +0 3840861048: sio_revents: revents = 0x1, took 5309ns 3840894502: sio_revents: revents = 0x0, took 1258ns 03860916113: clk +193 +0, wr +201 +0 rd: +192 +0 3860914786: sio_revents: revents = 0x1, took 3352ns 3860944957: sio_revents: revents = 0x0, took 1257ns 3868556889: sio_revents: revents = 0x0, took 1327ns 3868571206: sio_revents: revents = 0x0, took 1257ns 3868584267: sio_revents: revents = 0x0, took 1257ns 3868596768: sio_revents: revents = 0x0, took 1257ns 3868609479: sio_revents: revents = 0x0, took 1258ns 3868621981: sio_revents: revents = 0x0, took 1257ns 3868634692: sio_revents: revents = 0x0, took 1257ns 3868647124: sio_revents: revents = 0x0, took 1187ns 03880861317: clk +194 +0, wr +202 +0 rd: +193 +0 3880859710: sio_revents: revents = 0x1, took 5308ns 3880892815: sio_revents: revents = 0x0, took 1257ns 3898552393: sio_revents: revents = 0x0, took 1397ns 3898566850: sio_revents: revents = 0x0, took 1257ns 3898579771: sio_revents: revents = 0x0, took 1257ns 3898592343: sio_revents: revents = 0x0, took 1187ns 03900910165: clk +195 +0, wr +203 +0 rd: +194 +0 3900908559: sio_revents: revents = 0x1, took 5168ns 3900941943: sio_revents: revents = 0x0, took 1257ns 03920860956: clk +196 +0, wr +204 +0 rd: +195 +0 3920859629: sio_revents: revents = 0x1, took 3492ns 3920890010: sio_revents: revents = 0x0, took 1188ns 3928796323: sio_revents: revents = 0x0, took 1536ns 3928963593: sio_revents: revents = 0x0, took 1536ns 3929079809: sio_revents: revents = 0x0, took 1397ns 3929192044: sio_revents: revents = 0x0, took 1327ns 3929209853: sio_revents: revents = 0x0, took 1257ns 3929222704: sio_revents: revents = 0x0, took 1257ns 3929235555: sio_revents: revents = 0x0, took 1187ns 3929248126: sio_revents: revents = 0x0, took 1257ns 03940904986: clk +197 +0, wr +205 +0 rd: +196 +0 3940903379: sio_revents: revents = 0x1, took 5588ns 3940937252: sio_revents: revents = 0x0, took 1257ns 3958516583: sio_revents: revents = 0x0, took 1606ns 3958532926: sio_revents: revents = 0x0, took 1257ns 3958546265: sio_revents: revents = 0x0, took 1257ns 3958560373: sio_revents: revents = 0x0, took 1257ns 03960862971: clk +198 +0, wr +206 +0 rd: +197 +0 3960861294: sio_revents: revents = 0x1, took 5727ns 3960895726: sio_revents: revents = 0x0, took 1257ns 03980903997: clk +199 +0, wr +207 +0 rd: +198 +0 3980902670: sio_revents: revents = 0x1, took 3422ns 3980932841: sio_revents: revents = 0x0, took 1187ns 3988592824: sio_revents: revents = 0x0, took 1396ns 3988607490: sio_revents: revents = 0x0, took 1257ns 3988620620: sio_revents: revents = 0x0, took 1258ns 3988633192: sio_revents: revents = 0x0, took 1257ns 3988645903: sio_revents: revents = 0x0, took 1257ns 3988658405: sio_revents: revents = 0x0, took 1257ns 3988671325: sio_revents: revents = 0x0, took 1257ns 3988683757: sio_revents: revents = 0x0, took 1257ns 04000861493: clk +200 +0, wr +208 +0 rd: +199 +0 4000859956: sio_revents: revents = 0x1, took 5448ns 4000893550: sio_revents: revents = 0x0, took 1257ns 4018550823: sio_revents: revents = 0x0, took 1327ns 4018565210: sio_revents: revents = 0x0, took 1258ns 4018578061: sio_revents: revents = 0x0, took 1257ns 4018590633: sio_revents: revents = 0x0, took 1187ns 04020912855: clk +201 +0, wr +209 +0 rd: +200 +0 4020911249: sio_revents: revents = 0x1, took 5378ns 4020944773: sio_revents: revents = 0x0, took 1257ns 04040861412: clk +202 +0, wr +210 +0 rd: +201 +0 4040860085: sio_revents: revents = 0x1, took 3282ns 4040890116: sio_revents: revents = 0x0, took 1258ns 4048559388: sio_revents: revents = 0x0, took 1327ns 4048573705: sio_revents: revents = 0x0, took 1257ns 4048586766: sio_revents: revents = 0x0, took 1257ns 4048599337: sio_revents: revents = 0x0, took 1257ns 4048612188: sio_revents: revents = 0x0, took 1257ns 4048624619: sio_revents: revents = 0x0, took 1258ns 4048637261: sio_revents: revents = 0x0, took 1257ns 4048649693: sio_revents: revents = 0x0, took 1257ns 04060921644: clk +203 +0, wr +211 +0 rd: +202 +0 4060920108: sio_revents: revents = 0x1, took 5238ns 4060953282: sio_revents: revents = 0x0, took 1188ns 4078550702: sio_revents: revents = 0x0, took 1396ns 4078565089: sio_revents: revents = 0x0, took 1327ns 4078578149: sio_revents: revents = 0x0, took 1257ns 4078590651: sio_revents: revents = 0x0, took 1257ns 04080862448: clk +204 +0, wr +212 +0 rd: +203 +0 4080860842: sio_revents: revents = 0x1, took 5238ns 4080894226: sio_revents: revents = 0x0, took 1257ns 04100932039: clk +205 +0, wr +213 +0 rd: +204 +0 4100930712: sio_revents: revents = 0x1, took 3423ns 4100960954: sio_revents: revents = 0x0, took 1187ns 4108591184: sio_revents: revents = 0x0, took 1397ns 4108605781: sio_revents: revents = 0x0, took 1257ns 4108618701: sio_revents: revents = 0x0, took 1257ns 4108631273: sio_revents: revents = 0x0, took 1257ns 4108644054: sio_revents: revents = 0x0, took 1187ns 4108656555: sio_revents: revents = 0x0, took 1188ns 4108668987: sio_revents: revents = 0x0, took 1257ns 4108681488: sio_revents: revents = 0x0, took 1188ns 04120862646: clk +206 +0, wr +214 +0 rd: +205 +0 4120861040: sio_revents: revents = 0x1, took 5448ns 4120894494: sio_revents: revents = 0x0, took 1257ns 4138511259: sio_revents: revents = 0x0, took 1258ns 4138524739: sio_revents: revents = 0x0, took 1257ns 4138537799: sio_revents: revents = 0x0, took 1188ns 4138550371: sio_revents: revents = 0x0, took 1187ns 04140934682: clk +207 +0, wr +215 +0 rd: +206 +0 4140933146: sio_revents: revents = 0x1, took 5238ns 4140966600: sio_revents: revents = 0x0, took 1187ns 04160861937: clk +208 +0, wr +216 +0 rd: +207 +0 4160860680: sio_revents: revents = 0x1, took 3282ns 4160890781: sio_revents: revents = 0x0, took 1257ns 4168590573: sio_revents: revents = 0x0, took 1327ns 4168604961: sio_revents: revents = 0x0, took 1257ns 4168617951: sio_revents: revents = 0x0, took 1187ns 4168630523: sio_revents: revents = 0x0, took 1257ns 4168643234: sio_revents: revents = 0x0, took 1257ns 4168655735: sio_revents: revents = 0x0, took 1257ns 4168668446: sio_revents: revents = 0x0, took 1258ns 4168680878: sio_revents: revents = 0x0, took 1257ns 04180937814: clk +209 +0, wr +217 +0 rd: +208 +0 4180936277: sio_revents: revents = 0x1, took 5308ns 4180969592: sio_revents: revents = 0x0, took 1187ns 4198553182: sio_revents: revents = 0x0, took 1397ns 4198612687: sio_revents: revents = 0x0, took 1537ns 4198628052: sio_revents: revents = 0x0, took 1257ns 4198640763: sio_revents: revents = 0x0, took 1327ns 04200862834: clk +210 +0, wr +218 +0 rd: +209 +0 4200861227: sio_revents: revents = 0x1, took 5378ns 4200894891: sio_revents: revents = 0x0, took 1187ns 04220936196: clk +211 +0, wr +219 +0 rd: +210 +0 4220934869: sio_revents: revents = 0x1, took 3283ns 4220964901: sio_revents: revents = 0x0, took 1187ns 4228556649: sio_revents: revents = 0x0, took 1466ns 4228571175: sio_revents: revents = 0x0, took 1258ns 4228584306: sio_revents: revents = 0x0, took 1257ns 4228596877: sio_revents: revents = 0x0, took 1257ns 4228609658: sio_revents: revents = 0x0, took 1187ns 4228622090: sio_revents: revents = 0x0, took 1257ns 4228634801: sio_revents: revents = 0x0, took 1257ns 4228647163: sio_revents: revents = 0x0, took 1257ns 04240861775: clk +212 +0, wr +220 +0 rd: +211 +0 4240860238: sio_revents: revents = 0x1, took 5308ns 4240893553: sio_revents: revents = 0x0, took 1257ns 4258551524: sio_revents: revents = 0x0, took 1397ns 4258565772: sio_revents: revents = 0x0, took 1257ns 4258578763: sio_revents: revents = 0x0, took 1257ns 4258591264: sio_revents: revents = 0x0, took 1257ns 04260937931: clk +213 +0, wr +221 +0 rd: +212 +0 4260936325: sio_revents: revents = 0x1, took 5238ns 4260969709: sio_revents: revents = 0x0, took 1257ns 04280861484: clk +214 +0, wr +222 +0 rd: +213 +0 4280860018: sio_revents: revents = 0x1, took 4120ns 4280891446: sio_revents: revents = 0x0, took 1187ns 4288590191: sio_revents: revents = 0x0, took 1396ns 4288604927: sio_revents: revents = 0x0, took 1257ns 4288618057: sio_revents: revents = 0x0, took 1257ns 4288630698: sio_revents: revents = 0x0, took 1258ns 4288643410: sio_revents: revents = 0x0, took 1257ns 4288656889: sio_revents: revents = 0x0, took 1257ns 4288669530: sio_revents: revents = 0x0, took 1188ns 4288681962: sio_revents: revents = 0x0, took 1257ns 04300946441: clk +215 +0, wr +223 +0 rd: +214 +0 4300944834: sio_revents: revents = 0x1, took 5448ns 4300978288: sio_revents: revents = 0x0, took 1188ns 4318511663: sio_revents: revents = 0x0, took 1327ns 4318525212: sio_revents: revents = 0x0, took 1258ns 4318538063: sio_revents: revents = 0x0, took 1188ns 4318550635: sio_revents: revents = 0x0, took 1187ns 04320862521: clk +216 +0, wr +224 +0 rd: +215 +0 4320860914: sio_revents: revents = 0x1, took 5239ns 4320894368: sio_revents: revents = 0x0, took 1258ns 04340951248: clk +217 +0, wr +225 +0 rd: +216 +0 4340949991: sio_revents: revents = 0x1, took 3213ns 4340980023: sio_revents: revents = 0x0, took 1257ns 4348594120: sio_revents: revents = 0x0, took 1397ns 4348608577: sio_revents: revents = 0x0, took 1257ns 4348621567: sio_revents: revents = 0x0, took 1258ns 4348634209: sio_revents: revents = 0x0, took 1187ns 4348646920: sio_revents: revents = 0x0, took 1257ns 4348659421: sio_revents: revents = 0x0, took 1257ns 4348672133: sio_revents: revents = 0x0, took 1257ns 4348684634: sio_revents: revents = 0x0, took 1257ns 04360862091: clk +218 +0, wr +226 +0 rd: +217 +0 4360860484: sio_revents: revents = 0x1, took 5308ns 4360893798: sio_revents: revents = 0x0, took 1258ns 4378550932: sio_revents: revents = 0x0, took 1397ns 4378565389: sio_revents: revents = 0x0, took 1257ns 4378578380: sio_revents: revents = 0x0, took 1257ns 4378590951: sio_revents: revents = 0x0, took 1188ns 04380955567: clk +219 +0, wr +227 +0 rd: +218 +0 4380954031: sio_revents: revents = 0x1, took 5238ns 4380987555: sio_revents: revents = 0x0, took 1257ns 04400861591: clk +220 +0, wr +228 +0 rd: +219 +0 4400860263: sio_revents: revents = 0x1, took 3283ns 4400890295: sio_revents: revents = 0x0, took 1187ns 4408560614: sio_revents: revents = 0x0, took 1397ns 4408576398: sio_revents: revents = 0x0, took 1258ns 4408589459: sio_revents: revents = 0x0, took 1257ns 4408602100: sio_revents: revents = 0x0, took 1257ns 4408614881: sio_revents: revents = 0x0, took 1257ns 4408627382: sio_revents: revents = 0x0, took 1258ns 4408640094: sio_revents: revents = 0x0, took 1257ns 4408652595: sio_revents: revents = 0x0, took 1257ns 04420965963: clk +221 +0, wr +229 +0 rd: +220 +0 4420964426: sio_revents: revents = 0x1, took 5168ns 4420997461: sio_revents: revents = 0x0, took 1257ns 4438550601: sio_revents: revents = 0x0, took 1397ns 4438564849: sio_revents: revents = 0x0, took 1257ns 4438577909: sio_revents: revents = 0x0, took 1257ns 4438590411: sio_revents: revents = 0x0, took 1257ns 04440863256: clk +222 +0, wr +230 +0 rd: +221 +0 4440861719: sio_revents: revents = 0x1, took 5098ns 4440895033: sio_revents: revents = 0x0, took 1257ns 04460972098: clk +223 +0, wr +231 +0 rd: +222 +0 4460970561: sio_revents: revents = 0x1, took 4819ns 4461003177: sio_revents: revents = 0x0, took 1257ns 4468569712: sio_revents: revents = 0x0, took 1467ns 4468584378: sio_revents: revents = 0x0, took 1257ns 4468597439: sio_revents: revents = 0x0, took 1257ns 4468610010: sio_revents: revents = 0x0, took 1257ns 4468622721: sio_revents: revents = 0x0, took 1257ns 4468635223: sio_revents: revents = 0x0, took 1257ns 4468648004: sio_revents: revents = 0x0, took 1187ns 4468660436: sio_revents: revents = 0x0, took 1257ns 04480862686: clk +224 +0, wr +232 +0 rd: +223 +0 4480861149: sio_revents: revents = 0x1, took 5238ns 4480894393: sio_revents: revents = 0x0, took 1258ns 4498552016: sio_revents: revents = 0x0, took 1397ns 4498566194: sio_revents: revents = 0x0, took 1257ns 4498579045: sio_revents: revents = 0x0, took 1257ns 4498591546: sio_revents: revents = 0x0, took 1257ns 04500968734: clk +225 +0, wr +233 +0 rd: +224 +0 4500967197: sio_revents: revents = 0x1, took 5169ns 4501000372: sio_revents: revents = 0x0, took 1257ns 04520861697: clk +226 +0, wr +234 +0 rd: +225 +0 4520860370: sio_revents: revents = 0x1, took 4260ns 4520891728: sio_revents: revents = 0x0, took 1258ns 4528590682: sio_revents: revents = 0x0, took 1327ns 4528605209: sio_revents: revents = 0x0, took 1257ns 4528618200: sio_revents: revents = 0x0, took 1187ns 4528630771: sio_revents: revents = 0x0, took 1188ns 4528643482: sio_revents: revents = 0x0, took 1258ns 4528655984: sio_revents: revents = 0x0, took 1257ns 4528668555: sio_revents: revents = 0x0, took 1257ns 4528680917: sio_revents: revents = 0x0, took 1257ns 04540966628: clk +227 +0, wr +235 +0 rd: +226 +0 4540965091: sio_revents: revents = 0x1, took 5168ns 4540998126: sio_revents: revents = 0x0, took 1257ns 4558512155: sio_revents: revents = 0x0, took 1327ns 4558525704: sio_revents: revents = 0x0, took 1257ns 4558538695: sio_revents: revents = 0x0, took 1326ns 4558551266: sio_revents: revents = 0x0, took 1257ns 04560863362: clk +228 +0, wr +236 +0 rd: +227 +0 4560861825: sio_revents: revents = 0x1, took 5168ns 4560894930: sio_revents: revents = 0x0, took 1257ns 04580964242: clk +229 +0, wr +237 +0 rd: +228 +0 4580962915: sio_revents: revents = 0x1, took 3282ns 4580993016: sio_revents: revents = 0x0, took 1188ns 4588591888: sio_revents: revents = 0x0, took 1396ns 4588606275: sio_revents: revents = 0x0, took 1257ns 4588619335: sio_revents: revents = 0x0, took 1257ns 4588631837: sio_revents: revents = 0x0, took 1187ns 4588644478: sio_revents: revents = 0x0, took 1257ns 4588656980: sio_revents: revents = 0x0, took 1257ns 4588669831: sio_revents: revents = 0x0, took 1187ns 4588682262: sio_revents: revents = 0x0, took 1257ns 04600862093: clk +230 +0, wr +238 +0 rd: +229 +0 4600860557: sio_revents: revents = 0x1, took 5238ns 4600893592: sio_revents: revents = 0x0, took 1187ns 4618551633: sio_revents: revents = 0x0, took 1397ns 4618566160: sio_revents: revents = 0x0, took 1257ns 4618579011: sio_revents: revents = 0x0, took 1257ns 4618591583: sio_revents: revents = 0x0, took 1257ns 04620962345: clk +231 +0, wr +239 +0 rd: +230 +0 4620960808: sio_revents: revents = 0x1, took 24375ns 4621013958: sio_revents: revents = 0x0, took 1257ns 04640861663: clk +232 +0, wr +240 +0 rd: +231 +0 4640860336: sio_revents: revents = 0x1, took 3422ns 4640890647: sio_revents: revents = 0x0, took 1118ns 4648557544: sio_revents: revents = 0x0, took 1467ns 4648572141: sio_revents: revents = 0x0, took 1187ns 4648585201: sio_revents: revents = 0x0, took 1257ns 4648597912: sio_revents: revents = 0x0, took 1187ns 4648610554: sio_revents: revents = 0x0, took 1257ns 4648623125: sio_revents: revents = 0x0, took 1257ns 4648635836: sio_revents: revents = 0x0, took 1257ns 4648648338: sio_revents: revents = 0x0, took 1257ns 04660955908: clk +233 +0, wr +241 +0 rd: +232 +0 4660954302: sio_revents: revents = 0x1, took 5308ns 4660987337: sio_revents: revents = 0x0, took 1257ns 4678552699: sio_revents: revents = 0x0, took 1397ns 4678567017: sio_revents: revents = 0x0, took 1257ns 4678580007: sio_revents: revents = 0x0, took 1257ns 4678592439: sio_revents: revents = 0x0, took 1257ns 04680863398: clk +234 +0, wr +242 +0 rd: +233 +0 4680861861: sio_revents: revents = 0x1, took 5239ns 4680895385: sio_revents: revents = 0x0, took 1258ns 04700946049: clk +235 +0, wr +243 +0 rd: +234 +0 4700944792: sio_revents: revents = 0x1, took 3283ns 4700975034: sio_revents: revents = 0x0, took 1187ns 4708575511: sio_revents: revents = 0x0, took 1397ns 4708590108: sio_revents: revents = 0x0, took 1257ns 4708603168: sio_revents: revents = 0x0, took 1258ns 4708615810: sio_revents: revents = 0x0, took 1257ns 4708628661: sio_revents: revents = 0x0, took 1257ns 4708641232: sio_revents: revents = 0x0, took 1257ns 4708653873: sio_revents: revents = 0x0, took 1257ns 4708666375: sio_revents: revents = 0x0, took 1187ns 04720863666: clk +236 +0, wr +244 +0 rd: +235 +0 4720862060: sio_revents: revents = 0x1, took 5936ns 4720896491: sio_revents: revents = 0x0, took 1258ns 4738535676: sio_revents: revents = 0x0, took 1467ns 4738549784: sio_revents: revents = 0x0, took 1257ns 4738565219: sio_revents: revents = 0x0, took 1257ns 4738577860: sio_revents: revents = 0x0, took 1257ns 04740933258: clk +237 +0, wr +245 +0 rd: +236 +0 4740931651: sio_revents: revents = 0x1, took 5308ns 4740965175: sio_revents: revents = 0x0, took 1257ns 04760862607: clk +238 +0, wr +246 +0 rd: +237 +0 4760861350: sio_revents: revents = 0x1, took 3283ns 4760891592: sio_revents: revents = 0x0, took 1187ns 4768592710: sio_revents: revents = 0x0, took 1397ns 4768607237: sio_revents: revents = 0x0, took 1258ns 4768620298: sio_revents: revents = 0x0, took 1257ns 4768632939: sio_revents: revents = 0x0, took 1257ns 4768645650: sio_revents: revents = 0x0, took 1257ns 4768658222: sio_revents: revents = 0x0, took 1257ns 4768670863: sio_revents: revents = 0x0, took 1257ns 4768683365: sio_revents: revents = 0x0, took 1257ns 04780924446: clk +239 +0, wr +247 +0 rd: +238 +0 4780922910: sio_revents: revents = 0x1, took 5308ns 4780956224: sio_revents: revents = 0x0, took 1257ns 4798553574: sio_revents: revents = 0x0, took 1466ns 4798568729: sio_revents: revents = 0x0, took 1257ns 4798581789: sio_revents: revents = 0x0, took 1257ns 4798594361: sio_revents: revents = 0x0, took 1257ns 04800863644: clk +240 +0, wr +248 +0 rd: +239 +0 4800862107: sio_revents: revents = 0x1, took 5238ns 4800895492: sio_revents: revents = 0x0, took 1187ns 04820920105: clk +241 +0, wr +249 +0 rd: +240 +0 4820918778: sio_revents: revents = 0x1, took 3492ns 4820949229: sio_revents: revents = 0x0, took 1257ns 4828559065: sio_revents: revents = 0x0, took 1257ns 4828573452: sio_revents: revents = 0x0, took 1257ns 4828586373: sio_revents: revents = 0x0, took 1327ns 4828598945: sio_revents: revents = 0x0, took 1257ns 4828611795: sio_revents: revents = 0x0, took 1258ns 4828624297: sio_revents: revents = 0x0, took 1257ns 4828637008: sio_revents: revents = 0x0, took 1257ns 4828649649: sio_revents: revents = 0x0, took 1257ns 04840862515: clk +242 +0, wr +250 +0 rd: +241 +0 4840860909: sio_revents: revents = 0x1, took 6635ns 4840895550: sio_revents: revents = 0x0, took 1257ns 4858551566: sio_revents: revents = 0x0, took 1397ns 4858565884: sio_revents: revents = 0x0, took 1257ns 4858578874: sio_revents: revents = 0x0, took 1257ns 4858591236: sio_revents: revents = 0x0, took 1188ns 04860911992: clk +243 +0, wr +251 +0 rd: +242 +0 4860910386: sio_revents: revents = 0x1, took 5168ns 4860943630: sio_revents: revents = 0x0, took 1187ns 04880862015: clk +244 +0, wr +252 +0 rd: +243 +0 4880860758: sio_revents: revents = 0x1, took 3213ns 4880890720: sio_revents: revents = 0x0, took 1257ns 4888564391: sio_revents: revents = 0x0, took 1397ns 4888580734: sio_revents: revents = 0x0, took 1257ns 4888593725: sio_revents: revents = 0x0, took 1257ns 4888606226: sio_revents: revents = 0x0, took 1257ns 4888619077: sio_revents: revents = 0x0, took 1257ns 4888631648: sio_revents: revents = 0x0, took 1258ns 4888644290: sio_revents: revents = 0x0, took 1257ns 4888656791: sio_revents: revents = 0x0, took 1257ns 04900911841: clk +245 +0, wr +253 +0 rd: +244 +0 4900910305: sio_revents: revents = 0x1, took 5308ns 4900943479: sio_revents: revents = 0x0, took 1257ns 4918553330: sio_revents: revents = 0x0, took 1397ns 4918567788: sio_revents: revents = 0x0, took 1257ns 4918580708: sio_revents: revents = 0x0, took 1257ns 4918593210: sio_revents: revents = 0x0, took 1257ns 04920863680: clk +246 +0, wr +254 +0 rd: +245 +0 4920862074: sio_revents: revents = 0x1, took 5308ns 4920895667: sio_revents: revents = 0x0, took 1257ns 04940910922: clk +247 +0, wr +255 +0 rd: +246 +0 4940909595: sio_revents: revents = 0x1, took 3353ns 4940939837: sio_revents: revents = 0x0, took 1187ns 4948597933: sio_revents: revents = 0x0, took 1397ns 4948612530: sio_revents: revents = 0x0, took 1257ns 4948625590: sio_revents: revents = 0x0, took 1258ns 4948638162: sio_revents: revents = 0x0, took 1257ns 4948650873: sio_revents: revents = 0x0, took 1257ns 4948663514: sio_revents: revents = 0x0, took 1187ns 4948677203: sio_revents: revents = 0x0, took 1187ns 4948689705: sio_revents: revents = 0x0, took 1257ns 04960862272: clk +248 +0, wr +256 +0 rd: +247 +0 4960860736: sio_revents: revents = 0x1, took 5307ns 4960893980: sio_revents: revents = 0x0, took 1257ns 4978551672: sio_revents: revents = 0x0, took 1397ns 4978611037: sio_revents: revents = 0x0, took 1537ns 4978626263: sio_revents: revents = 0x0, took 1257ns 4978638904: sio_revents: revents = 0x0, took 1257ns 04980909444: clk +249 +0, wr +257 +0 rd: +248 +0 4980907908: sio_revents: revents = 0x1, took 5378ns 4980941641: sio_revents: revents = 0x0, took 1257ns 05000862959: clk +250 +0, wr +258 +0 rd: +249 +0 5000861632: sio_revents: revents = 0x1, took 3353ns 5000891734: sio_revents: revents = 0x0, took 1257ns 5008558491: sio_revents: revents = 0x0, took 1397ns 5008573088: sio_revents: revents = 0x0, took 1257ns 5008586218: sio_revents: revents = 0x0, took 1257ns 5008598859: sio_revents: revents = 0x0, took 1257ns 5008611710: sio_revents: revents = 0x0, took 1257ns 5008624282: sio_revents: revents = 0x0, took 1257ns 5008636923: sio_revents: revents = 0x0, took 1257ns 5008649354: sio_revents: revents = 0x0, took 1258ns 05020907617: clk +251 +0, wr +259 +0 rd: +250 +0 5020906081: sio_revents: revents = 0x1, took 5308ns 5020939535: sio_revents: revents = 0x0, took 1187ns 5038552179: sio_revents: revents = 0x0, took 1327ns 5038566427: sio_revents: revents = 0x0, took 1257ns 5038579348: sio_revents: revents = 0x0, took 1257ns 5038591989: sio_revents: revents = 0x0, took 1257ns 05040863158: clk +252 +0, wr +260 +0 rd: +251 +0 5040861621: sio_revents: revents = 0x1, took 5308ns 5040895215: sio_revents: revents = 0x0, took 1187ns 05060906628: clk +253 +0, wr +261 +0 rd: +252 +0 5060905371: sio_revents: revents = 0x1, took 3283ns 5060935403: sio_revents: revents = 0x0, took 1187ns 5068562281: sio_revents: revents = 0x0, took 1396ns 5068576808: sio_revents: revents = 0x0, took 1257ns 5068589798: sio_revents: revents = 0x0, took 1257ns 5068603696: sio_revents: revents = 0x0, took 1258ns 5068616477: sio_revents: revents = 0x0, took 1188ns 5068628979: sio_revents: revents = 0x0, took 1187ns 5068641690: sio_revents: revents = 0x0, took 1187ns 5068654052: sio_revents: revents = 0x0, took 1257ns 05080862518: clk +254 +0, wr +262 +0 rd: +253 +0 5080860981: sio_revents: revents = 0x1, took 5239ns 5080894156: sio_revents: revents = 0x0, took 1187ns 5098553385: sio_revents: revents = 0x0, took 1397ns 5098567563: sio_revents: revents = 0x0, took 1257ns 5098580483: sio_revents: revents = 0x0, took 1258ns 5098593055: sio_revents: revents = 0x0, took 1257ns 05100908503: clk +255 +0, wr +263 +0 rd: +254 +0 5100906966: sio_revents: revents = 0x1, took 5308ns 5100940490: sio_revents: revents = 0x0, took 1187ns 05120862926: clk +256 +0, wr +264 +0 rd: +255 +0 5120861599: sio_revents: revents = 0x1, took 3352ns 5120891840: sio_revents: revents = 0x0, took 1188ns 5128592680: sio_revents: revents = 0x0, took 1397ns 5128607207: sio_revents: revents = 0x0, took 1257ns 5128620197: sio_revents: revents = 0x0, took 1257ns 5128632699: sio_revents: revents = 0x0, took 1257ns 5128645550: sio_revents: revents = 0x0, took 1257ns 5128658051: sio_revents: revents = 0x0, took 1257ns 5128670902: sio_revents: revents = 0x0, took 1257ns 5128683403: sio_revents: revents = 0x0, took 1258ns 05140908981: clk +257 +0, wr +265 +0 rd: +256 +0 5140907444: sio_revents: revents = 0x1, took 5308ns 5140940688: sio_revents: revents = 0x0, took 1258ns 5158513594: sio_revents: revents = 0x0, took 1327ns 5158527003: sio_revents: revents = 0x0, took 1257ns 5158539854: sio_revents: revents = 0x0, took 1257ns 5158552425: sio_revents: revents = 0x0, took 1257ns 05160863823: clk +258 +0, wr +266 +0 rd: +257 +0 5160862356: sio_revents: revents = 0x1, took 5098ns 5160895391: sio_revents: revents = 0x0, took 1257ns 05180898633: clk +259 +0, wr +267 +0 rd: +258 +0 5180897306: sio_revents: revents = 0x1, took 3213ns 5180927338: sio_revents: revents = 0x0, took 1257ns 5188595142: sio_revents: revents = 0x0, took 1397ns 5188609739: sio_revents: revents = 0x0, took 1257ns 5188622869: sio_revents: revents = 0x0, took 1257ns 5188635441: sio_revents: revents = 0x0, took 1257ns 5188648292: sio_revents: revents = 0x0, took 1257ns 5188660723: sio_revents: revents = 0x0, took 1257ns 5188673434: sio_revents: revents = 0x0, took 1257ns 5188685936: sio_revents: revents = 0x0, took 1257ns 05200862694: clk +260 +0, wr +268 +0 rd: +259 +0 5200861158: sio_revents: revents = 0x1, took 5237ns 5200894262: sio_revents: revents = 0x0, took 1257ns 5218552513: sio_revents: revents = 0x0, took 1397ns 5218566691: sio_revents: revents = 0x0, took 1257ns 5218579681: sio_revents: revents = 0x0, took 1258ns 5218592253: sio_revents: revents = 0x0, took 1257ns 05220889542: clk +261 +0, wr +269 +0 rd: +260 +0 5220888006: sio_revents: revents = 0x1, took 5308ns 5220922228: sio_revents: revents = 0x0, took 1257ns 05240863242: clk +262 +0, wr +270 +0 rd: +261 +0 5240861705: sio_revents: revents = 0x1, took 4749ns 5240894181: sio_revents: revents = 0x0, took 1188ns 5248561776: sio_revents: revents = 0x0, took 1397ns 5248576094: sio_revents: revents = 0x0, took 1257ns 5248589154: sio_revents: revents = 0x0, took 1257ns 5248601726: sio_revents: revents = 0x0, took 1257ns 5248614506: sio_revents: revents = 0x0, took 1328ns 5248627148: sio_revents: revents = 0x0, took 1257ns 5248639859: sio_revents: revents = 0x0, took 1187ns 5248652291: sio_revents: revents = 0x0, took 1257ns 05260882966: clk +263 +0, wr +271 +0 rd: +262 +0 5260881360: sio_revents: revents = 0x1, took 5308ns 5260914604: sio_revents: revents = 0x0, took 1257ns 5278556652: sio_revents: revents = 0x0, took 1397ns 5278571109: sio_revents: revents = 0x0, took 1257ns 5278584100: sio_revents: revents = 0x0, took 1257ns 5278596601: sio_revents: revents = 0x0, took 1257ns 05280863230: clk +264 +0, wr +272 +0 rd: +263 +0 5280861694: sio_revents: revents = 0x1, took 5168ns 5280896265: sio_revents: revents = 0x0, took 1257ns 05300879044: clk +265 +0, wr +273 +0 rd: +264 +0 5300877577: sio_revents: revents = 0x1, took 3981ns 5300908866: sio_revents: revents = 0x0, took 1187ns 5308595947: sio_revents: revents = 0x0, took 1466ns 5308610614: sio_revents: revents = 0x0, took 1257ns 5308623604: sio_revents: revents = 0x0, took 1257ns 5308636176: sio_revents: revents = 0x0, took 1257ns 5308648887: sio_revents: revents = 0x0, took 1257ns 5308661458: sio_revents: revents = 0x0, took 1257ns 5308674169: sio_revents: revents = 0x0, took 1257ns 5308686531: sio_revents: revents = 0x0, took 1257ns 05320862870: clk +266 +0, wr +274 +0 rd: +265 +0 5320861333: sio_revents: revents = 0x1, took 5308ns 5320894718: sio_revents: revents = 0x0, took 1257ns 5338514207: sio_revents: revents = 0x0, took 1327ns 5338527686: sio_revents: revents = 0x0, took 1327ns 5338540537: sio_revents: revents = 0x0, took 1327ns 5338553178: sio_revents: revents = 0x0, took 1257ns 05340885318: clk +267 +0, wr +275 +0 rd: +266 +0 5340883782: sio_revents: revents = 0x1, took 5168ns 5340916956: sio_revents: revents = 0x0, took 1397ns 05360862300: clk +268 +0, wr +276 +0 rd: +267 +0 5360861043: sio_revents: revents = 0x1, took 3283ns 5360891843: sio_revents: revents = 0x0, took 1187ns 5368593590: sio_revents: revents = 0x0, took 1397ns 5368607978: sio_revents: revents = 0x0, took 1257ns 5368620968: sio_revents: revents = 0x0, took 1257ns 5368633470: sio_revents: revents = 0x0, took 1257ns 5368646321: sio_revents: revents = 0x0, took 1187ns 5368658752: sio_revents: revents = 0x0, took 1258ns 5368671463: sio_revents: revents = 0x0, took 1258ns 5368683895: sio_revents: revents = 0x0, took 1257ns 05380890964: clk +269 +0, wr +277 +0 rd: +268 +0 5380889428: sio_revents: revents = 0x1, took 5238ns 5380922323: sio_revents: revents = 0x0, took 1257ns 5398554454: sio_revents: revents = 0x0, took 1396ns 5398568771: sio_revents: revents = 0x0, took 1257ns 5398581831: sio_revents: revents = 0x0, took 1257ns 5398595520: sio_revents: revents = 0x0, took 1397ns 05400864035: clk +270 +0, wr +278 +0 rd: +269 +0 5400862498: sio_revents: revents = 0x1, took 5099ns 5400895673: sio_revents: revents = 0x0, took 1257ns 05420892071: clk +271 +0, wr +279 +0 rd: +270 +0 5420890744: sio_revents: revents = 0x1, took 3352ns 5420921544: sio_revents: revents = 0x0, took 1257ns 5428562529: sio_revents: revents = 0x0, took 1397ns 5428576986: sio_revents: revents = 0x0, took 1257ns 5428589837: sio_revents: revents = 0x0, took 1257ns 5428602478: sio_revents: revents = 0x0, took 1258ns 5428615190: sio_revents: revents = 0x0, took 1257ns 5428627761: sio_revents: revents = 0x0, took 1187ns 5428640472: sio_revents: revents = 0x0, took 1257ns 5428652834: sio_revents: revents = 0x0, took 1257ns 05440863465: clk +272 +0, wr +280 +0 rd: +271 +0 5440861928: sio_revents: revents = 0x1, took 5308ns 5440895103: sio_revents: revents = 0x0, took 1187ns 5458553284: sio_revents: revents = 0x0, took 1397ns 5458567462: sio_revents: revents = 0x0, took 1257ns 5458580453: sio_revents: revents = 0x0, took 1257ns 5458593024: sio_revents: revents = 0x0, took 1257ns 05460891221: clk +273 +0, wr +281 +0 rd: +272 +0 5460889755: sio_revents: revents = 0x1, took 5168ns 5460923069: sio_revents: revents = 0x0, took 1257ns 05480862336: clk +274 +0, wr +282 +0 rd: +273 +0 5480861079: sio_revents: revents = 0x1, took 3283ns 5480891181: sio_revents: revents = 0x0, took 1187ns 5488560592: sio_revents: revents = 0x0, took 1397ns 5488574840: sio_revents: revents = 0x0, took 1187ns 5488587830: sio_revents: revents = 0x0, took 1187ns 5488600262: sio_revents: revents = 0x0, took 1257ns 5488613043: sio_revents: revents = 0x0, took 1257ns 5488625544: sio_revents: revents = 0x0, took 1187ns 5488638116: sio_revents: revents = 0x0, took 1257ns 5488650827: sio_revents: revents = 0x0, took 1257ns 05500892258: clk +275 +0, wr +283 +0 rd: +274 +0 5500890582: sio_revents: revents = 0x1, took 6006ns 5500924874: sio_revents: revents = 0x0, took 1257ns 5518554001: sio_revents: revents = 0x0, took 1397ns 5518568668: sio_revents: revents = 0x0, took 1257ns 5518581798: sio_revents: revents = 0x0, took 1257ns 5518594299: sio_revents: revents = 0x0, took 1257ns 05520863582: clk +276 +0, wr +284 +0 rd: +275 +0 5520861906: sio_revents: revents = 0x1, took 5378ns 5520895430: sio_revents: revents = 0x0, took 1257ns 05540898113: clk +277 +0, wr +285 +0 rd: +276 +0 5540896786: sio_revents: revents = 0x1, took 3422ns 5540926958: sio_revents: revents = 0x0, took 1187ns 5548595042: sio_revents: revents = 0x0, took 1397ns 5548625004: sio_revents: revents = 0x0, took 1257ns 5548638553: sio_revents: revents = 0x0, took 1187ns 5548651124: sio_revents: revents = 0x0, took 1258ns 5548663905: sio_revents: revents = 0x0, took 1257ns 5548676407: sio_revents: revents = 0x0, took 1257ns 5548688978: sio_revents: revents = 0x0, took 1257ns 5548701340: sio_revents: revents = 0x0, took 1257ns 05560863012: clk +278 +0, wr +286 +0 rd: +277 +0 5560861476: sio_revents: revents = 0x1, took 5308ns 5560894790: sio_revents: revents = 0x0, took 1257ns 5578555626: sio_revents: revents = 0x0, took 1396ns 5578569943: sio_revents: revents = 0x0, took 1257ns 5578582933: sio_revents: revents = 0x0, took 1258ns 5578595505: sio_revents: revents = 0x0, took 1187ns 05580903899: clk +279 +0, wr +287 +0 rd: +278 +0 5580902293: sio_revents: revents = 0x1, took 5377ns 5580935816: sio_revents: revents = 0x0, took 1257ns 05600863700: clk +280 +0, wr +288 +0 rd: +279 +0 5600862303: sio_revents: revents = 0x1, took 4749ns 5600894849: sio_revents: revents = 0x0, took 1257ns 5608561746: sio_revents: revents = 0x0, took 1396ns 5608576063: sio_revents: revents = 0x0, took 1257ns 5608589054: sio_revents: revents = 0x0, took 1327ns 5608601625: sio_revents: revents = 0x0, took 1257ns 5608614336: sio_revents: revents = 0x0, took 1257ns 5608626908: sio_revents: revents = 0x0, took 1257ns 5608639619: sio_revents: revents = 0x0, took 1257ns 5608652120: sio_revents: revents = 0x0, took 1257ns 5609293752: sio_revents: revents = 0x0, took 1537ns mplayer0 vol=127,pst=run,mmc=off,rmsg,widl: STOP message mplayer0 vol=127,pst=run,mmc=off: stopping 05620909894: clk +281 +0, wr +289 +0 rd: +280 +0 5620908288: sio_revents: revents = 0x1, took 5657ns 05640863060: clk +282 +0, wr +290 +0 rd: +281 +0 5640861523: sio_revents: revents = 0x1, took 4680ns 05660916658: clk +283 +0, wr +291 +0 rd: +282 +0 5660915331: sio_revents: revents = 0x1, took 3352ns 05680863608: clk +284 +0, wr +292 +0 rd: +283 +0 5680862141: sio_revents: revents = 0x1, took 4889ns 05700925935: clk +285 +0, wr +293 +0 rd: +284 +0 5700924678: sio_revents: revents = 0x1, took 3283ns 05720863457: clk +286 +0, wr +294 +0 rd: +285 +0 5720862199: sio_revents: revents = 0x1, took 3143ns 05740926134: clk +287 +0, wr +295 +0 rd: +286 +0 5740924877: sio_revents: revents = 0x1, took 3282ns 05760863934: clk +288 +0, wr +296 +0 rd: +287 +0 5760862328: sio_revents: revents = 0x1, took 5028ns 05780930173: clk +289 +0, wr +297 +0 rd: +288 +0 5780928916: sio_revents: revents = 0x1, took 3213ns 05800862945: clk +290 +0, wr +298 +0 rd: +289 +0 5800861618: sio_revents: revents = 0x1, took 3353ns 05820933654: clk +291 +0, wr +299 +0 rd: +290 +0 5820932257: sio_revents: revents = 0x1, took 3842ns 05840863493: clk +292 +0, wr +300 +0 rd: +291 +0 5840862236: sio_revents: revents = 0x1, took 3282ns 05860936297: clk +293 +0, wr +301 +0 rd: +292 +0 5860935110: sio_revents: revents = 0x1, took 3073ns 05880862853: clk +294 +0, wr +302 +0 rd: +293 +0 5880861596: sio_revents: revents = 0x1, took 3143ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: stopped 5880875075: sio_revents: revents = 0x0, took 1258ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: building STOP message 5880896098: sio_revents: revents = 0x0, took 1327ns mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: BYE message mplayer0 vol=127,pst=ini,mmc=off,rmsg,widl: closing snd0 pst=run: device released sock(sock|zom): destroyed 05900944457: clk +295 +0, wr +303 +0 rd: +294 +0 5900942781: sio_revents: revents = 0x1, took 6425ns libsndio: polls: 1592, samples = 283200 snd0 pst=run: device stopped snd0 pst=run: stopped, load avg = 23604 / 5877349 snd0 pst=ini: closing snd0 pst=cfg: closed sio(rsnd/0|zom): destroyed listen(/tmp/aucat-1000/aucat0|zom): destroyed snd0 pst=cfg: draining nothing to do... snd0 pst=cfg: deleting
