Hi

I've got a scripting driver that updates/creates Exchange mailboxes via
Poweshell commands. It works great other than being very slow. The
speed issue is down a wait every time a Powershell command is kicked
off, the IDM processing is rapid as with all my drivers. I believe the
problem could be that a successful powershell script 'exists with code
0' meaning it returns nothing. I've set the script timeout value to 3
seconds but it still takes approx 40 seconds for some Powershell
commands to complete. Anyone come across this? I'm on IDM v 3.6.1,
scripting driver v. 3.6.0.
I've added a trace on the Remote loader side so you can see what I
mean:

Code:
--------------------

2010-11-09 10:04:04 [1936@16776] (drv_connection): Connection_receiveBytesWithCommand: starting
2010-11-09 10:04:04 [1936@16776] (drv_connection): Connection_waitForDocument: starting
2010-11-09 10:04:04 [1936@16980] (trace): Received.
2010-11-09 10:04:04 [1936@16980] (trace): <nds ndsversion="8.x" dtdversion="3.5">
2010-11-09 10:04:04 [1936@16980] (trace): <source>
2010-11-09 10:04:04 [1936@16980] (trace): <product version="3.6.11.4904">DirXML</product>
2010-11-09 10:04:04 [1936@16980] (trace): <contact>Novell, Inc.</contact>
2010-11-09 10:04:04 [1936@16980] (trace): </source>
2010-11-09 10:04:04 [1936@16980] (trace): <input>
2010-11-09 10:04:04 [1936@16980] (trace): <add src-entry-id="329597" src-dn="\META\meta\IDVault\Users\idmtest102" qualified-src-dn="O=meta\OU=IDVault\OU=Users\CN=idmtest102" event-id="mmu-idm-lisa#20101109100318#1#1" class-name="User">
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="CN">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1262770858#9" naming="true">idmtest102</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="sAMAccountName">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1273086938#16">idmtest102</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="DirXML-ExShellState">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1288886645#2">Enabled</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="Given Name">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1262770858#33">idmtest102</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="idv_Exchange">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1288896342#1">Yes</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="EmailAlias">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1263213601#1">idm.test.102a</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="Surname">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string" timestamp="1289296998#2">tester102</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="DirXML-ExchCustomEvent">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string">mailboxEnable</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="DirXML-ExchCustomEventParam1">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string">idmtest102</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): <add-attr attr-name="DirXML-ExchCustomEventParam2">
2010-11-09 10:04:04 [1936@16980] (trace): <value type="string">idm.test.102a</value>
2010-11-09 10:04:04 [1936@16980] (trace): </add-attr>
2010-11-09 10:04:04 [1936@16980] (trace): </add>
2010-11-09 10:04:04 [1936@16980] (trace): </input>
2010-11-09 10:04:04 [1936@16980] (trace): </nds>
2010-11-09 10:04:04 [1936@16980] (trace): Received document for subscriber channel
2010-11-09 10:04:04 [1936@16980] (drv_connection): Connection_queueDocument: starting
2010-11-09 10:04:04 [1936@16980] (drv_connection): Connection_queueDocument: ending
2010-11-09 10:04:04 [1936@16980] (drv_connection): Connection_receiveBytes: starting
2010-11-09 10:04:04 [1936@16980] (trace): Waiting for receive...
2010-11-09 10:04:04 [1936@16776] (drv_connection): Connection_receiveBytesWithCommand: ending
2010-11-09 10:04:04 [1936@16776] (drv_driver): Driver_registerObjectDn: starting
2010-11-09 10:04:04 [1936@16776] (drv_driver): Driver_registerObjectDn: ending
2010-11-09 10:04:04 [1936@16776] (trace): Calling SubscriptionShim.execute()
2010-11-09 10:04:04 [1936@16776] (trace): --->DriverShim subscriber execute()..
2010-11-09 10:04:04 [1936@16776] (trace): --->DriverShim subscriber dispatch() command = add
2010-11-09 10:04:04 [1936@16776] (trace): WindowsScript addHandler
2010-11-09 10:04:04 [1936@16776] (trace): WindowsScript addHandler creating input document
2010-11-09 10:04:04 [1936@16776] (trace): C:\Users\DIRXML~1\AppData\Local\Temp\WSDC8B2.tmp
2010-11-09 10:04:04 [1936@16776] (trace): WindowsScript addHandler executing subscriber script
2010-11-09 10:04:04 [1936@16776] (trace): WindowsScript_executeCommand: Executing command powershell -PSConsoleFile "C:\Program Files\Microsoft\Exchange Server\V14\Bin\exshell.psc1" "scripts\powershell\Subscriber.ps1" "C:\Users\DIRXML~1\AppData\Local\Temp\WSDC8B2. tmp" "C:\Users\DIRXML~1\AppData\Local\Temp\WSDF346. tmp"
*******
******* there is now a 40 second wait for Powershell to return "exited with code 0"
*******
2010-11-09 10:04:44 [1936@16776] (trace): WindowsScript_executeCommand: command exited with code 0
2010-11-09 10:04:44 [1936@16776] (trace): WindowsScript addHandler: complete
2010-11-09 10:04:44 [1936@16776] (trace): --->DriverShim subscriber dispatch() finished
2010-11-09 10:04:44 [1936@16776] (trace): SubscriptionShim.execute() returned:
2010-11-09 10:04:44 [1936@16776] (trace): <nds dtdversion="1.1" ndsversion="8.6">
2010-11-09 10:04:44 [1936@16776] (trace): <source>
2010-11-09 10:04:44 [1936@16776] (trace): <product version="3.6.0" build="May 23 2008 10:58:36" />
2010-11-09 10:04:44 [1936@16776] (trace): <contact />
2010-11-09 10:04:44 [1936@16776] (trace): </source>
2010-11-09 10:04:44 [1936@16776] (trace): <output>
2010-11-09 10:04:44 [1936@16776] (trace): <add-association dest-dn="\meta\meta\IDVault\Users\idmtest102" event-id="mmu-idm-lisa#20101109100318#1#1">147d6106f5f4b348821b703df 5be2184</add-association>
2010-11-09 10:04:44 [1936@16776] (trace): <status event-id="mmu-idm-lisa#20101109100318#1#1" level="success">Successful Add Event (idmtest102)</status>
2010-11-09 10:04:44 [1936@16776] (trace): </output>
2010-11-09 10:04:44 [1936@16776] (trace): </nds>
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_sendReply: starting
2010-11-09 10:04:44 [1936@16776] (drv_connection): Connection_send: starting
2010-11-09 10:04:44 [1936@16776] (trace): Sending...
2010-11-09 10:04:44 [1936@16776] (trace): <nds dtdversion="1.1" ndsversion="8.6">
2010-11-09 10:04:44 [1936@16776] (trace): <source>
2010-11-09 10:04:44 [1936@16776] (trace): <product version="3.6.0" build="May 23 2008 10:58:36" />
2010-11-09 10:04:44 [1936@16776] (trace): <contact />
2010-11-09 10:04:44 [1936@16776] (trace): </source>
2010-11-09 10:04:44 [1936@16776] (trace): <output>
2010-11-09 10:04:44 [1936@16776] (trace): <add-association dest-dn="\meta\meta\IDVault\Users\idmtest102" event-id="mmu-idm-lisa#20101109100318#1#1">147d6106f5f4b348821b703df 5be2184</add-association>
2010-11-09 10:04:44 [1936@16776] (trace): <status event-id="mmu-idm-lisa#20101109100318#1#1" level="success">Successful Add Event (idmtest102)</status>
2010-11-09 10:04:44 [1936@16776] (trace): </output>
2010-11-09 10:04:44 [1936@16776] (trace): </nds>
2010-11-09 10:04:44 [1936@16776] (trace): Document sent.
2010-11-09 10:04:44 [1936@16776] (drv_connection): Connection_send: ending
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_sendReply: ending
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_checkShimError: starting
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_checkShimError: ending rc = 1
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_unregisterObjectDn: starting
2010-11-09 10:04:44 [1936@16776] (drv_driver): Driver_unregisterObjectDn: ending
2010-11-09 10:04:44 [1936@16776] (drv_connection): Connection_receiveBytesWithCommand: starting
2010-11-09 10:04:44 [1936@16776] (drv_connection): Connection_waitForDocument: starting
2010-11-09 10:04:59 [1936@17220] (trace): WindowsScript_executeCommand: command exited with code 0
2010-11-09 10:04:59 [1936@17220] (trace): --->DriverShim publisher channel: checking change log
2010-11-09 10:05:58 [1936@17220] (trace): --->DriverShim publisher channel: polling...

--------------------


Thanks for any advice offered


--
Mike
Manchester Met Uni
------------------------------------------------------------------------
mikepreece's Profile: http://forums.novell.com/member.php?userid=611
View this thread: http://forums.novell.com/showthread.php?t=425476