multidim interop: Debug sizes and execution times

.github/actions/run-interop-ping-test/action.yml

@@ -91,6 +91,19 @@ runs:
           exit 1
         fi
 
+    - name: Print implementation images
+      working-directory: ${{ steps.find-workdir.outputs.WORK_DIR }}
+      run: head impl/*/*/*image.json
+      shell: bash
+    - name: Print the image sizes
+      working-directory: ${{ steps.find-workdir.outputs.WORK_DIR }}
+      run: docker image ls
+      shell: bash
+    - name: Print Docker disk usage
+      working-directory: ${{ steps.find-workdir.outputs.WORK_DIR }}
+      run: docker system df
+      shell: bash
+
     - name: Push the image cache
       if: env.PUSH_CACHE == 'true'
       working-directory: ${{ steps.find-workdir.outputs.WORK_DIR }}

multidim-interop/helpers/cache.ts

@@ -91,7 +91,12 @@ switch (modeStr) {
                         const dockerLoadedMsg = child_process.execSync(`curl https://s3.amazonaws.com/${AWS_BUCKET}/imageCache/${cacheKey}-${arch}.tar.gz  | docker image load`).toString();
                         const loadedImageId = dockerLoadedMsg.match(/Loaded image( ID)?: (.*)/)[2];
                         if (loadedImageId) {
-                            console.log(`Cache hit for ${loadedImageId}`);
+                            let imageSize = "unknown"
+                            try {
+                                const imageSizeBytes = child_process.execSync(`docker image inspect ${loadedImageId} -f "{.Size}"`).toString().trim();
+                                imageSize = imageSizeBytes
+                            } catch { }
+                            console.log(`Cache hit for ${loadedImageId}. Size ${imageSize}.`);
                             fs.writeFileSync(path.join(implFolder, 'image.json'), JSON.stringify({ imageID: loadedImageId }) + "\n");
                             cacheHit = true
                         }

multidim-interop/src/compose-runner.ts

@@ -21,6 +21,7 @@ export type RunOpts = {
 export type RunFailure = any
 
 export async function run(namespace: string, compose: ComposeSpecification, opts: RunOpts): Promise<RunFailure | null> {
+    const start = Date.now()
     // sanitize namespace
     const sanitizedNamespace = namespace.replace(/[^a-zA-Z0-9]/g, "-")
     const dir = path.join(tmpdir(), "compose-runner", sanitizedNamespace)
@@ -54,7 +55,18 @@ export async function run(namespace: string, compose: ComposeSpecification, opts
         clearTimeout(timeoutId)
         try {
             const testResultsParsed = dialerTimings(dialerStdout(stdout))
-            console.log("Finished:", namespace, testResultsParsed)
+            const elapsed = (Date.now() - start) / 1000
+            console.log(`Finished in ${elapsed}s:`, namespace, testResultsParsed)
+            if (elapsed > 30) {
+                // Really slow, why?
+                console.log("Slow interop test. Debugging info:")
+                console.log("stdout:")
+                console.log(stdout)
+                console.log("")
+                console.log("stderr:")
+                console.log(stderr)
+                console.log("")
+            }
         } catch (e) {
             console.log("Failed to parse test results.")
             console.log("stdout:")