VIA-254 Add temporary extra logging around session retrieval and auth callbacks

Author: donna-belsey-nhs

auth.ts

@@ -60,6 +60,7 @@ export const { handlers, signIn, signOut, auth } = NextAuth(async () => {
         },
 
         async jwt({ token, account, profile }) {
+          log.info("jwt / getToken callback invoked");
           let response;
           try {
             profilePerformanceStart(AuthJWTPerformanceMarker);
@@ -73,6 +74,7 @@ export const { handlers, signIn, signOut, auth } = NextAuth(async () => {
         },
 
         async session({ session, token }) {
+          log.info("getsession callback invoked");
           let response;
           try {
             profilePerformanceStart(AuthSessionPerformanceMarker);

src/app/_components/vaccine/Vaccine.tsx

@@ -15,13 +15,16 @@ import { getContentForVaccine } from "@src/services/content-api/content-service"
 import { ContentErrorTypes, StyledVaccineContent } from "@src/services/content-api/types";
 import { getEligibilityForPerson } from "@src/services/eligibility-api/domain/eligibility-filter-service";
 import { Eligibility, EligibilityErrorTypes } from "@src/services/eligibility-api/types";
+import { logger } from "@src/utils/logger";
 import { profilePerformanceEnd, profilePerformanceStart } from "@src/utils/performance";
 import { requestScopedStorageWrapper } from "@src/utils/requestScopedStorageWrapper";
 import { Session } from "next-auth";
 import React, { JSX } from "react";
 
 import styles from "./styles.module.css";
 
+const log = logger.child({ name: "vaccine component" });
+
 interface VaccineProps {
   vaccineType: VaccineTypes;
 }
@@ -35,7 +38,9 @@ const Vaccine = async ({ vaccineType }: VaccineProps) => {
 const VaccineComponent = async ({ vaccineType }: VaccineProps): Promise<JSX.Element> => {
   profilePerformanceStart(VaccinePagePerformanceMarker);
 
+  log.info("vaccine component calling auth()");
   const session: Session | null = await auth();
+  log.info("vaccine component auth() call complete");
   const nhsNumber: NhsNumber | undefined = session?.user.nhs_number as NhsNumber;
   const vaccineInfo: VaccineDetails = VaccineInfo[vaccineType];
 

src/middleware.ts

@@ -28,7 +28,10 @@ const middlewareWrapper = async (request: NextRequest) => {
   const config: AppConfig = await configProvider();
 
   let response: NextResponse;
+  log.info("middleware calling auth()");
   const session: Session | null = await auth();
+  log.info("middleware auth() call complete");
+
   if (!session?.user) {
     log.info({ context: { nextUrl: request.nextUrl.href } }, "Missing user session, redirecting to login");
     response = NextResponse.redirect(new URL(config.NHS_APP_REDIRECT_LOGIN_URL));

src/services/eligibility-api/gateway/fetch-eligibility-content.ts

@@ -41,7 +41,9 @@ export const fetchEligibilityContent = async (nhsNumber: NhsNumber): Promise<Eli
   };
 
   if (config.IS_APIM_AUTH_ENABLED) {
+    log.info("elid service fetching apim token");
     const apimAccessToken = await getApimAccessToken();
+    log.info("elid service retrieved apim token");
     headers = { ...headers, Authorization: `Bearer ${apimAccessToken}` };
   }
 

src/utils/auth/apim/get-apim-access-token.ts

@@ -16,7 +16,9 @@ const getApimAccessToken = async (): Promise<AccessToken> => {
    *
    * @returns A Promise, resolving to the APIM access token.
    */
+  log.info("awaiting getJwtToken");
   const token = await getJwtToken();
+  log.info("getJwtToken complete");
 
   if (!token?.apim?.access_token) {
     log.error({ context: { token } }, "APIM access token is not present on JWT token");