|
| 1 | +import { ApolloDriver, ApolloDriverConfig } from '@nestjs/apollo' |
| 2 | +import { INestApplication } from '@nestjs/common' |
| 3 | +import { GraphQLModule } from '@nestjs/graphql' |
| 4 | +import { Test, TestingModule } from '@nestjs/testing' |
| 5 | +import { getDataSourceToken, TypeOrmModule } from '@nestjs/typeorm' |
| 6 | +import { NestjsQueryTypeOrmModule } from '@ptc-org/nestjs-query-typeorm' |
| 7 | +import request from 'supertest' |
| 8 | +import { DataSource } from 'typeorm' |
| 9 | + |
| 10 | +import { NestjsQueryGraphQLModule, PagingStrategies } from '../../../src' |
| 11 | +import { TodoItemDto } from './dtos/todo-item.dto' |
| 12 | +import { TodoListDto } from './dtos/todo-list.dto' |
| 13 | +import { TodoItem } from './entities/todo-item.entity' |
| 14 | +import { TodoList } from './entities/todo-list.entity' |
| 15 | +import { createTestData } from './fixtures/test-data' |
| 16 | + |
| 17 | +describe('Federation N+1 Integration Test (Based on User Demo)', () => { |
| 18 | + let app: INestApplication |
| 19 | + let dataSource: DataSource |
| 20 | + let queryCount: number |
| 21 | + let executedQueries: string[] |
| 22 | + |
| 23 | + beforeAll(async () => { |
| 24 | + // Setup query monitoring to track SQL execution |
| 25 | + queryCount = 0 |
| 26 | + executedQueries = [] |
| 27 | + |
| 28 | + const module: TestingModule = await Test.createTestingModule({ |
| 29 | + imports: [ |
| 30 | + TypeOrmModule.forRoot({ |
| 31 | + type: 'sqlite', |
| 32 | + database: ':memory:', |
| 33 | + entities: [TodoList, TodoItem], |
| 34 | + synchronize: true, |
| 35 | + logging: true, |
| 36 | + logger: { |
| 37 | + logQuery: (query, parameters) => { |
| 38 | + queryCount++ |
| 39 | + const fullQuery = parameters ? `${query} -- PARAMETERS: [${parameters.join(',')}]` : query |
| 40 | + executedQueries.push(fullQuery) |
| 41 | + console.log(`[SQL ${queryCount}] ${fullQuery}`) |
| 42 | + }, |
| 43 | + logQueryError: (error, query, parameters) => { |
| 44 | + console.error('Query Error:', error, query, parameters) |
| 45 | + }, |
| 46 | + logQuerySlow: (time, query, parameters) => { |
| 47 | + console.warn('Slow Query:', time, query, parameters) |
| 48 | + }, |
| 49 | + logSchemaBuild: (message) => { |
| 50 | + console.log('Schema Build:', message) |
| 51 | + }, |
| 52 | + logMigration: (message) => { |
| 53 | + console.log('Migration:', message) |
| 54 | + }, |
| 55 | + log: (level, message) => { |
| 56 | + console.log(`[${level}]`, message) |
| 57 | + } |
| 58 | + } |
| 59 | + }), |
| 60 | + TypeOrmModule.forFeature([TodoList, TodoItem]), |
| 61 | + GraphQLModule.forRoot<ApolloDriverConfig>({ |
| 62 | + driver: ApolloDriver, |
| 63 | + autoSchemaFile: true, |
| 64 | + debug: true, |
| 65 | + playground: false |
| 66 | + }), |
| 67 | + // Unified NestJS Query module to test ReferenceResolver without Federation conflicts |
| 68 | + NestjsQueryGraphQLModule.forFeature({ |
| 69 | + imports: [NestjsQueryTypeOrmModule.forFeature([TodoList, TodoItem])], |
| 70 | + resolvers: [ |
| 71 | + { |
| 72 | + EntityClass: TodoList, |
| 73 | + DTOClass: TodoListDto, |
| 74 | + pagingStrategy: PagingStrategies.NONE, |
| 75 | + // Test ReferenceResolver with referenceBy configuration |
| 76 | + referenceBy: { key: 'id' } |
| 77 | + }, |
| 78 | + { |
| 79 | + EntityClass: TodoItem, |
| 80 | + DTOClass: TodoItemDto, |
| 81 | + pagingStrategy: PagingStrategies.NONE, |
| 82 | + // Test ReferenceResolver with referenceBy configuration |
| 83 | + referenceBy: { key: 'id' } |
| 84 | + } |
| 85 | + ] |
| 86 | + }) |
| 87 | + ] |
| 88 | + }).compile() |
| 89 | + |
| 90 | + app = module.createNestApplication() |
| 91 | + |
| 92 | + // Enable DEBUG logging to see DataLoader logs |
| 93 | + app.useLogger(['error', 'warn', 'log', 'debug']) |
| 94 | + |
| 95 | + await app.init() |
| 96 | + |
| 97 | + dataSource = app.get<DataSource>(getDataSourceToken()) |
| 98 | + |
| 99 | + // Create test data matching your demo scenario |
| 100 | + await createTestData(dataSource) |
| 101 | + }) |
| 102 | + |
| 103 | + beforeEach(() => { |
| 104 | + // Reset query tracking before each test |
| 105 | + queryCount = 0 |
| 106 | + executedQueries = [] |
| 107 | + }) |
| 108 | + |
| 109 | + describe('N+1 Query Prevention - TodoList with Items', () => { |
| 110 | + it('should prevent N+1 queries when fetching TodoLists with their items', async () => { |
| 111 | + console.log('\\n=== Testing TodoList with Items Query ===') |
| 112 | + |
| 113 | + const query = ` |
| 114 | + query { |
| 115 | + todoLists { |
| 116 | + id |
| 117 | + name |
| 118 | + items { |
| 119 | + id |
| 120 | + content |
| 121 | + } |
| 122 | + } |
| 123 | + } |
| 124 | + ` |
| 125 | + |
| 126 | + const response = await request(app.getHttpServer()).post('/graphql').send({ query }).expect(200) |
| 127 | + |
| 128 | + // Verify data correctness |
| 129 | + expect(response.body.data.todoLists).toHaveLength(5) |
| 130 | + expect(response.body.data.todoLists[0].items).toHaveLength(11) |
| 131 | + |
| 132 | + console.log(`\\nTotal SQL queries executed: ${queryCount}`) |
| 133 | + console.log('\\nExecuted queries:') |
| 134 | + executedQueries.forEach((sqlQuery, index) => { |
| 135 | + console.log(` ${index + 1}. ${sqlQuery}`) |
| 136 | + }) |
| 137 | + |
| 138 | + // Key assertion: Should use efficient queries, NOT N+1 |
| 139 | + // Ideally should be 2 queries: |
| 140 | + // 1. SELECT all TodoLists |
| 141 | + // 2. SELECT all TodoItems WHERE listId IN (1,2,3,4,5) |
| 142 | + expect(queryCount).toBeLessThan(10) // Much less than 55+ N+1 queries |
| 143 | + |
| 144 | + // Check for efficient batch queries |
| 145 | + const hasEfficientItemQuery = executedQueries.some( |
| 146 | + (sqlQuery) => |
| 147 | + sqlQuery.includes('test_todo_item') && |
| 148 | + sqlQuery.includes('listId') && |
| 149 | + (sqlQuery.includes('IN') || sqlQuery.includes('=')) |
| 150 | + ) |
| 151 | + expect(hasEfficientItemQuery).toBe(true) |
| 152 | + }) |
| 153 | + }) |
| 154 | + |
| 155 | + describe('N+1 Query Prevention - TodoItem with List References', () => { |
| 156 | + it('should prevent N+1 queries when resolving TodoItem list references', async () => { |
| 157 | + console.log('\\n=== Testing TodoItem List Reference Resolution ===') |
| 158 | + |
| 159 | + const query = ` |
| 160 | + query { |
| 161 | + todoItems { |
| 162 | + id |
| 163 | + content |
| 164 | + list { |
| 165 | + id |
| 166 | + name |
| 167 | + } |
| 168 | + } |
| 169 | + } |
| 170 | + ` |
| 171 | + |
| 172 | + const response = await request(app.getHttpServer()).post('/graphql').send({ query }).expect(200) |
| 173 | + |
| 174 | + // Verify data correctness - should have 55 items (5 lists * 11 items each) |
| 175 | + expect(response.body.data.todoItems).toHaveLength(55) |
| 176 | + |
| 177 | + // Each item should have its list resolved |
| 178 | + expect(response.body.data.todoItems[0].list).toBeDefined() |
| 179 | + expect(response.body.data.todoItems[0].list.name).toBeDefined() |
| 180 | + |
| 181 | + console.log(`\\nTotal SQL queries for reference resolution: ${queryCount}`) |
| 182 | + console.log('\\nExecuted queries:') |
| 183 | + executedQueries.forEach((sqlQuery, index) => { |
| 184 | + console.log(` ${index + 1}. ${sqlQuery}`) |
| 185 | + }) |
| 186 | + |
| 187 | + // Key assertion: Should batch reference resolution |
| 188 | + // Should NOT execute 55 separate queries for TodoList references |
| 189 | + expect(queryCount).toBeLessThan(10) |
| 190 | + |
| 191 | + // Check for batch reference query |
| 192 | + const hasBatchReferenceQuery = executedQueries.some( |
| 193 | + (sqlQuery) => sqlQuery.includes('test_todo_list') && sqlQuery.includes('IN') |
| 194 | + ) |
| 195 | + expect(hasBatchReferenceQuery).toBe(true) |
| 196 | + }) |
| 197 | + }) |
| 198 | + |
| 199 | + describe('DataLoader Behavior Verification', () => { |
| 200 | + it('should show DataLoader batching logs in debug mode', async () => { |
| 201 | + console.log('\\n=== Testing DataLoader Debug Logs ===') |
| 202 | + |
| 203 | + // Capture console output to verify DataLoader logs |
| 204 | + const consoleLogs: string[] = [] |
| 205 | + const logSpy = jest.spyOn(console, 'log').mockImplementation((...args) => { |
| 206 | + const message = args.join(' ') |
| 207 | + consoleLogs.push(message) |
| 208 | + // Optionally, call the original implementation if you want logs to still appear: |
| 209 | + // jest.requireActual('console').log(...args) |
| 210 | + }) |
| 211 | + |
| 212 | + const query = ` |
| 213 | + query { |
| 214 | + todoLists { |
| 215 | + id |
| 216 | + name |
| 217 | + } |
| 218 | + } |
| 219 | + ` |
| 220 | + |
| 221 | + await request(app.getHttpServer()).post('/graphql').send({ query }).expect(200) |
| 222 | + |
| 223 | + // Restore console.log |
| 224 | + logSpy.mockRestore() |
| 225 | + |
| 226 | + // Check for DataLoader debug logs (optional in test environment) |
| 227 | + const dataLoaderLogs = consoleLogs.filter((log) => log.includes('DataLoaderFactory') || log.includes('ReferenceLoader')) |
| 228 | + |
| 229 | + console.log(`\\nFound ${dataLoaderLogs.length} DataLoader debug logs:`) |
| 230 | + dataLoaderLogs.forEach((log, index) => { |
| 231 | + console.log(` ${index + 1}. ${log}`) |
| 232 | + }) |
| 233 | + |
| 234 | + // Debug logs may not be available in all test environments, but the query should work |
| 235 | + expect(true).toBe(true) // Test passes as long as GraphQL query succeeds |
| 236 | + }) |
| 237 | + }) |
| 238 | + |
| 239 | + describe('Performance Comparison', () => { |
| 240 | + it('should demonstrate the N+1 fix performance improvement', async () => { |
| 241 | + console.log('\\n=== Performance Comparison Test ===') |
| 242 | + |
| 243 | + // Test the most problematic query from your demo |
| 244 | + const complexQuery = ` |
| 245 | + query { |
| 246 | + todoLists { |
| 247 | + id |
| 248 | + name |
| 249 | + items { |
| 250 | + id |
| 251 | + content |
| 252 | + list { |
| 253 | + id |
| 254 | + name |
| 255 | + } |
| 256 | + } |
| 257 | + } |
| 258 | + } |
| 259 | + ` |
| 260 | + |
| 261 | + const startTime = Date.now() |
| 262 | + |
| 263 | + const response = await request(app.getHttpServer()).post('/graphql').send({ query: complexQuery }).expect(200) |
| 264 | + |
| 265 | + const endTime = Date.now() |
| 266 | + const executionTime = endTime - startTime |
| 267 | + |
| 268 | + console.log(`\\nQuery execution time: ${executionTime}ms`) |
| 269 | + console.log(`Total SQL queries: ${queryCount}`) |
| 270 | + console.log( |
| 271 | + `Data returned: ${response.body.data.todoLists.length} lists with ${response.body.data.todoLists[0].items.length} items each` |
| 272 | + ) |
| 273 | + |
| 274 | + // Verify nested data is correct |
| 275 | + expect(response.body.data.todoLists).toHaveLength(5) |
| 276 | + expect(response.body.data.todoLists[0].items).toHaveLength(11) |
| 277 | + expect(response.body.data.todoLists[0].items[0].list).toBeDefined() |
| 278 | + |
| 279 | + // Key performance assertion: |
| 280 | + // Without fix: Would need 100+ queries (similar to your demo logs) |
| 281 | + // With fix: Should need <15 queries total |
| 282 | + console.log(`\\n✅ Performance improvement: Using ${queryCount} queries instead of 100+ N+1 queries`) |
| 283 | + expect(queryCount).toBeLessThan(15) |
| 284 | + }) |
| 285 | + }) |
| 286 | + |
| 287 | + afterAll(async () => { |
| 288 | + if (dataSource) { |
| 289 | + await dataSource.destroy() |
| 290 | + } |
| 291 | + if (app) { |
| 292 | + await app.close() |
| 293 | + } |
| 294 | + }) |
| 295 | +}) |
0 commit comments