Hi all,
a workflow is tracing the error "Invalid Group Detected" on the log
of the User Application. This happens when the workflow creates some
entities on the Identity Vault.
Entities are properly created and the workflow ends successfully.
Unfortunately, I do not understand what the error refers to.

How can I avoid this error? Any suggestion will be appreciated.

Thanks,

Alessandro


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

2011-12-22 16:29:11,656 INFO [STDOUT] (http-0.0.0.0-80-9) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('startDateTime')
2011-12-22 16:29:11,656 INFO [STDOUT] (http-0.0.0.0-80-9) 4308580216 [http-0.0.0.0-80-9] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('startDateTime')
2011-12-22 16:29:11,659 INFO [STDOUT] (http-0.0.0.0-80-9) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 1324596600000
2011-12-22 16:29:11,659 INFO [STDOUT] (http-0.0.0.0-80-9) 4308580218 [http-0.0.0.0-80-9] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 1324596600000
2011-12-22 16:29:11,660 INFO [STDOUT] (http-0.0.0.0-80-9) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('endDateTime')
2011-12-22 16:29:11,660 INFO [STDOUT] (http-0.0.0.0-80-9) 4308580220 [http-0.0.0.0-80-9] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('endDateTime')
2011-12-22 16:29:11,663 INFO [STDOUT] (http-0.0.0.0-80-9) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 1324679400000
2011-12-22 16:29:11,663 INFO [STDOUT] (http-0.0.0.0-80-9) 4308580222 [http-0.0.0.0-80-9] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 1324679400000
2011-12-22 16:29:27,588 INFO [STDOUT] (Thread-42) DEBUG [RBPM] [com.novell.soa.af.impl.core.EngineImpl:run] Heartbeat updated for engine: ENGINE, time: 2011-12-22 16:29:27.0
2011-12-22 16:29:27,588 INFO [STDOUT] (Thread-42) 4308596149 [Thread-42] DEBUG com.novell.soa.af.impl.core.EngineImpl - Heartbeat updated for engine: ENGINE, time: 2011-12-22 16:29:27.0
2011-12-22 16:29:35,406 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Started
2011-12-22 16:29:35,407 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603966 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Started
2011-12-22 16:29:35,408 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Done
2011-12-22 16:29:35,408 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603968 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Done
2011-12-22 16:29:35,409 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Started
2011-12-22 16:29:35,409 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603969 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Started
2011-12-22 16:29:35,410 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Done
2011-12-22 16:29:35,411 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603970 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Done
2011-12-22 16:29:35,412 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Started
2011-12-22 16:29:35,412 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603972 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Started
2011-12-22 16:29:35,413 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Done
2011-12-22 16:29:35,413 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603973 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Done
2011-12-22 16:29:35,414 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: initiator
2011-12-22 16:29:35,414 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603974 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: initiator
2011-12-22 16:29:35,416 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,416 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603976 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,417 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: process.getTimestamp()
2011-12-22 16:29:35,417 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603977 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: process.getTimestamp()
2011-12-22 16:29:35,419 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: Thu Dec 22 16:00:05 CET 2011
2011-12-22 16:29:35,419 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603979 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Thu Dec 22 16:00:05 CET 2011
2011-12-22 16:29:35,421 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: recipient
2011-12-22 16:29:35,421 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603981 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: recipient
2011-12-22 16:29:35,422 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,422 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603982 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,424 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('sapAccount')
2011-12-22 16:29:35,424 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603984 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('sapAccount')
2011-12-22 16:29:35,426 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:35,426 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603985 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:35,427 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('startDate')
2011-12-22 16:29:35,427 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603987 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('startDate')
2011-12-22 16:29:35,429 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223130000000+0100
2011-12-22 16:29:35,430 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603989 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223130000000+0100
2011-12-22 16:29:35,431 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('endDate')
2011-12-22 16:29:35,431 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603991 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('endDate')
2011-12-22 16:29:35,433 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223130000000+0100
2011-12-22 16:29:35,433 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603993 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223130000000+0100
2011-12-22 16:29:35,435 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('reason')
2011-12-22 16:29:35,435 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603995 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('reason')
2011-12-22 16:29:35,437 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: Test Busy
2011-12-22 16:29:35,437 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603997 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Test Busy
2011-12-22 16:29:35,438 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('startDateTime')
2011-12-22 16:29:35,438 INFO [STDOUT] (http-0.0.0.0-80-24) 4308603998 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('startDateTime')
2011-12-22 16:29:35,441 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 1324596600000
2011-12-22 16:29:35,441 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604000 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 1324596600000
2011-12-22 16:29:35,442 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('endDateTime')
2011-12-22 16:29:35,442 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604002 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('endDateTime')
2011-12-22 16:29:35,444 INFO [STDOUT] (http-0.0.0.0-80-24) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 1324679400000
2011-12-22 16:29:35,444 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604004 [http-0.0.0.0-80-24] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 1324679400000
2011-12-22 16:29:35,481 INFO [STDOUT] (http-0.0.0.0-80-24) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Workflow_Approved] Initiated by cn=U.Test,ou=Users,o=Company, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity3, Recipient: cn=A.Christie,ou=Users,o=Company, Secondary User: null
2011-12-22 16:29:35,521 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604040 [http-0.0.0.0-80-24] INFO com.novell.soa.af.impl.LogEvent - [Workflow_Approved] Initiated by cn=U.Test,ou=Users,o=Company, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity3, Recipient: cn=A.Christie,ou=Users,o=Company, Secondary User: null
2011-12-22 16:29:35,560 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:forwa rd] forward(), activity=CFO Privileged Access Approval[Activity3], link=APPROVED[Activity3, log_approval]
2011-12-22 16:29:35,560 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604120 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - forward(), activity=CFO Privileged Access Approval[Activity3], link=APPROVED[Activity3, log_approval]
2011-12-22 16:29:35,565 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.link.Link:<init>] Link() FORWARD[log_approval, Activity]
2011-12-22 16:29:35,565 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604124 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.link.Link - Link() FORWARD[log_approval, Activity]
2011-12-22 16:29:35,566 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:forward] forward() current=CFO Privileged Access Approval[Activity3], next=Trace Access Granted[log_approval]
2011-12-22 16:29:35,566 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604126 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.ProcessImpl - forward() current=CFO Privileged Access Approval[Activity3], next=Trace Access Granted[log_approval]
2011-12-22 16:29:35,570 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=CFO Privileged Access Approval[Activity3], newStatus=Departing
2011-12-22 16:29:35,570 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604130 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=CFO Privileged Access Approval[Activity3], newStatus=Departing
2011-12-22 16:29:35,581 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.timers.ActivityTimer:cancel] cancel() timed activity=Activity3 timerType =-1
2011-12-22 16:29:35,581 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604141 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.timers.ActivityTimer - cancel() timed activity=Activity3 timerType =-1
2011-12-22 16:29:35,583 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yDepart] notifyDepart(), activity=CFO Privileged Access Approval[Activity3]
2011-12-22 16:29:35,583 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604143 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyDepart(), activity=CFO Privileged Access Approval[Activity3]
2011-12-22 16:29:35,586 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:apply Changes] applyChanges() activity=CFO Privileged Access Approval[Activity3]
2011-12-22 16:29:35,586 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604145 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - applyChanges() activity=CFO Privileged Access Approval[Activity3]
2011-12-22 16:29:35,589 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Started
2011-12-22 16:29:35,590 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604149 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Started
2011-12-22 16:29:35,591 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Done
2011-12-22 16:29:35,591 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604151 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Done
2011-12-22 16:29:35,592 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Started
2011-12-22 16:29:35,592 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604152 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Started
2011-12-22 16:29:35,594 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Done
2011-12-22 16:29:35,594 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604153 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Done
2011-12-22 16:29:35,595 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Started
2011-12-22 16:29:35,595 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604155 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Started
2011-12-22 16:29:35,597 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Done
2011-12-22 16:29:35,597 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604156 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Done
2011-12-22 16:29:35,602 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=CFO Privileged Access Approval[Activity3], newStatus=Idle
2011-12-22 16:29:35,602 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604162 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=CFO Privileged Access Approval[Activity3], newStatus=Idle
2011-12-22 16:29:35,604 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Trace Access Granted[log_approval], newStatus=Arriving
2011-12-22 16:29:35,604 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604164 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Trace Access Granted[log_approval], newStatus=Arriving
2011-12-22 16:29:35,617 INFO [STDOUT] (http-0.0.0.0-80-24) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity3, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,657 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604177 [http-0.0.0.0-80-24] INFO com.novell.soa.af.impl.LogEvent - [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity3, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,698 INFO [STDOUT] (http-0.0.0.0-80-24) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:startActiv ity] startActivity() activity=Trace Access Granted[log_approval], newThread=true, pooled = true
2011-12-22 16:29:35,698 INFO [STDOUT] (http-0.0.0.0-80-24) 4308604258 [http-0.0.0.0-80-24] TRACE com.novell.soa.af.impl.core.ProcessImpl - startActivity() activity=Trace Access Granted[log_approval], newThread=true, pooled = true
2011-12-22 16:29:35,701 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yArrive] notifyArrive(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,701 INFO [STDOUT] (pool-13-thread-3) 4308604261 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyArrive(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,701 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNoderocess] process(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,701 INFO [STDOUT] (pool-13-thread-3) 4308604262 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - process(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,702 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Trace Access Granted[log_approval], newStatus=Running
2011-12-22 16:29:35,702 INFO [STDOUT] (pool-13-thread-3) 4308604262 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Trace Access Granted[log_approval], newStatus=Running
2011-12-22 16:29:35,716 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Started
2011-12-22 16:29:35,716 INFO [STDOUT] (pool-13-thread-3) 4308604277 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Started
2011-12-22 16:29:35,717 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfRequest] registerNrfRequest - Done
2011-12-22 16:29:35,717 INFO [STDOUT] (pool-13-thread-3) 4308604277 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfRequest - Done
2011-12-22 16:29:35,717 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Started
2011-12-22 16:29:35,717 INFO [STDOUT] (pool-13-thread-3) 4308604278 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Started
2011-12-22 16:29:35,717 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterNrfResourceRequest] registerNrfResourceRequest - Done
2011-12-22 16:29:35,718 INFO [STDOUT] (pool-13-thread-3) 4308604278 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerNrfResourceRequest - Done
2011-12-22 16:29:35,718 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Started
2011-12-22 16:29:35,718 INFO [STDOUT] (pool-13-thread-3) 4308604279 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Started
2011-12-22 16:29:35,718 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:regi sterAttestation] registerAttestation - Done
2011-12-22 16:29:35,718 INFO [STDOUT] (pool-13-thread-3) 4308604279 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.DataItemEvaluator - registerAttestation - Done
2011-12-22 16:29:35,719 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: IDVault.get(Activity3.getUser() , 'user', 'LastName') + ' ' + IDVault.get(Activity3.getUser() , 'user', 'FirstName');
2011-12-22 16:29:35,719 INFO [STDOUT] (pool-13-thread-3) 4308604280 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: IDVault.get(Activity3.getUser() , 'user', 'LastName') + ' ' + IDVault.get(Activity3.getUser() , 'user', 'FirstName');
2011-12-22 16:29:35,757 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Test User
2011-12-22 16:29:35,757 INFO [STDOUT] (pool-13-thread-3) 4308604317 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Test User
2011-12-22 16:29:35,757 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: 'Request for account ' + IDVault.get(flowdata.get('sapAccount'), 'SAPAdministrator', 'sapUsername') + ' has been approved.'
2011-12-22 16:29:35,757 INFO [STDOUT] (pool-13-thread-3) 4308604318 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: 'Request for account ' + IDVault.get(flowdata.get('sapAccount'), 'SAPAdministrator', 'sapUsername') + ' has been approved.'
2011-12-22 16:29:35,764 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,764 INFO [STDOUT] (pool-13-thread-3) 4308604324 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,764 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.activity.LogActivityrocess] [User_Message] Initiated by cn=A.Christie,ou=Users,o=Company, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: log_approval, Recipient: cn=A.Christie,ou=Users,o=Company, Author: Test User, Message: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,805 INFO [STDOUT] (pool-13-thread-3) 4308604325 [pool-13-thread-3] INFO com.novell.soa.af.impl.activity.LogActivity - [User_Message] Initiated by cn=A.Christie,ou=Users,o=Company, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: log_approval, Recipient: cn=A.Christie,ou=Users,o=Company, Author: Test User, Message: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,806 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: 'Request for account ' + IDVault.get(flowdata.get('sapAccount'), 'SAPAdministrator', 'sapUsername') + ' has been approved.'
2011-12-22 16:29:35,806 INFO [STDOUT] (pool-13-thread-3) 4308604366 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: 'Request for account ' + IDVault.get(flowdata.get('sapAccount'), 'SAPAdministrator', 'sapUsername') + ' has been approved.'
2011-12-22 16:29:35,813 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,813 INFO [STDOUT] (pool-13-thread-3) 4308604373 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Request for account S.ADMIN has been approved.
2011-12-22 16:29:35,824 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:forwa rd] forward(), activity=Trace Access Granted[log_approval], link=FORWARD[log_approval, Activity]
2011-12-22 16:29:35,824 INFO [STDOUT] (pool-13-thread-3) 4308604385 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - forward(), activity=Trace Access Granted[log_approval], link=FORWARD[log_approval, Activity]
2011-12-22 16:29:35,828 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.link.Link:<init>] Link() FORWARD[Activity, Activity2]
2011-12-22 16:29:35,828 INFO [STDOUT] (pool-13-thread-3) 4308604388 [pool-13-thread-3] TRACE com.novell.soa.af.impl.link.Link - Link() FORWARD[Activity, Activity2]
2011-12-22 16:29:35,828 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:forward] forward() current=Trace Access Granted[log_approval], next=Schedule Account Enable[Activity]
2011-12-22 16:29:35,828 INFO [STDOUT] (pool-13-thread-3) 4308604389 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - forward() current=Trace Access Granted[log_approval], next=Schedule Account Enable[Activity]
2011-12-22 16:29:35,831 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Trace Access Granted[log_approval], newStatus=Departing
2011-12-22 16:29:35,831 INFO [STDOUT] (pool-13-thread-3) 4308604392 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Trace Access Granted[log_approval], newStatus=Departing
2011-12-22 16:29:35,838 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yDepart] notifyDepart(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,838 INFO [STDOUT] (pool-13-thread-3) 4308604399 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyDepart(), activity=Trace Access Granted[log_approval]
2011-12-22 16:29:35,840 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Trace Access Granted[log_approval], newStatus=Idle
2011-12-22 16:29:35,840 INFO [STDOUT] (pool-13-thread-3) 4308604400 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Trace Access Granted[log_approval], newStatus=Idle
2011-12-22 16:29:35,840 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Enable[Activity], newStatus=Arriving
2011-12-22 16:29:35,841 INFO [STDOUT] (pool-13-thread-3) 4308604401 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Enable[Activity], newStatus=Arriving
2011-12-22 16:29:35,850 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: log_approval, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,889 INFO [STDOUT] (pool-13-thread-3) 4308604411 [pool-13-thread-3] INFO com.novell.soa.af.impl.LogEvent - [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: log_approval, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,898 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:startActiv ity] startActivity() activity=Schedule Account Enable[Activity], newThread=false, pooled = false
2011-12-22 16:29:35,898 INFO [STDOUT] (pool-13-thread-3) 4308604459 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - startActivity() activity=Schedule Account Enable[Activity], newThread=false, pooled = false
2011-12-22 16:29:35,898 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yArrive] notifyArrive(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:35,899 INFO [STDOUT] (pool-13-thread-3) 4308604459 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyArrive(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:35,899 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNoderocess] process(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:35,899 INFO [STDOUT] (pool-13-thread-3) 4308604460 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - process(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:35,899 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Enable[Activity], newStatus=Running
2011-12-22 16:29:35,899 INFO [STDOUT] (pool-13-thread-3) 4308604460 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Enable[Activity], newStatus=Running
2011-12-22 16:29:35,910 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'CN=' + 'WOE' + process.getRequestId().toUpperCase() + ',' + flowdata.get('workorderLocation');
2011-12-22 16:29:35,910 INFO [STDOUT] (pool-13-thread-3) 4308604471 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'CN=' + 'WOE' + process.getRequestId().toUpperCase() + ',' + flowdata.get('workorderLocation');
2011-12-22 16:29:35,912 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:35,912 INFO [STDOUT] (pool-13-thread-3) 4308604472 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:35,912 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'WOE' + process.getRequestId().toUpperCase();
2011-12-22 16:29:35,912 INFO [STDOUT] (pool-13-thread-3) 4308604473 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'WOE' + process.getRequestId().toUpperCase();
2011-12-22 16:29:35,913 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: WOEC20080B1FF2A4A46BD097F5C4A1B5EC7
2011-12-22 16:29:35,913 INFO [STDOUT] (pool-13-thread-3) 4308604474 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: WOEC20080B1FF2A4A46BD097F5C4A1B5EC7
2011-12-22 16:29:35,913 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:35,913 INFO [STDOUT] (pool-13-thread-3) 4308604474 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:35,919 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223003000+0100
2011-12-22 16:29:35,919 INFO [STDOUT] (pool-13-thread-3) 4308604479 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223003000+0100
2011-12-22 16:29:35,919 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('sapAccount');
2011-12-22 16:29:35,920 INFO [STDOUT] (pool-13-thread-3) 4308604480 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('sapAccount');
2011-12-22 16:29:35,921 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:35,921 INFO [STDOUT] (pool-13-thread-3) 4308604481 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:35,921 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:35,921 INFO [STDOUT] (pool-13-thread-3) 4308604482 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:35,927 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223003000+0100
2011-12-22 16:29:35,927 INFO [STDOUT] (pool-13-thread-3) 4308604487 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223003000+0100
2011-12-22 16:29:35,927 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'pending'
2011-12-22 16:29:35,927 INFO [STDOUT] (pool-13-thread-3) 4308604488 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'pending'
2011-12-22 16:29:35,928 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: pending
2011-12-22 16:29:35,928 INFO [STDOUT] (pool-13-thread-3) 4308604489 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: pending
2011-12-22 16:29:35,928 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: recipient
2011-12-22 16:29:35,928 INFO [STDOUT] (pool-13-thread-3) 4308604489 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: recipient
2011-12-22 16:29:35,929 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,929 INFO [STDOUT] (pool-13-thread-3) 4308604490 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:35,929 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:35,929 INFO [STDOUT] (pool-13-thread-3) 4308604490 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:35,935 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223233000+0100
2011-12-22 16:29:35,935 INFO [STDOUT] (pool-13-thread-3) 4308604495 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223233000+0100
2011-12-22 16:29:35,935 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'Enable_Account'
2011-12-22 16:29:35,935 INFO [STDOUT] (pool-13-thread-3) 4308604496 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'Enable_Account'
2011-12-22 16:29:35,936 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: Enable_Account
2011-12-22 16:29:35,936 INFO [STDOUT] (pool-13-thread-3) 4308604497 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Enable_Account
2011-12-22 16:29:35,949 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProvisioningUtil:isGro up] Invalid Group Detected: [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
com.novell.soa.af.impl.prvsrv.ProvisionException: Invalid Group Detected: [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
at com.novell.soa.af.impl.core.VDXUtil.getGroupMember s(VDXUtil.java:124)
at com.novell.soa.af.impl.core.ProvisioningUtil.getGr oupMembers(ProvisioningUtil.java:123)
at com.novell.soa.af.impl.core.ProvisioningUtil.isGro up(ProvisioningUtil.java:72)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:299)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.LogActivity.proces s(LogActivity.java:139)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.activity.RunnableActivity.r un(RunnableActivity.java:50)
at java.util.concurrent.ThreadPoolExecutor$Worker.run Task(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
2011-12-22 16:29:35,949 INFO [STDOUT] (pool-13-thread-3) 4308604509 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProvisioningUtil - Invalid Group Detected: [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
com.novell.soa.af.impl.prvsrv.ProvisionException: Invalid Group Detected: [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
at com.novell.soa.af.impl.core.VDXUtil.getGroupMember s(VDXUtil.java:124)
at com.novell.soa.af.impl.core.ProvisioningUtil.getGr oupMembers(ProvisioningUtil.java:123)
at com.novell.soa.af.impl.core.ProvisioningUtil.isGro up(ProvisioningUtil.java:72)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:299)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.LogActivity.proces s(LogActivity.java:139)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.activity.RunnableActivity.r un(RunnableActivity.java:50)
at java.util.concurrent.ThreadPoolExecutor$Worker.run Task(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
2011-12-22 16:29:35,962 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doR equest] Provision Request: Entity = [WorkOrder] Identifier = [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company]
Attributes = [CN=[WOEC20080B1FF2A4A46BD097F5C4A1B5EC7] multivalued=[false] operation=[1]DirXML-DueDate=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-nwoContent=[cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-nwoDeleteDueDate=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-nwoStatus=[pending] multivalued=[false] operation=[1]DirXML-Other1=[cn=A.Christie,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-Other2=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-woType=[Enable_Account] multivalued=[false] operation=[1]]
2011-12-22 16:29:35,962 INFO [STDOUT] (pool-13-thread-3) 4308604523 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - Provision Request: Entity = [WorkOrder] Identifier = [CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company]
Attributes = [CN=[WOEC20080B1FF2A4A46BD097F5C4A1B5EC7] multivalued=[false] operation=[1]DirXML-DueDate=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-nwoContent=[cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-nwoDeleteDueDate=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-nwoStatus=[pending] multivalued=[false] operation=[1]DirXML-Other1=[cn=A.Christie,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-Other2=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-woType=[Enable_Account] multivalued=[false] operation=[1]]
2011-12-22 16:29:35,967 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doA ddProvisionRequest] creating entity: entity = [<?xml version="1.0" encoding="UTF-8"?><entity editable="false" hidden="false" removable="false">
<key>CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wor korder,OU=Data,O=Company</key>
<display-label>CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wo rkorder,OU=Data,O=Company</display-label>
<attributes>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoStatus</key>
<value>pending</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoContent</key>
<value>cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-DueDate</key>
<value>20111223003000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-woType</key>
<value>Enable_Account</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other2</key>
<value>20111223233000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other1</key>
<value>cn=A.Christie,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>CN</key>
<value>WOEC20080B1FF2A4A46BD097F5C4A1B5EC7</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoDeleteDueDate</key>
<value>20111223003000+0100</value>
</attribute>
</attributes>
</entity>
2011-12-22 16:29:35,967 INFO [STDOUT] (pool-13-thread-3) 4308604527 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - creating entity: entity = [<?xml version="1.0" encoding="UTF-8"?><entity editable="false" hidden="false" removable="false">
<key>CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wor korder,OU=Data,O=Company</key>
<display-label>CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wo rkorder,OU=Data,O=Company</display-label>
<attributes>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoStatus</key>
<value>pending</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoContent</key>
<value>cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-DueDate</key>
<value>20111223003000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-woType</key>
<value>Enable_Account</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other2</key>
<value>20111223233000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other1</key>
<value>cn=A.Christie,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>CN</key>
<value>WOEC20080B1FF2A4A46BD097F5C4A1B5EC7</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoDeleteDueDate</key>
<value>20111223003000+0100</value>
</attribute>
</attributes>
</entity>
2011-12-22 16:29:35,997 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doR equest] VDM object added. Object = CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:35,997 INFO [STDOUT] (pool-13-thread-3) 4308604558 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - VDM object added. Object = CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:35,998 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Provision_Granted] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity, Recipient: CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,037 INFO [STDOUT] (pool-13-thread-3) 4308604558 [pool-13-thread-3] INFO com.novell.soa.af.impl.LogEvent - [Provision_Granted] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity, Recipient: CN=WOEC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,121 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:forwa rd] forward(), activity=Schedule Account Enable[Activity], link=FORWARD[Activity, Activity2]
2011-12-22 16:29:36,121 INFO [STDOUT] (pool-13-thread-3) 4308604682 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - forward(), activity=Schedule Account Enable[Activity], link=FORWARD[Activity, Activity2]
2011-12-22 16:29:36,125 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.link.Link:<init>] Link() FORWARD[Activity2, finish]
2011-12-22 16:29:36,125 INFO [STDOUT] (pool-13-thread-3) 4308604685 [pool-13-thread-3] TRACE com.novell.soa.af.impl.link.Link - Link() FORWARD[Activity2, finish]
2011-12-22 16:29:36,126 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:forward] forward() current=Schedule Account Enable[Activity], next=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,126 INFO [STDOUT] (pool-13-thread-3) 4308604686 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - forward() current=Schedule Account Enable[Activity], next=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,129 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Enable[Activity], newStatus=Departing
2011-12-22 16:29:36,129 INFO [STDOUT] (pool-13-thread-3) 4308604689 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Enable[Activity], newStatus=Departing
2011-12-22 16:29:36,277 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yDepart] notifyDepart(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:36,277 INFO [STDOUT] (pool-13-thread-3) 4308604837 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyDepart(), activity=Schedule Account Enable[Activity]
2011-12-22 16:29:36,278 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:apply Changes] applyChanges() activity=Schedule Account Enable[Activity]
2011-12-22 16:29:36,278 INFO [STDOUT] (pool-13-thread-3) 4308604839 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - applyChanges() activity=Schedule Account Enable[Activity]
2011-12-22 16:29:36,281 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateTarget] evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2011-12-22 16:29:36,281 INFO [STDOUT] (pool-13-thread-3) 4308604841 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2011-12-22 16:29:36,282 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateTarget] result: com.novell.soa.script.mozilla.javascript.Undefined @e12bd8
2011-12-22 16:29:36,282 INFO [STDOUT] (pool-13-thread-3) 4308604842 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: com.novell.soa.script.mozilla.javascript.Undefined @e12bd8
2011-12-22 16:29:36,285 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Enable[Activity], newStatus=Idle
2011-12-22 16:29:36,285 INFO [STDOUT] (pool-13-thread-3) 4308604845 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Enable[Activity], newStatus=Idle
2011-12-22 16:29:36,286 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Arriving
2011-12-22 16:29:36,286 INFO [STDOUT] (pool-13-thread-3) 4308604846 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Arriving
2011-12-22 16:29:36,306 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,345 INFO [STDOUT] (pool-13-thread-3) 4308604866 [pool-13-thread-3] INFO com.novell.soa.af.impl.LogEvent - [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,360 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:startActiv ity] startActivity() activity=Schedule Account Disable[Activity2], newThread=false, pooled = false
2011-12-22 16:29:36,360 INFO [STDOUT] (pool-13-thread-3) 4308604920 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - startActivity() activity=Schedule Account Disable[Activity2], newThread=false, pooled = false
2011-12-22 16:29:36,360 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yArrive] notifyArrive(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,360 INFO [STDOUT] (pool-13-thread-3) 4308604921 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyArrive(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,361 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNoderocess] process(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,361 INFO [STDOUT] (pool-13-thread-3) 4308604922 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - process(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,361 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Running
2011-12-22 16:29:36,361 INFO [STDOUT] (pool-13-thread-3) 4308604922 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Running
2011-12-22 16:29:36,372 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'CN=' + 'WOD' + process.getRequestId().toUpperCase() + ',' + flowdata.get('workorderLocation')
2011-12-22 16:29:36,372 INFO [STDOUT] (pool-13-thread-3) 4308604932 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'CN=' + 'WOD' + process.getRequestId().toUpperCase() + ',' + flowdata.get('workorderLocation')
2011-12-22 16:29:36,374 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,374 INFO [STDOUT] (pool-13-thread-3) 4308604934 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,374 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'WOD' + process.getRequestId().toUpperCase();
2011-12-22 16:29:36,374 INFO [STDOUT] (pool-13-thread-3) 4308604935 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'WOD' + process.getRequestId().toUpperCase();
2011-12-22 16:29:36,375 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: WODC20080B1FF2A4A46BD097F5C4A1B5EC7
2011-12-22 16:29:36,375 INFO [STDOUT] (pool-13-thread-3) 4308604935 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: WODC20080B1FF2A4A46BD097F5C4A1B5EC7
2011-12-22 16:29:36,376 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,376 INFO [STDOUT] (pool-13-thread-3) 4308604936 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,381 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223233000+0100
2011-12-22 16:29:36,382 INFO [STDOUT] (pool-13-thread-3) 4308604942 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223233000+0100
2011-12-22 16:29:36,382 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: flowdata.get('sapAccount');
2011-12-22 16:29:36,382 INFO [STDOUT] (pool-13-thread-3) 4308604943 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: flowdata.get('sapAccount');
2011-12-22 16:29:36,383 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:36,384 INFO [STDOUT] (pool-13-thread-3) 4308604944 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company
2011-12-22 16:29:36,384 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,384 INFO [STDOUT] (pool-13-thread-3) 4308604945 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getEndDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d=new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getEndDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,390 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223233000+0100
2011-12-22 16:29:36,390 INFO [STDOUT] (pool-13-thread-3) 4308604950 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223233000+0100
2011-12-22 16:29:36,390 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'pending'
2011-12-22 16:29:36,390 INFO [STDOUT] (pool-13-thread-3) 4308604951 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'pending'
2011-12-22 16:29:36,391 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: pending
2011-12-22 16:29:36,391 INFO [STDOUT] (pool-13-thread-3) 4308604951 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: pending
2011-12-22 16:29:36,391 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: recipient
2011-12-22 16:29:36,391 INFO [STDOUT] (pool-13-thread-3) 4308604952 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: recipient
2011-12-22 16:29:36,392 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,392 INFO [STDOUT] (pool-13-thread-3) 4308604952 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,392 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:36,392 INFO [STDOUT] (pool-13-thread-3) 4308604953 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: function getStartDate(dateValue) {



function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyyMMddHHmmssZ');

return df.format(d);

};



if (dateValue != '') {

return printDate(dateValue);

}

}



getStartDate(flowdata.get('startDateTime'));
2011-12-22 16:29:36,398 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: 20111223003000+0100
2011-12-22 16:29:36,398 INFO [STDOUT] (pool-13-thread-3) 4308604959 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 20111223003000+0100
2011-12-22 16:29:36,399 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] evaluating source expression: 'Disable_Account'
2011-12-22 16:29:36,399 INFO [STDOUT] (pool-13-thread-3) 4308604959 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating source expression: 'Disable_Account'
2011-12-22 16:29:36,400 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateSource] result: Disable_Account
2011-12-22 16:29:36,400 INFO [STDOUT] (pool-13-thread-3) 4308604960 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Disable_Account
2011-12-22 16:29:36,433 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProvisioningUtil:isGro up] Invalid Group Detected: [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
com.novell.soa.af.impl.prvsrv.ProvisionException: Invalid Group Detected: [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
at com.novell.soa.af.impl.core.VDXUtil.getGroupMember s(VDXUtil.java:124)
at com.novell.soa.af.impl.core.ProvisioningUtil.getGr oupMembers(ProvisioningUtil.java:123)
at com.novell.soa.af.impl.core.ProvisioningUtil.isGro up(ProvisioningUtil.java:72)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:299)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:320)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.LogActivity.proces s(LogActivity.java:139)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.activity.RunnableActivity.r un(RunnableActivity.java:50)
at java.util.concurrent.ThreadPoolExecutor$Worker.run Task(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
2011-12-22 16:29:36,433 INFO [STDOUT] (pool-13-thread-3) 4308604993 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProvisioningUtil - Invalid Group Detected: [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
com.novell.soa.af.impl.prvsrv.ProvisionException: Invalid Group Detected: [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company].
at com.novell.soa.af.impl.core.VDXUtil.getGroupMember s(VDXUtil.java:124)
at com.novell.soa.af.impl.core.ProvisioningUtil.getGr oupMembers(ProvisioningUtil.java:123)
at com.novell.soa.af.impl.core.ProvisioningUtil.isGro up(ProvisioningUtil.java:72)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:299)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.ProvisionActivity. process(ProvisionActivity.java:320)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1664)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1554)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:259)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:234)
at com.novell.soa.af.impl.activity.LogActivity.proces s(LogActivity.java:139)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:200)
at com.novell.soa.af.impl.activity.RunnableActivity.r un(RunnableActivity.java:50)
at java.util.concurrent.ThreadPoolExecutor$Worker.run Task(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (Unknown Source)
at java.lang.Thread.run(Unknown Source)
2011-12-22 16:29:36,444 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doR equest] Provision Request: Entity = [WorkOrder] Identifier = [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company]
Attributes = [CN=[WODC20080B1FF2A4A46BD097F5C4A1B5EC7] multivalued=[false] operation=[1]DirXML-DueDate=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-nwoContent=[cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-nwoDeleteDueDate=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-nwoStatus=[pending] multivalued=[false] operation=[1]DirXML-Other1=[cn=A.Christie,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-Other2=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-woType=[Disable_Account] multivalued=[false] operation=[1]]
2011-12-22 16:29:36,444 INFO [STDOUT] (pool-13-thread-3) 4308605004 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - Provision Request: Entity = [WorkOrder] Identifier = [CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company]
Attributes = [CN=[WODC20080B1FF2A4A46BD097F5C4A1B5EC7] multivalued=[false] operation=[1]DirXML-DueDate=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-nwoContent=[cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-nwoDeleteDueDate=[20111223233000+0100] multivalued=[false] operation=[1]DirXML-nwoStatus=[pending] multivalued=[false] operation=[1]DirXML-Other1=[cn=A.Christie,ou=Users,o=Company] multivalued=[false] operation=[1]DirXML-Other2=[20111223003000+0100] multivalued=[false] operation=[1]DirXML-woType=[Disable_Account] multivalued=[false] operation=[1]]
2011-12-22 16:29:36,449 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doA ddProvisionRequest] creating entity: entity = [<?xml version="1.0" encoding="UTF-8"?><entity editable="false" hidden="false" removable="false">
<key>CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wor korder,OU=Data,O=Company</key>
<display-label>CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wo rkorder,OU=Data,O=Company</display-label>
<attributes>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoStatus</key>
<value>pending</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoContent</key>
<value>cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-DueDate</key>
<value>20111223233000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-woType</key>
<value>Disable_Account</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other2</key>
<value>20111223003000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other1</key>
<value>cn=A.Christie,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>CN</key>
<value>WODC20080B1FF2A4A46BD097F5C4A1B5EC7</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoDeleteDueDate</key>
<value>20111223233000+0100</value>
</attribute>
</attributes>
</entity>
2011-12-22 16:29:36,449 INFO [STDOUT] (pool-13-thread-3) 4308605009 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - creating entity: entity = [<?xml version="1.0" encoding="UTF-8"?><entity editable="false" hidden="false" removable="false">
<key>CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wor korder,OU=Data,O=Company</key>
<display-label>CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Wo rkorder,OU=Data,O=Company</display-label>
<attributes>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoStatus</key>
<value>pending</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoContent</key>
<value>cn=S.ADMIN,ou=SAP Administrators,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-DueDate</key>
<value>20111223233000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-woType</key>
<value>Disable_Account</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other2</key>
<value>20111223003000+0100</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-Other1</key>
<value>cn=A.Christie,ou=Users,o=Company</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>CN</key>
<value>WODC20080B1FF2A4A46BD097F5C4A1B5EC7</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>DirXML-nwoDeleteDueDate</key>
<value>20111223233000+0100</value>
</attribute>
</attributes>
</entity>
2011-12-22 16:29:36,474 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.prvsrv.ProvisionRequest:doR equest] VDM object added. Object = CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,474 INFO [STDOUT] (pool-13-thread-3) 4308605035 [pool-13-thread-3] TRACE com.novell.soa.af.impl.prvsrv.ProvisionRequest - VDM object added. Object = CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,475 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Provision_Granted] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity2, Recipient: CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,513 INFO [STDOUT] (pool-13-thread-3) 4308605035 [pool-13-thread-3] INFO com.novell.soa.af.impl.LogEvent - [Provision_Granted] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity2, Recipient: CN=WODC20080B1FF2A4A46BD097F5C4A1B5EC7,OU=Workorde r,OU=Data,O=Company
2011-12-22 16:29:36,525 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:forwa rd] forward(), activity=Schedule Account Disable[Activity2], link=FORWARD[Activity2, finish]
2011-12-22 16:29:36,526 INFO [STDOUT] (pool-13-thread-3) 4308605086 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - forward(), activity=Schedule Account Disable[Activity2], link=FORWARD[Activity2, finish]
2011-12-22 16:29:36,529 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:forward] forward() current=Schedule Account Disable[Activity2], next=Finish[finish]
2011-12-22 16:29:36,529 INFO [STDOUT] (pool-13-thread-3) 4308605089 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - forward() current=Schedule Account Disable[Activity2], next=Finish[finish]
2011-12-22 16:29:36,532 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Departing
2011-12-22 16:29:36,533 INFO [STDOUT] (pool-13-thread-3) 4308605093 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Departing
2011-12-22 16:29:36,540 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yDepart] notifyDepart(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,540 INFO [STDOUT] (pool-13-thread-3) 4308605100 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyDepart(), activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,542 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:apply Changes] applyChanges() activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,542 INFO [STDOUT] (pool-13-thread-3) 4308605102 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - applyChanges() activity=Schedule Account Disable[Activity2]
2011-12-22 16:29:36,544 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateTarget] evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2011-12-22 16:29:36,544 INFO [STDOUT] (pool-13-thread-3) 4308605105 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2011-12-22 16:29:36,545 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateTarget] result: com.novell.soa.script.mozilla.javascript.Undefined @e12bd8
2011-12-22 16:29:36,545 INFO [STDOUT] (pool-13-thread-3) 4308605106 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: com.novell.soa.script.mozilla.javascript.Undefined @e12bd8
2011-12-22 16:29:36,548 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Idle
2011-12-22 16:29:36,548 INFO [STDOUT] (pool-13-thread-3) 4308605109 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Schedule Account Disable[Activity2], newStatus=Idle
2011-12-22 16:29:36,549 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Finish[finish], newStatus=Arriving
2011-12-22 16:29:36,549 INFO [STDOUT] (pool-13-thread-3) 4308605109 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Finish[finish], newStatus=Arriving
2011-12-22 16:29:36,558 INFO [STDOUT] (pool-13-thread-3) INFO [RBPM] [com.novell.soa.af.impl.LogEvent:logAFEvent] [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity2, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,597 INFO [STDOUT] (pool-13-thread-3) 4308605118 [pool-13-thread-3] INFO com.novell.soa.af.impl.LogEvent - [Workflow_Forwarded] Initiated by System, Process ID: c20080b1ff2a4a46bd097f5c4a1b5ec7, Process Name: cn=SAP_Privileged_Access_Request,cn=RequestDefs,cn =AppConfig,cn=UserApplication,cn=DriverSet,ou=Serv ices,o=Company:112, Activity: Activity2, Recipient: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,606 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.ProcessImpl:startActiv ity] startActivity() activity=Finish[finish], newThread=false, pooled = false
2011-12-22 16:29:36,606 INFO [STDOUT] (pool-13-thread-3) 4308605166 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.ProcessImpl - startActivity() activity=Finish[finish], newThread=false, pooled = false
2011-12-22 16:29:36,606 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNode:notif yArrive] notifyArrive(), activity=Finish[finish]
2011-12-22 16:29:36,606 INFO [STDOUT] (pool-13-thread-3) 4308605167 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - notifyArrive(), activity=Finish[finish]
2011-12-22 16:29:36,607 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNoderocess] process(), activity=Finish[finish]
2011-12-22 16:29:36,607 INFO [STDOUT] (pool-13-thread-3) 4308605167 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - process(), activity=Finish[finish]
2011-12-22 16:29:36,607 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.activity.ActivityNodeersistStatus] persistStatus() activity=Finish[finish], newStatus=Running
2011-12-22 16:29:36,607 INFO [STDOUT] (pool-13-thread-3) 4308605168 [pool-13-thread-3] TRACE com.novell.soa.af.impl.activity.ActivityNode - persistStatus() activity=Finish[finish], newStatus=Running
2011-12-22 16:29:36,618 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: flowdata.get('IDM_COMPLETED_APPROVAL_STATUS')
2011-12-22 16:29:36,618 INFO [STDOUT] (pool-13-thread-3) 4308605178 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: flowdata.get('IDM_COMPLETED_APPROVAL_STATUS')
2011-12-22 16:29:36,619 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: approved
2011-12-22 16:29:36,619 INFO [STDOUT] (pool-13-thread-3) 4308605180 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: approved
2011-12-22 16:29:36,620 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: initiator
2011-12-22 16:29:36,620 INFO [STDOUT] (pool-13-thread-3) 4308605180 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: initiator
2011-12-22 16:29:36,621 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,621 INFO [STDOUT] (pool-13-thread-3) 4308605181 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=A.Christie,ou=Users,o=Company
2011-12-22 16:29:36,627 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[requestStatus]
2011-12-22 16:29:36,627 INFO [STDOUT] (pool-13-thread-3) 4308605188 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[requestStatus]
2011-12-22 16:29:36,628 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: process.getApprovalStatus()
2011-12-22 16:29:36,628 INFO [STDOUT] (pool-13-thread-3) 4308605189 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: process.getApprovalStatus()
2011-12-22 16:29:36,629 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Approved
2011-12-22 16:29:36,629 INFO [STDOUT] (pool-13-thread-3) 4308605189 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Approved
2011-12-22 16:29:36,629 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [requestStatus] evaluated to:[Approved]
2011-12-22 16:29:36,630 INFO [STDOUT] (pool-13-thread-3) 4308605190 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [requestStatus] evaluated to:[Approved]
2011-12-22 16:29:36,630 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[NOTIFICATION_TEMPLATE]
2011-12-22 16:29:36,630 INFO [STDOUT] (pool-13-thread-3) 4308605191 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[NOTIFICATION_TEMPLATE]
2011-12-22 16:29:36,630 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: 'cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security'
2011-12-22 16:29:36,631 INFO [STDOUT] (pool-13-thread-3) 4308605191 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: 'cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security'
2011-12-22 16:29:36,633 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security
2011-12-22 16:29:36,633 INFO [STDOUT] (pool-13-thread-3) 4308605193 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security
2011-12-22 16:29:36,634 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [NOTIFICATION_TEMPLATE] evaluated to:[cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security]
2011-12-22 16:29:36,634 INFO [STDOUT] (pool-13-thread-3) 4308605194 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [NOTIFICATION_TEMPLATE] evaluated to:[cn=SAP Notification Privileged Access Completed,cn=Default Notification Collection,cn=Security]
2011-12-22 16:29:36,634 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[requestSubmissionTime]
2011-12-22 16:29:36,634 INFO [STDOUT] (pool-13-thread-3) 4308605195 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[requestSubmissionTime]
2011-12-22 16:29:36,635 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: process.getTimestamp().toString()
2011-12-22 16:29:36,635 INFO [STDOUT] (pool-13-thread-3) 4308605195 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: process.getTimestamp().toString()
2011-12-22 16:29:36,636 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Thu Dec 22 16:00:05 CET 2011
2011-12-22 16:29:36,636 INFO [STDOUT] (pool-13-thread-3) 4308605196 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Thu Dec 22 16:00:05 CET 2011
2011-12-22 16:29:36,636 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [requestSubmissionTime] evaluated to:[Thu Dec 22 16:00:05 CET 2011]
2011-12-22 16:29:36,636 INFO [STDOUT] (pool-13-thread-3) 4308605197 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [requestSubmissionTime] evaluated to:[Thu Dec 22 16:00:05 CET 2011]
2011-12-22 16:29:36,637 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[requestId]
2011-12-22 16:29:36,637 INFO [STDOUT] (pool-13-thread-3) 4308605197 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[requestId]
2011-12-22 16:29:36,638 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: process.getRequestId()
2011-12-22 16:29:36,638 INFO [STDOUT] (pool-13-thread-3) 4308605198 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: process.getRequestId()
2011-12-22 16:29:36,638 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: c20080b1ff2a4a46bd097f5c4a1b5ec7
2011-12-22 16:29:36,638 INFO [STDOUT] (pool-13-thread-3) 4308605199 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: c20080b1ff2a4a46bd097f5c4a1b5ec7
2011-12-22 16:29:36,639 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [requestId] evaluated to:[c20080b1ff2a4a46bd097f5c4a1b5ec7]
2011-12-22 16:29:36,639 INFO [STDOUT] (pool-13-thread-3) 4308605199 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [requestId] evaluated to:[c20080b1ff2a4a46bd097f5c4a1b5ec7]
2011-12-22 16:29:36,639 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[recipientFullName]
2011-12-22 16:29:36,639 INFO [STDOUT] (pool-13-thread-3) 4308605200 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[recipientFullName]
2011-12-22 16:29:36,640 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: IDVault.get(recipient, 'user', 'FirstName') + ' ' + IDVault.get(recipient, 'user', 'LastName')
2011-12-22 16:29:36,640 INFO [STDOUT] (pool-13-thread-3) 4308605200 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: IDVault.get(recipient, 'user', 'FirstName') + ' ' + IDVault.get(recipient, 'user', 'LastName')
2011-12-22 16:29:36,650 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: Alana Christie
2011-12-22 16:29:36,650 INFO [STDOUT] (pool-13-thread-3) 4308605210 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: Alana Christie
2011-12-22 16:29:36,650 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [recipientFullName] evaluated to:[Alana Christie]
2011-12-22 16:29:36,651 INFO [STDOUT] (pool-13-thread-3) 4308605211 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [recipientFullName] evaluated to:[Alana Christie]
2011-12-22 16:29:36,651 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[requestTitle]
2011-12-22 16:29:36,651 INFO [STDOUT] (pool-13-thread-3) 4308605212 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[requestTitle]
2011-12-22 16:29:36,651 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: process.getName()
2011-12-22 16:29:36,652 INFO [STDOUT] (pool-13-thread-3) 4308605212 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: process.getName()
2011-12-22 16:29:36,652 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: SAP Privileged Access Request
2011-12-22 16:29:36,652 INFO [STDOUT] (pool-13-thread-3) 4308605213 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: SAP Privileged Access Request
2011-12-22 16:29:36,653 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [requestTitle] evaluated to:[SAP Privileged Access Request]
2011-12-22 16:29:36,653 INFO [STDOUT] (pool-13-thread-3) 4308605213 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [requestTitle] evaluated to:[SAP Privileged Access Request]
2011-12-22 16:29:36,653 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[toDate]
2011-12-22 16:29:36,653 INFO [STDOUT] (pool-13-thread-3) 4308605214 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[toDate]
2011-12-22 16:29:36,654 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyy-MM-dd');

return df.format(d);

};



printDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,654 INFO [STDOUT] (pool-13-thread-3) 4308605214 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: function printDate(dateValue) {

var l = parseInt(dateValue);

var d = new java.util.Date(l);

var df = new java.text.SimpleDateFormat('yyyy-MM-dd');

return df.format(d);

};



printDate(flowdata.get('endDateTime'));
2011-12-22 16:29:36,659 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: 2011-12-23
2011-12-22 16:29:36,660 INFO [STDOUT] (pool-13-thread-3) 4308605220 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: 2011-12-23
2011-12-22 16:29:36,660 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [toDate] evaluated to:[2011-12-23]
2011-12-22 16:29:36,660 INFO [STDOUT] (pool-13-thread-3) 4308605221 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [toDate] evaluated to:[2011-12-23]
2011-12-22 16:29:36,661 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[sapAccount]
2011-12-22 16:29:36,661 INFO [STDOUT] (pool-13-thread-3) 4308605221 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[sapAccount]
2011-12-22 16:29:36,661 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: IDVault.get(flowdata.get('sapAccount') , 'SAPAdministrator', 'sapUsername')
2011-12-22 16:29:36,661 INFO [STDOUT] (pool-13-thread-3) 4308605222 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: IDVault.get(flowdata.get('sapAccount') , 'SAPAdministrator', 'sapUsername')
2011-12-22 16:29:36,667 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: S.ADMIN
2011-12-22 16:29:36,667 INFO [STDOUT] (pool-13-thread-3) 4308605227 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: S.ADMIN
2011-12-22 16:29:36,668 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [sapAccount] evaluated to:[S.ADMIN]
2011-12-22 16:29:36,668 INFO [STDOUT] (pool-13-thread-3) 4308605228 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [sapAccount] evaluated to:[S.ADMIN]
2011-12-22 16:29:36,668 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Trying to evaluate mapping target:[TASKLIST_CONTEXT]
2011-12-22 16:29:36,668 INFO [STDOUT] (pool-13-thread-3) 4308605229 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Trying to evaluate mapping target:[TASKLIST_CONTEXT]
2011-12-22 16:29:36,669 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] evaluating to string: 'IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS'
2011-12-22 16:29:36,669 INFO [STDOUT] (pool-13-thread-3) 4308605229 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - evaluating to string: 'IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS'
2011-12-22 16:29:36,670 INFO [STDOUT] (pool-13-thread-3) DEBUG [RBPM] [com.novell.soa.af.impl.core.DataItemEvaluator:eval uateToString] result: IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS
2011-12-22 16:29:36,670 INFO [STDOUT] (pool-13-thread-3) 4308605230 [pool-13-thread-3] DEBUG com.novell.soa.af.impl.core.DataItemEvaluator - result: IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS
2011-12-22 16:29:36,670 INFO [STDOUT] (pool-13-thread-3) TRACE [RBPM] [com.novell.soa.af.impl.core.WorkflowNotification:e valuateMappings] Expression [TASKLIST_CONTEXT] evaluated to:[IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS]
2011-12-22 16:29:36,670 INFO [STDOUT] (pool-13-thread-3) 4308605231 [pool-13-thread-3] TRACE com.novell.soa.af.impl.core.WorkflowNotification - Expression [TASKLIST_CONTEXT] evaluated to:[IDM/workDashboard.do?apwaLeftNavItem=JSP_MENU_TASKS]

...


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


--
afolli
------------------------------------------------------------------------
afolli's Profile: http://forums.novell.com/member.php?userid=6964
View this thread: http://forums.novell.com/showthread.php?t=449936