Skip to content
This repository was archived by the owner on Sep 20, 2024. It is now read-only.

Comments

Added improvements for 0x525A and interrupt map#4

Open
syscl wants to merge 17 commits intophilmb3487:masterfrom
syscl:master
Open

Added improvements for 0x525A and interrupt map#4
syscl wants to merge 17 commits intophilmb3487:masterfrom
syscl:master

Conversation

@syscl
Copy link
Contributor

@syscl syscl commented Feb 9, 2018

No description provided.

@Sniki
Copy link

Sniki commented Feb 9, 2018

@syscl i tried to build the kext with your recent changes, i installed the kext but as soon as i plug in the SD Card, after like 5 sec the system crashes (Reboots instantly).

Im running macOS High Sierra 10.13.3

My SD Card Reader is 10EC 5227 ; Lenovo Thinkpad L440.

With previous kext from Sinetek, there was no power management but it worked as long as i didn't let laptop to sleep.

If you need more details, let me know what kind of logs you need to see what's going on with it

Thank you !

@syscl
Copy link
Contributor Author

syscl commented Feb 9, 2018

Thank you! Please provide logs with my maclog.

Would you mind trying my latest prebuilt driver?
If yes please post an issue in my GitHub:)

Thank you in advance,
syscl

@Sniki
Copy link

Sniki commented Feb 9, 2018

@syscl forgive for this but im new to Github and im still not familiar, at your fork of Sinetek-rtsx there is no issues tab
could you please link me where to post or just write it shortly for me here
Here is the log that i attached which i collected with your maclog,
You can start looking into it from 7:17PM today which is where i rebooted after i rebuilt caches that i installed your prebuilt kext.

Logs Sinetek-rtsx.txt

@syscl
Copy link
Contributor Author

syscl commented Feb 9, 2018

Sure @BesnikRrustemi , please post it here: https://github.com/syscl/Sinetek-rtsx/issues

Click the New issue tap to create a new issue.

I am looking in your log and will upload test drivers for you once you created a new issue tab.

syscl

@philmb3487
Copy link
Owner

philmb3487 commented Feb 18, 2018

Thanks for your work trying to untangle this mess @syscl. As said the logic in my driver might be wrong, and this is not made better by the fact there are not really any publicly available datasheets. There is one blog in Chinese language with more information about what the various control register bits do, might be worthwhile looking at that, but I couldn't find a leaked datasheet either.

The code is wrong..

struct rtsx_softc : public IOPCIDevice

This structure has to be a IOService, it will attach to a parent IOPCIDevice nub, instead of being an IOPCIDevice nub itself. If you change that, it should stop crashing so much during power management init and deinit.

There's 2 ways to detect the card.. the first will fire off an interrupt, and then the second way would be to have a timer call itself and have a state machine ... waiting for card -> card inserted not init -> card inited, commands coming -> card removing -> etc etc.
The NetBSD or OpenBSD driver's approach is the first one. BUT:

Another issue is that the IOKit model is single threaded, meaning that the interrupt will wait for the workloop to be released. This is problematic because when the code is waiting for an interrupt (a command has been sent to the microchip on the PCIe bus, then waiting for signal back), the Workloop lock is already being held by the driver (either the interrupt that gave us the signal to init the card, or the timer polling for a card being there).

In BSD terms : tsleep() will put the thread to sleep waiting for a wakeup()
In IOKit terms: we can't tsleep() the thread (Workloop in IOKit terms) too easily, so have to find another workaround for that.

Hope it helps.

tsleep() is actually available and you can use it, it's just if doing that you'll have to capture the interrupt without going through the IOKit workloop stuff at all, and make damn sure your threads don't crash the machine. I think this is what I did in this version of the driver before I tried doing it in pure IOKit

@syscl
Copy link
Contributor Author

syscl commented Feb 19, 2018

Thank you so much for you pointing out the inherence issue, I have then changed the rtsx_softc from IOPCIDevice to IOService to stop the kernel panic during card insert detect.

One more step forward, the requestPowerDomainState() is not the correct member function for this driver, so in commits v15, I tried and figured out the right way to power up the sd-card, that is PMinit() and registerPowerDriver(). Yes, with the latest commit, now the detection of sd-card insertion work! But system cannot identify the correct parameters for cards, in other words, the errors now are more likely caused by rtsx_card_insert(), which I will dig into it next (any ideas @sinetek ?).

Anyway, I am really happy that the driver now will not cause panic when card get inserted, and the log when card insert is as follow

2018-02-18 22:17:16.290753-0600  localhost kernel[0]: rtsx_softc::probe(PXSX)
2018-02-18 22:17:16.290764-0600  localhost kernel[0]: rtsx_softc::start(PXSX) <1>
2018-02-18 22:17:16.290847-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx_attach() ===>
2018-02-18 22:17:16.291445-0600  localhost kernel[0]: (Sinetek-rtsx) : 4-bit
2018-02-18 22:17:16.291452-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: STAILQ_INIT ===>
2018-02-18 22:17:16.291454-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: STAILQ_INIT <===
2018-02-18 22:17:16.291457-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: TAILQ_INIT: tskq ===>
2018-02-18 22:17:16.291459-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: TAILQ_INIT: tskq <===
2018-02-18 22:17:16.291461-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: TAILQ_INIT: sc_intrq ===>
2018-02-18 22:17:16.291463-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: TAILQ_INIT: sc_intrq <===
2018-02-18 22:17:16.291465-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: sdmmc_init_task ===>
2018-02-18 22:17:16.291467-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: sdmmc_init_task <===
2018-02-18 22:17:16.291469-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: sc->flags ===>
2018-02-18 22:17:16.291471-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach: sc_flags <===
2018-02-18 22:17:16.291473-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc_attach() <===
2018-02-18 22:17:16.291475-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx_attach() sc->flags, RTSX_F_CARD_PRESENT ===>
2018-02-18 22:17:16.291478-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx_attach() sc->flags, RTSX_F_CARD_PRESENT <===
2018-02-18 22:17:16.291479-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx_attach() <===
2018-02-18 22:17:21.717624-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx_card_insert()  ===>
2018-02-18 22:17:21.917673-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: attach card
2018-02-18 22:17:23.169888-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 52 arg=0x80000c08 data=0x0 dlen=0 flags=0x1c01 proc="" (error 60)
2018-02-18 22:17:24.170451-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 5 arg=0 data=0x0 dlen=0 flags=0x1031 proc="" (error 60)
2018-02-18 22:17:24.171402-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 0 arg=0 data=0x0 dlen=0 flags=0x21 proc="" (error 0)
2018-02-18 22:17:24.173040-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.174638-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.174641-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: host_ocr=403c0000
2018-02-18 22:17:24.174644-0600  localhost kernel[0]: (Sinetek-rtsx) card_ocr=ff8000 new_ocr=c0000
2018-02-18 22:17:24.176142-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 0 arg=0 data=0x0 dlen=0 flags=0x21 proc="" (error 0)
2018-02-18 22:17:24.177736-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 8 arg=0x123 data=0x0 dlen=0 flags=0x1c31 proc="" (error 0)
2018-02-18 22:17:24.179341-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.180964-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.192605-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.194203-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.205878-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.207471-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.219075-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.220691-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.232306-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.233933-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.245677-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:24.247296-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-18 22:17:24.250001-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 2 arg=0 data=0x0 dlen=0 flags=0x1631 proc="" (error 0)
2018-02-18 22:17:24.251597-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 3 arg=0 data=0x0 dlen=0 flags=0x1c31 proc="" (error 0)
2018-02-18 22:17:25.252719-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 2 arg=0 data=0x0 dlen=0 flags=0x1631 proc="" (error 60)
2018-02-18 22:17:25.255470-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 9 arg=0x12340000 data=0x0 dlen=0 flags=0x1601 proc="" (error 0)
2018-02-18 22:17:25.255482-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: CID:
2018-02-18 22:17:25.255491-0600  localhost kernel[0]: (Sinetek-rtsx) mid=0x02 oid=0x544d pnm="SA16G" rev=0x12 psn=0x20f177a7 mdt=0da
2018-02-18 22:17:25.257129-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 7 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.257141-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: read_bl_len=512 sector_size=512
2018-02-18 22:17:25.258773-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 16 arg=0x200 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.259112-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.259606-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 51 arg=0 data=<private> dlen=8 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.259623-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: sdmmc_mem_decode_scr: 0002358003010000 ver=0, spec=2, bus width=5
2018-02-18 22:17:25.259626-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: change bus width
2018-02-18 22:17:25.259762-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 55 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.259870-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 6 arg=0x2 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.259884-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: switch func mode 0
2018-02-18 22:17:25.260176-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 6 arg=0xfffff0 data=<private> dlen=64 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.260185-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: switch func mode 1(func=1)
2018-02-18 22:17:25.260453-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 6 arg=0x80fffff1 data=<private> dlen=64 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.260688-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: blk_attack()
2018-02-18 22:17:25.260711-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: attaching SDDisk, num_blocks:30703616  blk_size:512
2018-02-18 22:17:25.260747-0600  localhost kernel[0]: (Sinetek-rtsx) sdmmc: config_pending_decr() XXX
2018-02-18 22:17:25.462481-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:25.462498-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:25.462510-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:25.463317-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.463466-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.663986-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:25.664002-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:25.664013-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:25.664662-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.664783-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:25.865893-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:25.865900-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:25.865909-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:25.866536-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:25.866632-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:26.066736-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:26.066739-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:26.066745-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:26.067372-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:26.067457-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:26.267560-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:26.267566-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:26.267573-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:26.268189-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:26.268275-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:26.468582-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:26.468593-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:26.468603-0600  localhost kernel[0]: (Sinetek-rtsx) would: 1  last block 30703615
2018-02-18 22:17:26.469240-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:26.469343-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:26.670020-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:26.670035-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:26.670046-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:17:26.670704-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:26.670848-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:26.871954-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:26.871973-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:26.871988-0600  localhost kernel[0]: (Sinetek-rtsx) would: 8  last block 30703615
2018-02-18 22:17:26.872644-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x8 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:26.872792-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:27.088575-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:27.088608-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:27.088643-0600  localhost kernel[0]: (Sinetek-rtsx) would: 3006686  last block 30703615
2018-02-18 22:17:27.089577-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x2de0de data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:27.089727-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:44.648686-0600  localhost kernel[0]: (Sandbox) <Sandbox`kernel_report> Sandbox: mds(69) deny(1) file-write-xattr /Users/syscl/github/Sinetek-rtsx/Sinetek-rtsx/Sinetek_rtsx.cpp
2018-02-18 22:17:53.116543-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:53.116553-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:53.116575-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:53.117506-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.117669-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.318399-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:53.318409-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:53.318426-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:17:53.319077-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.319205-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.520386-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:17:53.520405-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:53.520443-0600  localhost kernel[0]: (Sinetek-rtsx) would: 64  last block 30703615
2018-02-18 22:17:53.521143-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x40 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.521281-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.521288-0600  localhost kernel[0]: (Sinetek-rtsx) would: 65  last block 30703615
2018-02-18 22:17:53.521748-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x41 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.521882-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.521899-0600  localhost kernel[0]: (Sinetek-rtsx) would: 66  last block 30703615
2018-02-18 22:17:53.522362-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x42 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.522501-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.522514-0600  localhost kernel[0]: (Sinetek-rtsx) would: 67  last block 30703615
2018-02-18 22:17:53.522971-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x43 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.523110-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.723368-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:17:53.723378-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:53.723408-0600  localhost kernel[0]: (Sinetek-rtsx) would: 64  last block 30703615
2018-02-18 22:17:53.724064-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x40 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.724191-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.724197-0600  localhost kernel[0]: (Sinetek-rtsx) would: 65  last block 30703615
2018-02-18 22:17:53.724657-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x41 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.724761-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.724766-0600  localhost kernel[0]: (Sinetek-rtsx) would: 66  last block 30703615
2018-02-18 22:17:53.725210-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x42 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.725337-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.725344-0600  localhost kernel[0]: (Sinetek-rtsx) would: 67  last block 30703615
2018-02-18 22:17:53.725784-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x43 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.726022-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.926196-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:17:53.926202-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:53.926226-0600  localhost kernel[0]: (Sinetek-rtsx) would: 68  last block 30703615
2018-02-18 22:17:53.926835-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x44 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.926930-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.926936-0600  localhost kernel[0]: (Sinetek-rtsx) would: 69  last block 30703615
2018-02-18 22:17:53.927334-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x45 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.927407-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.927410-0600  localhost kernel[0]: (Sinetek-rtsx) would: 70  last block 30703615
2018-02-18 22:17:53.927800-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x46 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.927879-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:53.927883-0600  localhost kernel[0]: (Sinetek-rtsx) would: 71  last block 30703615
2018-02-18 22:17:53.928285-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x47 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:53.928423-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.128640-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:17:54.128646-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:54.128666-0600  localhost kernel[0]: (Sinetek-rtsx) would: 72  last block 30703615
2018-02-18 22:17:54.129251-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x48 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.129343-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.129355-0600  localhost kernel[0]: (Sinetek-rtsx) would: 73  last block 30703615
2018-02-18 22:17:54.129741-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x49 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.129813-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.129816-0600  localhost kernel[0]: (Sinetek-rtsx) would: 74  last block 30703615
2018-02-18 22:17:54.130199-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4a data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.130325-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.130334-0600  localhost kernel[0]: (Sinetek-rtsx) would: 75  last block 30703615
2018-02-18 22:17:54.130709-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4b data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.130816-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.331360-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:17:54.331363-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:54.331386-0600  localhost kernel[0]: (Sinetek-rtsx) would: 76  last block 30703615
2018-02-18 22:17:54.331952-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4c data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.332062-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.332065-0600  localhost kernel[0]: (Sinetek-rtsx) would: 77  last block 30703615
2018-02-18 22:17:54.332420-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4d data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.332504-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.332511-0600  localhost kernel[0]: (Sinetek-rtsx) would: 78  last block 30703615
2018-02-18 22:17:54.332903-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4e data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.332982-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.332989-0600  localhost kernel[0]: (Sinetek-rtsx) would: 79  last block 30703615
2018-02-18 22:17:54.333334-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4f data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.333428-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.534228-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:54.534246-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:54.534287-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:17:54.534972-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.535103-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.736350-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:54.736369-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:54.736406-0600  localhost kernel[0]: (Sinetek-rtsx) would: 1  last block 30703615
2018-02-18 22:17:54.737086-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.737214-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:54.937299-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:54.937310-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:54.937337-0600  localhost kernel[0]: (Sinetek-rtsx) would: 2  last block 30703615
2018-02-18 22:17:54.937978-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x2 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:54.938097-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:55.139266-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:55.139299-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:55.139366-0600  localhost kernel[0]: (Sinetek-rtsx) would: 3  last block 30703615
2018-02-18 22:17:55.140069-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x3 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:55.140192-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:55.341350-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:55.341369-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:55.341410-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:17:55.342067-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:55.342207-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:55.542471-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:55.542485-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:55.542516-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:17:55.543168-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:55.543291-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:55.743477-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:55.743491-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:55.743519-0600  localhost kernel[0]: (Sinetek-rtsx) would: 5  last block 30703615
2018-02-18 22:17:55.744166-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x5 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:55.744308-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:55.945387-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:55.945397-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:55.945415-0600  localhost kernel[0]: (Sinetek-rtsx) would: 6  last block 30703615
2018-02-18 22:17:55.946023-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x6 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:55.946121-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:56.146398-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:56.146406-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:56.146425-0600  localhost kernel[0]: (Sinetek-rtsx) would: 7  last block 30703615
2018-02-18 22:17:56.147033-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x7 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:56.147147-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:56.347419-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:56.347426-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:56.347448-0600  localhost kernel[0]: (Sinetek-rtsx) would: 8  last block 30703615
2018-02-18 22:17:56.348080-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x8 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:56.348177-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:56.548420-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:56.548429-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:56.548457-0600  localhost kernel[0]: (Sinetek-rtsx) would: 9  last block 30703615
2018-02-18 22:17:56.549048-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x9 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:56.549150-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:56.749251-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:56.749261-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:56.749288-0600  localhost kernel[0]: (Sinetek-rtsx) would: 10  last block 30703615
2018-02-18 22:17:56.749889-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xa data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:56.749985-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:56.950061-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:56.950072-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:56.950099-0600  localhost kernel[0]: (Sinetek-rtsx) would: 11  last block 30703615
2018-02-18 22:17:56.950724-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xb data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:56.950829-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:57.151912-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:57.151928-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:57.151960-0600  localhost kernel[0]: (Sinetek-rtsx) would: 12  last block 30703615
2018-02-18 22:17:57.152574-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xc data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:57.152691-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:57.353806-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:57.353822-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:57.353858-0600  localhost kernel[0]: (Sinetek-rtsx) would: 13  last block 30703615
2018-02-18 22:17:57.354489-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xd data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:57.354627-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:57.555546-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:57.555560-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:57.555593-0600  localhost kernel[0]: (Sinetek-rtsx) would: 14  last block 30703615
2018-02-18 22:17:57.556205-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xe data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:57.556331-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:57.757425-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:57.757439-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:57.757472-0600  localhost kernel[0]: (Sinetek-rtsx) would: 15  last block 30703615
2018-02-18 22:17:57.758091-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0xf data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:57.758228-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:57.958720-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:57.958733-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:57.958766-0600  localhost kernel[0]: (Sinetek-rtsx) would: 16  last block 30703615
2018-02-18 22:17:57.959313-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x10 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:57.959402-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:58.159619-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:58.159630-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:58.159652-0600  localhost kernel[0]: (Sinetek-rtsx) would: 17  last block 30703615
2018-02-18 22:17:58.160207-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x11 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:58.160310-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:58.361402-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:58.361417-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:58.361445-0600  localhost kernel[0]: (Sinetek-rtsx) would: 18  last block 30703615
2018-02-18 22:17:58.362031-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x12 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:58.362170-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:58.563261-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:58.563277-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:58.563316-0600  localhost kernel[0]: (Sinetek-rtsx) would: 19  last block 30703615
2018-02-18 22:17:58.563885-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x13 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:58.564003-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:58.764236-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:58.764252-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:58.764276-0600  localhost kernel[0]: (Sinetek-rtsx) would: 20  last block 30703615
2018-02-18 22:17:58.764803-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x14 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:58.764880-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:58.965124-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:58.965140-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:58.965173-0600  localhost kernel[0]: (Sinetek-rtsx) would: 21  last block 30703615
2018-02-18 22:17:58.965732-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x15 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:58.965855-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:59.166814-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:59.166858-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:59.166920-0600  localhost kernel[0]: (Sinetek-rtsx) would: 22  last block 30703615
2018-02-18 22:17:59.167468-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x16 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:59.167598-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:59.368769-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:59.368788-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:59.368849-0600  localhost kernel[0]: (Sinetek-rtsx) would: 23  last block 30703615
2018-02-18 22:17:59.369409-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x17 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:59.369539-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:59.570732-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:59.570751-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:59.570813-0600  localhost kernel[0]: (Sinetek-rtsx) would: 24  last block 30703615
2018-02-18 22:17:59.571379-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x18 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:59.571513-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:59.772609-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:59.772619-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:59.772668-0600  localhost kernel[0]: (Sinetek-rtsx) would: 25  last block 30703615
2018-02-18 22:17:59.773187-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x19 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:59.773302-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:17:59.973508-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:17:59.973511-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:17:59.973539-0600  localhost kernel[0]: (Sinetek-rtsx) would: 26  last block 30703615
2018-02-18 22:17:59.974045-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1a data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:17:59.974126-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:00.174251-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:00.174258-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:00.174298-0600  localhost kernel[0]: (Sinetek-rtsx) would: 27  last block 30703615
2018-02-18 22:18:00.174807-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1b data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:00.174914-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:00.374999-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:00.375005-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:00.375046-0600  localhost kernel[0]: (Sinetek-rtsx) would: 28  last block 30703615
2018-02-18 22:18:00.375543-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1c data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:00.375632-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:00.575708-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:00.575716-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:00.575757-0600  localhost kernel[0]: (Sinetek-rtsx) would: 29  last block 30703615
2018-02-18 22:18:00.576236-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1d data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:00.576492-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:00.777579-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:00.777595-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:00.777658-0600  localhost kernel[0]: (Sinetek-rtsx) would: 30  last block 30703615
2018-02-18 22:18:00.778192-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1e data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:00.778325-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:00.979458-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:00.979477-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:00.979554-0600  localhost kernel[0]: (Sinetek-rtsx) would: 31  last block 30703615
2018-02-18 22:18:00.980100-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x1f data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:00.980200-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.181339-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:01.181370-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:01.181437-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:18:01.182141-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.182277-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.382614-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:01.382623-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:01.382652-0600  localhost kernel[0]: (Sinetek-rtsx) would: 8  last block 30703615
2018-02-18 22:18:01.383277-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x8 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.383386-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.588739-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:01.588770-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:01.588850-0600  localhost kernel[0]: (Sinetek-rtsx) would: 0  last block 30703615
2018-02-18 22:18:01.589589-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.589734-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.790419-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 512
2018-02-18 22:18:01.790431-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:01.790471-0600  localhost kernel[0]: (Sinetek-rtsx) would: 4  last block 30703615
2018-02-18 22:18:01.791098-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.791211-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.991496-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:18:01.991506-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:01.991526-0600  localhost kernel[0]: (Sinetek-rtsx) would: 64  last block 30703615
2018-02-18 22:18:01.992145-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x40 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.992275-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.992282-0600  localhost kernel[0]: (Sinetek-rtsx) would: 65  last block 30703615
2018-02-18 22:18:01.992717-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x41 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.992822-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.992825-0600  localhost kernel[0]: (Sinetek-rtsx) would: 66  last block 30703615
2018-02-18 22:18:01.993294-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x42 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.993415-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:01.993419-0600  localhost kernel[0]: (Sinetek-rtsx) would: 67  last block 30703615
2018-02-18 22:18:01.993894-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x43 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:01.994021-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.194316-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:18:02.194330-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:02.194355-0600  localhost kernel[0]: (Sinetek-rtsx) would: 64  last block 30703615
2018-02-18 22:18:02.194999-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x40 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.195097-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.195102-0600  localhost kernel[0]: (Sinetek-rtsx) would: 65  last block 30703615
2018-02-18 22:18:02.195541-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x41 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.195636-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.195647-0600  localhost kernel[0]: (Sinetek-rtsx) would: 66  last block 30703615
2018-02-18 22:18:02.196091-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x42 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.196191-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.196201-0600  localhost kernel[0]: (Sinetek-rtsx) would: 67  last block 30703615
2018-02-18 22:18:02.196659-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x43 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.196776-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.396954-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:18:02.396962-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:02.396981-0600  localhost kernel[0]: (Sinetek-rtsx) would: 68  last block 30703615
2018-02-18 22:18:02.397588-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x44 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.397670-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.397673-0600  localhost kernel[0]: (Sinetek-rtsx) would: 69  last block 30703615
2018-02-18 22:18:02.398078-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x45 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.398164-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.398167-0600  localhost kernel[0]: (Sinetek-rtsx) would: 70  last block 30703615
2018-02-18 22:18:02.398555-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x46 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.398646-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.398653-0600  localhost kernel[0]: (Sinetek-rtsx) would: 71  last block 30703615
2018-02-18 22:18:02.399052-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x47 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.399134-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.599380-0600  localhost kernel[0]: (Sinetek-rtsx) read_task_impl_  sz 2048
2018-02-18 22:18:02.599394-0600  localhost kernel[0]: (Sinetek-rtsx) sf->csd.sector_size 512
2018-02-18 22:18:02.599423-0600  localhost kernel[0]: (Sinetek-rtsx) would: 72  last block 30703615
2018-02-18 22:18:02.600092-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x48 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.600204-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.600210-0600  localhost kernel[0]: (Sinetek-rtsx) would: 73  last block 30703615
2018-02-18 22:18:02.600605-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x49 data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.600693-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.600698-0600  localhost kernel[0]: (Sinetek-rtsx) would: 74  last block 30703615
2018-02-18 22:18:02.601085-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4a data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.601183-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.601189-0600  localhost kernel[0]: (Sinetek-rtsx) would: 75  last block 30703615
2018-02-18 22:18:02.601578-0600  localhost kernel[0]: (Sinetek-rtsx) rtsx: cmd 17 arg=0x4b data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.601672-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.802789-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:216)> read_task_impl_  sz 2048
2018-02-18 22:18:02.802798-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:217)> sf->csd.sector_size 512
2018-02-18 22:18:02.802820-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:239)> would: 76  last block 30703615
2018-02-18 22:18:02.803399-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 17 arg=0x4c data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.803502-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.803506-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:239)> would: 77  last block 30703615
2018-02-18 22:18:02.803869-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 17 arg=0x4d data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.803970-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.803973-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:239)> would: 78  last block 30703615
2018-02-18 22:18:02.804322-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 17 arg=0x4e data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.804414-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-18 22:18:02.804418-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:239)> would: 79  last block 30703615
2018-02-18 22:18:02.804780-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 17 arg=0x4f data=<private> dlen=512 flags=0x1c51 proc="" (error 0)
2018-02-18 22:18:02.804859-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`sdmmc_dump_command(rtsx_softc*, sdmmc_command*) (sdmmc.cpp:756)> rtsx: cmd 13 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01

And, please let me know if you still have the link about the control register bits in Chinese :)
syscl

@philmb3487
Copy link
Owner

philmb3487 commented Feb 19, 2018

@syscl I think you're close.

2018-02-18 22:18:02.802789-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:216)> read_task_impl_  sz 2048
2018-02-18 22:18:02.802798-0600  localhost kernel[0]: (Sinetek-rtsx) <Sinetek-rtsx`read_task_impl_(void*) (SDDisk.cpp:217)> sf->csd.sector_size 512

I don't know if you're getting back valid information, but at least the chip doesn't time out like mine always did (Error 60 vs Error 0). I would look into rewriting class SDDisk read_task_impl, the code is probably too bad to use there.

@syscl
Copy link
Contributor Author

syscl commented Feb 19, 2018

@sinetek , after commit v19, I got log like this

2018-02-19 09:15:09.113968-0600  localhost kernel[0]: rtsx_softc::probe(PXSX)
2018-02-19 09:15:09.113974-0600  localhost kernel[0]: rtsx_softc::start(PXSX) <1>
2018-02-19 09:22:07.860409-0600  localhost kernel[0]: rtsx_softc::probe(PXSX)
2018-02-19 09:22:07.860425-0600  localhost kernel[0]: rtsx_softc::start(PXSX) <7>
2018-02-19 09:22:07.860433-0600  localhost kernel[0]: PXSX: rtsx_softc (0x5110bae270fe48b9) is already a child
2018-02-19 09:22:35.654967-0600  localhost kernel[0]: (kernel) rtsx: cmd 7 arg=0 data=0x0 dlen=0 flags=0x1 proc="" (error 19)
2018-02-19 09:22:35.655018-0600  localhost kernel[0]: (kernel) rtsx: voltage change ocr=0x0
2018-02-19 09:22:35.655379-0600  localhost kernel[0]: (kernel) rtsx: host reset
2018-02-19 09:24:25.818784-0600  localhost kernel[0]: (kernel) rtsx: card inserted
2018-02-19 09:24:26.019921-0600  localhost kernel[0]: (kernel) rtsx: attach card
2018-02-19 09:24:26.019933-0600  localhost kernel[0]: (kernel) rtsx: voltage change ocr=0x403c0000
2018-02-19 09:24:26.272167-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 52
2018-02-19 09:24:27.273162-0600  localhost kernel[0]: (kernel) rtsx: soft reset
2018-02-19 09:24:27.273292-0600  localhost kernel[0]: (kernel) rtsx: cmd 52 arg=0x80000c08 data=0x0 dlen=0 flags=0x1c01 proc="" (error 60)
2018-02-19 09:24:27.273301-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 5
2018-02-19 09:24:28.274028-0600  localhost kernel[0]: (kernel) rtsx: soft reset
2018-02-19 09:24:28.274176-0600  localhost kernel[0]: (kernel) rtsx: cmd 5 arg=0 data=0x0 dlen=0 flags=0x1031 proc="" (error 60)
2018-02-19 09:24:28.274186-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 0
2018-02-19 09:24:28.275209-0600  localhost kernel[0]: (kernel) rtsx: cmd 0 arg=0 data=0x0 dlen=0 flags=0x21 proc="" (error 0)
2018-02-19 09:24:28.275214-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.276962-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.276967-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.278718-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.278723-0600  localhost kernel[0]: (kernel) rtsx: host_ocr=403c0000
2018-02-19 09:24:28.278731-0600  localhost kernel[0]: (kernel) rtsx: voltage change ocr=0xc0000
2018-02-19 09:24:28.279499-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 0
2018-02-19 09:24:28.280620-0600  localhost kernel[0]: (kernel) rtsx: cmd 0 arg=0 data=0x0 dlen=0 flags=0x21 proc="" (error 0)
2018-02-19 09:24:28.280625-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 8
2018-02-19 09:24:28.282350-0600  localhost kernel[0]: (kernel) rtsx: cmd 8 arg=0x123 data=0x0 dlen=0 flags=0x1c31 proc="" (error 0)
2018-02-19 09:24:28.282356-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.284147-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.284153-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.285906-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.296547-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.298374-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.298385-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.300203-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.311306-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.313141-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.313151-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.314943-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.326045-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.327885-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.327895-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.329658-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.340761-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.342598-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.342608-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.344408-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.355510-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:28.357340-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:28.357350-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 41
2018-02-19 09:24:28.359172-0600  localhost kernel[0]: (kernel) rtsx: cmd 41 arg=0x403c0000 data=0x0 dlen=0 flags=0x1031 proc="" (error 0)
2018-02-19 09:24:28.359183-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 2
2018-02-19 09:24:28.362049-0600  localhost kernel[0]: (kernel) rtsx: cmd 2 arg=0 data=0x0 dlen=0 flags=0x1631 proc="" (error 0)
2018-02-19 09:24:28.362055-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 3
2018-02-19 09:24:28.363804-0600  localhost kernel[0]: (kernel) rtsx: cmd 3 arg=0 data=0x0 dlen=0 flags=0x1c31 proc="" (error 0)
2018-02-19 09:24:28.363809-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 2
2018-02-19 09:24:29.364401-0600  localhost kernel[0]: (kernel) rtsx: soft reset
2018-02-19 09:24:29.364523-0600  localhost kernel[0]: (kernel) rtsx: cmd 2 arg=0 data=0x0 dlen=0 flags=0x1631 proc="" (error 60)
2018-02-19 09:24:29.364557-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 9
2018-02-19 09:24:29.367446-0600  localhost kernel[0]: (kernel) rtsx: cmd 9 arg=0x12340000 data=0x0 dlen=0 flags=0x1601 proc="" (error 0)
2018-02-19 09:24:29.367452-0600  localhost kernel[0]: (kernel) rtsx: CID:
2018-02-19 09:24:29.367464-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 7
2018-02-19 09:24:29.369183-0600  localhost kernel[0]: (kernel) rtsx: cmd 7 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:29.369190-0600  localhost kernel[0]: (kernel) rtsx: read_bl_len=512 sector_size=512
2018-02-19 09:24:29.369193-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 16
2018-02-19 09:24:29.370965-0600  localhost kernel[0]: (kernel) rtsx: cmd 16 arg=0x200 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:29.371239-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55
2018-02-19 09:24:29.371392-0600  localhost kernel[0]: (kernel) rtsx: cmd 55 arg=0x12340000 data=0x0 dlen=0 flags=0x1c01 proc="" (error 0)
2018-02-19 09:24:29.371396-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 51
2018-02-19 09:24:29.371516-0600  localhost kernel[0]: (kernel) rtsx: read xfer: 8 bytes with block size 8
2018-02-19 09:24:29.371944-0600  localhost kernel[0]: (kernel) rtsx: xfer done, error=0
2018-02-19 09:24:29.371965-0600  localhost kernel[0]: (kernel) rtsx: cmd 51 arg=0 data=<private> dlen=8 flags=0x1c51 proc="" (error 0)
2018-02-19 09:24:29.371976-0600  localhost kernel[0]: (kernel) rtsx: sdmmc_mem_decode_scr: 0002358003010000 ver=0, spec=2, bus width=5
2018-02-19 09:24:29.371979-0600  localhost kernel[0]: (kernel) rtsx: change bus width
2018-02-19 09:24:29.371983-0600  localhost kernel[0]: (kernel) rtsx: executing cmd 55

No more errors when I inserted sdcard, but the problem is now that system cannot detect the sdcard either (though I can see it in the log). Maybe I need to have a look into doAsyncReadWrite(), which I think the source still lack of some routines such as sdmmc_mem_read_block() and sdmmc_mem_write_block(), I'm not sure but want to give it a try.

Another issue is that, when I added provider_->joinPMtree(this) after I called PMinit() and registerPowerDriver() I got instant reboot (kp). Do you know why this will happen?

Anyway, this driver is pretty promising for me, because I am waiting for PCI-E sdcard driver quiet a long time.

syscl

@macmeister1967
Copy link

Card Reader recognised, but no card mounted... this is getting spammed in the Console...

default 02:23:15.891807 +1000 kernel rtsx: cmd 17 arg=0 data= dlen=512 flags=0x1c51 proc="" (error 60)

Thanks for working on this... you will be a hero if this reaches fruition !!

@philmb3487
Copy link
Owner

philmb3487 commented Sep 6, 2018

Unfortunately, there is nothing I can really do because my hardware is a bit faulty. (I get errors under strange conditions even under Linux)

If I ever get another thinkpad with a realtek chip I will look into this again, until then I handed the project over to @syscl.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.

Labels

None yet

Projects

None yet

Development

Successfully merging this pull request may close these issues.

7 participants