=== RUN TestDuplicatePlaylist_Success 2025/12/07 11:23:24 github.com/testcontainers/testcontainers-go - Connected to docker: Server Version: 28.1.1 API Version: 1.46 Operating System: Fedora Linux 40 (Workstation Edition) Total Memory: 15703 MB Testcontainers for Go Version: v0.33.0 Resolved Docker Host: unix:///var/run/docker.sock Resolved Docker Socket Path: /var/run/docker.sock Test SessionID: 2b0e6583d78b45c8d90f78a6cbdc9a3c90ee50500a3c76145760dabeffa25f9f Test ProcessID: 01a186b2-6902-4d82-921b-b87ef18d90ef 2025/12/07 11:23:24 🐳 Creating container for image testcontainers/ryuk:0.8.1 2025/12/07 11:23:31 ✅ Container created: 36247c2b4dbf 2025/12/07 11:23:31 🐳 Starting container: 36247c2b4dbf 2025/12/07 11:23:31 ✅ Container started: 36247c2b4dbf 2025/12/07 11:23:31 ⏳ Waiting for container id 36247c2b4dbf image: testcontainers/ryuk:0.8.1. Waiting for: &{Port:8080/tcp timeout: PollInterval:100ms skipInternalCheck:false} 2025/12/07 11:23:31 🔔 Container is ready: 36247c2b4dbf 2025/12/07 11:23:31 🐳 Creating container for image postgres:15-alpine 2025/12/07 11:23:41 ✅ Container created: 67a43ba9a0e9 2025/12/07 11:24:37 🐳 Starting container: 67a43ba9a0e9 2025/12/07 11:24:38 ✅ Container started: 67a43ba9a0e9 2025/12/07 11:24:38 ⏳ Waiting for container id 67a43ba9a0e9 image: postgres:15-alpine. Waiting for: &{timeout: deadline:0xc000474548 Strategies:[0xc0002a2720]} 2025/12/07 11:24:40 🔔 Container is ready: 67a43ba9a0e9 2025/12/07 11:24:42 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:77 ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) [2.079ms] [rows:0] INSERT INTO "tracks" ("id","user_id","title","artist","album","duration","genre","year","file_path","file_size","format","bitrate","sample_rate","waveform_path","cover_art_path","is_public","status","status_message","stream_status","stream_manifest_url","play_count","like_count","created_at","updated_at","deleted_at") VALUES ('0267f30f-8c44-4740-92cc-6a398156486f','243c6a99-0ada-4cd4-b452-00bcc5c5ab8a','Test Track 579c33ac','Test Artist','',180,'',0,'/test/track.mp3',5242880,'mp3',0,0,'','',true,'completed','','pending','',0,0,'2025-12-07 11:24:42.201','2025-12-07 11:24:42.201',NULL) playlist_duplicate_transaction_test.go:78: Error Trace: /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:78 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:96 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:133 Error: Received unexpected error: ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) Test: TestDuplicatePlaylist_Success --- FAIL: TestDuplicatePlaylist_Success (78.29s) === RUN TestDuplicatePlaylist_RollbackOnPlaylistNotFound 2025/12/07 11:24:42 /home/senke/Documents/veza/veza-backend-api/internal/services/playlist_duplicate_service.go:56 record not found [2.597ms] [rows:0] SELECT * FROM "playlists" WHERE id = '3c709430-4b7c-41e7-acae-c54469d6b719' AND "playlists"."deleted_at" IS NULL ORDER BY "playlists"."id" LIMIT 1 --- PASS: TestDuplicatePlaylist_RollbackOnPlaylistNotFound (0.81s) === RUN TestDuplicatePlaylist_RollbackOnTrackError 2025/12/07 11:24:43 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:77 ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) [1.728ms] [rows:0] INSERT INTO "tracks" ("id","user_id","title","artist","album","duration","genre","year","file_path","file_size","format","bitrate","sample_rate","waveform_path","cover_art_path","is_public","status","status_message","stream_status","stream_manifest_url","play_count","like_count","created_at","updated_at","deleted_at") VALUES ('ff00921f-c219-4ee9-9709-38839d709e7d','298f0470-78eb-4750-85d3-a5300fe677b2','Test Track 8ffde33d','Test Artist','',180,'',0,'/test/track.mp3',5242880,'mp3',0,0,'','',true,'completed','','pending','',0,0,'2025-12-07 11:24:43.737','2025-12-07 11:24:43.737',NULL) playlist_duplicate_transaction_test.go:78: Error Trace: /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:78 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:96 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:216 Error: Received unexpected error: ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) Test: TestDuplicatePlaylist_RollbackOnTrackError --- FAIL: TestDuplicatePlaylist_RollbackOnTrackError (0.86s) === RUN TestDuplicatePlaylist_Coherence 2025/12/07 11:24:44 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:77 ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) [1.733ms] [rows:0] INSERT INTO "tracks" ("id","user_id","title","artist","album","duration","genre","year","file_path","file_size","format","bitrate","sample_rate","waveform_path","cover_art_path","is_public","status","status_message","stream_status","stream_manifest_url","play_count","like_count","created_at","updated_at","deleted_at") VALUES ('b6e91dd5-9480-4b0c-9d96-9688db99a827','09fb130c-b3c9-4e91-8f8e-2fc60b28b84e','Test Track cf64d0de','Test Artist','',180,'',0,'/test/track.mp3',5242880,'mp3',0,0,'','',true,'completed','','pending','',0,0,'2025-12-07 11:24:44.812','2025-12-07 11:24:44.812',NULL) playlist_duplicate_transaction_test.go:78: Error Trace: /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:78 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:96 /home/senke/Documents/veza/veza-backend-api/tests/transactions/playlist_duplicate_transaction_test.go:274 Error: Received unexpected error: ERROR: null value in column "creator_id" of relation "tracks" violates not-null constraint (SQLSTATE 23502) Test: TestDuplicatePlaylist_Coherence --- FAIL: TestDuplicatePlaylist_Coherence (0.93s) === RUN TestDuplicatePlaylist_EmptyPlaylist logger.go:146: 2025-12-07T11:24:45.590+0100 INFO Playlist duplicated {"original_playlist_id": "90687653-a53b-4ad4-ae42-0bcdd45cd753", "new_playlist_id": "6506ff1f-3424-4fc2-9a5d-d1d18875ceed", "user_id": "5065a477-e86f-4f83-8405-35972c836550", "tracks_count": 0} --- PASS: TestDuplicatePlaylist_EmptyPlaylist (0.77s) === RUN TestAssignRoleToUser_Success 2025/12/07 11:24:46 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("11c43f22-4005-4733-a813-ffac46548325") to a uint8: invalid syntax [0.786ms] [rows:1] SELECT id FROM users WHERE id = '11c43f22-4005-4733-a813-ffac46548325' FOR UPDATE 2025/12/07 11:24:46 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:206 record not found [2.092ms] [rows:0] SELECT * FROM "user_roles" WHERE user_id = '11c43f22-4005-4733-a813-ffac46548325' AND role_id = 'e5a3352f-eee8-43e8-8215-491deaf27ce4' ORDER BY "user_roles"."id" LIMIT 1 FOR UPDATE logger.go:146: 2025-12-07T11:24:46.255+0100 INFO Role assigned to user successfully {"user_id": "11c43f22-4005-4733-a813-ffac46548325", "role_id": "e5a3352f-eee8-43e8-8215-491deaf27ce4"} --- PASS: TestAssignRoleToUser_Success (0.68s) === RUN TestAssignRoleToUser_RollbackOnUserNotFound 2025/12/07 11:24:46 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:186 record not found [2.512ms] [rows:0] SELECT * FROM "users" WHERE "users"."id" = 'de704f52-1582-4570-820d-e0b1cedc79a1' AND "users"."deleted_at" IS NULL ORDER BY "users"."id" LIMIT 1 FOR UPDATE --- PASS: TestAssignRoleToUser_RollbackOnUserNotFound (0.57s) === RUN TestAssignRoleToUser_RollbackOnRoleNotFound 2025/12/07 11:24:47 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("e468e35d-44f9-437c-bbf8-552e5d5c661e") to a uint8: invalid syntax [0.947ms] [rows:1] SELECT id FROM users WHERE id = 'e468e35d-44f9-437c-bbf8-552e5d5c661e' FOR UPDATE 2025/12/07 11:24:47 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:195 record not found [0.905ms] [rows:0] SELECT * FROM "roles" WHERE "roles"."id" = '2572083a-1f04-4e93-be0e-a8826179cf4f' ORDER BY "roles"."id" LIMIT 1 FOR UPDATE --- PASS: TestAssignRoleToUser_RollbackOnRoleNotFound (0.49s) === RUN TestAssignRoleToUser_RollbackOnDuplicate 2025/12/07 11:24:47 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("ace322b8-8940-4b6e-8aba-ff57b157a4b1") to a uint8: invalid syntax [1.383ms] [rows:1] SELECT id FROM users WHERE id = 'ace322b8-8940-4b6e-8aba-ff57b157a4b1' FOR UPDATE 2025/12/07 11:24:47 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:206 record not found [1.257ms] [rows:0] SELECT * FROM "user_roles" WHERE user_id = 'ace322b8-8940-4b6e-8aba-ff57b157a4b1' AND role_id = '7f6bc073-2e73-4d37-aa45-bea24c55981e' ORDER BY "user_roles"."id" LIMIT 1 FOR UPDATE logger.go:146: 2025-12-07T11:24:47.899+0100 INFO Role assigned to user successfully {"user_id": "ace322b8-8940-4b6e-8aba-ff57b157a4b1", "role_id": "7f6bc073-2e73-4d37-aa45-bea24c55981e"} 2025/12/07 11:24:47 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("ace322b8-8940-4b6e-8aba-ff57b157a4b1") to a uint8: invalid syntax [0.443ms] [rows:1] SELECT id FROM users WHERE id = 'ace322b8-8940-4b6e-8aba-ff57b157a4b1' FOR UPDATE --- PASS: TestAssignRoleToUser_RollbackOnDuplicate (0.55s) === RUN TestAssignRoleToUser_Concurrency 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [1.428ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:206 record not found [2.932ms] [rows:0] SELECT * FROM "user_roles" WHERE user_id = '5aefa230-0589-4040-9b83-c120025843db' AND role_id = '9db728ed-7024-4f0a-98ab-d92897930a7e' ORDER BY "user_roles"."id" LIMIT 1 FOR UPDATE logger.go:146: 2025-12-07T11:24:48.456+0100 INFO Role assigned to user successfully {"user_id": "5aefa230-0589-4040-9b83-c120025843db", "role_id": "9db728ed-7024-4f0a-98ab-d92897930a7e"} 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [10.655ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [17.798ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [21.313ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [34.158ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [38.100ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [48.667ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [57.975ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [66.207ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE 2025/12/07 11:24:48 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("5aefa230-0589-4040-9b83-c120025843db") to a uint8: invalid syntax [74.205ms] [rows:1] SELECT id FROM users WHERE id = '5aefa230-0589-4040-9b83-c120025843db' FOR UPDATE --- PASS: TestAssignRoleToUser_Concurrency (0.62s) === RUN TestAssignRoleToUser_Atomicity 2025/12/07 11:24:49 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("7831daac-b1b6-4f94-b075-945ad3e6168d") to a uint8: value out of range [1.083ms] [rows:1] SELECT id FROM users WHERE id = '7831daac-b1b6-4f94-b075-945ad3e6168d' FOR UPDATE 2025/12/07 11:24:49 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:206 record not found [1.191ms] [rows:0] SELECT * FROM "user_roles" WHERE user_id = '7831daac-b1b6-4f94-b075-945ad3e6168d' AND role_id = 'ec81012f-bc9f-4146-a1ce-494a0fb8700a' ORDER BY "user_roles"."id" LIMIT 1 FOR UPDATE logger.go:146: 2025-12-07T11:24:49.083+0100 INFO Role assigned to user successfully {"user_id": "7831daac-b1b6-4f94-b075-945ad3e6168d", "role_id": "ec81012f-bc9f-4146-a1ce-494a0fb8700a"} 2025/12/07 11:24:49 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:175 sql: Scan error on column index 0, name "id": converting driver.Value type string ("502ddb31-4564-4a7a-8597-23173d6d2bbd") to a uint8: value out of range [0.603ms] [rows:1] SELECT id FROM users WHERE id = '502ddb31-4564-4a7a-8597-23173d6d2bbd' FOR UPDATE 2025/12/07 11:24:49 /home/senke/Documents/veza/veza-backend-api/internal/services/rbac_service.go:195 record not found [0.618ms] [rows:0] SELECT * FROM "roles" WHERE "roles"."id" = 'ec81012f-bc9f-4146-a1ce-494a0fb8700a' ORDER BY "roles"."id" LIMIT 1 FOR UPDATE rbac_transaction_test.go:253: Error Trace: /home/senke/Documents/veza/veza-backend-api/tests/transactions/rbac_transaction_test.go:253 Error: Not equal: expected: 1 actual : 0 Test: TestAssignRoleToUser_Atomicity Messages: First assignment should still exist --- FAIL: TestAssignRoleToUser_Atomicity (0.56s) === RUN TestToggleLike_Success 2025/12/07 11:24:49 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:138 record not found [3.379ms] [rows:0] SELECT * FROM "likes" WHERE user_id = '2fb8ecb9-d592-4aba-a222-55e83e8b731d' AND target_id = '97ee790e-aa4c-4d89-b7c7-516133e1597e' AND target_type = 'post' ORDER BY "likes"."id" LIMIT 1 --- PASS: TestToggleLike_Success (0.55s) === RUN TestToggleLike_Unlike 2025/12/07 11:24:50 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:138 record not found [4.081ms] [rows:0] SELECT * FROM "likes" WHERE user_id = '76ee046c-be40-47df-8c0b-a57872fcdbae' AND target_id = 'f9a607e2-fd5d-4dbf-a36d-70b1aef98991' AND target_type = 'post' ORDER BY "likes"."id" LIMIT 1 --- PASS: TestToggleLike_Unlike (0.45s) === RUN TestToggleLike_RollbackOnError 2025/12/07 11:24:50 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:138 record not found [3.890ms] [rows:0] SELECT * FROM "likes" WHERE user_id = '38df6295-fd47-444c-9fd4-3ab4791d6da9' AND target_id = 'ecfc6256-afee-4009-ad4e-7c31833fa883' AND target_type = 'post' ORDER BY "likes"."id" LIMIT 1 social_transaction_test.go:190: Error Trace: /home/senke/Documents/veza/veza-backend-api/tests/transactions/social_transaction_test.go:190 Error: An error is expected but got nil. Test: TestToggleLike_RollbackOnError Messages: ToggleLike should fail --- FAIL: TestToggleLike_RollbackOnError (0.45s) === RUN TestToggleLike_Coherence 2025/12/07 11:24:50 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:138 record not found [3.181ms] [rows:0] SELECT * FROM "likes" WHERE user_id = '1c6256fe-1db0-41f4-a919-039e0703bcb8' AND target_id = '3715ab28-0038-42d9-9b65-4ccbc57f9cf5' AND target_type = 'post' ORDER BY "likes"."id" LIMIT 1 2025/12/07 11:24:50 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:138 record not found [0.380ms] [rows:0] SELECT * FROM "likes" WHERE user_id = '77d31b5c-5c85-491e-954d-b8f417613126' AND target_id = '3715ab28-0038-42d9-9b65-4ccbc57f9cf5' AND target_type = 'post' ORDER BY "likes"."id" LIMIT 1 --- PASS: TestToggleLike_Coherence (0.44s) === RUN TestAddComment_Success --- PASS: TestAddComment_Success (0.36s) === RUN TestAddComment_RollbackOnError 2025/12/07 11:24:51 /home/senke/Documents/veza/veza-backend-api/internal/core/social/service.go:196 record not found [2.500ms] [rows:0] SELECT * FROM "posts" WHERE id = 'c80e89a9-a3ba-4f4b-89a5-b26a2cfcb01d' AND "posts"."deleted_at" IS NULL ORDER BY "posts"."id" LIMIT 1 --- PASS: TestAddComment_RollbackOnError (0.38s) === RUN TestAddComment_Coherence --- PASS: TestAddComment_Coherence (0.36s) FAIL FAIL veza-backend-api/tests/transactions 88.221s FAIL