Uploaded image for project: 'mod-permissions'
  1. mod-permissions
  2. MODPERMS-177

Error when upgrading mod-permissions from v5.14.3

    XMLWordPrintable

Details

    • Bug
    • Status: Closed (View Workflow)
    • P1
    • Resolution: Done
    • 5.14.4, 6.0.0
    • 5.14.5, 6.0.1
    • None
    • CP: sprint 132, CP: sprint 133, CP: sprint 135
    • 3
    • Core: Platform
    • TBD

    Description

      Overview: In a Kiwi environment with mod-permissions v5.14.3 running, the following error occurred after we deployed and enabled a snapshot of mod-permissions for Lotus, which was taken on Thursday 1/27.

      Steps to Reproduce:

      1. Start out with mod-permission v5.14.3 running
      2. Deploy and enable a snapshot of mod-permissions that's in master.

      Expected Results:

      • Enabling would succeed.
        Actual Results:
      • Getting the following error message:
        • 2022-01-28T12:36:22.424-05:00	17:36:22 [] [] [] [] ERROR PostgresClient Unrecognized field "tags" (class org.folio.rest.jaxrs.model.PermissionUser), not marked as ignorable (4 known properties: "id", "permissions", "userId", "metadata"])
          2022-01-28T12:36:22.424-05:00	at [Source: (String)"{"id":"813e4c96-343c-402b-8083-727f7dd7176b","tags":[],"dummy":false,"childOf":["folio_admin"],"mutable":false,"visible":false,"grantedTo":[],"description":"to reduce X-Okapi-Token size","displayName":"module permissions for one op","permissionName":"modperms.circulation.override-check-out-by-barcode.post","subPermissions":["circulation-storage.loans.item.post","circulation-storage.loans.item.get","circulation-storage.loans.collection.get","circulation.rules.loan-policy.get","circulation.rules.r"[truncated 1492 chars]; line: 1, column: 54] (through reference chain: org.folio.rest.jaxrs.model.PermissionUser["tags"])
          2022-01-28T12:36:22.424-05:00	com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "tags" (class org.folio.rest.jaxrs.model.PermissionUser), not marked as ignorable (4 known properties: "id", "permissions", "userId", "metadata"])
          2022-01-28T12:36:22.424-05:00	at [Source: (String)"{"id":"813e4c96-343c-402b-8083-727f7dd7176b","tags":[],"dummy":false,"childOf":["folio_admin"],"mutable":false,"visible":false,"grantedTo":[],"description":"to reduce X-Okapi-Token size","displayName":"module permissions for one op","permissionName":"modperms.circulation.override-check-out-by-barcode.post","subPermissions":["circulation-storage.loans.item.post","circulation-storage.loans.item.get","circulation-storage.loans.collection.get","circulation.rules.loan-policy.get","circulation.rules.r"[truncated 1492 chars]; line: 1, column: 54] (through reference chain: org.folio.rest.jaxrs.model.PermissionUser["tags"])
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException.from(UnrecognizedPropertyException.java:61) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.DeserializationContext.handleUnknownProperty(DeserializationContext.java:1127) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.std.StdDeserializer.handleUnknownProperty(StdDeserializer.java:1989) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownProperty(BeanDeserializerBase.java:1700) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownVanilla(BeanDeserializerBase.java:1678) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:319) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:176) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.deser.DefaultDeserializationContext.readRootValue(DefaultDeserializationContext.java:322) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4674) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3629) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3597) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.PostgresClient.deserializeRow(PostgresClient.java:3048) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.PostgresClient.deserializeResults(PostgresClient.java:3006) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.PostgresClient.processResults(PostgresClient.java:2968) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.Conn.lambda$31(Conn.java:793) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.PostgresClient.lambda$71(PostgresClient.java:2235) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at org.folio.rest.persist.PostgresClient.lambda$106(PostgresClient.java:3226) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl$3.onSuccess(FutureImpl.java:141) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureBase.emitSuccess(FutureBase.java:60) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl.tryComplete(FutureImpl.java:211) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.tryComplete(PromiseImpl.java:23) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.QueryResultBuilder.tryComplete(QueryResultBuilder.java:102) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.QueryResultBuilder.tryComplete(QueryResultBuilder.java:35) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.Promise.complete(Promise.java:66) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.Promise.handle(Promise.java:51) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.Promise.handle(Promise.java:29) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl$3.onSuccess(FutureImpl.java:141) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureBase.emitSuccess(FutureBase.java:60) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl.tryComplete(FutureImpl.java:211) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.tryComplete(PromiseImpl.java:23) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.onSuccess(PromiseImpl.java:49) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.handle(PromiseImpl.java:41) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.TransactionImpl.lambda$wrap$0(TransactionImpl.java:72) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl$3.onSuccess(FutureImpl.java:141) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureBase.lambda$emitSuccess$0(FutureBase.java:54) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.EventLoopContext.execute(EventLoopContext.java:81) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.DuplicatedContext.execute(DuplicatedContext.java:173) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureBase.emitSuccess(FutureBase.java:51) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.FutureImpl.tryComplete(FutureImpl.java:211) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.tryComplete(PromiseImpl.java:23) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.onSuccess(PromiseImpl.java:49) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.handle(PromiseImpl.java:41) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.future.PromiseImpl.handle(PromiseImpl.java:23) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.command.CommandResponse.fire(CommandResponse.java:46) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.SocketConnectionBase.handleMessage(SocketConnectionBase.java:287) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.pgclient.impl.PgSocketConnection.handleMessage(PgSocketConnection.java:96) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.sqlclient.impl.SocketConnectionBase.lambda$init$0(SocketConnectionBase.java:99) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.ContextImpl.emit(ContextImpl.java:274) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:22) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.net.impl.NetSocketImpl.handleMessage(NetSocketImpl.java:394) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.net.impl.ConnectionBase.read(ConnectionBase.java:156) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.core.net.impl.VertxHandler.channelRead(VertxHandler.java:153) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.pgclient.impl.codec.PgEncoder.lambda$write$0(PgEncoder.java:87) ~[ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.pgclient.impl.codec.PgCommandCodec.handleReadyForQuery(PgCommandCodec.java:139) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.pgclient.impl.codec.PgDecoder.decodeReadyForQuery(PgDecoder.java:237) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.vertx.pgclient.impl.codec.PgDecoder.channelRead(PgDecoder.java:96) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:722) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:658) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:584) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:496) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [ms.jar:?]
          2022-01-28T12:36:22.424-05:00	at java.lang.Thread.run(Thread.java:829) [?:?]
          

      Interested parties: adam

      TestRail: Results

        Attachments

          Issue Links

            Activity

              People

                adam Adam Dickmeiss
                mtraneis Martin Tran
                Votes:
                0 Vote for this issue
                Watchers:
                7 Start watching this issue

                Dates

                  Created:
                  Updated:
                  Resolved:

                  TestRail: Runs

                    TestRail: Cases