No USB port visible on macos 10.12.2

17 posts / 0 new
Last post
narms
No USB port visible on macos 10.12.2

Having issues with using Simplify3D and xyzhub (which uses the virtualhere server).

Mac running macos Sierra 10.12.2 (don't have 10.12.0 anymore)
Can install virtualhere on the mac, and after waiting ca. 30-60 seconds) see the server, but there are no USB ports accessible to Simplify3D.

Checked, via console, and see the following entries under install.log
Jan 2 19:02:57 Alexs-MacBook-Air Installer[5577]: osxdriver.pkg : com.virtualhere.osxdriver : 1.0
Jan 2 19:02:58 Alexs-MacBook-Air installd[376]: PackageKit: Executing script "./preinstall" in /private/tmp/PKInstallSandbox.86p8Sk/Scripts/com.virtualhere.osxdriver.0zjeYk
Jan 2 19:02:58 Alexs-MacBook-Air installd[376]: ./preinstall: (kernel) Kext com.virtualhere.vhhcd not found for unload request.
Jan 2 19:02:58 Alexs-MacBook-Air installd[376]: ./preinstall: Failed to unload com.virtualhere.vhhcd - (libkern/kext) not found.
Jan 2 19:03:28 Alexs-MacBook-Air installd[376]: PackageKit: Executing script "./postinstall" in /private/tmp/PKInstallSandbox.86p8Sk/Scripts/com.virtualhere.osxdriver.0zjeYk
Jan 2 19:03:28 Alexs-MacBook-Air installd[376]: PackageKit: Writing receipt for com.virtualhere.osxdriver to /

Maybe the drivers are not getting installed into the system? Not sure if thats a problem...
Note also that under USB on "system report" there is no entry for anything representing a (via virtualhere) USB connected 3D printer.
[Sidenote: I have been able to connect via virtualhere running on a windows10 machine (netbook) which is unfortunately a pain to use, hence only really
proof of concept/functionality of the server]

Thanks for any hints to get this going on the mac.

Neil

Michael
it sounds like the driver is

it sounds like the driver is installed. To figure out if it was use the printer via virtualhere then right click USB Hubs->System Messages and it will contain something like the following:

17:25:31 INFO  :Successfully bound to driver
17:25:31 INFO  :Connected to the VirtualHere Client Driver (Version 2)

Regarding the printer, i think most printers use a serial interface to their on-board microcontroller. What printer is it? I'll take a look at their website to see what drivers they require/use

narms
Thanks Michael,

Thanks Michael,
yes, it looks like it is bound to the driver:
19:33:22 INFO :assert:../src/common/event.cpp,1869,SearchDynamicEventTable,nNew != dynamicEvents.size(),
19:39:47 INFO :Carried and installed driver are both version 1.0.96
19:39:47 INFO :Successfully bound to driver
19:39:47 INFO :Connected to the VirtualHere Client Driver (Version 2)

Strange, it still cannot be seen by Simplify3D (connecting to a Da Vinci 1.0). This used to work, but haven't had it working since before El Capitan.
Will try a different USB connected item (camera) and see if that works.

Does this bind to a certain /dev/tty ? Could permissions be an issue? Grasping at straws here, as I am not an expert on COM stacks or USB...

narms
No luck with a camera either

No alternative USB devices seem to be recognized by the mac (macOS Sierra 10.12.2). I am using an xyzhub from wctek. Perhaps I should try running a server on
a vanilla raspberry pi without the WCTek software.

Are any others having issues with this version of macOS? Or is it just my setup?

Any insight appreciated.
Neil

Michael
.

I think its osx then. What happens is apple re-wrote the entire usb stack in 10.11. They removed support for 3rd party USB host controllers like virtualhere. I complained as did other companies and apple hacked back in support again for 10.12.2. I notice for example that the virtualhere host controller does not appear in the apple system dialog. Even when a device is connected it doesnt show. This tells me they have quickly hacked back in support but not really put official support back in. Ive tried contacting them multiple times and they basically just say nothing. So my guess is that your software might be trying to walk the device tree to find the davinci printer. Since the device tree is not properly formed with the virtualhere host controller it cannot see it. Strangely most devices do work via virtualhere in 10.12 eg. iPad, serial devices etc and basically everything except webcams. Software that uses those devices however can sometimes not see the virtualhere shared devices. I think perhaps apple might put "official" support for 3rd party host controllers back in at some point so i've been watching the release notes for each osx build. ...

caseyboardman
trouble with Arduino on and 10.12.2

Connecting to an Arduino Uno (a genuine one, not a clone) with VirtualHere client 3.9.1 on macOS 10.12.2, VirtualHere says I'm using it, but the serial port doesn't show up (no /dev/tty.* files added).

Running `system_profiler SPUSBDataType` doesn't show anything VirtualHere related.

Running `ioreg -p IOUSB -l` gives me (I've snipped this down):
+-o VirtualHere USB Enhanced Host Controller@1
| | {
| | "sessionID" = 1953801604428
| | "idProduct" = 0
| | "bNumConfigurations" = 1
| | "iManufacturer" = 2
| | "bcdDevice" = 512
| | "Bus Power Available" = 250
| | "bMaxPacketSize0" = 64
| | "USB Product Name" = "VirtualHere USB Enhanced Host Controller"
| | "iProduct" = 1
| | "iSerialNumber" = 0
| | "USB Address" = 1
| | "IOUserClientClass" = "IOUSBDeviceUserClientV2"
| | "bDeviceSubClass" = 0
| | "bDeviceClass" = 9
| | "bcdUSB" = 512
| | "locationID" = 16777216
| | "Ports" = 15
| | "IOCFPlugInTypes" = {"9dc7b780-9ec0-11d4-a54f-000a27052861"="IOUSBFamily.kext/Contents/PlugIns/IOUSBLib.bundle"}
| | "bDeviceProtocol" = 1
| | "USB Vendor Name" = "VirtualHere Pty. Ltd."
| | "Device Speed" = 2
| | "idVendor" = 0
| | "Requested Power" = 0
| | "IOGeneralInterest" = "IOCommand is not serializable"
| | "Low Power Displayed" = No
| | }
| |
| +-o IOUSBDevice@1100000
| {
| "sessionID" = 84548538068521
| "idProduct" = 67
| "bNumConfigurations" = 1
| "iManufacturer" = 1
| "bcdDevice" = 1
| "Bus Power Available" = 250
| "bMaxPacketSize0" = 8
| "iProduct" = 2
| "iSerialNumber" = 220
| "USB Address" = 2
| "bDeviceClass" = 2
| "IOUserClientClass" = "IOUSBDeviceUserClientV2"
| "bDeviceSubClass" = 0
| "locationID" = 17825792
| "bcdUSB" = 272
| "PortNum" = 1
| "IOCFPlugInTypes" = {"9dc7b780-9ec0-11d4-a54f-000a27052861"="IOUSBFamily.kext/Contents/PlugIns/IOUSBLib.bundle"}
| "bDeviceProtocol" = 0
| "USB Vendor Name" = "Arduino (www.arduino.cc)"
| "Device Speed" = 1
| "idVendor" = 9025
| "USB Serial Number" = "74133353437351716212"
| "IOGeneralInterest" = "IOCommand is not serializable"
| }
|
+-o com_virtualhere_vhhcd
{
"Card Type" = "Built-in"
"IOPowerManagement" = {"ChildrenPowerState"=1,"DevicePowerState"=1,"CurrentPowerState"=1,"CapabilityFlags"=2,"MaxPowerState"=1}
"IOUserClientClass" = "com_virtualhere_vhhcduc"
}

Is there something I can do/try to get this connection to work?

Michael
.

I think if its a genuine uno it uses the atmel chip to directly provide a usb->serial interface. So the theory is that the osx kernel would look at the bDeviceClass of 2 which corresponds to ( http://www.usb.org/developers/defined_class ) Communications and load the driver for that.

Look at 10.12.3 (beta) in the directory /System/Library/Extensions/AppleUSBCDC.kext/Contents/Info.plist

the entry for the bDeviceClass of 2 (and beDeviceSubClass */bDeviceProtocol *) should trigger that driver to load

In the OSX "Console" log does it shown any atttempt to load that driver, perhaps with an error code?

caseyboardman
My Info.plist entry appears

My Info.plist entry appears correct:
<key>CDCCompositeDevice</key>
<dict>
<key>IOClass</key>
<string>AppleUSBCDCCompositeDevice</string>
<key>IOProviderClass</key>
<string>IOUSBHostDevice</string>
<key>bDeviceClass</key>
<integer>2</integer>
<key>bDeviceProtocol</key>
<string>*</string>
<key>bDeviceSubClass</key>
<string>*</string>
</dict>

My console log has the WARNING: CTRL ACK ERROR -32

2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.239 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.239 com_virtualhere_vhhcd::handleDeviceBindAckMsg (4, 1)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.239 com_virtualhere_vhhcd::bindDevice Full speed device at remote address 4 is now attached local port[1] and is device zero
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.242 com_virtualhere_vhhcd::clearHubPortFeature port[1] wValue=0010
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.242 com_virtualhere_vhhcd::hubResetChangeConnection port[1]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::UIMCreateInterruptTransfer
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::handleUIMCreateInterruptTransferForHub
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::SetRootHubPortFeature port[1] wValue=0004
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::setHubPortFeature port[1] wValue=0004
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::hubPortReset port[1]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.344 com_virtualhere_vhhcd::createResetPortMsg (id=98,p=1)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.345 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.346 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.346 com_virtualhere_vhhcd::handleResetPortMsgAck port[1] ok
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.355 com_virtualhere_vhhcd::gatedHubResetPortTimerFired port[1] ok
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.464 com_virtualhere_vhhcd::clearHubPortFeature port[1] wValue=0014
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.464 com_virtualhere_vhhcd::hubResetResetChange port[1]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.475 com_virtualhere_vhhcd::handleUIMCreateControlEndpointForDevice (fa=0 ep00)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.475 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=0 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.475 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=0 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.475 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=0 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.475 com_virtualhere_vhhcd::createControlMsg (id=99) (4 - SOK,80,06,0100,0000,0008,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.476 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::handleCtrlRequestAck (id=99,sr=0,al=8,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=99) received [,12,01,10,01,02,00,00,08]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=0 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=0 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::setDeviceAddress (to 2)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::setDeviceAddress port[1] now assigned function address 2 and remote address 4
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.477 com_virtualhere_vhhcd::handleCtrlRequestAck (id=100,sr=0,al=0,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.478 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::UIMDeleteEndpoint (f=0 ep=180)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::handleUIMCreateControlEndpointForDevice (fa=2 ep00)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.479 com_virtualhere_vhhcd::createControlMsg (id=102) (4 - SOK,80,06,0100,0000,0012,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.480 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::handleCtrlRequestAck (id=102,sr=0,al=18,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=102) received [,12,01,10,01,02,00,00,08,41,23,43,00,01,00,01,02,dc,01]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.481 com_virtualhere_vhhcd::createControlMsg (id=103) (4 - SOK,80,06,0302,0409,0002,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleCtrlRequestAck (id=103,sr=-32,al=0,lr=-536854449)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 WARNING, com_virtualhere_vhhcd::handleCtrlRequestAck (id 103) CTRL ACK ERROR -32
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.482 com_virtualhere_vhhcd::createControlMsg (id=104) (4 - SOK,80,06,0302,0409,0100,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleCtrlRequestAck (id=104,sr=-32,al=0,lr=-536854449)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 WARNING, com_virtualhere_vhhcd::handleCtrlRequestAck (id 104) CTRL ACK ERROR -32
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::UIMClearEndpointStall (fa=2 ep180) passing true for clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbort
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleEndpointAbortForDevice (fa=2 ep80) clearToggle
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 WARNING, com_virtualhere_vhhcd::handleEndpointAbortForDevice skipping, no existing endpoint (fa=2 ep80)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.483 com_virtualhere_vhhcd::createControlMsg (id=105) (4 - SOK,80,06,0301,0409,0002,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::handleCtrlRequestAck (id=105,sr=0,al=2,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=105) received [,32,03]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.484 com_virtualhere_vhhcd::createControlMsg (id=106) (4 - SOK,80,06,0301,0409,0032,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.485 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::handleCtrlRequestAck (id=106,sr=0,al=50,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=106) received [,32,03,41,00,72,00,64,00,75,00,69,00,6e,00,6f,00,20,00,28,00,77,00,77,00,77,00,2e,00,61,00,72,00]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.486 com_virtualhere_vhhcd::createControlMsg (id=107) (4 - SOK,80,06,03dc,0409,0002,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::handleCtrlRequestAck (id=107,sr=0,al=2,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=107) received [,2a,03]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.487 com_virtualhere_vhhcd::createControlMsg (id=108) (4 - SOK,80,06,03dc,0409,002a,in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::handleCtrlRequestAck (id=108,sr=0,al=42,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=108) received [,2a,03,37,00,34,00,31,00,33,00,33,00,33,00,35,00,33,00,34,00,33,00,37,00,33,00,35,00,31,00,37,00]
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::UIMCreateInterruptTransfer
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.488 com_virtualhere_vhhcd::handleUIMCreateInterruptTransferForHub
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.771 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.771 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.771 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.771 com_virtualhere_vhhcd::createControlMsg (id=109) (4 - SOK,80,06,0200,0000,0004,in)
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::handleCtrlRequestAck (id=109,sr=0,al=4,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=109) received [,09,02,3e,00]
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 in)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::handleUIMControlTransfer (fa=2 ep00 out)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.773 com_virtualhere_vhhcd::createControlMsg (id=110) (4 - SOK,80,06,0200,0000,003e,in)
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.774 com_virtualhere_vhhcd::rx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.774 com_virtualhere_vhhcd::tx
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.774 com_virtualhere_vhhcd::handleCtrlMsgAck
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.774 com_virtualhere_vhhcd::handleCtrlRequestAck (id=110,sr=0,al=62,lr=0)
2/1/17 12:16:03.000 PM kernel[0] USBF: 1386770.775 com_virtualhere_vhhcd::debugPrintCtrlData ctrl data (id=110) received [,09,02,3e,00,02,01,00,c0,32,09,04,00,00,01,02,02,01,00,05,24,00,01,10,04,24,02,06,05,24,06,00,01]
2/1/17 12:16:03.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:04.000 PM kernel[0] IOBluetoothUSBDFU::probe
2/1/17 12:16:04.000 PM kernel[0] IOBluetoothUSBDFU::probe avoid Bluetooth automatic firmware update because USB Bluetooth active
2/1/17 12:16:04.000 PM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/1/17 12:16:04.000 PM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/1/17 12:16:04.000 PM kernel[0] HoRNDIS: probe: probe: this composite device is not for us
2/1/17 12:16:14.680 PM launchservicesd[102] SecTaskLoadEntitlements failed error=22

Thoughts?

Michael
.

Thanks casey that log is very useful. Is that from 10.12.2?

Anyway can you download this build https://www.virtualhere.com/sites/default/files/usbclient/test/VirtualHe...

and run that and if it doesnt work can you send me the log again.

What is happening is that osx is asking for a string descriptor of that device but the device is returning an error. Usually a device should not do this but yours seems to for some reason. This new test build of the client will return an different error back to osx

caseyboardman
Yes, I am on 12.2.2.

Yes, I am on 12.2.2.

Still no dice. Hope this log helps:

2/2/17 8:59:43.000 AM kernel[0] USBF: 680.308 com_virtualhere_vhhcd::bindDevice Full speed device at remote address 4 is now attached local port[1] and is device zero
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.410 com_virtualhere_vhhcd::hubPortReset port[1]
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.421 com_virtualhere_vhhcd::gatedHubResetPortTimerFired port[1] ok
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.528 com_virtualhere_vhhcd::hubResetResetChange port[1]
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.540 com_virtualhere_vhhcd::setDeviceAddress port[1] now assigned function address 2 and remote address 4
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.545 WARNING, com_virtualhere_vhhcd::handleCtrlRequestAck (id 19) CTRL ACK ERROR -32 (returning kIOUSBBufferUnderrunErr)
2/2/17 8:59:43.000 AM kernel[0] USBF: 680.546 WARNING, com_virtualhere_vhhcd::handleCtrlRequestAck (id 20) CTRL ACK ERROR -32 (returning kIOUSBBufferUnderrunErr)
2/2/17 8:59:43.000 AM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/2/17 8:59:43.000 AM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/2/17 8:59:43.000 AM kernel[0] HoRNDIS: probe: probe: this composite device is not for us

caseyboardman
Correction: 10.12.2

Correction: 10.12.2

Michael
ok, i assume this device

ok, i assume this device works ok directly connected. Im not sure what else to try because the device is not returning a result for a GET_DESCRIPTOR of a string type at index 2. The device is sending back an error when the driver in osx asks for this particular value. If it works ok directly connected, perhaps its just return ok but no data. Can you confirm it works ok directly connected?

caseyboardman
The device works when

The device works when directly connected. Here is information from direct connection:

Console log

2/3/17 9:15:08.000 AM kernel[0] HoRNDIS: init: HoRNDIS tethering driver for Mac OS X, by Joshua Wise (rel8pre2 (PRERELEASE))
2/3/17 9:15:08.000 AM kernel[0] HoRNDIS: probe: probe: came in with a score of 60000
2/3/17 9:15:08.000 AM kernel[0] HoRNDIS: message: unknown message type e000401f
2/3/17 9:15:08.000 AM kernel[0] HoRNDIS: message: unknown message type e0000230
2/3/17 9:15:08.000 AM kernel[0] HoRNDIS: probe: probe: this composite device is not for us

$ ls /dev/tty.*
/dev/tty.Bluetooth-Incoming-Port /dev/tty.usbmodem1461

system_profiler SPUSBDataType

Communication Device:

Product ID: 0x0043
Vendor ID: 0x2341
Version: 0.01
Serial Number: 952333532313513061E2
Speed: Up to 12 Mb/sec
Manufacturer: Arduino (www.arduino.cc)
Location ID: 0x14600000 / 13
Current Available (mA): 1000
Current Required (mA): 100
Extra Operating Current (mA): 0

ioreg -p IOUSB -l

| +-o IOUSBHostDevice@14600000 <class AppleUSBDevice, id 0x100001642, registered, matched, active, busy 0 (4 ms), retain 13>
| {
| "sessionID" = 88005932088989
| "iManufacturer" = 1
| "bNumConfigurations" = 1
| "idProduct" = 67
| "bcdDevice" = 1
| "Bus Power Available" = 500
| "USB Address" = 13
| "bMaxPacketSize0" = 8
| "iProduct" = 2
| "iSerialNumber" = 220
| "bDeviceClass" = 2
| "Built-In" = No
| "locationID" = 341835776
| "bDeviceSubClass" = 0
| "bcdUSB" = 272
| "USB Product Name" = "IOUSBHostDevice"
| "PortNum" = 6
| "non-removable" = "no"
| "IOCFPlugInTypes" = {"9dc7b780-9ec0-11d4-a54f-000a27052861"="IOUSBFamily.kext/Contents/PlugIns/IOUSBLib.bundle"}
| "bDeviceProtocol" = 0
| "IOUserClientClass" = "IOUSBDeviceUserClientV2"
| "IOPowerManagement" = {"DevicePowerState"=0,"CurrentPowerState"=3,"CapabilityFlags"=65536,"MaxPowerState"=4,"DriverPowerState"=3}
| "Device Speed" = 1
| "USB Vendor Name" = "Arduino (www.arduino.cc)"
| "idVendor" = 9025
| "IOGeneralInterest" = "IOCommand is not serializable"
| "USB Serial Number" = "952333532313513061E2"
| "IOClassNameOverride" = "IOUSBDevice"
| }

The server is version 2.8.8 and is on Debian 8, if that helps.

I can try again in macOS 10.12.3 next week if you think that is worth a shot.

Michael
.

OK, 2.8.8 is quite old, can you update the server to 3.2.3 on this webpage and give it a try. If it still fails ill do a new build of the client to just ignore this error and return OK with zero data and see if that fixes the problem. But try 3.2.3 of the server first

caseyboardman
Finally coming back to this.

Finally coming back to this. Apologies for the delay.

No dice with latest client (4.1.6) and server at version 3.2.3. Going to try updating the server and will report back.

caseyboardman
Finally coming back to this.

Finally coming back to this. Apologies for the delay.

No luck with latest client (4.1.6) and server at version 3.2.3. Tried again just now with server at 3.3.5, still no dice. Running macOS 10.12.5.

Additionally, I wasn't seeing any log message in the Console with "virtualhere" in them. Plenty of "USBF" lines, but some with empty content.

Also, seeing this a lot:

default 09:28:51.972294 -0400 trustd cert[2]: AnchorTrusted =(leaf)[force]> 0
default 09:28:51.972622 -0400 trustd asynchronously fetching CRL (http://crl.apple.com/codesigning.crl) for client (kextd[58])
default 09:28:51.972977 -0400 trustd asynchronously fetching CRL (http://www.apple.com/appleca/root.crl) for client (kextd[58])

Let me know any commands/searches/things you want me to try and I will report back by the end of June (I'm out of the office next week).

Michael
.

I have lodged a bug report with apple again and they acknowledge there has been more bugs creeping into OSX in the last few updates regarding USB , so if it doesnt work it might work in a later dot build of osx.

Log in or register to post comments