danhuawang commented on issue #11709:
URL: https://github.com/apache/gravitino/issues/11709#issuecomment-4727443706
```
grep -B 20 -A 60 "2026-06-17 07:45:24.056" gravitino-server.log
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.base/java.lang.Thread.run(Thread.java:840)
2026-06-17 07:45:23.730 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.MetalakeOperations.createMetalake(MetalakeOperations.java:126)]
- Received create metalake request for iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:23.743 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:23] admin CREATE_METALAKE iceberg_rest_view_e2e_6199ae1f
SUCCESS
2026-06-17 07:45:23.744 INFO [Gravitino-webserver-77]
[org.apache.gravitino.job.BuiltInJobTemplateEventListener.createAuxlibClassLoader(BuiltInJobTemplateEventListener.java:228)]
- Loading built-in job templates from 1 gravitino-jobs JAR file(s) in auxlib
directory
2026-06-17 07:45:23.915 INFO [Gravitino-webserver-77]
[org.apache.gravitino.job.BuiltInJobTemplateEventListener.registerNewBuiltInJobTemplate(BuiltInJobTemplateEventListener.java:300)]
- Registered built-in job template builtin-sparkpi under metalake
iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:23.918 INFO [Gravitino-webserver-77]
[org.apache.gravitino.job.BuiltInJobTemplateEventListener.registerNewBuiltInJobTemplate(BuiltInJobTemplateEventListener.java:300)]
- Registered built-in job template builtin-iceberg-rewrite-data-files under
metalake iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:23.920 INFO [Gravitino-webserver-77]
[org.apache.gravitino.job.BuiltInJobTemplateEventListener.registerNewBuiltInJobTemplate(BuiltInJobTemplateEventListener.java:300)]
- Registered built-in job template builtin-iceberg-update-stats under metalake
iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:23.920 INFO [Gravitino-webserver-77]
[org.apache.gravitino.job.BuiltInJobTemplateEventListener.onPostEvent(BuiltInJobTemplateEventListener.java:143)]
- Registered built-in job templates for metalake:
iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:23.925 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:23] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f.system.user.admin SUCCESS
2026-06-17 07:45:23.933 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:23] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f SUCCESS
2026-06-17 07:45:23.933 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.MetalakeOperations.lambda$createMetalake$2(MetalakeOperations.java:137)]
- Metalake created: iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:24.010 INFO [Gravitino-webserver-59]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[2294172941837738120, METALAKE, 3558215396701569329, CREATE_CATALOG] ---> false
2026-06-17 07:45:24.010 INFO [Gravitino-webserver-59]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:24.011 INFO [Gravitino-webserver-59]
[org.apache.gravitino.server.web.rest.CatalogOperations.createCatalog(CatalogOperations.java:144)]
- Received create catalog request for metalake: iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:24.030 INFO [Gravitino-webserver-59]
[org.apache.gravitino.connector.BaseCatalog.initAuthorizationPluginInstance(BaseCatalog.java:280)]
- Authorization provider is not set!
2026-06-17 07:45:24.030 INFO [Gravitino-webserver-59]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin CREATE_CATALOG
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2 SUCCESS
2026-06-17 07:45:24.035 INFO [Gravitino-webserver-59]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2 SUCCESS
2026-06-17 07:45:24.035 INFO [Gravitino-webserver-59]
[org.apache.gravitino.server.web.rest.CatalogOperations.lambda$createCatalog$2(CatalogOperations.java:159)]
- Catalog created: iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:24.056 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.SchemaOperations.createSchema(SchemaOperations.java:144)]
- Received create schema request:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db
2026-06-17 07:45:24.136 INFO [Gravitino-webserver-77]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of REST
2026-06-17 07:45:24.224 INFO [Gravitino-webserver-77]
[org.apache.iceberg.rest.auth.AuthManagers.loadAuthManager(AuthManagers.java:108)]
- Loading AuthManager implementation:
org.apache.gravitino.iceberg.common.rest.auth.UserPrincipalForwardingAuthManager
2026-06-17 07:45:24.285 INFO [iceberg-rest-48]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of JDBC
2026-06-17 07:45:24.292 INFO [iceberg-rest-48]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.aws.s3.S3FileIO
2026-06-17 07:45:24.318 INFO [Gravitino-webserver-77]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.io.ResolvingFileIO
2026-06-17 07:45:24.351 INFO [iceberg-rest-50]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.createNamespace(IcebergNamespaceOperations.java:251)]
- Create Iceberg namespace, catalog: catalog_iceberg_s3_3,
createNamespaceRequest: CreateNamespaceRequest{namespace=iv_5da86ddb_db,
properties={comment=Flink Iceberg REST view e2e db,
gravitino.identifier=gravitino.v1.uid3821352211875691238}}
2026-06-17 07:45:24.356 INFO [iceberg-rest-50]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
test.catalog_iceberg_s3_3.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:24.357 INFO [iceberg-rest-50]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_5da86ddb_db from Iceberg
2026-06-17 07:45:24.358 ERROR [iceberg-rest-50]
[org.apache.gravitino.catalog.OperationDispatcher.operateOnEntity(OperationDispatcher.java:211)]
- Entity for test.catalog_iceberg_s3_3.iv_5da86ddb_db doesn't exist in
Gravitino, this is unexpected if this is created by Gravitino. With this
situation the returned object will not contain the metadata from Gravitino
2026-06-17 07:45:24.359 INFO [iceberg-rest-50]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_5da86ddb_db from Iceberg
2026-06-17 07:45:24.359 ERROR [iceberg-rest-50]
[org.apache.gravitino.catalog.OperationDispatcher.operateOnEntity(OperationDispatcher.java:211)]
- Entity for test.catalog_iceberg_s3_3.iv_5da86ddb_db doesn't exist in
Gravitino, this is unexpected if this is created by Gravitino. With this
situation the returned object will not contain the metadata from Gravitino
2026-06-17 07:45:24.359 WARN [iceberg-rest-50]
[org.apache.gravitino.catalog.SchemaOperationDispatcher.importSchema(SchemaOperationDispatcher.java:388)]
- The Schema uid gravitino.v1.uid3821352211875691238 existed but still needs
to be imported, this could be happened when Schema is renamed by external
systems not controlled by Gravitino. In this case, we need to overwrite the
stored entity to keep consistency.
2026-06-17 07:45:24.373 INFO [iceberg-rest-51]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_5da86ddb_db
2026-06-17 07:45:24.374 INFO [iceberg-rest-51]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
test.catalog_iceberg_s3_3.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:24.375 INFO [Gravitino-webserver-77]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.createSchema(IcebergCatalogOperations.java:226)]
- Created Iceberg schema (database) iv_5da86ddb_db in Iceberg
currentUser:admin
comment: Flink Iceberg REST view e2e db
metadata: {gravitino.identifier=gravitino.v1.uid3821352211875691238}
2026-06-17 07:45:24.379 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin CREATE_SCHEMA
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:24.386 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:24.386 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.SchemaOperations.lambda$createSchema$1(SchemaOperations.java:155)]
- Schema created:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db
2026-06-17 07:45:24.400 INFO [Gravitino-webserver-59]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f.system.user.service-account-postman-client
SUCCESS
2026-06-17 07:45:24.415 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] admin UNKNOWN_OPERATION
iceberg_rest_view_e2e_6199ae1f SUCCESS
2026-06-17 07:45:24.629 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.MetalakeOperations.loadMetalake(MetalakeOperations.java:157)]
- Received load metalake request for metalake: iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:24.630 INFO [Gravitino-webserver-56]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:24] service-account-postman-client LOAD_METALAKE
iceberg_rest_view_e2e_6199ae1f SUCCESS
2026-06-17 07:45:24.630 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.MetalakeOperations.lambda$loadMetalake$3(MetalakeOperations.java:165)]
- Metalake loaded: iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:25.404 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, METALAKE, 3558215396701569329, USE_CATALOG] ---> false
2026-06-17 07:45:25.404 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.406 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, CATALOG, 4371322952627727698, USE_CATALOG] ---> false
2026-06-17 07:45:25.406 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.407 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:267)]
- Received load catalog request for catalog:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.408 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:25] service-account-postman-client LOAD_CATALOG
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2 SUCCESS
2026-06-17 07:45:25.408 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:272)]
- Catalog loaded: iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.440 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, METALAKE, 3558215396701569329, USE_CATALOG] ---> false
2026-06-17 07:45:25.440 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.440 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, CATALOG, 4371322952627727698, USE_CATALOG] ---> false
2026-06-17 07:45:25.440 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.441 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:267)]
- Received load catalog request for catalog:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.441 INFO [Gravitino-webserver-56]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:25] service-account-postman-client LOAD_CATALOG
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2 SUCCESS
2026-06-17 07:45:25.441 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:272)]
- Catalog loaded: iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.452 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, METALAKE, 3558215396701569329, USE_CATALOG] ---> false
2026-06-17 07:45:25.452 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.452 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, CATALOG, 4371322952627727698, USE_CATALOG] ---> false
2026-06-17 07:45:25.452 INFO [Gravitino-webserver-77]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.453 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.MetadataObjectCredentialOperations.getCredentials(MetadataObjectCredentialOperations.java:91)]
- Received get credentials request for object type: catalog, full name:
iceberg_rest_view_oauth2 under metalake: iceberg_rest_view_e2e_6199ae1f
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, METALAKE, 3558215396701569329, USE_CATALOG] ---> false
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, CATALOG, 4371322952627727698, USE_CATALOG] ---> false
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:267)]
- Received load catalog request for catalog:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:25] service-account-postman-client LOAD_CATALOG
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2 SUCCESS
2026-06-17 07:45:25.986 INFO [Gravitino-webserver-56]
[org.apache.gravitino.server.web.rest.CatalogOperations.loadCatalog(CatalogOperations.java:272)]
- Catalog loaded: iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2
2026-06-17 07:45:25.991 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.SchemaOperations.loadSchema(SchemaOperations.java:178)]
- Received load schema request for schema:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db
2026-06-17 07:45:25.994 INFO [iceberg-rest-50]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_5da86ddb_db
2026-06-17 07:45:25.995 INFO [iceberg-rest-50]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:25] service-account-postman-client UNKNOWN_OPERATION
test.catalog_iceberg_s3_3.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:25.996 INFO [Gravitino-webserver-77]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_5da86ddb_db from Iceberg
2026-06-17 07:45:25.997 INFO [Gravitino-webserver-77]
[org.apache.gravitino.audit.FileAuditWriter.doWrite(FileAuditWriter.java:71)] -
[2026-06-17 07:45:25] service-account-postman-client LOAD_SCHEMA
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db SUCCESS
2026-06-17 07:45:25.997 INFO [Gravitino-webserver-77]
[org.apache.gravitino.server.web.rest.SchemaOperations.lambda$loadSchema$2(SchemaOperations.java:186)]
- Schema loaded:
iceberg_rest_view_e2e_6199ae1f.iceberg_rest_view_oauth2.iv_5da86ddb_db
2026-06-17 07:45:26.105 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[1628725638061758191, METALAKE, 3558215396701569329, USE_CATALOG] ---> false
2026-06-17 07:45:26.105 INFO [Gravitino-webserver-56]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
```
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
