search

dotnet / orleans

Cloud Native application framework for .NET
https://docs.microsoft.com/dotnet/orleans
MIT License
10.04k stars 2.02k forks source link

Getting "Orleans.Runtime.OrleansException: Cannot find an invoker for interface GrainInterfaces.IMyGrain (ID=-865503811,0xCC6975BD)." #1335

Closed apuchkov closed 8 years ago

apuchkov commented 8 years ago

I'm trying to create an app similar to Minimal Orleans Application, but with stand alone silo.

All packages are added from nuget, no Orleans templates were used, so no version conflicts.

Silo seems to be starting successfully, but then client can't invoke grain method.

Is it a bug or am I missing something?

Solution overview

image

GrainInterfaces Project

IClientGrain.cs

public interface IClientGrain : Orleans.IGrainWithIntegerKey
{
    Task<string> ProcessClient();
}

packages.config

<?xml version="1.0" encoding="utf-8"?>
<packages>
  <package id="Microsoft.Orleans.Core" version="1.1.2" targetFramework="net452" />
  <package id="Newtonsoft.Json" version="6.0.8" targetFramework="net452" />
</packages>

Grains Project

ClientGrain.cs

public class ClientGrain : Grain, IClientGrain
{
    public Task<string> ProcessClient()
    {
        Console.WriteLine("Processing client");

        return Task.FromResult("Processing client");
    }
}

packages.config

<?xml version="1.0" encoding="utf-8"?>
<packages>
  <package id="Microsoft.Orleans.Core" version="1.1.2" targetFramework="net452" />
  <package id="Microsoft.Orleans.OrleansHost" version="1.1.2" targetFramework="net452" />
  <package id="Microsoft.Orleans.OrleansRuntime" version="1.1.2" targetFramework="net452" />
  <package id="Newtonsoft.Json" version="6.0.8" targetFramework="net452" />
</packages>

Debug Properties

image

bin/Debug/OrleansConfiguration.xml

<?xml version="1.0" encoding="utf-8" ?>
<OrleansConfiguration xmlns="urn:orleans">
  <Globals>
    <SeedNode Address="localhost" Port="11111" />
  </Globals>
  <Defaults>
    <Networking Address="localhost" Port="11111" />
    <ProxyingGateway Address="localhost" Port="30000" />
  </Defaults>
</OrleansConfiguration>

MemberExtractOrleans Project

Program.cs

class Program
    {
        static void Main(string[] args)
        {

            Console.WriteLine("Waiting for Orleans Silo to start. Press Enter to proceed...");
            Console.ReadLine();

            Orleans.GrainClient.Initialize("ClientConfiguration.xml");

            var c1 = GrainClient.GrainFactory.GetGrain<IClientGrain>(1);
            Console.WriteLine(c1.ProcessClient().Result);

            Console.ReadLine();
        }
    }

ClientConfiguration.xml

<?xml version="1.0" encoding="utf-8" ?>
<ClientConfiguration xmlns="urn:orleans">
  <Gateway Address="localhost" Port="30000"/>
</ClientConfiguration>

packages.config

<?xml version="1.0" encoding="utf-8"?>
<packages>
  <package id="Microsoft.CodeAnalysis.Analyzers" version="1.0.0" targetFramework="net452" />
  <package id="Microsoft.CodeAnalysis.Common" version="1.0.0" targetFramework="net452" />
  <package id="Microsoft.CodeAnalysis.CSharp" version="1.0.0" targetFramework="net452" />
  <package id="Microsoft.Orleans.Client" version="1.1.2" targetFramework="net452" />
  <package id="Microsoft.Orleans.Core" version="1.1.2" targetFramework="net452" />
  <package id="Microsoft.Orleans.OrleansCodeGenerator" version="1.1.2" targetFramework="net452" />
  <package id="Microsoft.Orleans.OrleansProviders" version="1.1.2" targetFramework="net452" />
  <package id="Newtonsoft.Json" version="6.0.8" targetFramework="net452" />
  <package id="System.Collections.Immutable" version="1.1.36" targetFramework="net452" />
  <package id="System.Reflection.Metadata" version="1.0.21" targetFramework="net452" />
</packages>

Log Files

Grains\bin\Debug\CWI-ALEX-2016-01-26-16.39.40.232Z.log

[2016-01-26 16:39:40.323 GMT     1  WARNING 101722  CodeGenerator   ]   Could not load file or assembly 'OrleansCodeGenerator' or one of its dependencies. The system cannot find the file specified.   
Exc level 0: System.IO.FileNotFoundException: Could not load file or assembly 'OrleansCodeGenerator' or one of its dependencies. The system cannot find the file specified.
   at System.Reflection.RuntimeAssembly._nLoad(AssemblyName fileName, String codeBase, Evidence assemblySecurity, RuntimeAssembly locationHint, StackCrawlMark& stackMark, IntPtr pPrivHostBinder, Boolean throwOnFileNotFound, Boolean forIntrospection, Boolean suppressSecurityChecks)
   at System.Reflection.RuntimeAssembly.InternalLoadAssemblyName(AssemblyName assemblyRef, Evidence assemblySecurity, RuntimeAssembly reqAssembly, StackCrawlMark& stackMark, IntPtr pPrivHostBinder, Boolean throwOnFileNotFound, Boolean forIntrospection, Boolean suppressSecurityChecks)
   at System.Reflection.Assembly.Load(AssemblyName assemblyRef)
   at Orleans.Runtime.AssemblyLoader.TryLoadAndCreateInstance[T](String assemblyName, TraceLogger logger)
[2016-01-26 16:39:40.339 GMT     1  WARNING 103806  CodeGenerator   ]   Code generator assembly (OrleansCodeGenerator.dll) not present. 
[2016-01-26 16:39:40.630 GMT     1  INFO    100404  Silo    127.0.0.1:11111]    Silo starting with GC settings: ServerGC=False GCLatencyMode=Interactive    
[2016-01-26 16:39:40.631 GMT     1  WARNING 100405  Silo    127.0.0.1:11111]    Note: Silo not running with ServerGC turned on or with GCLatencyMode.Batch enabled - recommend checking app config : <configuration>-<runtime>-<gcServer enabled="true"> and <configuration>-<runtime>-<gcConcurrent enabled="false"/>  
[2016-01-26 16:39:40.632 GMT     1  INFO    100403  Silo    127.0.0.1:11111]    -------------- Initializing Primary silo on host CWI-ALEX MachineName CWI-ALEX at 127.0.0.1:11111, gen 191522380 -------------- 
[2016-01-26 16:39:40.648 GMT     1  INFO    100415  Silo    127.0.0.1:11111]    Starting silo CWI-ALEX with runtime Version='1.1.2.0 (Release).' .NET version='4.0.30319.42000' Is .NET 4.5=True OS version='Microsoft Windows NT 6.2.9200.0' Config= 
Config File Name: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansConfiguration.xml
Host: CWI-ALEX
Start time: 2016-01-26 16:39:40.635 GMT
Primary node: 127.0.0.1:11111
Platform version info:
   .NET version: 4.0.30319.42000
   Is .NET 4.5=True
   OS version: Microsoft Windows NT 6.2.9200.0
   GC Type=Client GCLatencyMode=Interactive
Global configuration:
   System Ids:
      ServiceId: 00000000-0000-0000-0000-000000000000
      DeploymentId: apuchkov
   Subnet: 
   Seed nodes: 127.0.0.1:11111
   Messaging:
       Response timeout: 00:30:00
       Maximum resend count: 0
       Resend On Timeout: False
       Maximum Socket Age: 10675199.02:48:05.4775807
       Drop Expired Messages: True
       Silo Sender queues: 8
       Gateway Sender queues: 8
       Use standard (.NET) serializer: False
       Use fallback json serializer: False
       Buffer Pool Buffer Size: 4096
       Buffer Pool Max Size: 10000
       Buffer Pool Preallocation Size: 250
       Use Message Batching: False
       Max Message Batching Size: 10
       Maximum forward count: 2
   Liveness:
      LivenessEnabled: True
      LivenessType: MembershipTableGrain
      ProbeTimeout: 00:00:10
      TableRefreshTimeout: 00:01:00
      DeathVoteExpirationTimeout: 00:02:00
      NumMissedProbesLimit: 3
      NumProbedSilos: 3
      NumVotesForDeathDeclaration: 2
      UseLivenessGossip: True
      IAmAliveTablePublishTimeout: 00:05:00
      NumMissedTableIAmAliveLimit: 2
      MaxJoinAttemptTime: 00:05:00
      ExpectedClusterSize: 20
   SystemStore:
      SystemStore ConnectionString: null
      Reminders ConnectionString: null
   Application:
      Defaults:
         Deactivate if idle for: 02:00:00

   PlacementStrategy: 
         Default Placement Strategy: RandomPlacement
         Deployment Load Publisher Refresh Time: 00:00:01
         Activation CountBased Placement Choose Out Of: 2
   Grain directory cache:
      Maximum size: 1000000 grains
      Initial TTL: 00:00:30
      Maximum TTL: 00:04:00
      TTL extension factor: 2.00
      Directory Caching Strategy: Adaptive
   Grain directory:
      Lazy deregistration delay: 00:01:00
      Client registration refresh: 00:05:00
   Reminder Service:
       ReminderServiceType: ReminderTableGrain
   Consistent Ring:
       Use Virtual Buckets Consistent Ring: True
       Num Virtual Buckets Consistent Ring: 30
   Providers:
       No providers configured.
Silo configuration:
   Silo Name: CWI-ALEX
   Generation: 191522380
   Host Name or IP Address: localhost
   DNS Host Name: CWI-ALEX
   Port: 11111
   Subnet: 
   Preferred Address Family: InterNetwork
   Proxy Gateway: 127.0.0.1:30000
   IsPrimaryNode: True
   Scheduler: 
         Max Active Threads: 8
         Processor Count: 8
         Delay Warning Threshold: 00:00:10
         Activation Scheduling Quantum: 00:00:00.1000000
         Turn Warning Length Threshold: 00:00:00.2000000
         Inject More Worker Threads: False
         MinDotNetThreadPoolSize: 200
         .NET thread pool sizes - Min: Worker Threads=8 Completion Port Threads=8
         .NET thread pool sizes - Max: Worker Threads=32767 Completion Port Threads=1000
         .NET ServicePointManager - DefaultConnectionLimit=200 Expect100Continue=False UseNagleAlgorithm=False
   Load Shedding Enabled: False
   Load Shedding Limit: 95
   SiloShutdownEventName: 
   Debug: 
   Tracing: 
     Default Trace Level: Info
     TraceLevelOverrides: None
     Trace to Console: True
     Trace File Name: c:\Users\apuchkov\Documents\Visual Studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\CWI-ALEX-2016-01-26-16.39.40.232Z.log
     Write Messaging Traces: False
     LargeMessageWarningThreshold: 85000
     PropagateActivityId: False
     BulkMessageLimit: 5
   Statistics: 
     MetricsTableWriteInterval: 00:00:30
     PerfCounterWriteInterval: 00:00:30
     LogWriteInterval: 00:05:00
     WriteLogStatisticsToTable: True
     StatisticsCollectionLevel: Info

[2016-01-26 16:39:40.665 GMT     1  INFO    100445  Silo    127.0.0.1:11111]    Successfully loaded Orleans.Runtime.Startup.ConfigureStartupBuilder from OrleansDependencyInjection.dll 
[2016-01-26 16:39:40.716 GMT     1  WARNING 100447  Silo    127.0.0.1:11111]    Caught a FileNotFoundException calling ConfigureStartup(). Ignoring it. {0} 
Exc level 0: System.IO.FileNotFoundException: Could not load file or assembly 'Microsoft.Extensions.DependencyInjection.Abstractions, Version=1.0.0.0, Culture=neutral, PublicKeyToken=adb9793829ddae60' or one of its dependencies. The system cannot find the file specified.
   at Orleans.Runtime.Startup.ConfigureStartupBuilder.ConfigureStartup(String startupTypeName)
   at Orleans.Runtime.Startup.ConfigureStartupBuilder.Orleans.Runtime.Startup.IStartupBuilder.ConfigureStartup(String startupTypeName)
   at Orleans.Runtime.Silo..ctor(String name, SiloType siloType, ClusterConfiguration config, ILocalDataStore keyStore)
[2016-01-26 16:39:41.931 GMT     1  INFO    100000  Scheduler.OrleansTaskScheduler  127.0.0.1:11111]    Starting OrleansTaskScheduler with 8 Max Active application Threads and 1 system thread.    
[2016-01-26 16:39:41.939 GMT     1  INFO    101010  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Opened a listening socket at address 127.0.0.1:11111    
[2016-01-26 16:39:41.946 GMT     1  INFO    101010  Runtime.Messaging.GatewayAcceptor   127.0.0.1:11111]    Opened a listening socket at address 127.0.0.1:30000    
[2016-01-26 16:39:41.971 GMT     1  INFO    100000  VirtualBucketsRingProvider  127.0.0.1:11111]    Starting VirtualBucketsRingProvider on silo S127.0.0.1:11111:191522380/xC11F0DBA.   
[2016-01-26 16:39:42.003 GMT     1  INFO    103003  VirtualBucketsRingProvider  127.0.0.1:11111]    Added Server S127.0.0.1:11111:191522380/xC11F0DBA. Current view: [S127.0.0.1:11111:191522380 -> <MultiRange: Size=x100000000, %Ring=100.000%>]  
[2016-01-26 16:39:42.007 GMT     1  INFO    103005  VirtualBucketsRingProvider  127.0.0.1:11111]    -NotifyLocalRangeSubscribers about old <(0 0], Size=x100000000, %Ring=100.000%> new <MultiRange: Size=x100000000, %Ring=100.000%> increased? True   
[2016-01-26 16:39:42.025 GMT     1  INFO    100294  SystemStatus    127.0.0.1:11111]    SystemStatus=Created    
[2016-01-26 16:39:42.057 GMT     1  INFO    100422  Silo    127.0.0.1:11111]    -------------- Started silo S127.0.0.1:11111:191522380, ConsistentHashCode C11F0DBA --------------  
[2016-01-26 16:39:42.060 GMT     1  INFO    100441  OrleansSiloHost 127.0.0.1:11111]    Silo shutdown event name: CWI-ALEX-Shutdown 
[2016-01-26 16:39:42.060 GMT     1  INFO    100442  OrleansSiloHost 127.0.0.1:11111]    Created and set shutdown event CWI-ALEX-Shutdown    
[2016-01-26 16:39:42.066 GMT     1  INFO    100294  SystemStatus    127.0.0.1:11111]    SystemStatus=Starting   
[2016-01-26 16:39:42.067 GMT     1  INFO    100401  Silo    127.0.0.1:11111]    Silo Start()    
[2016-01-26 16:39:42.069 GMT     1  INFO    100430  Silo    127.0.0.1:11111]    Configured ThreadPool.SetMinThreads() to values: 200,200. Previous values are: 8,8. 
[2016-01-26 16:39:42.070 GMT     1  INFO    100436  Silo    127.0.0.1:11111]    Configured .NET ServicePointManager to Expect100Continue=False, DefaultConnectionLimit=200, UseNagleAlgorithm=False to improve Azure storage performance.   
[2016-01-26 16:39:42.087 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    Searching for assemblies in C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug...    
[2016-01-26 16:39:42.097 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\GrainInterfaces.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:42.121 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Newtonsoft.Json.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:42.122 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Orleans.dll
    * Assembly filename is excluded.    
[2016-01-26 16:39:42.123 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansDependencyInjection.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:42.124 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansRuntime.dll
    * Assembly filename is excluded.    
[2016-01-26 16:39:42.125 GMT     1  WARNING 101705  AssemblyLoader.Silo 127.0.0.1:11111]    Unable to find directory C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Applications; skipping.  
[2016-01-26 16:39:42.126 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    Loading assembly C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Grains.dll...    
[2016-01-26 16:39:42.135 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    1 assemblies loaded.    
[2016-01-26 16:39:42.160 GMT     1  INFO    101711  AssemblyLoader.Silo 127.0.0.1:11111]    Loaded grain type summary for 6 types: 
Grain class Grains.Grains.ClientGrain [-426232758 (0xE698344A)] from Grains.dll implementing interfaces: GrainInterfaces.GrainInterfaces.IClientGrain [-865503811 (0xCC6975BD)]
Grain class Orleans.Runtime.Management.Orleans.Runtime.Management.ManagementGrain [1954798034 (0x7483D9D2)] from OrleansRuntime.dll implementing interfaces: Orleans.Runtime.Orleans.Runtime.IManagementGrain [-1734666656 (0x989B1660)]
Grain class Orleans.Runtime.MembershipService.Orleans.Runtime.MembershipService.GrainBasedMembershipTable [-2103923544 (0x8298ACA8)] from OrleansRuntime.dll implementing interfaces: Orleans.Orleans.IMembershipTableGrain [-1256503757 (0xB51B4633)]
Grain class Orleans.Runtime.ReminderService.Orleans.Runtime.ReminderService.GrainBasedReminderTable [-55315191 (0xFCB3F509)] from OrleansRuntime.dll implementing interfaces: Orleans.Orleans.IReminderTableGrain [-1135060418 (0xBC585A3E)]
Grain class Orleans.Storage.Orleans.Storage.MemoryStorageGrain [819348208 (0x30D642F0)] from OrleansRuntime.dll implementing interfaces: Orleans.Storage.Orleans.Storage.IMemoryStorageGrain [577125491 (0x22663C73)]
Grain class Orleans.Streams.Orleans.Streams.PubSubRendezvousGrain [1903070868 (0x716E8E94)] from OrleansRuntime.dll implementing interfaces: Orleans.Streams.Orleans.Streams.IPubSubRendezvousGrain [1746702088 (0x681C8F08)]

[2016-01-26 16:39:42.180 GMT     7  INFO    100000  Runtime.Scheduler.WorkerPoolThread/1    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/1 on managed thread 7    
[2016-01-26 16:39:42.242 GMT     9  INFO    100000  Runtime.Scheduler.WorkerPoolThread/3    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/3 on managed thread 9    
[2016-01-26 16:39:42.179 GMT    14  INFO    100000  Runtime.Scheduler.WorkerPoolThread/System.9 127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/System.9 on managed thread 14    
[2016-01-26 16:39:42.325 GMT    13  INFO    100000  Runtime.Scheduler.WorkerPoolThread/7    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/7 on managed thread 13   
[2016-01-26 16:39:42.265 GMT    11  INFO    100000  Runtime.Scheduler.WorkerPoolThread/5    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/5 on managed thread 11   
[2016-01-26 16:39:42.265 GMT    10  INFO    100000  Runtime.Scheduler.WorkerPoolThread/4    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/4 on managed thread 10   
[2016-01-26 16:39:42.201 GMT     8  INFO    100000  Runtime.Scheduler.WorkerPoolThread/2    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/2 on managed thread 8    
[2016-01-26 16:39:42.325 GMT    12  INFO    100000  Runtime.Scheduler.WorkerPoolThread/6    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/6 on managed thread 12   
[2016-01-26 16:39:42.201 GMT     6  INFO    100000  Runtime.Scheduler.WorkerPoolThread/0    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/0 on managed thread 6    
[2016-01-26 16:39:42.372 GMT    15  INFO    100000  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAcceptor on managed thread 15  
[2016-01-26 16:39:42.373 GMT    16  INFO    100000  Runtime.Messaging.SiloMessageSender/PingSender  127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.SiloMessageSender/PingSender on managed thread 16 
[2016-01-26 16:39:42.395 GMT    17  INFO    100000  Runtime.Messaging.SiloMessageSender/SystemSender    127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.SiloMessageSender/SystemSender on managed thread 17   
[2016-01-26 16:39:42.418 GMT    15  INFO    100328  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Stopping AsyncAgent Runtime.Messaging.IncomingMessageAcceptor that runs on managed thread 15    
[2016-01-26 16:39:42.440 GMT    24  INFO    100000  Runtime.Messaging.IncomingMessageAgent/Application  127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/Application on managed thread 24 
[2016-01-26 16:39:42.440 GMT    22  INFO    100000  Runtime.Messaging.IncomingMessageAgent/System   127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/System on managed thread 22  
[2016-01-26 16:39:42.419 GMT    23  INFO    100000  Runtime.Messaging.IncomingMessageAgent/Ping 127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/Ping on managed thread 23    
[2016-01-26 16:39:42.461 GMT    21  INFO    100000  Runtime.GrainDirectory.AdaptiveDirectoryCacheMaintainer`1   127.0.0.1:11111]    Starting AsyncAgent Runtime.GrainDirectory.AdaptiveDirectoryCacheMaintainer`1 on managed thread 21  
[2016-01-26 16:39:42.502 GMT     1  INFO    100000  MembershipFactory   127.0.0.1:11111]    Creating membership oracle for type=MembershipTableGrain    
[2016-01-26 16:39:42.513 GMT     1  INFO    100000  ReminderFactory 127.0.0.1:11111]    Creating reminder system target for type=ReminderTableGrain 
[2016-01-26 16:39:42.536 GMT    13  INFO    100635  MembershipFactory   127.0.0.1:11111]    Creating membership table provider for type=MembershipTableGrain    
[2016-01-26 16:39:42.562 GMT     9  INFO    100637  GrainBasedMembershipTable   127.0.0.1:11111]    GrainBasedMembershipTable Activated.    
[2016-01-26 16:39:42.577 GMT    13  INFO    100507  Catalog 127.0.0.1:11111]    Before collection#1: memory=9MB, #activations=1, collector=<#Activations=0, #Buckets=0, buckets=[]>.    
[2016-01-26 16:39:42.580 GMT    13  INFO    100508  Catalog 127.0.0.1:11111]    After collection#1: memory=9MB, #activations=1, collected 0 activations, collector=<#Activations=0, #Buckets=0, buckets=[]>, collection time=00:00:00.0078649.  
[2016-01-26 16:39:42.678 GMT     6  INFO    100000  GrainBasedMembershipTable   127.0.0.1:11111]    InitializeMembershipTable True. 
[2016-01-26 16:39:42.690 GMT    14  INFO    100603  MembershipOracle    127.0.0.1:11111]    MembershipOracle starting on host = CWI-ALEX address = S127.0.0.1:11111:191522380 at 2016-01-26 16:39:42.505 GMT, backOffMax = 00:00:20 
[2016-01-26 16:39:46.894 GMT    13  INFO    100652  MembershipOracle    127.0.0.1:11111]    -CleanupTable called on silo startup. Membership table 0 silos, 0 are Active, 0 are Dead, Version=<0, 0>. All silos: [] 
[2016-01-26 16:39:46.942 GMT    12  INFO    100660  MembershipOracle    127.0.0.1:11111]    Starting IAmAliveUpdateTimer.   
[2016-01-26 16:39:46.955 GMT    12  INFO    100604  MembershipOracle    127.0.0.1:11111]    -BecomeActive   
[2016-01-26 16:39:46.961 GMT    12  INFO    100614  MembershipOracle    127.0.0.1:11111]    About to send pings to 0 nodes in order to validate communication in the Joining state. Pinged nodes = []   
[2016-01-26 16:39:46.971 GMT    12  INFO    100634  MembershipOracle    127.0.0.1:11111]    -ReadAll (called from BecomeActive) Membership table 1 silos, 1 are Active, 0 are Dead, Version=<2, 5>. All silos: [SiloAddress=S127.0.0.1:11111:191522380 InstanceName=CWI-ALEX Status=Active] 
[2016-01-26 16:39:46.973 GMT    12  INFO    100605  MembershipOracle    127.0.0.1:11111]    -Finished BecomeActive. 
[2016-01-26 16:39:46.975 GMT     1  INFO    100702  WindowsPerfCountersStatistics   127.0.0.1:11111]    Starting Windows perf counter stats collection with frequency=00:00:30  
[2016-01-26 16:39:46.978 GMT     1  WARNING 100701  WindowsPerfCountersStatistics   127.0.0.1:11111]    Windows perf counters not found -- defaulting to in-memory counters. Run OrleansCounterControl.exe as Administrator to create perf counters for Orleans.    
[2016-01-26 16:39:46.988 GMT     1  INFO    100714  SiloPerformanceMetrics  127.0.0.1:11111]    Starting Silo Table metrics reporter with reportFrequency=00:00:30  
[2016-01-26 16:39:46.991 GMT    14  INFO    100000  DeploymentLoadPublisher 127.0.0.1:11111]    Starting DeploymentLoadPublisher.   
[2016-01-26 16:39:47.019 GMT    14  INFO    100000  DeploymentLoadPublisher 127.0.0.1:11111]    Started DeploymentLoadPublisher.    
[2016-01-26 16:39:47.020 GMT     1  INFO    100000  Watchdog    127.0.0.1:11111]    Starting Silo Watchdog. 
[2016-01-26 16:39:47.022 GMT    26  INFO    100000  Runtime.Watchdog    127.0.0.1:11111]    Starting AsyncAgent Runtime.Watchdog on managed thread 26   
[2016-01-26 16:39:47.024 GMT    14  INFO    102925  ReminderService 127.0.0.1:11111]    Starting reminder system target on: S127.0.0.1:11111:191522380 xC11F0DBA, with range <MultiRange: Size=x100000000, %Ring=100.000%>  
[2016-01-26 16:39:47.048 GMT     7  INFO    100000  GrainBasedReminderTable_S127.0.0.1:11111:191522380*grn/FCB3F509/00003039@985265b9   127.0.0.1:11111]    GrainBasedReminderTable *grn/FCB3F509/00003039 Activated. Full identity: [ActivationAddress: S127.0.0.1:11111:191522380*grn/FCB3F509/00003039@985265b9, Full GrainId: [GrainId: *grn/FCB3F509/0000000000000000000000000000303903fffffffcb3f509-0xBB9ED002, IdCategory: Grain, BaseTypeCode: -55315191 (xFCB3F509), PrimaryKey: 12345 (x3039), UniformHashCode: 3147747330 (0xBB9ED002)], Full ActivationId: @486aeb7bef15c4c64b82c86b985265b90000000000000000]  
[2016-01-26 16:39:47.068 GMT    14  INFO    102926  ReminderService 127.0.0.1:11111]    Reminder system target started OK on: S127.0.0.1:11111:191522380 xC11F0DBA, with range <MultiRange: Size=x100000000, %Ring=100.000%>    
[2016-01-26 16:39:47.078 GMT    18  INFO    100000  Runtime.Messaging.GatewayAcceptor   127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.GatewayAcceptor on managed thread 18  
[2016-01-26 16:39:47.080 GMT    18  INFO    100328  Runtime.Messaging.GatewayAcceptor   127.0.0.1:11111]    Stopping AsyncAgent Runtime.Messaging.GatewayAcceptor that runs on managed thread 18    
[2016-01-26 16:39:47.080 GMT    19  INFO    100000  Runtime.Messaging.GatewayClientCleanupAgent 127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.GatewayClientCleanupAgent on managed thread 19    
[2016-01-26 16:39:47.080 GMT     1  INFO    100294  SystemStatus    127.0.0.1:11111]    SystemStatus=Running    
[2016-01-26 16:39:47.106 GMT     1  INFO    100424  OrleansSiloHost 127.0.0.1:11111]    Silo startup event name: CWI-ALEX   
[2016-01-26 16:39:47.108 GMT     1  INFO    100425  OrleansSiloHost 127.0.0.1:11111]    Created and set startup event CWI-ALEX  
[2016-01-26 16:39:47.109 GMT     1  INFO    100402  OrleansSiloHost 127.0.0.1:11111]    Silo CWI-ALEX started successfully  
[2016-01-26 16:39:57.083 GMT    28  INFO    101301  Orleans.Messaging.Gateway   127.0.0.1:11111]    Recorded opened socket from endpoint 127.0.0.1:54861, client ID *cli/3c2ce48d.  
[2016-01-26 16:39:57.161 GMT    30  INFO    100000  Runtime.Messaging.GatewaySender/GatewaySiloSender_0 127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.GatewaySender/GatewaySiloSender_0 on managed thread 30    
[2016-01-26 16:39:57.252 GMT    13  WARNING 100329  InsideRuntimeClient 127.0.0.1:11111]    Exception during Invoke of message: NewPlacement Request S127.0.0.1:11111:191522380*cli/3c2ce48d@f6d3a488->S127.0.0.1:11111:191522380*grn/E698344A/00000001@b336088e #4: global::GrainInterfaces.IClientGrain:ProcessClient()   
Exc level 0: Orleans.Runtime.OrleansException: Cannot find an invoker for interface GrainInterfaces.IClientGrain (ID=-865503811,0xCC6975BD).
   at Orleans.Runtime.GrainTypeManager.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.ActivationData.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.<Invoke>d__6.MoveNext()
[2016-01-26 16:39:59.694 GMT    26  WARNING 100150  Watchdog    127.0.0.1:11111]    .NET Runtime Platform stalled for 00:00:02.6607705 - possibly GC? We are now using total of 8MB memory. gc=7, 5, 3  
[2016-01-26 16:39:59.705 GMT     8  WARNING 100329  InsideRuntimeClient 127.0.0.1:11111]    Exception during Invoke of message: Request S127.0.0.1:11111:191522380*cli/3c2ce48d@f6d3a488->S127.0.0.1:11111:191522380*grn/E698344A/00000001@b336088e #5: global::GrainInterfaces.IClientGrain:ProcessClient()    
Exc level 0: Orleans.Runtime.OrleansException: Cannot find an invoker for interface GrainInterfaces.IClientGrain (ID=-865503811,0xCC6975BD).
   at Orleans.Runtime.GrainTypeManager.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.ActivationData.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.<Invoke>d__6.MoveNext()

Grains\bin\Debug\help-2016-01-26-14.29.42.903Z.log

[2016-01-26 14:29:42.968 GMT     1  WARNING 101722  CodeGenerator   ]   Could not load file or assembly 'OrleansCodeGenerator' or one of its dependencies. The system cannot find the file specified.   
Exc level 0: System.IO.FileNotFoundException: Could not load file or assembly 'OrleansCodeGenerator' or one of its dependencies. The system cannot find the file specified.
   at System.Reflection.RuntimeAssembly._nLoad(AssemblyName fileName, String codeBase, Evidence assemblySecurity, RuntimeAssembly locationHint, StackCrawlMark& stackMark, IntPtr pPrivHostBinder, Boolean throwOnFileNotFound, Boolean forIntrospection, Boolean suppressSecurityChecks)
   at System.Reflection.RuntimeAssembly.InternalLoadAssemblyName(AssemblyName assemblyRef, Evidence assemblySecurity, RuntimeAssembly reqAssembly, StackCrawlMark& stackMark, IntPtr pPrivHostBinder, Boolean throwOnFileNotFound, Boolean forIntrospection, Boolean suppressSecurityChecks)
   at System.Reflection.Assembly.Load(AssemblyName assemblyRef)
   at Orleans.Runtime.AssemblyLoader.TryLoadAndCreateInstance[T](String assemblyName, TraceLogger logger)
[2016-01-26 14:29:42.983 GMT     1  WARNING 103806  CodeGenerator   ]   Code generator assembly (OrleansCodeGenerator.dll) not present. 
[2016-01-26 14:29:43.189 GMT     1  INFO    100404  Silo    127.0.0.1:11111]    Silo starting with GC settings: ServerGC=False GCLatencyMode=Interactive    
[2016-01-26 14:29:43.191 GMT     1  WARNING 100405  Silo    127.0.0.1:11111]    Note: Silo not running with ServerGC turned on or with GCLatencyMode.Batch enabled - recommend checking app config : <configuration>-<runtime>-<gcServer enabled="true"> and <configuration>-<runtime>-<gcConcurrent enabled="false"/>  
[2016-01-26 14:29:43.192 GMT     1  INFO    100403  Silo    127.0.0.1:11111]    -------------- Initializing Primary silo on host CWI-ALEX MachineName CWI-ALEX at 127.0.0.1:11111, gen 191514583 -------------- 
[2016-01-26 14:29:43.213 GMT     1  INFO    100415  Silo    127.0.0.1:11111]    Starting silo help with runtime Version='1.1.2.0 (Release).' .NET version='4.0.30319.42000' Is .NET 4.5=True OS version='Microsoft Windows NT 6.2.9200.0' Config= 
Config File Name: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansConfiguration.xml
Host: CWI-ALEX
Start time: 2016-01-26 14:29:43.195 GMT
Primary node: 127.0.0.1:11111
Platform version info:
   .NET version: 4.0.30319.42000
   Is .NET 4.5=True
   OS version: Microsoft Windows NT 6.2.9200.0
   GC Type=Client GCLatencyMode=Interactive
Global configuration:
   System Ids:
      ServiceId: 00000000-0000-0000-0000-000000000000
      DeploymentId: apuchkov
   Subnet: 
   Seed nodes: 127.0.0.1:11111
   Messaging:
       Response timeout: 00:00:30
       Maximum resend count: 0
       Resend On Timeout: False
       Maximum Socket Age: 10675199.02:48:05.4775807
       Drop Expired Messages: True
       Silo Sender queues: 8
       Gateway Sender queues: 8
       Use standard (.NET) serializer: False
       Use fallback json serializer: False
       Buffer Pool Buffer Size: 4096
       Buffer Pool Max Size: 10000
       Buffer Pool Preallocation Size: 250
       Use Message Batching: False
       Max Message Batching Size: 10
       Maximum forward count: 2
   Liveness:
      LivenessEnabled: True
      LivenessType: MembershipTableGrain
      ProbeTimeout: 00:00:10
      TableRefreshTimeout: 00:01:00
      DeathVoteExpirationTimeout: 00:02:00
      NumMissedProbesLimit: 3
      NumProbedSilos: 3
      NumVotesForDeathDeclaration: 2
      UseLivenessGossip: True
      IAmAliveTablePublishTimeout: 00:05:00
      NumMissedTableIAmAliveLimit: 2
      MaxJoinAttemptTime: 00:05:00
      ExpectedClusterSize: 20
   SystemStore:
      SystemStore ConnectionString: null
      Reminders ConnectionString: null
   Application:
      Defaults:
         Deactivate if idle for: 02:00:00

   PlacementStrategy: 
         Default Placement Strategy: RandomPlacement
         Deployment Load Publisher Refresh Time: 00:00:01
         Activation CountBased Placement Choose Out Of: 2
   Grain directory cache:
      Maximum size: 1000000 grains
      Initial TTL: 00:00:30
      Maximum TTL: 00:04:00
      TTL extension factor: 2.00
      Directory Caching Strategy: Adaptive
   Grain directory:
      Lazy deregistration delay: 00:01:00
      Client registration refresh: 00:05:00
   Reminder Service:
       ReminderServiceType: ReminderTableGrain
   Consistent Ring:
       Use Virtual Buckets Consistent Ring: True
       Num Virtual Buckets Consistent Ring: 30
   Providers:
       No providers configured.
Silo configuration:
   Silo Name: help
   Generation: 191514583
   Host Name or IP Address: localhost
   DNS Host Name: CWI-ALEX
   Port: 11111
   Subnet: 
   Preferred Address Family: InterNetwork
   Proxy Gateway: 127.0.0.1:30000
   IsPrimaryNode: True
   Scheduler: 
         Max Active Threads: 8
         Processor Count: 8
         Delay Warning Threshold: 00:00:10
         Activation Scheduling Quantum: 00:00:00.1000000
         Turn Warning Length Threshold: 00:00:00.2000000
         Inject More Worker Threads: False
         MinDotNetThreadPoolSize: 200
         .NET thread pool sizes - Min: Worker Threads=8 Completion Port Threads=8
         .NET thread pool sizes - Max: Worker Threads=32767 Completion Port Threads=1000
         .NET ServicePointManager - DefaultConnectionLimit=200 Expect100Continue=False UseNagleAlgorithm=False
   Load Shedding Enabled: False
   Load Shedding Limit: 95
   SiloShutdownEventName: 
   Debug: 
   Tracing: 
     Default Trace Level: Info
     TraceLevelOverrides: None
     Trace to Console: True
     Trace File Name: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\help-2016-01-26-14.29.42.903Z.log
     Write Messaging Traces: False
     LargeMessageWarningThreshold: 85000
     PropagateActivityId: False
     BulkMessageLimit: 5
   Statistics: 
     MetricsTableWriteInterval: 00:00:30
     PerfCounterWriteInterval: 00:00:30
     LogWriteInterval: 00:05:00
     WriteLogStatisticsToTable: True
     StatisticsCollectionLevel: Info

[2016-01-26 14:29:43.248 GMT     1  INFO    100445  Silo    127.0.0.1:11111]    Successfully loaded Orleans.Runtime.Startup.ConfigureStartupBuilder from OrleansDependencyInjection.dll 
[2016-01-26 14:29:43.292 GMT     1  WARNING 100447  Silo    127.0.0.1:11111]    Caught a FileNotFoundException calling ConfigureStartup(). Ignoring it. {0} 
Exc level 0: System.IO.FileNotFoundException: Could not load file or assembly 'Microsoft.Extensions.DependencyInjection.Abstractions, Version=1.0.0.0, Culture=neutral, PublicKeyToken=adb9793829ddae60' or one of its dependencies. The system cannot find the file specified.
   at Orleans.Runtime.Startup.ConfigureStartupBuilder.ConfigureStartup(String startupTypeName)
   at Orleans.Runtime.Silo..ctor(String name, SiloType siloType, ClusterConfiguration config, ILocalDataStore keyStore)
[2016-01-26 14:29:43.984 GMT     1  INFO    100000  Scheduler.OrleansTaskScheduler  127.0.0.1:11111]    Starting OrleansTaskScheduler with 8 Max Active application Threads and 1 system thread.    
[2016-01-26 14:29:43.991 GMT     1  INFO    101010  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Opened a listening socket at address 127.0.0.1:11111    
[2016-01-26 14:29:43.997 GMT     1  INFO    101010  Runtime.Messaging.GatewayAcceptor   127.0.0.1:11111]    Opened a listening socket at address 127.0.0.1:30000    
[2016-01-26 14:29:44.023 GMT     1  INFO    100000  VirtualBucketsRingProvider  127.0.0.1:11111]    Starting VirtualBucketsRingProvider on silo S127.0.0.1:11111:191514583/xBEDCD09E.   
[2016-01-26 14:29:44.057 GMT     1  INFO    103003  VirtualBucketsRingProvider  127.0.0.1:11111]    Added Server S127.0.0.1:11111:191514583/xBEDCD09E. Current view: [S127.0.0.1:11111:191514583 -> <MultiRange: Size=x100000000, %Ring=100.000%>]  
[2016-01-26 14:29:44.060 GMT     1  INFO    103005  VirtualBucketsRingProvider  127.0.0.1:11111]    -NotifyLocalRangeSubscribers about old <(0 0], Size=x100000000, %Ring=100.000%> new <MultiRange: Size=x100000000, %Ring=100.000%> increased? True   
[2016-01-26 14:29:44.080 GMT     1  INFO    100294  SystemStatus    127.0.0.1:11111]    SystemStatus=Created    
[2016-01-26 14:29:44.082 GMT     1  INFO    100422  Silo    127.0.0.1:11111]    -------------- Started silo S127.0.0.1:11111:191514583, ConsistentHashCode BEDCD09E --------------  
[2016-01-26 14:29:44.085 GMT     1  INFO    100441  OrleansSiloHost 127.0.0.1:11111]    Silo shutdown event name: help-Shutdown 
[2016-01-26 14:29:44.086 GMT     1  INFO    100442  OrleansSiloHost 127.0.0.1:11111]    Created and set shutdown event help-Shutdown    
[2016-01-26 14:29:44.093 GMT     1  INFO    100294  SystemStatus    127.0.0.1:11111]    SystemStatus=Starting   
[2016-01-26 14:29:44.093 GMT     1  INFO    100401  Silo    127.0.0.1:11111]    Silo Start()    
[2016-01-26 14:29:44.095 GMT     1  INFO    100430  Silo    127.0.0.1:11111]    Configured ThreadPool.SetMinThreads() to values: 200,200. Previous values are: 8,8. 
[2016-01-26 14:29:44.097 GMT     1  INFO    100436  Silo    127.0.0.1:11111]    Configured .NET ServicePointManager to Expect100Continue=False, DefaultConnectionLimit=200, UseNagleAlgorithm=False to improve Azure storage performance.   
[2016-01-26 14:29:44.119 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    Searching for assemblies in C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug...    
[2016-01-26 14:29:44.131 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\GrainInterfaces.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 14:29:44.154 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Newtonsoft.Json.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 14:29:44.157 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Orleans.dll
    * Assembly filename is excluded.    
[2016-01-26 14:29:44.160 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansDependencyInjection.dll
    * Assembly does not contain any acceptable grain types.
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 14:29:44.164 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    User assembly ignored: C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\OrleansRuntime.dll
    * Assembly filename is excluded.    
[2016-01-26 14:29:44.167 GMT     1  WARNING 101705  AssemblyLoader.Silo 127.0.0.1:11111]    Unable to find directory C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Applications; skipping.  
[2016-01-26 14:29:44.168 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    Loading assembly C:\Users\apuchkov\Documents\visual studio 2015\Projects\MemberExtractOrleans\Grains\bin\Debug\Grains.dll...    
[2016-01-26 14:29:44.173 GMT     1  INFO    100000  AssemblyLoader.Silo 127.0.0.1:11111]    1 assemblies loaded.    
[2016-01-26 14:29:44.198 GMT     1  INFO    101711  AssemblyLoader.Silo 127.0.0.1:11111]    Loaded grain type summary for 6 types: 
Grain class Grains.Grains.ClientGrain [-426232758 (0xE698344A)] from Grains.dll implementing interfaces: GrainInterfaces.GrainInterfaces.IClientGrain [-865503811 (0xCC6975BD)]
Grain class Orleans.Runtime.Management.Orleans.Runtime.Management.ManagementGrain [1954798034 (0x7483D9D2)] from OrleansRuntime.dll implementing interfaces: Orleans.Runtime.Orleans.Runtime.IManagementGrain [-1734666656 (0x989B1660)]
Grain class Orleans.Runtime.MembershipService.Orleans.Runtime.MembershipService.GrainBasedMembershipTable [-2103923544 (0x8298ACA8)] from OrleansRuntime.dll implementing interfaces: Orleans.Orleans.IMembershipTableGrain [-1256503757 (0xB51B4633)]
Grain class Orleans.Runtime.ReminderService.Orleans.Runtime.ReminderService.GrainBasedReminderTable [-55315191 (0xFCB3F509)] from OrleansRuntime.dll implementing interfaces: Orleans.Orleans.IReminderTableGrain [-1135060418 (0xBC585A3E)]
Grain class Orleans.Storage.Orleans.Storage.MemoryStorageGrain [819348208 (0x30D642F0)] from OrleansRuntime.dll implementing interfaces: Orleans.Storage.Orleans.Storage.IMemoryStorageGrain [577125491 (0x22663C73)]
Grain class Orleans.Streams.Orleans.Streams.PubSubRendezvousGrain [1903070868 (0x716E8E94)] from OrleansRuntime.dll implementing interfaces: Orleans.Streams.Orleans.Streams.IPubSubRendezvousGrain [1746702088 (0x681C8F08)]

[2016-01-26 14:29:44.230 GMT     8  INFO    100000  Runtime.Scheduler.WorkerPoolThread/2    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/2 on managed thread 8    
[2016-01-26 14:29:44.230 GMT     7  INFO    100000  Runtime.Scheduler.WorkerPoolThread/1    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/1 on managed thread 7    
[2016-01-26 14:29:44.231 GMT    14  INFO    100000  Runtime.Scheduler.WorkerPoolThread/System.9 127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/System.9 on managed thread 14    
[2016-01-26 14:29:44.231 GMT    11  INFO    100000  Runtime.Scheduler.WorkerPoolThread/5    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/5 on managed thread 11   
[2016-01-26 14:29:44.231 GMT     9  INFO    100000  Runtime.Scheduler.WorkerPoolThread/3    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/3 on managed thread 9    
[2016-01-26 14:29:44.231 GMT    10  INFO    100000  Runtime.Scheduler.WorkerPoolThread/4    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/4 on managed thread 10   
[2016-01-26 14:29:44.231 GMT     6  INFO    100000  Runtime.Scheduler.WorkerPoolThread/0    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/0 on managed thread 6    
[2016-01-26 14:29:44.232 GMT    12  INFO    100000  Runtime.Scheduler.WorkerPoolThread/6    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/6 on managed thread 12   
[2016-01-26 14:29:44.232 GMT    13  INFO    100000  Runtime.Scheduler.WorkerPoolThread/7    127.0.0.1:11111]    Starting AsyncAgent Runtime.Scheduler.WorkerPoolThread/7 on managed thread 13   
[2016-01-26 14:29:44.234 GMT    15  INFO    100000  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAcceptor on managed thread 15  
[2016-01-26 14:29:44.235 GMT    16  INFO    100000  Runtime.Messaging.SiloMessageSender/PingSender  127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.SiloMessageSender/PingSender on managed thread 16 
[2016-01-26 14:29:44.242 GMT    17  INFO    100000  Runtime.Messaging.SiloMessageSender/SystemSender    127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.SiloMessageSender/SystemSender on managed thread 17   
[2016-01-26 14:29:44.243 GMT    23  INFO    100000  Runtime.Messaging.IncomingMessageAgent/Ping 127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/Ping on managed thread 23    
[2016-01-26 14:29:44.243 GMT    22  INFO    100000  Runtime.Messaging.IncomingMessageAgent/System   127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/System on managed thread 22  
[2016-01-26 14:29:44.244 GMT    24  INFO    100000  Runtime.Messaging.IncomingMessageAgent/Application  127.0.0.1:11111]    Starting AsyncAgent Runtime.Messaging.IncomingMessageAgent/Application on managed thread 24 
[2016-01-26 14:29:44.245 GMT    21  INFO    100000  Runtime.GrainDirectory.AdaptiveDirectoryCacheMaintainer`1   127.0.0.1:11111]    Starting AsyncAgent Runtime.GrainDirectory.AdaptiveDirectoryCacheMaintainer`1 on managed thread 21  
[2016-01-26 14:29:44.256 GMT    15  INFO    100328  Runtime.Messaging.IncomingMessageAcceptor   127.0.0.1:11111]    Stopping AsyncAgent Runtime.Messaging.IncomingMessageAcceptor that runs on managed thread 15    
[2016-01-26 14:29:44.268 GMT     1  INFO    100000  MembershipFactory   127.0.0.1:11111]    Creating membership oracle for type=MembershipTableGrain    
[2016-01-26 14:29:44.280 GMT     1  INFO    100000  ReminderFactory 127.0.0.1:11111]    Creating reminder system target for type=ReminderTableGrain 
[2016-01-26 14:29:44.307 GMT    14  INFO    100635  MembershipFactory   127.0.0.1:11111]    Creating membership table provider for type=MembershipTableGrain    
[2016-01-26 14:29:44.337 GMT    13  INFO    100637  GrainBasedMembershipTable   127.0.0.1:11111]    GrainBasedMembershipTable Activated.    
[2016-01-26 14:29:44.351 GMT    14  INFO    100507  Catalog 127.0.0.1:11111]    Before collection#1: memory=9MB, #activations=1, collector=<#Activations=0, #Buckets=0, buckets=[]>.    
[2016-01-26 14:29:44.357 GMT    14  INFO    100508  Catalog 127.0.0.1:11111]    After collection#1: memory=9MB, #activations=1, collected 0 activations, collector=<#Activations=0, #Buckets=0, buckets=[]>, collection time=00:00:00.0118039.  
[2016-01-26 14:29:44.449 GMT    10  INFO    100000  GrainBasedMembershipTable   127.0.0.1:11111]    InitializeMembershipTable True. 
[2016-01-26 14:29:44.462 GMT    14  INFO    100603  MembershipOracle    127.0.0.1:11111]    MembershipOracle starting on host = CWI-ALEX address = S127.0.0.1:11111:191514583 at 2016-01-26 14:29:44.271 GMT, backOffMax = 00:00:20 
[2016-01-26 14:29:45.734 GMT     5  WARNING 100220  Silo    127.0.0.1:11111]    Process is exiting

\MemberExtractOrleans\bin\Debug\Client-2016-01-26-16.39.53.073Z.log

[2016-01-26 16:39:55.366 GMT    11  INFO    100000  AssemblyLoader.Client   ]   Recursively searching for assemblies in c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug...  
[2016-01-26 16:39:55.382 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\GrainInterfaces.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.578 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\Microsoft.CodeAnalysis.CSharp.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.663 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\Microsoft.CodeAnalysis.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.679 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\Newtonsoft.Json.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.682 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\Orleans.dll
    * Assembly filename is excluded.    
[2016-01-26 16:39:55.684 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\OrleansCodeGenerator.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.696 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\System.Collections.Immutable.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.710 GMT    11  INFO    100000  AssemblyLoader.Client   ]   User assembly ignored: c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\System.Reflection.Metadata.dll
    * Assembly contains no types assignable from Orleans.Providers.IProvider.   
[2016-01-26 16:39:55.713 GMT    11  INFO    100000  AssemblyLoader.Client   ]   Loading assembly c:\users\apuchkov\documents\visual studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\OrleansProviders.dll...    
[2016-01-26 16:39:55.724 GMT    11  INFO    100000  AssemblyLoader.Client   ]   1 assemblies loaded.    
[2016-01-26 16:39:55.797 GMT    11  INFO    100313  OutsideRuntimeClient    ]   ---------- Initializing OutsideRuntimeClient on CWI-ALEX at 192.168.43.1 Client Id = *cli/3c2ce48d ----------   
[2016-01-26 16:39:55.804 GMT    11  INFO    100314  OutsideRuntimeClient    ]   ---------- Starting OutsideRuntimeClient with runtime Version='1.1.2.0 (Release).' Config= 
 Platform version info:
   .NET version: 4.0.30319.42000
   Is .NET 4.5=True
   OS version: Microsoft Windows NT 6.2.9200.0
   GC Type=Client GCLatencyMode=Interactive
   Host: CWI-ALEX
   Processor Count: 8
Client Configuration:
   Config File Name: c:\Users\apuchkov\Documents\Visual Studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\ClientConfiguration.xml
   Start time: 2016-01-26 16:39:55.801 GMT
   Gateway Provider: Config
   Gateways[1]:
      127.0.0.1:30000
   Preferred Gateway Index: -1
   GatewayListRefreshPeriod: 00:01:00
   Azure:
      DeploymentId: apuchkov
      DataConnectionString: null
   Preferred Address Family: InterNetwork
   DNS Host Name: CWI-ALEX
   Client Name: Client
   Tracing: 
     Default Trace Level: Info
     TraceLevelOverrides: None
     Trace to Console: True
     Trace File Name: c:\Users\apuchkov\Documents\Visual Studio 2015\Projects\MemberExtractOrleans\MemberExtractOrleans\bin\Debug\Client-2016-01-26-16.39.53.073Z.log
     Write Messaging Traces: False
     LargeMessageWarningThreshold: 85000
     PropagateActivityId: False
     BulkMessageLimit: 5
   Statistics: 
     MetricsTableWriteInterval: 00:00:30
     PerfCounterWriteInterval: -00:00:00.0010000
     LogWriteInterval: 00:05:00
     WriteLogStatisticsToTable: True
     StatisticsCollectionLevel: Info
   Messaging:
       Response timeout: 00:30:00
       Maximum resend count: 0
       Resend On Timeout: False
       Maximum Socket Age: 10675199.02:48:05.4775807
       Drop Expired Messages: True
       Client Sender Buckets: 8192
       Use standard (.NET) serializer: False   [NOTE: This *MUST* match the setting on the server or nothing will work!]
       Use fallback json serializer: False   [NOTE: This *MUST* match the setting on the server or nothing will work!]
       Buffer Pool Buffer Size: 4096
       Buffer Pool Max Size: 10000
       Buffer Pool Preallocation Size: 250
       Use Message Batching: False
       Max Message Batching Size: 10
   Providers:
       No providers configured.

[2016-01-26 16:39:55.867 GMT    11  INFO    101309  Messaging.GatewayManager    ]   Found 1 knownGateways from Gateway listProvider [gwy.tcp://127.0.0.1:30000/0]   
[2016-01-26 16:39:57.077 GMT    11  INFO    100908  Messaging.GatewayConnection/GatewayClientSender_gwy.tcp://127.0.0.1:30000/0 192.168.43.1:0] Connected to gateway at address gwy.tcp://127.0.0.1:30000/0 on trial 0. 
[2016-01-26 16:39:57.082 GMT    19  INFO    100000  Messaging.GatewayConnection/GatewayClientSender_gwy.tcp://127.0.0.1:30000/0 192.168.43.1:0] Starting AsyncAgent Messaging.GatewayConnection/GatewayClientSender_gwy.tcp://127.0.0.1:30000/0 on managed thread 19    
[2016-01-26 16:39:57.082 GMT    20  INFO    100000  Messaging.GatewayClientReceiver/gwy.tcp://127.0.0.1:30000/0 192.168.43.1:0] Starting AsyncAgent Messaging.GatewayClientReceiver/gwy.tcp://127.0.0.1:30000/0 on managed thread 20    
[2016-01-26 16:39:57.205 GMT    11  INFO    100929  OutsideRuntimeClient    192.168.43.1:0] ---------- Started OutsideRuntimeClient with Global Client ID: C192.168.43.1:0:-191522395*cli/3c2ce48d@f6d3a488, client GUID ID: *cli/3c2ce48d
sergeybykov commented 8 years ago

Silo seems to be starting successfully, but then client can't invoke grain method.

What exactly happens? I don't see anything in the client log about it.

BTW, thank you for the beautiful and very informative report with all relevant information included! We should promote this as a gold standard for reporting issues.

apuchkov commented 8 years ago

Thanks @sergeybykov.

Exception stack trace is at the very end of Grains\bin\Debug\CWI-ALEX-2016-01-26-16.39.40.232Z.log:

[2016-01-26 16:39:59.705 GMT     8  WARNING 100329  InsideRuntimeClient 127.0.0.1:11111]    Exception during Invoke of message: Request S127.0.0.1:11111:191522380*cli/3c2ce48d@f6d3a488->S127.0.0.1:11111:191522380*grn/E698344A/00000001@b336088e #5: global::GrainInterfaces.IClientGrain:ProcessClient()    
Exc level 0: Orleans.Runtime.OrleansException: Cannot find an invoker for interface GrainInterfaces.IClientGrain (ID=-865503811,0xCC6975BD).
   at Orleans.Runtime.GrainTypeManager.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.ActivationData.GetInvoker(Int32 interfaceId, String genericGrainType)
   at Orleans.Runtime.InsideRuntimeClient.<Invoke>d__6.MoveNext()

It is thrown on this line of Program.cs

Console.WriteLine(c1.ProcessClient().Result);
sergeybykov commented 8 years ago

You need to add https://www.nuget.org/packages/Microsoft.Orleans.Templates.Interfaces/ to the grain interface project to enable compile time codegen.

There is a separate but related question why run time codegen didn't automatically kick in. /cc @ReubenBond

apuchkov commented 8 years ago

It helped, thanks a lot for a quick response!

sergeybykov commented 8 years ago

I see - I was looking for error in the wrong place (client log).

To clarify, the guide is correct as it mentions the steps of adding the template nugets?

Add Microsoft.Orleans.Templates.Interfaces to GrainInterfaces 2.Add Microsoft.Orleans.Templates.Grains to GrainCollection

There's still a question of why run time codegen didn't work here. I think we better keep this issue open for now, until we confirm that.

ReubenBond commented 8 years ago

There's still a question of why run time codegen didn't work here. I think we better keep this issue open for now, until we confirm that.

@sergeybykov runtime codegen didn't work because it was not included in any of the projects on the server. If Microsoft.Orleans.OrleansCodeGenerator was explicitly included or the *.Server package was used, then it would have run.

sergeybykov commented 8 years ago

Thank you, @ReubenBond. Makes sense. Microsoft.Orleans.OrleansCodeGenerator in included in the client project, but not in the server one which is the Grains project.

<package id="Microsoft.Orleans.OrleansCodeGenerator" version="1.1.2" targetFramework="net452" />

Looks like we can close this.