Closed github-actions[bot] closed 2 months ago
From the job run
UCX v0.27.2+1320240702082541
08:32:42 INFO [d.labs.ucx] UCX v0.27.2+1320240702082541 After job finishes, see debug logs at /Workspace/Users/REDACTED/.GxSA/logs/migrate-tables/run-510503637132105-0/migrate_views.log
08:32:42 WARN [d.l.u.assessment.secrets] Secret in config stored in plaintext.
08:32:42 WARN [d.l.u.assessment.secrets] Secret in config stored in plaintext.
08:33:04 INFO [d.l.blueprint.parallel][checking_databases_for_skip_property_0] checking databases for skip property 1/1, rps: 3.579/sec
08:33:04 INFO [d.l.blueprint.parallel] Finished 'checking databases for skip property' tasks: 100% results available (1/1). Took 0:00:00.291709
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_0] The intended target for hive_metastore.migrate_f0o7k.ucx_tmii3, ucx_cnpit.migrate_f0o7k.ucx_tmii3, already exists.
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_1] The intended target for hive_metastore.migrate_f0o7k.ucx_t0pck, ucx_cnpit.migrate_f0o7k.ucx_t0pck, already exists.
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_2] The intended target for hive_metastore.migrate_f0o7k.ucx_tuf86, ucx_cnpit.migrate_f0o7k.ucx_tuf86, already exists.
08:33:05 INFO [d.l.blueprint.parallel][checking_all_database_properties_3] checking all database properties 5/5, rps: 5.394/sec
08:33:05 INFO [d.l.blueprint.parallel] Finished 'checking all database properties' tasks: 40% results available (2/5). Took 0:00:00.937029
08:33:18 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_tmii3 is set as migrated
08:33:18 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_t0pck is set as migrated
08:33:19 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_tuf86 is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_tmii3 is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_t0pck is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_tuf86 is set as migrated
UCX v0.27.2+1320240702082541
08:32:42 INFO [d.labs.ucx] UCX v0.27.2+1320240702082541 After job finishes, see debug logs at /Workspace/Users/REDACTED/.GxSA/logs/migrate-tables/run-510503637132105-0/migrate_views.log
08:32:42 WARN [d.l.u.assessment.secrets] Secret in config stored in plaintext.
08:32:42 WARN [d.l.u.assessment.secrets] Secret in config stored in plaintext.
08:33:04 INFO [d.l.blueprint.parallel][checking_databases_for_skip_property_0] checking databases for skip property 1/1, rps: 3.579/sec
08:33:04 INFO [d.l.blueprint.parallel] Finished 'checking databases for skip property' tasks: 100% results available (1/1). Took 0:00:00.291709
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_0] The intended target for hive_metastore.migrate_f0o7k.ucx_tmii3, ucx_cnpit.migrate_f0o7k.ucx_tmii3, already exists.
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_1] The intended target for hive_metastore.migrate_f0o7k.ucx_t0pck, ucx_cnpit.migrate_f0o7k.ucx_t0pck, already exists.
08:33:04 INFO [d.l.u.hive_metastore.mapping][checking_all_database_properties_2] The intended target for hive_metastore.migrate_f0o7k.ucx_tuf86, ucx_cnpit.migrate_f0o7k.ucx_tuf86, already exists.
08:33:05 INFO [d.l.blueprint.parallel][checking_all_database_properties_3] checking all database properties 5/5, rps: 5.394/sec
08:33:05 INFO [d.l.blueprint.parallel] Finished 'checking all database properties' tasks: 40% results available (2/5). Took 0:00:00.937029
08:33:18 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_tmii3 is set as migrated
08:33:18 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_t0pck is set as migrated
08:33:19 INFO [d.l.u.hive_metastore.migration_status] migrate_f0o7k.ucx_tuf86 is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_tmii3 is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_t0pck is set as migrated
08:33:34 INFO [d.l.u.hive_metastore.migration_status][migrate_views_0] migrate_f0o7k.ucx_tuf86 is set as migrated
08:33:39 WARN [sqlglot][migrate_views_0] 'CREATE VIEW migrate_f0o7k.ucx_tq5d5 (
id,
value)
TBLPROPERTIES (
'RemoveAfter' = '2024070209',' contains unsupported syntax. Falling back to parsing as a 'Command'.
08:33:39 WARN [sqlglot][migrate_views_0] 'CREATE VIEW migrate_f0o7k.ucx_tq5d5 (
id,
value)
TBLPROPERTIES (
'RemoveAfter' = '2024070209',' contains unsupported syntax. Falling back to parsing as a 'Command'.
08:33:39 ERROR [d.l.blueprint.parallel][migrate_views_0] migrate views(ViewToMigrate(src=Table(catalog='hive_metastore', database='migrate_f0o7k', name='ucx_tq5d5', object_type='VIEW', table_format='', location='', view_text="CREATE VIEW migrate_f0o7k.ucx_tq5d5 (\n id,\n value)\nTBLPROPERTIES (\n 'RemoveAfter' = '2024070209',\n 'transient_lastDdlTime' = '1719908664')\nWITH SCHEMA BINDING\nAS SELECT * FROM hive_metastore.migrate_f0o7k.ucx_tmii3\n", upgraded_to=None, storage_properties=None, is_partitioned=False), rule=Rule(workspace_name='workspace', catalog_name='ucx_cnpit', src_schema='migrate_f0o7k', dst_schema='migrate_f0o7k', src_table='ucx_tq5d5', dst_table='ucx_tq5d5', recon_tolerance_percent=0, compare_rows=False)), [Grant(principal='REDACTED', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False), Grant(principal='ucx_GPat8', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False), Grant(principal='sdk-5cltrfst@example.com', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False), Grant(principal='ucx_G2mUT', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False), Grant(principal='sdk-ixyvumbd@example.com', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False), Grant(principal='REDACTED', action_type='ALL PRIVILEGES', catalog='hive_metastore', database='migrate_f0o7k', table=None, view='ucx_tq5d5', udf=None, any_file=False, anonymous_function=False)]) task failed: Expected a CREATE statement: Traceback (most recent call last):
File "/local_disk0/.ephemeral_nfs/cluster_libraries/python/lib/python3.11/site-packages/databricks/labs/blueprint/parallel.py", line 158, in inner
return func(*args, **kwargs), None
^^^^^^^^^^^^^^^^^^^^^
File "/local_disk0/.ephemeral_nfs/cluster_libraries/python/lib/python3.11/site-packages/databricks/labs/ucx/hive_metastore/table_migrate.py", line 201, in _migrate_view
return self._migrate_view_table(src_view, grants)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/local_disk0/.ephemeral_nfs/cluster_libraries/python/lib/python3.11/site-packages/databricks/labs/ucx/hive_metastore/table_migrate.py", line 214, in _migrate_view_table
view_migrate_sql = self._sql_migrate_view(src_view)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/local_disk0/.ephemeral_nfs/cluster_libraries/python/lib/python3.11/site-packages/databricks/labs/ucx/hive_metastore/table_migrate.py", line 233, in _sql_migrate_view
return src_view.sql_migrate_view(self.index())
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/local_disk0/.ephemeral_nfs/cluster_libraries/python/lib/python3.11/site-packages/databricks/labs/ucx/hive_metastore/view_migrate.py", line 62, in sql_migrate_view
assert isinstance(create, expressions.Create), 'Expected a CREATE statement'
AssertionError: Expected a CREATE statement
08:33:39 INFO [d.l.blueprint.parallel][migrate_views_0] migrate views 1/1, rps: 0.061/sec
08:33:39 FATAL [d.l.blueprint.parallel] All 'migrate views' tasks failed!!!
08:33:39 ERROR [d.labs.ucx] Execute `databricks workspace export //Users/REDACTED/.GxSA/logs/migrate-tables/run-510503637132105-0/migrate_views.log` locally to troubleshoot with more details. Expected a CREATE statement
Running from nightly #110
Running from nightly #113
❌ test_table_migration_job_refreshes_migration_status[regular-migrate-tables]: TimeoutError: Timed out after 0:05:00 (5m51.263s)
``` TimeoutError: Timed out after 0:05:00 07:04 DEBUG [databricks.labs.ucx.mixins.fixtures] added make_dbfs_data_copy fixture: dbfs:/mnt/TEST_MOUNT_NAME/a/b/ymQ7 07:04 INFO [databricks.labs.ucx.mixins.fixtures] Schema hive_metastore.migrate_ochuo: https://DATABRICKS_HOST/explore/data/hive_metastore/migrate_ochuo 07:04 DEBUG [databricks.labs.ucx.mixins.fixtures] added schema fixture: SchemaInfo(browse_only=None, catalog_name='hive_metastore', catalog_type=None, comment=None, created_at=None, created_by=None, effective_predictive_optimization_flag=None, enable_predictive_optimization=None, full_name='hive_metastore.migrate_ochuo', metastore_id=None, name='migrate_ochuo', owner=None, properties=None, schema_id=None, storage_location=None, storage_root=None, updated_at=None, updated_by=None) 07:04 INFO [databricks.labs.ucx.mixins.fixtures] Table hive_metastore.migrate_ochuo.ucx_tnkkj: https://DATABRICKS_HOST/explore/data/hive_metastore/migrate_ochuo/ucx_tnkkj 07:04 DEBUG [databricks.labs.ucx.mixins.fixtures] added table fixture: TableInfo(access_point=None, browse_only=None, catalog_name='hive_metastore', columns=None, comment=None, created_at=None, created_by=None, data_access_configuration_id=None, data_source_format=Running from nightly #109