Closed Bug 1957068 Opened 1 month ago Closed 8 days ago

Intermittent ContentAnalysisTest.GetDiagnosticInfo_FailedSignatureVerification | Value of: gotResponse

Categories

(Toolkit :: General, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=501415070&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BCAw1jTHSX6-FeJwc99D1g/runs/0/artifacts/public/logs/live_backing.log


[task 2025-03-28T12:13:31.832Z] 12:13:31     INFO -  TEST-START | ContentAnalysisTest.GetDiagnosticInfo_FailedSignatureVerification
[task 2025-03-28T12:13:31.832Z] 12:13:31     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Local DLP Content Analysis is active
[task 2025-03-28T12:13:31.840Z] 12:13:31     INFO -  [Demo] AgentWin{pipe="\\.\\pipe\contentanalysissdk-gtest-8F5505F6-4964-4B05-8A81-670E0EB5ADB4.S-1-5-21-420378277-974078517-2415777337-1005" stop=000000000000011C conn@0{handle=000000000000006C connected=false pid=0 rsize=0 fsize=0} conn@1{handle=0000000000000070 connected=false pid=0 rsize=0 fsize=0}}
[task 2025-03-28T12:13:33.834Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::AnalyzeContentRequestsCallback received 1 requests
[task 2025-03-28T12:13:33.836Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Content Analysis checking URL against URL filter list | URL: https://example.com/
[task 2025-03-28T12:13:33.836Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis CA request filter result: check
[task 2025-03-28T12:13:33.836Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis processing 1 given and synthesized requests
[task 2025-03-28T12:13:33.837Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::AnalyzeContentRequestPrivate analyzing request [20600045d40] with callback [20600432980]
[task 2025-03-28T12:13:33.837Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Local DLP Content Analysis is active
[task 2025-03-28T12:13:33.837Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Issuing ContentAnalysisRequest for token {99a62922-496e-4db4-8320-c7ed424ade41}
[task 2025-03-28T12:13:33.838Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysisRequest:
[task 2025-03-28T12:13:33.838Z] 12:13:33     INFO -    Expires: 1743164313
[task 2025-03-28T12:13:33.838Z] 12:13:33     INFO -    Analysis Type: 3
[task 2025-03-28T12:13:33.838Z] 12:13:33     INFO -    Request Token: {99a62922-496e-4db4-8320-c7ed424ade41}
[task 2025-03-28T12:13:33.839Z] 12:13:33     INFO -    User Action ID: {9f6125b6-b2e5-448a-8655-72ceafcff4b9}
[task 2025-03-28T12:13:33.839Z] 12:13:33     INFO -    User Action Requests Count: 1
[task 2025-03-28T12:13:33.839Z] 12:13:33     INFO -    File Path: <none>
[task 2025-03-28T12:13:33.839Z] 12:13:33     INFO -    Text Content: allow
[task 2025-03-28T12:13:33.839Z] 12:13:33     INFO -    Request Data Struct: <exists>
[task 2025-03-28T12:13:33.840Z] 12:13:33     INFO -      Url: https://example.com/
[task 2025-03-28T12:13:33.840Z] 12:13:33     INFO -      Email: <none>
[task 2025-03-28T12:13:33.840Z] 12:13:33     INFO -      SHA-256 Digest: <none>
[task 2025-03-28T12:13:33.840Z] 12:13:33     INFO -      Filename: <none>
[task 2025-03-28T12:13:33.840Z] 12:13:33     INFO -      Client Download Request struct: <none>
[task 2025-03-28T12:13:33.841Z] 12:13:33     INFO -    Client Metadata Struct: <exists>
[task 2025-03-28T12:13:33.841Z] 12:13:33     INFO -      Browser Struct: <exists>
[task 2025-03-28T12:13:33.841Z] 12:13:33     INFO -        Machine User: VM-DWDB4GP7TS6A\task_174316220047614
[task 2025-03-28T12:13:33.842Z] 12:13:33     INFO -  [Parent 1232: ContentAnalysisAgentIO #1]: E/contentanalysis DoAnalyzeRequest got err=-1 for request_token={99a62922-496e-4db4-8320-c7ed424ade41}, user_action_id={9f6125b6-b2e5-448a-8655-72ceafcff4b9}
[task 2025-03-28T12:13:33.842Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Failed to get client - trying to reconnect: NS_ERROR_FAILURE
[task 2025-03-28T12:13:33.842Z] 12:13:33     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Dispatching background task to create Content Analysis client
[task 2025-03-28T12:13:33.843Z] 12:13:33     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis is connected
[task 2025-03-28T12:13:43.844Z] 12:13:43  WARNING -  TEST-UNEXPECTED-FAIL | ContentAnalysisTest.GetDiagnosticInfo_FailedSignatureVerification | Value of: gotResponse
[task 2025-03-28T12:13:43.846Z] 12:13:43     INFO -    Actual: false
[task 2025-03-28T12:13:43.847Z] 12:13:43     INFO -  Expected: true
[task 2025-03-28T12:13:43.847Z] 12:13:43     INFO -   @ /builds/worker/checkouts/gecko/toolkit/components/contentanalysis/tests/gtest/TestContentAnalysis.cpp:675
[task 2025-03-28T12:13:43.847Z] 12:13:43  WARNING -  TEST-UNEXPECTED-FAIL | ContentAnalysisTest.GetDiagnosticInfo_FailedSignatureVerification | Value of: timedOut->mValue
[task 2025-03-28T12:13:43.847Z] 12:13:43     INFO -    Actual: true
[task 2025-03-28T12:13:43.848Z] 12:13:43     INFO -  Expected: false
[task 2025-03-28T12:13:43.848Z] 12:13:43     INFO -   @ /builds/worker/checkouts/gecko/toolkit/components/contentanalysis/tests/gtest/TestContentAnalysis.cpp:676
[task 2025-03-28T12:13:46.189Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis client has no signature
[task 2025-03-28T12:13:46.190Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: E/contentanalysis Got mismatched content analysis client signature! All content analysis operations will fail.
[task 2025-03-28T12:13:46.190Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: E/contentanalysis Failed to get client again for RunAnalyzeRequestTask, error=NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:46.191Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Failed to get client - trying to reconnect: NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:46.191Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Dispatching background task to create Content Analysis client
[task 2025-03-28T12:13:46.192Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis RunAnalyzeRequestTask failed to get client a second time for requestToken={99a62922-496e-4db4-8320-c7ed424ade41}, userActionId={9f6125b6-b2e5-448a-8655-72ceafcff4b9}
[task 2025-03-28T12:13:46.192Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::CancelWithError cancelling user action: {9f6125b6-b2e5-448a-8655-72ceafcff4b9} with error: NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:46.193Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis MultipartRequestCallback issuing response.  Permitted? no
[task 2025-03-28T12:13:46.193Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis [206004307a0] Called ContentAnalysisCallback::ContentResult
[task 2025-03-28T12:13:46.193Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis [206004300c0] Called ContentAnalysisCallback::ContentResult
[task 2025-03-28T12:13:46.194Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::CancelWithError user action not found -- either was after last response or before first request was submitted | userActionId: {9f6125b6-b2e5-448a-8655-72ceafcff4b9}
[task 2025-03-28T12:13:46.194Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis is connected
[task 2025-03-28T12:13:46.195Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis client has no signature
[task 2025-03-28T12:13:46.195Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: E/contentanalysis Got mismatched content analysis client signature! All content analysis operations will fail.
[task 2025-03-28T12:13:46.195Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: E/contentanalysis Failed to get client again for GetDiagnosticInfo, error=NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:46.196Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Failed to get client - trying to reconnect: NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:46.196Z] 12:13:46     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Dispatching background task to create Content Analysis client
[task 2025-03-28T12:13:46.196Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis is connected
[task 2025-03-28T12:13:46.197Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis client has no signature
[task 2025-03-28T12:13:46.197Z] 12:13:46     INFO -  [Parent 1232: BackgroundThreadPool #2]: E/contentanalysis Got mismatched content analysis client signature! All content analysis operations will fail.
[task 2025-03-28T12:13:46.206Z] 12:13:46     INFO -  [Demo] AgentWin{pipe="\\.\\pipe\contentanalysissdk-gtest-8F5505F6-4964-4B05-8A81-670E0EB5ADB4.S-1-5-21-420378277-974078517-2415777337-1005" stop=0000000000000104 conn@0{handle=000000000000012C connected=false pid=0 rsize=0 fsize=0} conn@1{handle=0000000000000134 connected=false pid=0 rsize=0 fsize=0}}
[task 2025-03-28T12:13:48.211Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::AnalyzeContentRequestsCallback received 1 requests
[task 2025-03-28T12:13:48.212Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Content Analysis checking URL against URL filter list | URL: https://example.com/
[task 2025-03-28T12:13:48.213Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis CA request filter result: check
[task 2025-03-28T12:13:48.213Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: E/contentanalysis Failed to get client again for SendCancelToAgent, error=NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:48.213Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis processing 1 given and synthesized requests
[task 2025-03-28T12:13:48.214Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysis::AnalyzeContentRequestPrivate analyzing request [206000454d0] with callback [20600432ec0]
[task 2025-03-28T12:13:48.214Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Local DLP Content Analysis is active
[task 2025-03-28T12:13:48.214Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Dispatching background task to create Content Analysis client
[task 2025-03-28T12:13:48.214Z] 12:13:48     INFO -  [Parent 1232: BackgroundThreadPool #2]: D/contentanalysis Content analysis is connected
[task 2025-03-28T12:13:48.215Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Issuing ContentAnalysisRequest for token {b9a84cf8-1c24-4bbc-9b60-79f806bcd8b1}
[task 2025-03-28T12:13:48.216Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysisRequest:
[task 2025-03-28T12:13:48.216Z] 12:13:48     INFO -    Expires: 1743164328
[task 2025-03-28T12:13:48.216Z] 12:13:48     INFO -    Analysis Type: 3
[task 2025-03-28T12:13:48.216Z] 12:13:48     INFO -    Request Token: {b9a84cf8-1c24-4bbc-9b60-79f806bcd8b1}
[task 2025-03-28T12:13:48.216Z] 12:13:48     INFO -    User Action ID: {2fb1e9ee-b878-4d3f-b5d6-c3f1f82dab87}
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -    User Action Requests Count: 1
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -    File Path: <none>
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -    Text Content: allowCleanup
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -    Request Data Struct: <exists>
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -      Url: https://example.com/
[task 2025-03-28T12:13:48.217Z] 12:13:48     INFO -      Email: <none>
[task 2025-03-28T12:13:48.218Z] 12:13:48     INFO -      SHA-256 Digest: <none>
[task 2025-03-28T12:13:48.218Z] 12:13:48     INFO -      Filename: <none>
[task 2025-03-28T12:13:48.218Z] 12:13:48     INFO -      Client Download Request struct: <none>
[task 2025-03-28T12:13:48.218Z] 12:13:48     INFO -    Client Metadata Struct: <exists>
[task 2025-03-28T12:13:48.218Z] 12:13:48     INFO -      Browser Struct: <exists>
[task 2025-03-28T12:13:48.219Z] 12:13:48     INFO -        Machine User: VM-DWDB4GP7TS6A\task_174316220047614
[task 2025-03-28T12:13:48.219Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: E/contentanalysis SendCancelToAgent failed to get the client with error NS_ERROR_INVALID_SIGNATURE
[task 2025-03-28T12:13:48.321Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysisResponse:
[task 2025-03-28T12:13:48.322Z] 12:13:48     INFO -    Request Token: {b9a84cf8-1c24-4bbc-9b60-79f806bcd8b1}
[task 2025-03-28T12:13:48.322Z] 12:13:48     INFO -    Result 0:
[task 2025-03-28T12:13:48.323Z] 12:13:48     INFO -        Status: 1
[task 2025-03-28T12:13:48.323Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis RunAnalyzeRequestTask on main thread about to send response
[task 2025-03-28T12:13:48.323Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Local DLP Content Analysis is active
[task 2025-03-28T12:13:48.324Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Issuing ContentAnalysisAcknowledgement
[task 2025-03-28T12:13:48.324Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis ContentAnalysisAcknowledgement:
[task 2025-03-28T12:13:48.324Z] 12:13:48     INFO -    Request Token: {b9a84cf8-1c24-4bbc-9b60-79f806bcd8b1}
[task 2025-03-28T12:13:48.324Z] 12:13:48     INFO -    Status: 1
[task 2025-03-28T12:13:48.324Z] 12:13:48     INFO -    Final Action: 1
[task 2025-03-28T12:13:48.325Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis RunAcknowledgeTask dispatching acknowledge task
[task 2025-03-28T12:13:48.325Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis Content analysis notifying observers and calling callback for token {b9a84cf8-1c24-4bbc-9b60-79f806bcd8b1}
[task 2025-03-28T12:13:48.325Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis MultipartRequestCallback issuing response.  Permitted? yes
[task 2025-03-28T12:13:48.326Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis [20600430ca0] Called ContentAnalysisCallback::ContentResult
[task 2025-03-28T12:13:48.326Z] 12:13:48     INFO -  [Parent 1232: Main Thread]: D/contentanalysis [20601dbee20] Called ContentAnalysisCallback::ContentResult
[task 2025-03-28T12:13:48.326Z] 12:13:48  WARNING -  TEST-UNEXPECTED-FAIL | ContentAnalysisTest.GetDiagnosticInfo_FailedSignatureVerification | test completed (time: 16494ms)
[task 2025-03-28T12:13:48.326Z] 12:13:48     INFO -  TEST-START | FOGFixture.BuiltinPingsRegistered
Status: NEW → RESOLVED
Closed: 8 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.