windows service not running

Software-based VM-centric and flash-friendly VM storage + free version

Moderators: anton (staff), art (staff), Max (staff), Anatoly (staff)

Post Reply
navsol
Posts: 2
Joined: Tue Aug 18, 2009 2:45 pm

Tue Aug 18, 2009 2:58 pm

Hi
I have setup starwind free on windows server 2003 machine and accessed it via ms iscsi initiator from a sbs machine and it worked to start with, I set up a 200 g virtual disk on the target machine and created a drive letter on the sbs machine. I had copied over some of the files to the new "disk" and left it running quite happily all by itself until the 2003 machine updated itself and restarted in the middle of the night. when i looked at the sbs machine we had lost the new disk and i couldn't access the iscsi disk, i was getting an error about the connection being actively refused.
the port is not blocked and when i look at the 2003 machine with the virtual disk on it the starwind service is not running. it will start and stop straight away, not sure if this ok the the starwind service or not. I know it is ok for some. When i try to log on to the localhost connection I again get the refused "actively".
I have attached the log file for the latest connection attempt.

Code: Select all

8/18 15:29:07.275 1680 Srv: StarWind iSCSI Target v4.2 (Build 20090702, Win64)
8/18 15:29:07.275 1680 Srv: Licensed to: 'Unknown', 'Unknown', (#1978, 0, 0).
8/18 15:29:07.291 1680 conf: Variable 'LogLevel' is set to '1'.
8/18 15:29:07.291 1680 conf: Variable 'LogMask' is set to '0x8fffffff'.
8/18 15:29:07.291 1680 conf: Variable 'Port' is set to '3260'.
8/18 15:29:07.291 1680 conf: Variable 'Interface' is set to '192.168.1.16'.
8/18 15:29:07.291 1680 conf: Variable 'Login' is set to 'navigate'.
8/18 15:29:07.291 1680 conf: Variable 'Password' is set to '##zTPrJBZOpgF71cv5pYd30g=='.
8/18 15:29:07.291 1680 conf: Variable 'MinBufferSize' is set to '65536'.
8/18 15:29:07.291 1680 conf: Variable 'AlignmentMask' is set to '0x0000'.
8/18 15:29:07.291 1680 conf: Variable 'MaxPendingRequests' is set to '64'.
8/18 15:29:07.291 1680 conf: Variable 'iScsiPingPeriod' is set to '0'.
8/18 15:29:07.291 1680 RAM: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:23)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
8/18 15:29:07.291 1680 RAM: *** SetLockPagesPrivilege: Cannot enable SE_LOCK_MEMORY privilege, please check the local policy.
8/18 15:29:07.291 1680 RAM: *** RamDisk_Init: Address Windowing Extensions (AWE) are not supported.
8/18 15:29:07.291 1680 conf: 'RamDisk.dll' loaded successfully.
8/18 15:29:07.307 1680 IMG: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|img'
8/18 15:29:07.307 1680 IMG: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'img'
8/18 15:29:07.307 1680 IMG: SscPort_Init: 	Comment : ''
8/18 15:29:07.307 1680 IMG: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:24)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/18 15:29:07.307 1680 conf: 'ImageFile.dll' loaded successfully.
8/18 15:29:07.307 1680 VDVD: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x18
	Alias: 'My Computer'
	Extensions: 'mds,iso'
8/18 15:29:07.307 1680 conf: 'VirtualDvd.dll' loaded successfully.
8/18 15:29:07.307 1680 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'ibv'
8/18 15:29:07.307 1680 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1e
	Alias: 'Snapshots'
	Extensions: 'ibvss'
8/18 15:29:07.307 1680 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x9
	Alias: 'Image Files'
	Extensions: 'img,dat'
8/18 15:29:07.307 1680 IBV: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:27)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ImageDir  = '*\'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/18 15:29:07.307 1680 conf: 'IBVolume.dll' loaded successfully.
8/18 15:29:07.307 1680 MIR: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Mirror Volumes'
	Extensions: 'mir'
8/18 15:29:07.307 1680 MIR: *SscPort_Init: 
	Dirname: '*'
	Rights: 0xc
	Alias: 'Image Files'
	Extensions: 'img,dat'
8/18 15:29:07.307 1680 conf: 'Mirror.dll' loaded successfully.
8/18 15:29:07.307 1680 DSKBR: SscPort_Init: (built Jul  8 2009 17:05:30)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/18 15:29:07.307 1680 conf: 'DiskBridge.dll' loaded successfully.
8/18 15:29:07.307 1680 VTAPE: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|vtl'
8/18 15:29:07.307 1680 VTAPE: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'vtl'
8/18 15:29:07.307 1680 VTAPE: SscPort_Init: 	Comment : ''
8/18 15:29:07.307 1680 VTAPE: *** SscPort_Init: Logging level is set to 1.
8/18 15:29:07.307 1680 VTAPE: *** SscPort_Init: DBG_ERROR   statements are ON.
8/18 15:29:07.307 1680 VTAPE: SscPort_Init: (built Jun  7 2009 10:57:11)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '    '
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/18 15:29:07.307 1680 VTAPE: *** TapeEmulCtrlApiStart: StarWind VTL License Type is 3
8/18 15:29:07.307 1680 VTAPE: *** TapeEmulCtrlApiStart: This Enterprise Edition supports a maximum of 64 devices, each up to 1048576 MB in length.
8/18 15:29:07.307 1680 conf: 'VirtualTape.dll' loaded successfully.
8/18 15:29:07.307 1680 S: SymId: 'ImageFile0': Assigned target name: 'server01disk', alias 'server01disk'
8/18 15:29:07.307 1680 IMG: SscPort_Create: Opening device 'ImageFile0', image file 'My Computer\C\swdisks\server01.img', buffering=0, async=1, readonly=0.
8/18 15:29:07.307 1680 IMG: ImageFile_Create: 'C:\swdisks\server01.img': type 0, geometry: sct 16, trks 32, cyl 690800, TotalSectors: 353689600
8/18 15:29:07.307 1680 IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '0806F7D53974996F'
8/18 15:29:07.307 1680 Tgt: : parameter 'header', value '0'.
8/18 15:29:07.307 1680 Tgt: : parameter 'file', value 'My Computer\C\swdisks\server01.img'.
8/18 15:29:07.307 1680 Tgt: : parameter 'buffering', value 'no'.
8/18 15:29:07.307 1680 Tgt: : parameter 'asyncmode', value 'yes'.
8/18 15:29:07.307 1680 Tgt: : parameter 'ImageSizeLow', value '700448768'.
8/18 15:29:07.307 1680 Tgt: : parameter 'ImageSizeHigh', value '42'.
8/18 15:29:07.307 1680 Tgt: : parameter 'readonly', value 'no'.
8/18 15:29:07.307 1680 S: server01disk: 1 session(s) allowed.
8/18 15:29:07.307 1680 conf: Target [0x000000000011D4F0] has been created. Device 'ImageFile0' is accesible as target 'server01disk'.
8/18 15:29:07.307 1680 conf: Variable 'AccessPolicy' is set to 'allow'.
8/18 15:29:07.307 1680 Srv: Refreshing device list (complete)...
8/18 15:29:07.307 1680 Srv: bind() to 192.168.1.16:3260 failed: The requested address is not valid in its context. (code: 10049).
8/18 15:29:07.307 1078 conf: Waiting for Control connections at 0.0.0.0:3261 ...
8/18 15:29:07.307 1078 conf: accept() failed: An operation was attempted on something that is not a socket. (code: 10038).
8/18 15:29:07.307 1078 conf: Stop listening to control connections.
8/18 15:29:07.307 1680 Srv: Shutdown completed.
8/18 15:29:07.307 1680 S: the device 'ImageFile0' is closed.
and this is the log when the server restarted after the updates:

Code: Select all

8/16 5:29:04.968 954 Srv: StarWind iSCSI Target v4.2 (Build 20090702, Win64)
8/16 5:29:04.984 954 Srv: Licensed to: 'Unknown', 'Unknown', (#1978, 0, 0).
8/16 5:29:05.250 954 conf: Variable 'LogLevel' is set to '1'.
8/16 5:29:05.250 954 conf: Variable 'LogMask' is set to '0x8fffffff'.
8/16 5:29:05.250 954 conf: Variable 'Port' is set to '3260'.
8/16 5:29:05.250 954 conf: Variable 'Interface' is set to '192.168.1.16'.
8/16 5:29:05.250 954 conf: Variable 'Login' is set to 'navigate'.
8/16 5:29:05.250 954 conf: Variable 'Password' is set to '##zTPrJBZOpgF71cv5pYd30g=='.
8/16 5:29:05.250 954 conf: Variable 'MinBufferSize' is set to '65536'.
8/16 5:29:05.250 954 conf: Variable 'AlignmentMask' is set to '0x0000'.
8/16 5:29:05.250 954 conf: Variable 'MaxPendingRequests' is set to '64'.
8/16 5:29:05.250 954 conf: Variable 'iScsiPingPeriod' is set to '0'.
8/16 5:29:05.406 954 RAM: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:23)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
8/16 5:29:05.421 954 conf: 'RamDisk.dll' loaded successfully.
8/16 5:29:05.562 954 IMG: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|img'
8/16 5:29:05.562 954 IMG: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'img'
8/16 5:29:05.562 954 IMG: SscPort_Init: 	Comment : ''
8/16 5:29:05.562 954 IMG: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:24)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/16 5:29:05.562 954 conf: 'ImageFile.dll' loaded successfully.
8/16 5:29:05.750 954 VDVD: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x18
	Alias: 'My Computer'
	Extensions: 'mds,iso'
8/16 5:29:05.750 954 conf: 'VirtualDvd.dll' loaded successfully.
8/16 5:29:06.062 954 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'ibv'
8/16 5:29:06.062 954 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1e
	Alias: 'Snapshots'
	Extensions: 'ibvss'
8/16 5:29:06.062 954 IBV: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x9
	Alias: 'Image Files'
	Extensions: 'img,dat'
8/16 5:29:06.062 954 IBV: SscPort_Init: (build 20090702, built Jul  8 2009 17:05:27)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ImageDir  = '*\'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/16 5:29:06.062 954 conf: 'IBVolume.dll' loaded successfully.
8/16 5:29:06.296 954 MIR: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'Mirror Volumes'
	Extensions: 'mir'
8/16 5:29:06.296 954 MIR: *SscPort_Init: 
	Dirname: '*'
	Rights: 0xc
	Alias: 'Image Files'
	Extensions: 'img,dat'
8/16 5:29:06.296 954 conf: 'Mirror.dll' loaded successfully.
8/16 5:29:06.437 954 DSKBR: SscPort_Init: (built Jul  8 2009 17:05:30)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '0001'
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/16 5:29:06.437 954 conf: 'DiskBridge.dll' loaded successfully.
8/16 5:29:06.546 954 VTAPE: SscPort_Init: Parse ImageDir: '*|cmdfv|My Computer|vtl'
8/16 5:29:06.546 954 VTAPE: *SscPort_Init: 
	Dirname: '*'
	Rights: 0x1f
	Alias: 'My Computer'
	Extensions: 'vtl'
8/16 5:29:06.546 954 VTAPE: SscPort_Init: 	Comment : ''
8/16 5:29:06.546 954 VTAPE: *** SscPort_Init: Logging level is set to 1.
8/16 5:29:06.546 954 VTAPE: *** SscPort_Init: DBG_ERROR   statements are ON.
8/16 5:29:06.546 954 VTAPE: SscPort_Init: (built Jun  7 2009 10:57:11)
	VendorId  = '        '
	ProductId = '                '
	Revision  = '    '
	DefImageDir  = '*\'
	Volumes   = 0
	ExeDir    = 'C:\Program Files\StarWind Software\StarWind\'
8/16 5:29:06.546 954 VTAPE: *** TapeEmulCtrlApiStart: StarWind VTL License Type is 3
8/16 5:29:06.546 954 VTAPE: *** TapeEmulCtrlApiStart: This Enterprise Edition supports a maximum of 64 devices, each up to 1048576 MB in length.
8/16 5:29:06.546 954 conf: 'VirtualTape.dll' loaded successfully.
8/16 5:29:06.546 954 S: SymId: 'ImageFile0': Assigned target name: 'server01disk', alias 'server01disk'
8/16 5:29:06.546 954 IMG: SscPort_Create: Opening device 'ImageFile0', image file 'My Computer\C\swdisks\server01.img', buffering=0, async=1, readonly=0.
8/16 5:29:06.640 954 IMG: ImageFile_Create: 'C:\swdisks\server01.img': type 0, geometry: sct 16, trks 32, cyl 690800, TotalSectors: 353689600
8/16 5:29:06.640 954 IMG: ImageFile_Create: VendorId 'ROCKET  ', ProductId 'IMAGEFILE       ', Revision '0001', Serial '0806F7D53974996F'
8/16 5:29:06.640 954 Tgt: : parameter 'header', value '0'.
8/16 5:29:06.640 954 Tgt: : parameter 'file', value 'My Computer\C\swdisks\server01.img'.
8/16 5:29:06.640 954 Tgt: : parameter 'buffering', value 'no'.
8/16 5:29:06.640 954 Tgt: : parameter 'asyncmode', value 'yes'.
8/16 5:29:06.640 954 Tgt: : parameter 'ImageSizeLow', value '700448768'.
8/16 5:29:06.640 954 Tgt: : parameter 'ImageSizeHigh', value '42'.
8/16 5:29:06.640 954 Tgt: : parameter 'readonly', value 'no'.
8/16 5:29:06.640 954 S: server01disk: 1 session(s) allowed.
8/16 5:29:06.640 954 conf: Target [0x000000000011D2E0] has been created. Device 'ImageFile0' is accesible as target 'server01disk'.
8/16 5:29:06.640 954 conf: Variable 'AccessPolicy' is set to 'allow'.
8/16 5:29:06.640 954 Srv: Refreshing device list (complete)...
8/16 5:29:06.703 97c conf: Waiting for Control connections at 0.0.0.0:3261 ...
8/16 5:29:06.703 954 Srv: bind() to 192.168.1.16:3260 failed: The requested address is not valid in its context. (code: 10049).
8/16 5:29:06.703 97c conf: Stop listening to control connections.
8/16 5:29:06.703 954 Srv: Shutdown completed.
8/16 5:29:06.703 954 S: the device 'ImageFile0' is closed.
Any ideas where i need to be looking?
Thanks.
Mark
Top
Robert (staff)
Posts: 303
Joined: Fri Feb 13, 2009 9:42 am

Wed Aug 19, 2009 12:26 pm

Mark,

Did you try re-installing StarWind application after the updates were installed on the PC?

Thanks
Robert
StarWind Software Inc.
http://www.starwindsoftware.com
Top
navsol
Posts: 2
Joined: Tue Aug 18, 2009 2:45 pm

Thu Aug 20, 2009 10:58 am

Hi
I have re-installed the software and it does indeed work now. Is this something I would need to do each time the system takes an update?
Top
Robert (staff)
Posts: 303
Joined: Fri Feb 13, 2009 9:42 am

Fri Aug 21, 2009 12:52 pm

We've not seen this before. Probably an update broke something that started preventing iSCSI to work as it should.

Thanks
Robert
StarWind Software Inc.
http://www.starwindsoftware.com
Top
Post Reply

Return to “StarWind Virtual SAN (VSAN) [+Free], HCI Appliance (HCA), Virtual HCI Appliance (VHCA) [+Free]”