Unable to connect to SAS workspace server using IWA from new Active directory domain

[vkrishna]

Hi,

I have a new requirement here. IT migrating users from Domain A to B in AD. I have a test account which is active in new domain B. I have created this new user identity in SMC(B\test) and added to respective SAS users group. We have added SAS_users group and domain users group from the new domain to SAS windows server groups.

Users form new domain were not able to connect to SAS workspace (SASAPP) using IWA from SAS Enterprise Guide.  I have verified the object spawner log that new client connection was accepted But if try to expand the SAS application server context it keeps on loading & no errors.

Spoiler

2018-08-23T15:57:51,421 INFO  [00613160] :TestSAS01@B - New client connection (51367) accepted from server port 8591 for IWA user TestSAS01@B. Encryption level is Credentials using encryption algorithm SASProprietary.  Peer IP address and port are [::ffff:10.1.102.83]:57929 for APPNAME=SAS Enterprise Guide.

If we use username and password in SAS EG profile It's working fine.

Object spawner running under system and SAS server is trusted for delegation in A domain.

I have verified the local security policy - Log on as  a batch job. new domain SAS users group added to nested groups.

It looks like Object spawner not able to pass the credentials. Any suggestions.

-V

[alexal]

@vkrishna,

Please add these lines to /<SASConfig>/Lev<X>/ObjectSpawner/logconfig.xml:

<logger name="Audit.Authentication">
     <level value="Trace"/>
</logger>

Restart the object spawner, repeat the problem and send to me the most recent object spawner log for review.

[vkrishna]

Spoiler

2018-08-27T14:47:05,901 TRACE [00000005] :SYSTEM@SAS_server - Select handle 1 entering wait for 60000 ms
2018-08-27T14:47:05,901 TRACE [00000005] :SYSTEM@SAS_server - About to wait on select list id=1 (empty-list)
2018-08-27T14:47:05,901 TRACE [00000005] :SYSTEM@SAS_server - select 1 handle list:r(620)
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 11
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Socket 32 option SOCK_NoDelay set to 1
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - New socket accepted parent-id=11 new-id=32 new native id 1164
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Socket 32 option SOCK_KeepAlive set to 1
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Socket 32 option SOCK_ReuseAddress set to 1
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Socket 32 option SOCK_CloseOnExec set to 1
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Socket 32 option SOCK_NonBlock set to 0
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 32 (fd=1164)
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x][s:32(1164)r-x]
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x][s:32(1164)r-x]
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)r(1164)e(992)e(920)e(988)e(948)e(928)e(924)e(800)e(1164)
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,893 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 32
2018-08-27T14:47:15,894 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 32
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e300, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,895 TRACE [00000288] :SYSTEM@SAS_server - tcpSockRead returning 12 bytes as requested on socket 32
2018-08-27T14:47:15,895 TRACE [00000288] :SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 32 (fd=1164)
2018-08-27T14:47:15,895 TRACE [00000288] :SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x][s:32(1164)r-x]
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e10160
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x][s:32(1164)r-x]
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)r(1164)e(992)e(920)e(988)e(948)e(928)e(924)e(800)e(1164)
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - Select wait received wakeup code 2
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 32
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 32
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,895 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e300, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - tcpSockRead returning 279 bytes as requested on socket 32
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - createCipher: Enter, type=SASProprietary
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - createCipher: algorithm found in extension: tkeencr
2018-08-27T14:47:15,895 DEBUG [00000289] :SYSTEM@SAS_server - loadAlg: Enter, name=tkeencr
2018-08-27T14:47:15,895 DEBUG [00000289] :SYSTEM@SAS_server - loadAlg: Extension tkeencr already loaded
2018-08-27T14:47:15,895 DEBUG [00000289] :SYSTEM@SAS_server - loadAlg: Exit, alg=0x1e79d00
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - OpenCipher: Enter, alg requested=SASProprietary
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - OpenCipher: Exit, status=0x0, cipher=0x1e69e60.
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - createCipher: Exit, cipher=0x1e69e60
2018-08-27T14:47:15,895 TRACE [00000289] :SYSTEM@SAS_server - tcpSockWrite successfully sent 1 bytes on socket 32
2018-08-27T14:47:15,897 TRACE [00000289] :SYSTEM@SAS_server - AcceptSecContext: Enter, cipher=0x1e69e60, len=0
2018-08-27T14:47:15,897 TRACE [00000289] :SYSTEM@SAS_server - AcceptSecContext: Exit, status=0 (0x0), outlen=0, rlen=0, again=0
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e10160
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,897 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,913 TRACE [00000289] :SYSTEM@SAS_server - tcpSockRead returning 4 bytes as requested on socket 32
2018-08-27T14:47:15,914 TRACE [00000289] :SYSTEM@SAS_server - tcpSockRead returning 92 bytes as requested on socket 32
2018-08-27T14:47:15,915 TRACE [00000289] :SYSTEM@SAS_server - decode: Enter, cipher=0x1e69e60, inlen=92
2018-08-27T14:47:15,915 TRACE [00000289] :SYSTEM@SAS_server - decode: Exit, status=0 (0x0), outlen=64
2018-08-27T14:47:15,916 TRACE [00000289] :SYSTEM@SAS_server - tcpSockRead returning 4 bytes as requested on socket 32
2018-08-27T14:47:15,916 DEBUG [00000289] :SYSTEM@SAS_server - tcpSockRead returning 2920 bytes of 5052 requested on socket 32
2018-08-27T14:47:15,967 TRACE [00000289] :SYSTEM@SAS_server - tcpSockRead returning 2132 bytes as requested on socket 32
2018-08-27T14:47:15,968 TRACE [00000289] :SYSTEM@SAS_server - decode: Enter, cipher=0x1e69e60, inlen=5052
2018-08-27T14:47:15,968 TRACE [00000289] :SYSTEM@SAS_server - decode: Exit, status=0 (0x0), outlen=3786
2018-08-27T14:47:15,968 INFO [00000289] :SYSTEM@SAS_server - IWA context established using Kerberos package.
2018-08-27T14:47:15,969 INFO [00000289] :test@newdomain - New client connection (30) accepted from server port 8591 for IWA user test@newdomain. Encryption level is Credentials using encryption algorithm SASProprietary. Peer IP address and port are [::ffff:10.1.101.97]:61898 for APPNAME=SAS Enterprise Guide.
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Preparing to launch server SASApp - Workspace Server under credentials test@newdomain.
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 64 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e400, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000284] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000284] 1:SYSTEM@SAS_server - tcpSockRead returning 8 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000284] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000284] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 83 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e400, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - tcpSockRead returning 4 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - tcpSockRead returning 92 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - decode: Enter, cipher=0x1e69780, inlen=92
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - decode: Exit, status=0 (0x0), outlen=64
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - tcpSockRead returning 4 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - tcpSockRead returning 128 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - decode: Enter, cipher=0x1e69780, inlen=128
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - decode: Exit, status=0 (0x0), outlen=91
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000290] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 64 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e4a0, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000291] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000291] 1:SYSTEM@SAS_server - tcpSockRead returning 16 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000291] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000291] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 64 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e400, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000292] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000292] 1:SYSTEM@SAS_server - tcpSockRead returning 8 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000292] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000292] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Application-specific option lookup searching for application named SAS Enterprise Guide for client 30
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 335 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e5e0, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000293] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000293] 1:SYSTEM@SAS_server - tcpSockRead returning 31 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000293] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000293] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Application-specific option lookup could not find any options for this application, continuing on without application-specific options.
2018-08-27T14:47:15,969 TRACE [00000289] :test@newdomain - tcpSockWriteVector successfully sent 64 bytes on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select wait on handle 2 found activity on 1 sockets
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 found activity 40000000 on socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 no longer waiting on E0000000 for socket 3
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - select wakeup on 2 success
2018-08-27T14:47:15,969 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0, 0x1e6e4a0, 0x0, 0x80000000, 0x0)
2018-08-27T14:47:15,969 TRACE [00000294] 1:SYSTEM@SAS_server - tcpSockRead returning 64 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000294] 1:SYSTEM@SAS_server - tcpSockRead returning 16 bytes as requested on socket 3
2018-08-27T14:47:15,969 TRACE [00000294] 1:SYSTEM@SAS_server - Select handle 2 is adding activity C0000000 on socket 3 (fd=800)
2018-08-27T14:47:15,969 TRACE [00000294] 1:SYSTEM@SAS_server - New socket list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Command being used is "E:\SAS\config\Lev1\SASApp\WorkspaceServer\WorkspaceServer.bat" -memsize 2147M -realmemsize 2147M.
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >nologo< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >noterminal< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >netencryptalgorithm< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >SASProprietary<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >metaserver< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >server.a.com<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >metaport< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >8561<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >metarepository< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >Foundation<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >locale< (Client requirement)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >en_US<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >objectserver< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >objectserverparms< (Standard options)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >protocol=bridge spawned spp=62414 cid=1 dnsmatch=server.a.com pb classfactory=440196D4-90F0-11D0-9F41-00A024BB830C server=OMSOBJ:SERVERCOMPONENT/A5139Z1P.AZ000008 cel=credentials recon keepalive=30<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Environment variables are:
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >METAUSER<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >TestSGSAS01@!*(generatedpassworddomain)*!<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >METAPASS<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - >********<
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - tkelsLaunch(token=3d1fb0 cmd='"E:\SAS\config\Lev1\SASApp\WorkspaceServer\WorkspaceServer.bat" -memsize 2147M -realmemsize 2147M' exitAction=0 flags=30)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child environment variable METAUSER=TestSGSAS01@!*(generatedpassworddomain)*!
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child environment variable METAPASS=4B8CF507AB3fFC1b99823e2C4DA9701e
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(nologo) value()
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(noterminal) value()
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(netencryptalgorithm) value(SASProprietary)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(metaserver) value(server.a.com)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(metaport) value(8561)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(metarepository) value(Foundation)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(locale) value(en_US)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(objectserver) value()
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child parameter name(objectserverparms) value(protocol=bridge spawned spp=62414 cid=1 dnsmatch=server.a.com pb classfactory=440196D4-90F0-11D0-9F41-00A024BB830C server=OMSOBJ:SERVERCOMPONENT/A5139Z1P.AZ000008 cel=credentials recon keepalive=30)
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - child handle SAS_SOCKET=1164
2018-08-27T14:47:15,969 DEBUG [00000289] :test@newdomain - Token 3d1fb0 represents user SG\TestSGSAS01
2018-08-27T14:47:15,973 DEBUG [00000289] :test@newdomain - process(2053da0) added to waiting thread(20545e0)
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : ALLUSERSPROFILE=C:\ProgramData
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : APPDATA=C:\Users\TestSGSAS01\AppData\Roaming
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : COMMONPROGRAMFILES=C:\Program Files\Common Files
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : COMMONPROGRAMW6432=C:\Program Files\Common Files
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : COMPUTERNAME=SAS_server
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : COMSPEC=C:\Windows\system32\cmd.exe
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : FP_NO_HOST_CHECK=NO
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : HOMEDRIVE=C:
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : HOMEPATH=\Users\TestSGSAS01
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : LOCALAPPDATA=C:\Users\TestSGSAS01\AppData\Local
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : LOGONSERVER=\\SEAASGDC02
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : METAPASS=4B8CF507AB3fFC1b99823e2C4DA9701e
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : METAUSER=TestSGSAS01@!*(generatedpassworddomain)*!
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : NUMBER_OF_PROCESSORS=2
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : OS=Windows_NT
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PATH=C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;E:\SAS\SASHome\Secure\ccme4;E:\SAS\SASHome\x86\Secure\ccme4
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROCESSOR_ARCHITECTURE=AMD64
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 2, GenuineIntel
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROCESSOR_LEVEL=6
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROCESSOR_REVISION=3f02
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROGRAMDATA=C:\ProgramData
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROGRAMFILES=C:\Program Files
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROGRAMFILES(X86)=C:\Program Files (x86)
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PROGRAMW6432=C:\Program Files
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PSMODULEPATH=C:\Windows\system32\WindowsPowerShell\v1.0\Modules\
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : PUBLIC=C:\Users\Public
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : SYSTEMDRIVE=C:
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : SYSTEMROOT=C:\Windows
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : TEMP=C:\Users\TESTSG~1\AppData\Local\Temp
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : TKELS_PARENT_PIPE=\\.\pipe\tkels.child.handleinfo.2068.14636
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : TMP=C:\Users\TESTSG~1\AppData\Local\Temp
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : USERDNSDOMAIN=SG.SEAGEN.COM
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : USERDOMAIN=SG
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : USERDOMAIN_ROAMINGPROFILE=SG
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : USERNAME=TestSGSAS01
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : USERPROFILE=C:\Users\TestSGSAS01
2018-08-27T14:47:15,973 TRACE [00000289] :test@newdomain - Child Environment variable : WINDIR=C:\Windows
2018-08-27T14:47:15,986 TRACE [00000009] :SYSTEM@SAS_server - puddleEnqueue(0x1e8f5e0) gave request to worker 0x1e100e0
2018-08-27T14:47:15,986 DEBUG [00000289] :test@newdomain - Process create for '"E:\SAS\config\Lev1\SASApp\WorkspaceServer\WorkspaceServer.bat" -memsize 2147M -realmemsize 2147M /nologo /noterminal /netencryptalgorithm SASProprietary /metaserver server.a.com /metaport 8561 /metarepository Foundation /locale en_US /objectserver /objectserverparms "protocol=bridge spawned spp=62414 cid=1 dnsmatch=server.a.com pb classfactory=440196D4-90F0-11D0-9F41-00A024BB830C server=OMSOBJ:SERVERCOMPONENT/A5139Z1P.AZ000008 cel=credentials recon keepalive=30"' worked pid=19660 handle 2053da0 status 55787680
2018-08-27T14:47:15,993 TRACE [00000009] :SYSTEM@SAS_server - 0x0=threadPoolEnqueue(0x1e8f5e0)
2018-08-27T14:47:15,993 TRACE [00000009] :SYSTEM@SAS_server - Select handle 2 entering wait for -1 ms
2018-08-27T14:47:15,993 TRACE [00000009] :SYSTEM@SAS_server - About to wait on select list id=2 [s:12(992)r-x][s:4(920)r-x][s:11(988)r-x][s:7(948)r-x][s:6(928)r-x][s:5(924)r-x][s:3(800)r-x]
2018-08-27T14:47:15,993 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:15,993 TRACE [00000009] :SYSTEM@SAS_server - select 2 handle list:r(792)r(992)r(920)r(988)r(948)r(928)r(924)r(800)e(992)e(920)e(988)e(948)e(928)e(924)e(800)
2018-08-27T14:47:16,001 DEBUG [00000296] :SYSTEM@SAS_server - StartWaitingThread(20545e0) started
2018-08-27T14:48:05,900 TRACE [00000005] :SYSTEM@SAS_server - Select handle 1 entering wait for 60000 ms
2018-08-27T14:48:05,900 TRACE [00000005] :SYSTEM@SAS_server - About to wait on select list id=1 (empty-list)
2018-08-27T14:48:05,900 TRACE [00000005] :SYSTEM@SAS_server - select 1 handle list:r(620)

@alexal, I have enabled the workspace server logs but no logs were created. any Idea

[alexal]

@vkrishna,

The workspace server log should be created because I see that the object spawner has tried to start it:

E:\SAS\config\Lev1\SASApp\WorkspaceServer\WorkspaceServer.bat" -memsize 2147M -realmemsize 2147M /nologo /noterminal /netencryptalgorithm SASProprietary /metaserver server.a.com /metaport 8561 /metarepository Foundation /locale en_US /objectserver /objectserverparms "protocol=bridge spawned

[vkrishna]

@alexal,

No workspace server session not started,

[alexal]

@vkrishna,

I that case I would suggest you open a track with our Windows Technical Support.

[vkrishna]

@alexa, I have already opened the track with SAS.

Thank you.

-V