danhuawang commented on issue #11709:
URL: https://github.com/apache/gravitino/issues/11709#issuecomment-4727908903
Update some log info:
```
gravitino@gravitino-env2-oauth2-auth-gravitino-helm-dbdbbdf5d-cgh7j:/opt/gravitino/logs$
grep -B 10 -A 50 "2026-06-17 08:53:51.012" gravitino-server.log
2026-06-17 08:53:49.854 INFO [Gravitino-webserver-65]
[org.apache.gravitino.connector.BaseCatalog.initAuthorizationPluginInstance(BaseCatalog.java:280)]
- Authorization provider is not set!
2026-06-17 08:53:49.971 INFO [Gravitino-webserver-73]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of REST
2026-06-17 08:53:50.124 INFO [Gravitino-webserver-73]
[org.apache.iceberg.rest.auth.AuthManagers.loadAuthManager(AuthManagers.java:108)]
- Loading AuthManager implementation:
org.apache.gravitino.iceberg.common.rest.auth.UserPrincipalForwardingAuthManager
2026-06-17 08:53:50.335 INFO [iceberg-rest-45]
[org.apache.gravitino.connector.BaseCatalog.initAuthorizationPluginInstance(BaseCatalog.java:280)]
- Authorization provider is not set!
2026-06-17 08:53:50.781 WARN [iceberg-rest-45]
[org.apache.hadoop.util.NativeCodeLoader.<clinit>(NativeCodeLoader.java:60)] -
Unable to load native-hadoop library for your platform... using builtin-java
classes where applicable
2026-06-17 08:53:50.818 INFO [iceberg-rest-45]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of JDBC
2026-06-17 08:53:50.842 INFO [iceberg-rest-45]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.aws.s3.S3FileIO
2026-06-17 08:53:50.941 INFO [Gravitino-webserver-73]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.io.ResolvingFileIO
2026-06-17 08:53:50.987 INFO [iceberg-rest-37]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of JDBC
2026-06-17 08:53:50.993 INFO [iceberg-rest-37]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.aws.s3.S3FileIO
2026-06-17 08:53:51.012 INFO [iceberg-rest-37]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.createNamespace(IcebergNamespaceOperations.java:251)]
- Create Iceberg namespace, catalog: catalog_iceberg_s3_3,
createNamespaceRequest: CreateNamespaceRequest{namespace=iv_19031212_db,
properties={comment=Flink Iceberg REST view e2e db,
gravitino.identifier=gravitino.v1.uid2049773347225293972}}
2026-06-17 08:53:51.039 INFO [iceberg-rest-37]
[org.apache.gravitino.iceberg.common.utils.IcebergCatalogUtil.loadCatalogBackend(IcebergCatalogUtil.java:192)]
- Load catalog backend of JDBC
2026-06-17 08:53:51.045 INFO [iceberg-rest-37]
[org.apache.iceberg.CatalogUtil.loadFileIO(CatalogUtil.java:397)] - Loading
custom FileIO implementation: org.apache.iceberg.aws.s3.S3FileIO
2026-06-17 08:53:51.056 INFO [iceberg-rest-37]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_19031212_db from Iceberg
2026-06-17 08:53:51.062 ERROR [iceberg-rest-37]
[org.apache.gravitino.catalog.OperationDispatcher.operateOnEntity(OperationDispatcher.java:211)]
- Entity for test.catalog_iceberg_s3_3.iv_19031212_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 08:53:51.064 INFO [iceberg-rest-37]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_19031212_db from Iceberg
2026-06-17 08:53:51.066 ERROR [iceberg-rest-37]
[org.apache.gravitino.catalog.OperationDispatcher.operateOnEntity(OperationDispatcher.java:211)]
- Entity for test.catalog_iceberg_s3_3.iv_19031212_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 08:53:51.066 WARN [iceberg-rest-37]
[org.apache.gravitino.catalog.SchemaOperationDispatcher.importSchema(SchemaOperationDispatcher.java:388)]
- The Schema uid gravitino.v1.uid2049773347225293972 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 08:53:51.161 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_19031212_db
2026-06-17 08:53:51.167 INFO [Gravitino-webserver-73]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.createSchema(IcebergCatalogOperations.java:226)]
- Created Iceberg schema (database) iv_19031212_db in Iceberg
currentUser:service-account-postman-client
comment: Flink Iceberg REST view e2e db
metadata: {gravitino.identifier=gravitino.v1.uid2049773347225293972}
2026-06-17 08:53:52.209 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, METALAKE, 2855879095592179769, USE_CATALOG] ---> false
2026-06-17 08:53:52.209 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.209 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, CATALOG, 4338768777176633364, USE_CATALOG] ---> false
2026-06-17 08:53:52.210 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.247 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, METALAKE, 2855879095592179769, USE_CATALOG] ---> false
2026-06-17 08:53:52.247 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.247 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, CATALOG, 4338768777176633364, USE_CATALOG] ---> false
2026-06-17 08:53:52.247 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.270 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, METALAKE, 2855879095592179769, USE_CATALOG] ---> false
2026-06-17 08:53:52.270 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.271 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, CATALOG, 4338768777176633364, USE_CATALOG] ---> false
2026-06-17 08:53:52.271 INFO [Gravitino-webserver-73]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.799 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, METALAKE, 2855879095592179769, USE_CATALOG] ---> false
2026-06-17 08:53:52.799 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.799 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, CATALOG, 4338768777176633364, USE_CATALOG] ---> false
2026-06-17 08:53:52.799 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.811 INFO [iceberg-rest-44]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_19031212_db
2026-06-17 08:53:52.814 INFO [Gravitino-webserver-70]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_19031212_db from Iceberg
2026-06-17 08:53:52.930 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, METALAKE, 2855879095592179769, USE_CATALOG] ---> false
2026-06-17 08:53:52.930 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.930 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:115)] - Request:
[4235952163520748398, CATALOG, 4338768777176633364, USE_CATALOG] ---> false
2026-06-17 08:53:52.930 INFO [Gravitino-webserver-75]
[org.casbin.jcasbin.util.Util.logEnforce(Util.java:117)] - Hit Policy: []
2026-06-17 08:53:52.972 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_19031212_db
2026-06-17 08:53:52.974 INFO [Gravitino-webserver-70]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_19031212_db from Iceberg
2026-06-17 08:53:52.981 INFO [iceberg-rest-44]
[org.apache.gravitino.iceberg.service.rest.IcebergNamespaceOperations.loadNamespace(IcebergNamespaceOperations.java:156)]
- Load Iceberg namespace, catalog: catalog_iceberg_s3_3, namespace:
iv_19031212_db
2026-06-17 08:53:52.983 INFO [Gravitino-webserver-70]
[org.apache.gravitino.catalog.lakehouse.iceberg.IcebergCatalogOperations.loadSchema(IcebergCatalogOperations.java:270)]
- Loaded Iceberg schema (database) iv_19031212_db from Iceberg
2026-06-17 08:53:52.991 INFO [Gravitino-webserver-70]
[org.apache.iceberg.rest.RESTSessionCatalog$Builder.tableDefaultProperties(RESTSessionCatalog.java:902)]
- Table properties set at catalog level through catalog properties: {}
2026-06-17 08:53:52.992 INFO [Gravitino-webserver-70]
[org.apache.iceberg.rest.RESTSessionCatalog$Builder.tableOverrideProperties(RESTSessionCatalog.java:916)]
- Table properties enforced at catalog level through catalog properties: {}
2026-06-17 08:53:53.009 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.service.rest.IcebergTableOperations.createTable(IcebergTableOperations.java:180)]
- Create Iceberg table, catalog: catalog_iceberg_s3_3, namespace:
iv_19031212_db, create table request: CreateTableRequest{name=tc_p0_01_base,
location=null, properties={write.distribution-mode=none, comment=,
gravitino.identifier=gravitino.v1.uid511863962650026753}, schema=table {
0: id: optional int
}, partitionSpec=org.apache.iceberg.UnboundPartitionSpec@4d116bf4,
writeOrder=null, stageCreate=false}, accessDelegation: vended-credentials,
isCredentialVending: true
2026-06-17 08:53:53.014 INFO [iceberg-rest-47]
[org.apache.iceberg.BaseMetastoreCatalog$BaseMetastoreCatalogTableBuilder.tableDefaultProperties(BaseMetastoreCatalog.java:269)]
- Table properties set at catalog level through catalog properties: {}
2026-06-17 08:53:53.016 INFO [iceberg-rest-47]
[org.apache.iceberg.BaseMetastoreCatalog$BaseMetastoreCatalogTableBuilder.tableOverrideProperties(BaseMetastoreCatalog.java:283)]
- Table properties enforced at catalog level through catalog properties: {}
2026-06-17 08:53:54.904 INFO [iceberg-rest-47]
[org.apache.iceberg.BaseMetastoreTableOperations.commit(BaseMetastoreTableOperations.java:131)]
- Successfully committed to table iv_19031212_db.tc_p0_01_base in 1878 ms
2026-06-17 08:53:54.910 INFO [iceberg-rest-47]
[org.apache.iceberg.BaseMetastoreTableOperations.refreshFromMetadataLocation(BaseMetastoreTableOperations.java:191)]
- Refreshing table metadata from new version:
s3://irc-e2e-env1-metalake-b-warehouse/gravitino-e2e-env2-oauth2-auth/test/catalog_iceberg_s3_3/iv_19031212_db/tc_p0_01_base/metadata/00000-f6b753bc-c326-45c8-b5d9-328ac95cf0e4.metadata.json
2026-06-17 08:53:55.210 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.common.ops.IcebergCatalogWrapper.loadTableMetadataCache(IcebergCatalogWrapper.java:510)]
- Load Iceberg table metadata cache for catalog: jdbc,
impl:org.apache.gravitino.iceberg.common.cache.LocalTableMetadataCache,
capacity: 1000, expire minutes: 60
2026-06-17 08:53:56.441 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.service.CatalogWrapperForREST.injectCredentialConfig(CatalogWrapperForREST.java:284)]
- Generate credential: s3-token for Iceberg table: iv_19031212_db.tc_p0_01_base
2026-06-17 08:53:56.451 INFO [iceberg-rest-47]
[org.apache.gravitino.iceberg.common.ops.IcebergCatalogWrapper.loadTableMetadataCache(IcebergCatalogWrapper.java:510)]
- Load Iceberg table metadata cache for catalog: jdbc,
impl:org.apache.gravitino.iceberg.common.cache.LocalTableMetadataCache,
capacity: 1000, expire minutes: 60
```
--
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]
