Skip to content

Instantly share code, notes, and snippets.

@bbrowning
Created April 13, 2018 00:46
Show Gist options
  • Save bbrowning/727158c6c668d9e81d57543fe67f5ff0 to your computer and use it in GitHub Desktop.
Save bbrowning/727158c6c668d9e81d57543fe67f5ff0 to your computer and use it in GitHub Desktop.
whisk.core.controller.test.ActionsApiTests > Actions API should update an existing action with attachment that is not cached FAILED
org.scalatest.exceptions.TestFailedException: "[2018-04-12T23:01:16.729Z] [INFO] [#sid_1] [CouchDbRestStore] [DEL] 'whisk_local_whisks' deleting document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name37, rev: 2-8d930037a7ed3763bde537cc0499a0f8' [marker:database_deleteDocument_start:227311]
[2018-04-12T23:01:16.743Z] [INFO] [#sid_1] [CouchDbRestStore] [marker:database_deleteDocument_finish:227325:14]
[2018-04-12T23:01:16.745Z] [INFO] [#tid_43] [CouchDbRestStore] [PUT] 'whisk_local_whisks' saving document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38, rev: null' [marker:database_saveDocument_start:1]
[2018-04-12T23:01:16.759Z] [INFO] [#tid_43] [CouchDbRestStore] [marker:database_saveDocument_finish:15:14]
[2018-04-12T23:01:16.766Z] [INFO] [#tid_43] [CouchDbRestStore] [ATT_PUT] 'whisk_local_whisks' uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38, rev: 1-b454fd27b342c939a659cbc38a62b85f' [marker:database_saveDocumentAttachment_start:22]
[2018-04-12T23:01:16.790Z] [INFO] [#tid_43] [CouchDbRestStore] [marker:database_saveDocumentAttachment_finish:46:24]
[2018-04-12T23:01:16.793Z] [DEBUG] [#tid_44] [LocalEntitlementProvider] checking user 'anon-J0erYG2IGDYzVH8V5FOkFxWcP92' has privilege 'PUT' for 'actions/anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38'
[2018-04-12T23:01:16.795Z] [DEBUG] [#tid_44] [LocalEntitlementProvider] authorized
[2018-04-12T23:01:16.797Z] [DEBUG] [#tid_44] [WhiskAction] read initiated
[2018-04-12T23:01:16.797Z] [INFO] [#tid_44] [WhiskAction] [GET] serving from datastore: CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38) [marker:database_cacheMiss_count:6]
[2018-04-12T23:01:16.797Z] [INFO] [#tid_44] [CouchDbRestStore] [GET] 'whisk_local_whisks' finding document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38' [marker:database_getDocument_start:6]
[2018-04-12T23:01:16.812Z] [INFO] [#tid_44] [CouchDbRestStore] [marker:database_getDocument_finish:21:15]
[2018-04-12T23:01:16.813Z] [DEBUG] [#tid_44] [WhiskAction] read backend part done, now marking cache entry as done
[2018-04-12T23:01:16.813Z] [DEBUG] [#tid_44] [Entry] read finished
[2018-04-12T23:01:16.813Z] [INFO] [#tid_44] [CouchDbRestStore] [ATT_GET] 'whisk_local_whisks' finding attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38, rev: 2-7e3d1e279982c351f9c1c2ceaf70e4e0' [marker:database_getDocumentAttachment_start:22]
[2018-04-12T23:01:16.821Z] [INFO] [#tid_44] [CouchDbRestStore] [marker:database_getDocumentAttachment_finish:30:8]
[2018-04-12T23:01:16.821Z] [INFO] [#tid_44] [WhiskAction] write initiated on existing cache entry, invalidating CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38), tid 44, state WriteInProgress
[2018-04-12T23:01:16.821Z] [DEBUG] [#tid_44] [ActionsApiTests] [PUT] entity exists, will try to update 'WhiskAction/anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38@0.0.1'
[2018-04-12T23:01:16.821Z] [DEBUG] [#tid_44] [WhiskAction] write backend part done, now marking cache entry as done
[2018-04-12T23:01:16.821Z] [DEBUG] [#tid_44] [Entry] write finished
[2018-04-12T23:01:16.821Z] [INFO] [#tid_44] [WhiskAction] write all done, caching CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38) Cached
[2018-04-12T23:01:16.821Z] [DEBUG] [#tid_44] [ActionsApiTests] [PUT] entity created/updated, writing back to datastore
[2018-04-12T23:01:16.822Z] [INFO] [#tid_44] [WhiskAction] write initiated on existing cache entry, invalidating CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38), tid 44, state WriteInProgress
[2018-04-12T23:01:16.822Z] [INFO] [#tid_44] [CouchDbRestStore] [PUT] 'whisk_local_whisks' saving document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38, rev: 2-7e3d1e279982c351f9c1c2ceaf70e4e0' [marker:database_saveDocument_start:31]
[2018-04-12T23:01:16.837Z] [INFO] [#tid_44] [CouchDbRestStore] [marker:database_saveDocument_finish:46:15]
[2018-04-12T23:01:16.837Z] [DEBUG] [#tid_44] [WhiskAction] write backend part done, now marking cache entry as done
[2018-04-12T23:01:16.837Z] [DEBUG] [#tid_44] [Entry] write finished
[2018-04-12T23:01:16.837Z] [INFO] [#tid_44] [WhiskAction] write all done, caching CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38) Cached
[2018-04-12T23:01:16.839Z] [INFO] [#tid_44] [WhiskAction] write initiated on existing cache entry, invalidating CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38), tid 44, state WriteInProgress
[2018-04-12T23:01:16.839Z] [INFO] [#tid_44] [CouchDbRestStore] [ATT_PUT] 'whisk_local_whisks' uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38, rev: 3-0e5a7f40fab1040c37b5aeca8ced04bf' [marker:database_saveDocumentAttachment_start:48]
[2018-04-12T23:01:16.858Z] [INFO] [#tid_44] [CouchDbRestStore] [marker:database_saveDocumentAttachment_finish:67:19]
[2018-04-12T23:01:16.859Z] [DEBUG] [#tid_44] [WhiskAction] write backend part done, now marking cache entry as done
[2018-04-12T23:01:16.859Z] [DEBUG] [#tid_44] [Entry] write finished
[2018-04-12T23:01:16.859Z] [INFO] [#tid_44] [WhiskAction] write all done, caching CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38) Cached
[2018-04-12T23:01:16.859Z] [DEBUG] [#tid_44] [ActionsApiTests] [PUT] entity success
" did not include substring that matched regex caching CacheKey\(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38\)(?s).*uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38(?s).*completed uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38(?s).*caching CacheKey\(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name38\)
at org.scalatest.MatchersHelper$.indicateFailure(MatchersHelper.scala:340)
at org.scalatest.Matchers$ResultOfIncludeWordForString.regex(Matchers.scala:2188)
at org.scalatest.Matchers$ResultOfIncludeWordForString.regex(Matchers.scala:2161)
at whisk.core.controller.test.ActionsApiTests$$anonfun$7.apply$mcV$sp(ActionsApiTests.scala:924)
at whisk.core.controller.test.ActionsApiTests$$anonfun$7.apply(ActionsApiTests.scala:877)
at whisk.core.controller.test.ActionsApiTests$$anonfun$7.apply(ActionsApiTests.scala:877)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FlatSpecLike$$anon$1.apply(FlatSpecLike.scala:1682)
at org.scalatest.TestSuite$class.withFixture(TestSuite.scala:196)
at org.scalatest.FlatSpec.withFixture(FlatSpec.scala:1685)
at org.scalatest.FlatSpecLike$class.invokeWithFixture$1(FlatSpecLike.scala:1679)
at org.scalatest.FlatSpecLike$$anonfun$runTest$1.apply(FlatSpecLike.scala:1692)
at org.scalatest.FlatSpecLike$$anonfun$runTest$1.apply(FlatSpecLike.scala:1692)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:289)
at org.scalatest.FlatSpecLike$class.runTest(FlatSpecLike.scala:1692)
at whisk.core.controller.test.ActionsApiTests.org$scalatest$BeforeAndAfterEach$$super$runTest(ActionsApiTests.scala:60)
at org.scalatest.BeforeAndAfterEach$class.runTest(BeforeAndAfterEach.scala:221)
at whisk.core.controller.test.ActionsApiTests.runTest(ActionsApiTests.scala:60)
at org.scalatest.FlatSpecLike$$anonfun$runTests$1.apply(FlatSpecLike.scala:1750)
at org.scalatest.FlatSpecLike$$anonfun$runTests$1.apply(FlatSpecLike.scala:1750)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:396)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:384)
at scala.collection.immutable.List.foreach(List.scala:392)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:384)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:373)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:410)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:384)
at scala.collection.immutable.List.foreach(List.scala:392)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:384)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:379)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:461)
at org.scalatest.FlatSpecLike$class.runTests(FlatSpecLike.scala:1750)
at org.scalatest.FlatSpec.runTests(FlatSpec.scala:1685)
at org.scalatest.Suite$class.run(Suite.scala:1147)
at org.scalatest.FlatSpec.org$scalatest$FlatSpecLike$$super$run(FlatSpec.scala:1685)
at org.scalatest.FlatSpecLike$$anonfun$run$1.apply(FlatSpecLike.scala:1795)
at org.scalatest.FlatSpecLike$$anonfun$run$1.apply(FlatSpecLike.scala:1795)
at org.scalatest.SuperEngine.runImpl(Engine.scala:521)
at org.scalatest.FlatSpecLike$class.run(FlatSpecLike.scala:1795)
at whisk.core.controller.test.ActionsApiTests.org$scalatest$BeforeAndAfterAll$$super$run(ActionsApiTests.scala:60)
at org.scalatest.BeforeAndAfterAll$class.liftedTree1$1(BeforeAndAfterAll.scala:213)
at org.scalatest.BeforeAndAfterAll$class.run(BeforeAndAfterAll.scala:210)
at whisk.core.controller.test.ActionsApiTests.run(ActionsApiTests.scala:60)
whisk.core.controller.test.ActionsApiTests > Actions API should put and then get an action with attachment from cache FAILED
org.scalatest.exceptions.TestFailedException: "[2018-04-12T23:01:16.420Z] [DEBUG] [#tid_40] [LocalEntitlementProvider] checking user 'anon-J0erYG2IGDYzVH8V5FOkFxWcP92' has privilege 'PUT' for 'actions/anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36'
[2018-04-12T23:01:16.421Z] [DEBUG] [#tid_40] [LocalEntitlementProvider] authorized
[2018-04-12T23:01:16.422Z] [DEBUG] [#tid_40] [WhiskAction] read initiated
[2018-04-12T23:01:16.422Z] [INFO] [#tid_40] [WhiskAction] [GET] serving from datastore: CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36) [marker:database_cacheMiss_count:3]
[2018-04-12T23:01:16.422Z] [INFO] [#tid_40] [CouchDbRestStore] [GET] 'whisk_local_whisks' finding document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36' [marker:database_getDocument_start:3]
[2018-04-12T23:01:16.428Z] [INFO] [#tid_40] [CouchDbRestStore] [marker:database_getDocument_finish:9:6]
[2018-04-12T23:01:16.428Z] [INFO] [#tid_40] [WhiskAction] invalidating CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36)
[2018-04-12T23:01:16.428Z] [DEBUG] [#tid_40] [ActionsApiTests] [PUT] entity does not exist, will try to create it
[2018-04-12T23:01:16.428Z] [DEBUG] [#tid_40] [ActionsApiTests] [PUT] entity created/updated, writing back to datastore
[2018-04-12T23:01:16.428Z] [INFO] [#tid_40] [WhiskAction] write initiated on new cache entry
[2018-04-12T23:01:16.428Z] [INFO] [#tid_40] [CouchDbRestStore] [PUT] 'whisk_local_whisks' saving document: 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36, rev: null' [marker:database_saveDocument_start:9]
[2018-04-12T23:01:16.443Z] [INFO] [#tid_40] [CouchDbRestStore] [marker:database_saveDocument_finish:24:15]
[2018-04-12T23:01:16.443Z] [DEBUG] [#tid_40] [WhiskAction] write backend part done, now marking cache entry as done
[2018-04-12T23:01:16.443Z] [DEBUG] [#tid_40] [Entry] write finished
[2018-04-12T23:01:16.443Z] [INFO] [#tid_40] [WhiskAction] write all done, caching CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36) Cached
[2018-04-12T23:01:16.443Z] [INFO] [#tid_40] [WhiskAction] write initiated on existing cache entry, invalidating CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36), tid 40, state WriteInProgress
[2018-04-12T23:01:16.444Z] [INFO] [#tid_40] [CouchDbRestStore] [ATT_PUT] 'whisk_local_whisks' uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36, rev: 1-f0ecd15ec5f0c4517291eba4eebec06f' [marker:database_saveDocumentAttachment_start:25]
[2018-04-12T23:01:16.472Z] [INFO] [#tid_40] [CouchDbRestStore] [marker:database_saveDocumentAttachment_finish:53:28]
[2018-04-12T23:01:16.472Z] [DEBUG] [#tid_40] [WhiskAction] write backend part done, now marking cache entry as done
[2018-04-12T23:01:16.472Z] [DEBUG] [#tid_40] [Entry] write finished
[2018-04-12T23:01:16.472Z] [INFO] [#tid_40] [WhiskAction] write all done, caching CacheKey(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36) Cached
[2018-04-12T23:01:16.472Z] [DEBUG] [#tid_40] [ActionsApiTests] [PUT] entity success
" did not include substring that matched regex caching CacheKey\(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36\)(?s).*uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36(?s).*completed uploading attachment 'jarfile' of document 'id: anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36(?s).*caching CacheKey\(anon-J0erYG2IGDYzVH8V5FOkFxWcP92/action_tests_name36\)
at org.scalatest.MatchersHelper$.indicateFailure(MatchersHelper.scala:340)
at org.scalatest.Matchers$ResultOfIncludeWordForString.regex(Matchers.scala:2188)
at org.scalatest.Matchers$ResultOfIncludeWordForString.regex(Matchers.scala:2161)
at whisk.core.controller.test.ActionsApiTests$$anonfun$5.apply$mcV$sp(ActionsApiTests.scala:784)
at whisk.core.controller.test.ActionsApiTests$$anonfun$5.apply(ActionsApiTests.scala:749)
at whisk.core.controller.test.ActionsApiTests$$anonfun$5.apply(ActionsApiTests.scala:749)
at org.scalatest.OutcomeOf$class.outcomeOf(OutcomeOf.scala:85)
at org.scalatest.OutcomeOf$.outcomeOf(OutcomeOf.scala:104)
at org.scalatest.Transformer.apply(Transformer.scala:22)
at org.scalatest.Transformer.apply(Transformer.scala:20)
at org.scalatest.FlatSpecLike$$anon$1.apply(FlatSpecLike.scala:1682)
at org.scalatest.TestSuite$class.withFixture(TestSuite.scala:196)
at org.scalatest.FlatSpec.withFixture(FlatSpec.scala:1685)
at org.scalatest.FlatSpecLike$class.invokeWithFixture$1(FlatSpecLike.scala:1679)
at org.scalatest.FlatSpecLike$$anonfun$runTest$1.apply(FlatSpecLike.scala:1692)
at org.scalatest.FlatSpecLike$$anonfun$runTest$1.apply(FlatSpecLike.scala:1692)
at org.scalatest.SuperEngine.runTestImpl(Engine.scala:289)
at org.scalatest.FlatSpecLike$class.runTest(FlatSpecLike.scala:1692)
at whisk.core.controller.test.ActionsApiTests.org$scalatest$BeforeAndAfterEach$$super$runTest(ActionsApiTests.scala:60)
at org.scalatest.BeforeAndAfterEach$class.runTest(BeforeAndAfterEach.scala:221)
at whisk.core.controller.test.ActionsApiTests.runTest(ActionsApiTests.scala:60)
at org.scalatest.FlatSpecLike$$anonfun$runTests$1.apply(FlatSpecLike.scala:1750)
at org.scalatest.FlatSpecLike$$anonfun$runTests$1.apply(FlatSpecLike.scala:1750)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:396)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:384)
at scala.collection.immutable.List.foreach(List.scala:392)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:384)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:373)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:410)
at org.scalatest.SuperEngine$$anonfun$traverseSubNodes$1$1.apply(Engine.scala:384)
at scala.collection.immutable.List.foreach(List.scala:392)
at org.scalatest.SuperEngine.traverseSubNodes$1(Engine.scala:384)
at org.scalatest.SuperEngine.org$scalatest$SuperEngine$$runTestsInBranch(Engine.scala:379)
at org.scalatest.SuperEngine.runTestsImpl(Engine.scala:461)
at org.scalatest.FlatSpecLike$class.runTests(FlatSpecLike.scala:1750)
at org.scalatest.FlatSpec.runTests(FlatSpec.scala:1685)
at org.scalatest.Suite$class.run(Suite.scala:1147)
at org.scalatest.FlatSpec.org$scalatest$FlatSpecLike$$super$run(FlatSpec.scala:1685)
at org.scalatest.FlatSpecLike$$anonfun$run$1.apply(FlatSpecLike.scala:1795)
at org.scalatest.FlatSpecLike$$anonfun$run$1.apply(FlatSpecLike.scala:1795)
at org.scalatest.SuperEngine.runImpl(Engine.scala:521)
at org.scalatest.FlatSpecLike$class.run(FlatSpecLike.scala:1795)
at whisk.core.controller.test.ActionsApiTests.org$scalatest$BeforeAndAfterAll$$super$run(ActionsApiTests.scala:60)
at org.scalatest.BeforeAndAfterAll$class.liftedTree1$1(BeforeAndAfterAll.scala:213)
at org.scalatest.BeforeAndAfterAll$class.run(BeforeAndAfterAll.scala:210)
at whisk.core.controller.test.ActionsApiTests.run(ActionsApiTests.scala:60)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment