Stikked

Untitled

From Cute Peafowl, 8 Years ago, written in Plain Text.
URL http://geopaste.scratchbook.ch/view/bc90c230
Embed
Download Paste or View Raw
  1.  
  2. [ushansu@ip-10-23-101-26 ~]$ java -jar -Dspring.config.location=application-prod.properties snapshot-migration-with-sleep.jar
  3. 2017-10-03 08:10:25.660  INFO 4678 --- [           main] c.pearson.lst.rta.migration.Application  : Starting Application with PID 4678 (/home/ushansu/snapshot-migration-with-sleep.jar started by ushansu in /home/ushansu)
  4. 2017-10-03 08:10:25.665  INFO 4678 --- [           main] c.pearson.lst.rta.migration.Application  : No active profile set, falling back to default profiles: default
  5. 2017-10-03 08:10:26.857  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookConfig        : Syncgateway initialized with syncgatewayUrl:http://10.23.1.127:4985, name:admin , pwd:testing123$
  6. 2017-10-03 08:10:26.865  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookConfig        : Couchbase initialized with url:couchbase://prod-couchbase-276574167.us-east-1.elb.amazonaws.com, bucket:notebook, pwd:testing123$
  7. 2017-10-03 08:10:26.890  INFO 4678 --- [           main] c.c.client.core.env.CoreEnvironment      : ioPoolSize is less than 3 (2), setting to: 3
  8. 2017-10-03 08:10:26.890  INFO 4678 --- [           main] c.c.client.core.env.CoreEnvironment      : computationPoolSize is less than 3 (2), setting to: 3
  9. 2017-10-03 08:10:27.105  INFO 4678 --- [           main] com.couchbase.client.core.CouchbaseCore  : CouchbaseEnvironment: {sslEnabled=false, sslKeystoreFile='null', sslKeystorePassword=false, sslKeystore=null, bootstrapHttpEnabled=true, bootstrapCarrierEnabled=true, bootstrapHttpDirectPort=8091, bootstrapHttpSslPort=18091, bootstrapCarrierDirectPort=11210, bootstrapCarrierSslPort=11207, ioPoolSize=3, computationPoolSize=3, responseBufferSize=16384, requestBufferSize=16384, kvServiceEndpoints=1, viewServiceEndpoints=12, queryServiceEndpoints=12, searchServiceEndpoints=12, configPollInterval=2500, ioPool=NioEventLoopGroup, kvIoPool=null, viewIoPool=null, searchIoPool=null, queryIoPool=null, coreScheduler=CoreScheduler, memcachedHashingStrategy=DefaultMemcachedHashingStrategy, eventBus=DefaultEventBus, packageNameAndVersion=couchbase-java-client/2.5.0 (git: 2.5.0, core: 1.5.0), retryStrategy=BestEffort, maxRequestLifetime=75000, retryDelay=ExponentialDelay{growBy 1.0 MICROSECONDS, powers of 2; lower=100, upper=100000}, reconnectDelay=ExponentialDelay{growBy 1.0 MILLISECONDS, powers of 2; lower=32, upper=4096}, observeIntervalDelay=ExponentialDelay{growBy 1.0 MICROSECONDS, powers of 2; lower=10, upper=100000}, keepAliveInterval=30000, continuousKeepAliveEnabled=true, keepAliveErrorThreshold=4, keepAliveTimeout=2500, autoreleaseAfter=2000, bufferPoolingEnabled=true, tcpNodelayEnabled=true, mutationTokensEnabled=false, socketConnectTimeout=1000, callbacksOnIoPool=false, disconnectTimeout=25000, requestBufferWaitStrategy=com.couchbase.client.core.env.DefaultCoreEnvironment$2@9629756, certAuthEnabled=false, coreSendHook=null, queryTimeout=75000, viewTimeout=75000, searchTimeout=75000, analyticsTimeout=75000, kvTimeout=2500, connectTimeout=600000, dnsSrvEnabled=false}
  10. 2017-10-03 08:10:27.769  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Connected to Node 52.55.152.194/52.55.152.194
  11. 2017-10-03 08:10:28.143  INFO 4678 --- [-computations-3] c.c.c.core.config.ConfigurationProvider  : Opened bucket notebook
  12. 2017-10-03 08:10:28.218  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Disconnected from Node 52.55.152.194/52.55.152.194
  13. 2017-10-03 08:10:28.223  INFO 4678 --- [      cb-io-1-1] c.c.client.core.endpoint.Endpoint        : [null][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  14. 2017-10-03 08:10:28.224  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Connected to Node 10.23.1.43/ip-10-23-1-43.ec2.internal
  15. 2017-10-03 08:10:28.262  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Connected to Node 10.23.1.125/ip-10-23-1-125.ec2.internal
  16. 2017-10-03 08:10:28.266  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Connected to Node 10.23.2.168/ip-10-23-2-168.ec2.internal
  17. 2017-10-03 08:10:28.275  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Connected to Node 10.23.2.84/ip-10-23-2-84.ec2.internal
  18. 2017-10-03 08:10:28.276  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Connected to Node 10.23.1.108/ip-10-23-1-108.ec2.internal
  19. 2017-10-03 08:10:28.277  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Connected to Node 10.23.2.124/ip-10-23-2-124.ec2.internal
  20. 2017-10-03 08:10:28.539  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff58bce224e4b0d2cbe234fdaa, userAssignment:eaaf95e1729049b4b1a267e254c11923, bookId:EX1XJIGJCS0, pages:[33], snapShotId:3a60b4c957e20ac36f28f17c91122e51
  21. 2017-10-03 08:10:28.540  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff59820e427512ea2353554187, userAssignment:ed1a9f4aab8c4ae5a249d73fafae102f, bookId:EX1SFQP5TCS, pages:[87], snapShotId:d350b5095346bd38acea68916e555d19
  22. 2017-10-03 08:10:28.541  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff5876bcdbe4b0a5f9f4dee8f1, userAssignment:a6b6ebc4e409428a82d2fe69991247f3, bookId:EX1SFQP5TCS, pages:[42], snapShotId:ae19ac4b6bbe598bcc79b0ad662a3d43
  23. 2017-10-03 08:10:28.548  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff5615a756e4b019aeed755581, userAssignment:c683ee9a882c432f844967da0f34a298, bookId:EX1SFQP5TCS, pages:[297, 298, 296], snapShotId:60184df49bc8ecdd544d23edffef7ee2
  24. 2017-10-03 08:10:28.549  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff59bc464f2df7c116df12f076, userAssignment:17855552de824e7288a36bd93cacb5ea, bookId:EX1XJIGJCS0, pages:[62], snapShotId:9e55f610596d75f51a6c9724b7f0a538
  25. 2017-10-03 08:10:28.549  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff59a1a7d42274792362bfb109, userAssignment:0844b5a279cd4cab909bd21882bf830f, bookId:EX1XJIGJCS0, pages:[35], snapShotId:3b5e6400c81a5faee31002d6e85b3955
  26. 2017-10-03 08:10:28.549  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff59a1a3bc1c677723d2f620ea, userAssignment:7523a90dea1f4449a64d8df36108b83f, bookId:EX1XJIGJCS0, pages:[33, 34, 35], snapShotId:2ad38537d890a17ee93fa6211e8ce598
  27. 2017-10-03 08:10:28.549  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff58bce221e4b036c4c9dcefc3, userAssignment:1854b0a8c0404ca9b7fc0ec690676f76, bookId:EX1XJIGJCS0, pages:[33], snapShotId:fe3028ba1eed8a4de7f4ef3a56a630fc
  28. 2017-10-03 08:10:28.550  INFO 4678 --- [           main] c.p.l.n.migration.services.CsvServiceV2  : UserId:ffffffff57b9fddde4b05a9002c299b8, userAssignment:9e03af02252f48128d24534416536220, bookId:EX1XJIGJCS0, pages:[36, 37, 38, 39, 40, 41], snapShotId:c4dce45028463b94d1617eba48df0d49
  29. 2017-10-03 08:10:28.596  WARN 4678 --- [           main] o.h.v.m.ParameterMessageInterpolator     : HV000184: ParameterMessageInterpolator has been chosen, EL interpolation will not be supported
  30. 2017-10-03 08:10:28.727  WARN 4678 --- [           main] o.h.v.m.ParameterMessageInterpolator     : HV000184: ParameterMessageInterpolator has been chosen, EL interpolation will not be supported
  31. 2017-10-03 08:10:29.147  INFO 4678 --- [           main] c.pearson.lst.rta.migration.Application  : Started Application in 4.024 seconds (JVM running for 4.472)
  32. 2017-10-03 08:10:29.148  INFO 4678 --- [           main] c.pearson.lst.rta.migration.Application  : ******************************************* SnapShot Script started *******************************************
  33. 2017-10-03 08:10:29.151  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 1/9, userAssignmentId:eaaf95e1729049b4b1a267e254c11923 #########
  34. 2017-10-03 08:10:29.151  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  35. 2017-10-03 08:10:29.253  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  36. 2017-10-03 08:10:29.603  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[33]
  37. 2017-10-03 08:10:29.615  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"3a60b4c957e20ac36f28f17c91122e51","userId":"ffffffff58bce224e4b0d2cbe234fdaa"}}
  38. 2017-10-03 08:10:29.661  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Connected to Node 10.23.1.188/ip-10-23-1-188.ec2.internal
  39. 2017-10-03 08:10:29.724  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  40. 2017-10-03 08:10:29.725  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='23.925968ms', executionTime='23.828961ms'}
  41. 2017-10-03 08:10:29.757  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  42. 2017-10-03 08:10:29.763  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff58bce224e4b0d2cbe234fdaa, pageContexts:[OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449]
  43. 2017-10-03 08:10:29.765  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449"],"userId":"ffffffff58bce224e4b0d2cbe234fdaa","bookId":"EX1XJIGJCS0"}}
  44. 2017-10-03 08:10:29.775  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Connected to Node 10.23.1.8/ip-10-23-1-8.ec2.internal
  45. 2017-10-03 08:10:29.799  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  46. 2017-10-03 08:10:29.800  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='20.371891ms', executionTime='20.303971ms'}
  47. 2017-10-03 08:10:29.800  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  48. 2017-10-03 08:10:29.800  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  49. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  50. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  51. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  52. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ eaaf95e1729049b4b1a267e254c11923 - 1 #################
  53.  
  54.  
  55. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 2/9, userAssignmentId:ed1a9f4aab8c4ae5a249d73fafae102f #########
  56. 2017-10-03 08:10:29.801  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  57. 2017-10-03 08:10:29.902  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/df803874-bfa2-4849-bb5f-c9189c891b0b/1/file/9780328905515_L1_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  58. 2017-10-03 08:10:29.940  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[87]
  59. 2017-10-03 08:10:29.940  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"d350b5095346bd38acea68916e555d19","userId":"ffffffff59820e427512ea2353554187"}}
  60. 2017-10-03 08:10:29.944  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Connected to Node 10.23.2.230/ip-10-23-2-230.ec2.internal
  61. 2017-10-03 08:10:30.009  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  62. 2017-10-03 08:10:30.010  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='59.793523ms', executionTime='59.691134ms'}
  63. 2017-10-03 08:10:30.010  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  64. 2017-10-03 08:10:30.011  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1SFQP5TCS, userId:ffffffff59820e427512ea2353554187, pageContexts:[OPS/xhtml/capitulo_2a_04_video_history.xhtml#P700101217900000000000000000F16C]
  65. 2017-10-03 08:10:30.011  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_2a_04_video_history.xhtml#P700101217900000000000000000F16C"],"userId":"ffffffff59820e427512ea2353554187","bookId":"EX1SFQP5TCS"}}
  66. 2017-10-03 08:10:30.013  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Connected to Node 10.23.2.231/ip-10-23-2-231.ec2.internal
  67. 2017-10-03 08:10:30.035  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  68. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='18.520949ms', executionTime='18.454611ms'}
  69. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  70. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  71. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  72. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  73. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  74. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ ed1a9f4aab8c4ae5a249d73fafae102f - 1 #################
  75.  
  76.  
  77. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 3/9, userAssignmentId:a6b6ebc4e409428a82d2fe69991247f3 #########
  78. 2017-10-03 08:10:30.036  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  79. 2017-10-03 08:10:30.137  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/df803874-bfa2-4849-bb5f-c9189c891b0b/1/file/9780328905515_L1_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  80. 2017-10-03 08:10:30.208  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[42]
  81. 2017-10-03 08:10:30.208  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"ae19ac4b6bbe598bcc79b0ad662a3d43","userId":"ffffffff5876bcdbe4b0a5f9f4dee8f1"}}
  82. 2017-10-03 08:10:30.235  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  83. 2017-10-03 08:10:30.235  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='22.289553ms', executionTime='22.218986ms'}
  84. 2017-10-03 08:10:30.236  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  85. 2017-10-03 08:10:30.236  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1SFQP5TCS, userId:ffffffff5876bcdbe4b0a5f9f4dee8f1, pageContexts:[OPS/xhtml/capitulo_1a_05b_cultura_el_mundo_hispano.xhtml#P700101217900000000000000000DB19]
  86. 2017-10-03 08:10:30.236  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_05b_cultura_el_mundo_hispano.xhtml#P700101217900000000000000000DB19"],"userId":"ffffffff5876bcdbe4b0a5f9f4dee8f1","bookId":"EX1SFQP5TCS"}}
  87. 2017-10-03 08:10:30.261  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  88. 2017-10-03 08:10:30.262  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=2614, elapsedTime='20.811093ms', executionTime='20.754607ms'}
  89. 2017-10-03 08:10:30.264  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 1
  90. 2017-10-03 08:10:30.264  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 1
  91. 2017-10-03 08:10:30.264  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : FOUND DIFF in ids...
  92. 2017-10-03 08:10:30.264  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing  ids...[EMPTY_SNAP_SHOT_MARKER]
  93. 2017-10-03 08:10:30.264  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : EXPECTED  ids...[5cb483437155418917fded8057a4b442]
  94. 2017-10-03 08:10:30.265  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : DELETE Note:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:EMPTY_SNAP_SHOT_MARKER, docUrl:/snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:EMPTY_SNAP_SHOT_MARKER?rev=1-140ed21141232a2ab625d5b7e1676712
  95. 2017-10-03 08:10:30.345  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : DELETE Response, Note:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:EMPTY_SNAP_SHOT_MARKER, {id=snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:EMPTY_SNAP_SHOT_MARKER, ok=true, rev=2-9f8976e460630f3c298d3a83c8db11b3}
  96. 2017-10-03 08:10:30.345  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Id:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:EMPTY_SNAP_SHOT_MARKER => OK
  97. 2017-10-03 08:10:30.347  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : PUT Note:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:5cb483437155418917fded8057a4b442, docUrl:/snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:5cb483437155418917fded8057a4b442
  98. 2017-10-03 08:10:30.351  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : PUT Response, Note:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:5cb483437155418917fded8057a4b442, {id=snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:5cb483437155418917fded8057a4b442, ok=true, rev=1-abaa1190c383bde4d849d31d1279af69}
  99. 2017-10-03 08:10:30.351  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : update snapshot note:snap_shot_ae19ac4b6bbe598bcc79b0ad662a3d43:5cb483437155418917fded8057a4b442 -> rev:1-abaa1190c383bde4d849d31d1279af69
  100. 2017-10-03 08:10:30.351  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"ae19ac4b6bbe598bcc79b0ad662a3d43","userId":"ffffffff5876bcdbe4b0a5f9f4dee8f1"}}
  101. 2017-10-03 08:10:30.412  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  102. 2017-10-03 08:10:30.412  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1923, elapsedTime='55.33402ms', executionTime='55.229123ms'}
  103. 2017-10-03 08:10:30.413  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Created 1 --- 1 Fetched
  104. 2017-10-03 08:10:30.413  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ a6b6ebc4e409428a82d2fe69991247f3 - 1 #################
  105.  
  106.  
  107. 2017-10-03 08:10:30.413  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 4/9, userAssignmentId:c683ee9a882c432f844967da0f34a298 #########
  108. 2017-10-03 08:10:30.413  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  109. 2017-10-03 08:10:30.514  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/df803874-bfa2-4849-bb5f-c9189c891b0b/1/file/9780328905515_L1_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  110. 2017-10-03 08:10:30.552  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[296, 297, 298]
  111. 2017-10-03 08:10:30.552  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"60184df49bc8ecdd544d23edffef7ee2","userId":"ffffffff5615a756e4b019aeed755581"}}
  112. 2017-10-03 08:10:30.577  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  113. 2017-10-03 08:10:30.577  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='20.288591ms', executionTime='20.206815ms'}
  114. 2017-10-03 08:10:30.578  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  115. 2017-10-03 08:10:30.578  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1SFQP5TCS, userId:ffffffff5615a756e4b019aeed755581, pageContexts:[OPS/xhtml/capitulo_6a_03_vocabulary_in_context.xhtml#P7001012179000000000000000015662, OPS/xhtml/capitulo_6a_03a_vocabulary_in_context.xhtml#P7001012179000000000000000015714, OPS/xhtml/capitulo_6a_04_video_history.xhtml#P700101217900000000000000001577C]
  116. 2017-10-03 08:10:30.579  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_6a_03_vocabulary_in_context.xhtml#P7001012179000000000000000015662","OPS/xhtml/capitulo_6a_03a_vocabulary_in_context.xhtml#P7001012179000000000000000015714","OPS/xhtml/capitulo_6a_04_video_history.xhtml#P700101217900000000000000001577C"],"userId":"ffffffff5615a756e4b019aeed755581","bookId":"EX1SFQP5TCS"}}
  117. 2017-10-03 08:10:30.639  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  118. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='57.479736ms', executionTime='57.417517ms'}
  119. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  120. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  121. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  122. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  123. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  124. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ c683ee9a882c432f844967da0f34a298 - 1 #################
  125.  
  126.  
  127. 2017-10-03 08:10:30.640  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 5/9, userAssignmentId:17855552de824e7288a36bd93cacb5ea #########
  128. 2017-10-03 08:10:30.641  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  129. 2017-10-03 08:10:30.741  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  130. 2017-10-03 08:10:30.796  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[62]
  131. 2017-10-03 08:10:30.796  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"9e55f610596d75f51a6c9724b7f0a538","userId":"ffffffff59bc464f2df7c116df12f076"}}
  132. 2017-10-03 08:10:30.821  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  133. 2017-10-03 08:10:30.821  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='21.236788ms', executionTime='21.156977ms'}
  134. 2017-10-03 08:10:30.822  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  135. 2017-10-03 08:10:30.822  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff59bc464f2df7c116df12f076, pageContexts:[OPS/xhtml/capitulo_1b_02_art_and_culture.xhtml#P70010121970000000000000000043D7]
  136. 2017-10-03 08:10:30.822  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1b_02_art_and_culture.xhtml#P70010121970000000000000000043D7"],"userId":"ffffffff59bc464f2df7c116df12f076","bookId":"EX1XJIGJCS0"}}
  137. 2017-10-03 08:10:30.845  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  138. 2017-10-03 08:10:30.845  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='19.41983ms', executionTime='19.345385ms'}
  139. 2017-10-03 08:10:30.845  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  140. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  141. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  142. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  143. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  144. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ 17855552de824e7288a36bd93cacb5ea - 1 #################
  145.  
  146.  
  147. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 6/9, userAssignmentId:0844b5a279cd4cab909bd21882bf830f #########
  148. 2017-10-03 08:10:30.846  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  149. 2017-10-03 08:10:30.947  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  150. 2017-10-03 08:10:30.990  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[35]
  151. 2017-10-03 08:10:30.990  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"3b5e6400c81a5faee31002d6e85b3955","userId":"ffffffff59a1a7d42274792362bfb109"}}
  152. 2017-10-03 08:10:31.036  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  153. 2017-10-03 08:10:31.036  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='41.640028ms', executionTime='41.544773ms'}
  154. 2017-10-03 08:10:31.036  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  155. 2017-10-03 08:10:31.037  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff59a1a7d42274792362bfb109, pageContexts:[OPS/xhtml/capitulo_1a_04_video_history.xhtml#P700101219700000000000000000358E]
  156. 2017-10-03 08:10:31.037  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_04_video_history.xhtml#P700101219700000000000000000358E"],"userId":"ffffffff59a1a7d42274792362bfb109","bookId":"EX1XJIGJCS0"}}
  157. 2017-10-03 08:10:31.058  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  158. 2017-10-03 08:10:31.058  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='18.369687ms', executionTime='18.318087ms'}
  159. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  160. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  161. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  162. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  163. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  164. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ 0844b5a279cd4cab909bd21882bf830f - 1 #################
  165.  
  166.  
  167. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 7/9, userAssignmentId:7523a90dea1f4449a64d8df36108b83f #########
  168. 2017-10-03 08:10:31.059  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  169. 2017-10-03 08:10:31.159  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  170. 2017-10-03 08:10:31.211  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[33, 34, 35]
  171. 2017-10-03 08:10:31.211  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"2ad38537d890a17ee93fa6211e8ce598","userId":"ffffffff59a1a3bc1c677723d2f620ea"}}
  172. 2017-10-03 08:10:31.234  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  173. 2017-10-03 08:10:31.235  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='19.710026ms', executionTime='19.631278ms'}
  174. 2017-10-03 08:10:31.235  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  175. 2017-10-03 08:10:31.235  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff59a1a3bc1c677723d2f620ea, pageContexts:[OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449, OPS/xhtml/capitulo_1a_03a_vocabulary_in_context.xhtml#P700101219700000000000000000351F, OPS/xhtml/capitulo_1a_04_video_history.xhtml#P700101219700000000000000000358E]
  176. 2017-10-03 08:10:31.235  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449","OPS/xhtml/capitulo_1a_03a_vocabulary_in_context.xhtml#P700101219700000000000000000351F","OPS/xhtml/capitulo_1a_04_video_history.xhtml#P700101219700000000000000000358E"],"userId":"ffffffff59a1a3bc1c677723d2f620ea","bookId":"EX1XJIGJCS0"}}
  177. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  178. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='21.749519ms', executionTime='21.67204ms'}
  179. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  180. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  181. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  182. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  183. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  184. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ 7523a90dea1f4449a64d8df36108b83f - 1 #################
  185.  
  186.  
  187. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 8/9, userAssignmentId:1854b0a8c0404ca9b7fc0ec690676f76 #########
  188. 2017-10-03 08:10:31.261  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  189. 2017-10-03 08:10:31.362  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  190. 2017-10-03 08:10:31.401  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[33]
  191. 2017-10-03 08:10:31.402  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"fe3028ba1eed8a4de7f4ef3a56a630fc","userId":"ffffffff58bce221e4b036c4c9dcefc3"}}
  192. 2017-10-03 08:10:31.428  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  193. 2017-10-03 08:10:31.428  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='22.747699ms', executionTime='22.666116ms'}
  194. 2017-10-03 08:10:31.429  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  195. 2017-10-03 08:10:31.429  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff58bce221e4b036c4c9dcefc3, pageContexts:[OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449]
  196. 2017-10-03 08:10:31.429  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_03_vocabulary_in_context.xhtml#P7001012197000000000000000003449"],"userId":"ffffffff58bce221e4b036c4c9dcefc3","bookId":"EX1XJIGJCS0"}}
  197. 2017-10-03 08:10:31.454  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  198. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='21.880091ms', executionTime='21.828366ms'}
  199. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  200. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  201. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  202. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  203. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  204. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ 1854b0a8c0404ca9b7fc0ec690676f76 - 1 #################
  205.  
  206.  
  207. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ######## Process item 9/9, userAssignmentId:9e03af02252f48128d24534416536220 #########
  208. 2017-10-03 08:10:31.455  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Sleep for 100 ms
  209. 2017-10-03 08:10:31.556  INFO 4678 --- [           main] c.p.l.r.migration.services.CCCIService   : Fetch TOC for url:https://content.openclass.com/eps/pearson-reader/api/item/4f545a99-4201-422f-b3b4-16b3c8d59f64/1/file/9780328905522_L2_Live_04032017_SE_NA/OPS/xhtml/toc.xhtml
  210. 2017-10-03 08:10:31.610  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Create snapShot for pages:[36, 37, 38, 39, 40, 41]
  211. 2017-10-03 08:10:31.611  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'snap_shot_note' and snapShotContext = $snapShotContext and userId = $userId and meta().id NOT LIKE '_sync%', params={"snapShotContext":"c4dce45028463b94d1617eba48df0d49","userId":"ffffffff57b9fddde4b05a9002c299b8"}}
  212. 2017-10-03 08:10:31.662  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  213. 2017-10-03 08:10:31.662  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=1, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=1059, elapsedTime='47.590881ms', executionTime='47.530337ms'}
  214. 2017-10-03 08:10:31.663  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Existing SnapShot count: 1
  215. 2017-10-03 08:10:31.663  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for bookId:EX1XJIGJCS0, userId:ffffffff57b9fddde4b05a9002c299b8, pageContexts:[OPS/xhtml/capitulo_1a_05_vocabulary_in_use.xhtml#P700101219700000000000000000360F, OPS/xhtml/capitulo_1a_05a_vocabulary_in_use.xhtml#P7001012197000000000000000003699, OPS/xhtml/capitulo_1a_05b_vocabulary_in_use.xhtml#P7001012197000000000000000003727, OPS/xhtml/capitulo_1a_05c_vocabulary_in_use.xhtml#P70010121970000000000000000037CA, OPS/xhtml/capitulo_1a_05d_culture.xhtml#P700101219700000000000000000383B, OPS/xhtml/capitulo_1a_05e_vocabulary_in_use.xhtml#P7001012197000000000000000003869]
  216. 2017-10-03 08:10:31.663  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Notes Query: ParameterizedN1qlQuery{statement=select meta().id, *, _sync.rev from notebook where type = 'note' and pageContext in $pageContexts and bookContext = $bookId and userId = $userId and meta().id NOT LIKE '_sync%', params={"pageContexts":["OPS/xhtml/capitulo_1a_05_vocabulary_in_use.xhtml#P700101219700000000000000000360F","OPS/xhtml/capitulo_1a_05a_vocabulary_in_use.xhtml#P7001012197000000000000000003699","OPS/xhtml/capitulo_1a_05b_vocabulary_in_use.xhtml#P7001012197000000000000000003727","OPS/xhtml/capitulo_1a_05c_vocabulary_in_use.xhtml#P70010121970000000000000000037CA","OPS/xhtml/capitulo_1a_05d_culture.xhtml#P700101219700000000000000000383B","OPS/xhtml/capitulo_1a_05e_vocabulary_in_use.xhtml#P7001012197000000000000000003869"],"userId":"ffffffff57b9fddde4b05a9002c299b8","bookId":"EX1XJIGJCS0"}}
  217. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Query Status: success
  218. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.CouchbaseService      : Metrics: N1qlMetrics{resultCount=0, errorCount=0, warningCount=0, mutationCount=0, sortCount=0, resultSize=0, elapsedTime='59.403277ms', executionTime='59.333059ms'}
  219. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : fetchNotes for pages count: 0
  220. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Expected SnapShot count: 0
  221. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Actual is 0 and available is 1, checking for EMPTY_MARKER...
  222. 2017-10-03 08:10:31.726  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : Since the existing is also EMPTY_MARKER, Skipped....
  223. 2017-10-03 08:10:31.727  INFO 4678 --- [           main] c.p.l.n.m.services.NotebookService       : SKIPPED NO changes in expected vs actual, [], [EMPTY_SNAP_SHOT_MARKER]
  224. 2017-10-03 08:10:31.727  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : ################ 9e03af02252f48128d24534416536220 - 1 #################
  225.  
  226.  
  227. 2017-10-03 08:10:31.727  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : Stats: created:1, deleted:1, delta:0
  228. 2017-10-03 08:10:31.727  INFO 4678 --- [           main] c.p.l.n.m.s.RecreateSnapShotService      : SKIPPED USER_ASSIGNMENTS due to CCI FAILURE: []
  229. 2017-10-03 08:10:31.727  INFO 4678 --- [           main] c.pearson.lst.rta.migration.Application  : ******************************************* SnapShot Completed *******************************************
  230. 2017-10-03 08:10:31.734  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.1.108/ip-10-23-1-108.ec2.internal
  231. 2017-10-03 08:10:31.734  INFO 4678 --- [      cb-io-1-3] c.c.client.core.endpoint.Endpoint        : [ip-10-23-1-125.ec2.internal/10.23.1.125:11210][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  232. 2017-10-03 08:10:31.735  INFO 4678 --- [      cb-io-1-2] c.c.client.core.endpoint.Endpoint        : [null][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  233. 2017-10-03 08:10:31.737  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.1.125/ip-10-23-1-125.ec2.internal
  234. 2017-10-03 08:10:31.737  INFO 4678 --- [      cb-io-1-1] c.c.client.core.endpoint.Endpoint        : [ip-10-23-1-43.ec2.internal/10.23.1.43:11210][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  235. 2017-10-03 08:10:31.737  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.1.43/ip-10-23-1-43.ec2.internal
  236. 2017-10-03 08:10:31.738  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.2.124/ip-10-23-2-124.ec2.internal
  237. 2017-10-03 08:10:31.739  INFO 4678 --- [      cb-io-1-2] c.c.client.core.endpoint.Endpoint        : [null][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  238. 2017-10-03 08:10:31.739  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.2.168/ip-10-23-2-168.ec2.internal
  239. 2017-10-03 08:10:31.739  INFO 4678 --- [      cb-io-1-3] c.c.client.core.endpoint.Endpoint        : [null][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  240. 2017-10-03 08:10:31.740  INFO 4678 --- [      cb-io-1-1] c.c.client.core.endpoint.Endpoint        : [ip-10-23-2-84.ec2.internal/10.23.2.84:11210][KeyValueEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  241. 2017-10-03 08:10:31.740  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.2.84/ip-10-23-2-84.ec2.internal
  242. 2017-10-03 08:10:31.741  INFO 4678 --- [      cb-io-1-2] c.c.client.core.endpoint.Endpoint        : [ip-10-23-1-188.ec2.internal/10.23.1.188:8093][QueryEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  243. 2017-10-03 08:10:31.742  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.1.188/ip-10-23-1-188.ec2.internal
  244. 2017-10-03 08:10:31.743  INFO 4678 --- [      cb-io-1-3] c.c.client.core.endpoint.Endpoint        : [ip-10-23-1-8.ec2.internal/10.23.1.8:8093][QueryEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  245. 2017-10-03 08:10:31.747  INFO 4678 --- [      cb-io-1-3] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.1.8/ip-10-23-1-8.ec2.internal
  246. 2017-10-03 08:10:31.748  INFO 4678 --- [      cb-io-1-1] c.c.client.core.endpoint.Endpoint        : [ip-10-23-2-230.ec2.internal/10.23.2.230:8093][QueryEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  247. 2017-10-03 08:10:31.749  INFO 4678 --- [      cb-io-1-1] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.2.230/ip-10-23-2-230.ec2.internal
  248. 2017-10-03 08:10:31.749  INFO 4678 --- [      cb-io-1-2] c.c.client.core.endpoint.Endpoint        : [ip-10-23-2-231.ec2.internal/10.23.2.231:8093][QueryEndpoint]: Got notified from Channel as inactive, attempting reconnect.
  249. 2017-10-03 08:10:31.750  INFO 4678 --- [      cb-io-1-2] com.couchbase.client.core.node.Node      : Disconnected from Node 10.23.2.231/ip-10-23-2-231.ec2.internal
  250. 2017-10-03 08:10:31.750  INFO 4678 --- [       Thread-6] c.c.c.core.config.ConfigurationProvider  : Closed bucket notebook
  251. 2017-10-03 08:10:31.751  INFO 4678 --- [       Thread-6] c.c.c.core.config.ConfigurationProvider  : Closed bucket notebook

Reply to "Untitled"