CThreadPool::Process - Exception - PeerAppSelection()

Options
Sergiy
Sergiy
edited June 2012 in Photon Server
Hi

recently I've got these error messages on Server.
Server version: Photon 3.0 RC9
.NET SDK: 3.0.1.8

2988: 13:48:18.292 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
2988: 13:51:40.652 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
2988: 13:54:20.761 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?

Comments

  • Sergiy
    Options
    Full Log:
    ...............................................................
    3088: 13:46:30.933 - Adding TCP listener on :0.0.0.0: 9998 with a listen backlog of: 150
    3088: 13:46:30.933 - Nagle disabled
    3088: 13:46:30.933 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.933 - MaxInboundMessageSize: 512000
    3088: 13:46:30.933 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.933 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP listener on :0.0.0.0: 9996 with a listen backlog of: 150
    3088: 13:46:30.980 - Nagle disabled
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - MaxInboundMessageSize: 512000
    3088: 13:46:30.980 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.980 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP listener on :0.0.0.0: 9991 with a listen backlog of: 150
    3088: 13:46:30.980 - Nagle disabled
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - MaxInboundMessageSize: 512000
    3088: 13:46:30.980 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.980 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP listener on :0.0.0.0: 9992 with a listen backlog of: 150
    3088: 13:46:30.980 - Nagle disabled
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - MaxInboundMessageSize: 512000
    3088: 13:46:30.980 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.980 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP listener on :0.0.0.0: 9993 with a listen backlog of: 150
    3088: 13:46:30.980 - Nagle disabled
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - MaxInboundMessageSize: 512000
    3088: 13:46:30.980 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.980 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP listener on :0.0.0.0: 9995 with a listen backlog of: 150
    3088: 13:46:30.980 - Nagle disabled
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - MaxInboundMessageSize: 512000
    3088: 13:46:30.980 - MaxOutboundMessageSize: 512000
    3088: 13:46:30.980 - Routing policy file requests to: "Policy"
    3088: 13:46:30.980 - Adding TCP Policy listener on :0.0.0.0: 843 with a listen backlog of: 150 and routing to application: "Policy"
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:30.980 - Adding TCP Policy listener on :0.0.0.0: 943 with a listen backlog of: 150 and routing to application: "Policy"
    3088: 13:46:30.980 - TCP inactivity timeout: 5000ms
    3088: 13:46:31.074 - Service is running...
    2988: 13:48:18.292 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 13:51:40.652 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 13:54:20.761 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:00:41.714 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:00:46.699 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:04:24.121 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:17:07.339 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:19:36.917 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:19:37.042 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:19:42.027 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:20:54.917 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:40:08.855 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:53:16.683 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2536: 14:54:07.199 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    2988: 14:58:20.699 - CThreadPool::Process - Exception - PeerAppSelection() - Init: Unexpected CLR application already selected; multiple init calls?
    3088: 15:03:21.683 - Service is stopped...
    3088: 15:03:21.683 - Server shutting down...
    3088: 15:03:21.683 - Notifying CLR applications of shutdown...
    3088: 15:03:21.699 - Shutting down TCP Outbound Connection Manager...
    3088: 15:03:21.699 - Shutting down ENet Outbound Connection Manager...
    3088: 15:03:21.714 - Disconnecting all outbound peers...
    3088: 15:03:21.714 - Shutting down ENet host...
    3088: 15:03:21.714 - Disconnecting all peers...
    3088: 15:03:21.714 - Shutting down socket servers...
    3088: 15:03:21.714 - Shutting down ENet thread pool...
    3088: 15:03:21.714 - Shutting down TCP inactivity timers...
    3088: 15:03:21.714 - WaitingForShutdownToComplete: TCP:0.0.0.0:9991
    3088: 15:03:31.714 - OnShutdownFailedToComplete: TCP:0.0.0.0:9991
    3088: 15:03:31.714 - 
    3088: 15:03:31.714 - WARNING: ***************************************************************
    3088: 15:03:31.714 - WARNING: Socket servers failed to shut down in time...
    3088: 15:03:31.714 - WARNING: Socket servers forcibly shut down...
    3088: 15:03:31.714 - WARNING: You should expect peers to leak in this situation
    3088: 15:03:31.714 - WARNING: ***************************************************************
    3088: 15:03:31.714 - 
    3088: 15:03:31.714 - Shutting down CLR applications...
    3088: 15:03:31.714 - Shutting down business logic thread pool...
    3088: 15:03:31.714 - Shutting down I/O thread pool...
    3088: 15:03:31.714 - Flushing ENET buffer allocator...
    3088: 15:03:31.714 - Flushing TCP buffer allocator...
    3088: 15:03:31.714 - Flushing TCP buffer handle allocator...
    3088: 15:03:31.714 - Flushing stream socket allocator...
    3088: 15:03:31.714 - Sockets were active during flush...
    3088: 15:03:31.714 - Flushing inbound datagram socket allocator...
    3088: 15:03:31.714 - Flushing outbound datagram socket allocator...
    3088: 15:03:31.714 - Destroying ENet thread pool
    3088: 15:03:31.714 - Destroying Business logic thread pool
    3088: 15:03:31.714 - Destroying servers
    3088: 15:03:31.714 - Destroying TCP Outbound Connection Manager
    3088: 15:03:31.714 - Destroying resolver
    3088: 15:03:31.714 - Destroying TCP Proxy Connection Manager
    3088: 15:03:31.714 - Destroying TCP inactivity timer
    3088: 15:03:31.714 - Destroying ENet host
    3088: 15:03:31.714 - Destroying ENet host
    3088: 15:03:31.714 - Destroying CLR dispatcher
    3088: 15:03:31.714 - Destroying CLR applications
    3088: 15:03:33.449 - Destroying I/O thread pool
    3088: 15:03:33.449 - Destroying ENet buffer allocator
    3088: 15:03:33.449 - Destroying TCP buffer allocator
    3088: 15:03:33.449 - Destroying TCP socket allocator
    3088: 15:03:33.449 - Destroying ENet inbound socket allocator
    3088: 15:03:33.449 - Destroying ENet outbound socket allocator
    3088: 15:03:33.449 - Destroying performance counters
    3088: 15:03:33.449 - 
    3088: 15:03:33.449 - WARNING: ***************************************************************
    3088: 15:03:33.449 - WARNING: CPhotonPeer leaks detected
    3088: 15:03:33.449 - WARNING: CTCPPeer leaks detected
    3088: 15:03:33.449 - WARNING: ***************************************************************
    3088: 15:03:33.449 - 
    3088: 15:03:33.449 - Shutdown complete...
    
  • Tobias
    Options
    The log is not complete.
    It looks like you open a lot of policy application ports. Is that meant like this?

    Is PeerAppSelection() in your code?
  • Sergiy
    Options
    I'm opening 5 ports, but they are just app ports, not policy. Policy ports are two - 843 and 943.
    No, PeerAppSelection() is not in my code - I was thinking that it's in Photon server code.

    Looks like I've found the cause of this problem - it was multiple Connect() calls from client .NET application