Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Dependabot unknown_error, doesn't create PR #1335

Closed
Ossiam opened this issue Sep 11, 2024 · 7 comments
Closed

Dependabot unknown_error, doesn't create PR #1335

Ossiam opened this issue Sep 11, 2024 · 7 comments

Comments

@Ossiam
Copy link

Ossiam commented Sep 11, 2024

Not sure if I should post about this issue here or in dependabot-core. I'm using the dependabot azure extension (useUpdateScriptvNext, image 1.34) to run dependabot on multiple C# repos. For my main repo, some packages fail to update but still creates a PR:

Dependabot encountered '2' error(s) during execution, please check the logs for more details.
+--------------------------------------+
|    Dependencies failed to update     |
+----------------------+---------------+
| MSTest.TestAdapter   | unknown_error |
| MSTest.TestFramework | unknown_error |
+----------------------+---------------+

But for my second repo, I get the error posted below, which causes it to not create a PR. Is there a flag I can use to print the full logs so I can pinpoint the issue?

After googling about the issue, some people suggested to add the nuget-feed as a registry, but that didn't resolve it.
On the dependabot-core github, some people have posted about similar issues, and that it's "resolved"
dependabot/dependabot-core#8992

registries: 
  public-nuget:
    type: nuget-feed
    url: https://api.nuget.org/v3/index.json

version: 2
updates:
  - package-ecosystem: "nuget"
    directory: "/src"
    registries:
      - public-nuget
2024/09/11 08:58:36 INFO <job_1726044198> Finished job processing
2024/09/11 08:58:36 INFO Results:
Dependabot encountered '1' error(s) during execution, please check the logs for more details.
+---------------+
|    Errors     |
+---------------+
| unknown_error |
+---------------+

Directory.packages.props for the project I'm trying to update:

<Project>
  <PropertyGroup>
    <ManagePackageVersionsCentrally>true</ManagePackageVersionsCentrally>
    <CentralPackageTransitivePinningEnabled>true</CentralPackageTransitivePinningEnabled>
  </PropertyGroup>
  <ItemGroup>
    <PackageVersion Include="Azure.Core" Version="1.42.0" />
    <PackageVersion Include="Azure.Messaging.EventHubs" Version="5.11.5" />
    <PackageVersion Include="Azure.Storage.Blobs" Version="12.21.2" />
    <PackageVersion Include="Azure.Storage.Files.DataLake" Version="12.19.1" />
    <PackageVersion Include="CSharpFunctionalExtensions" Version="2.42.5" />
    <PackageVersion Include="Dapper" Version="2.1.35" />
    <PackageVersion Include="dbup-sqlserver" Version="5.0.41" />
    <PackageVersion Include="FakeItEasy" Version="8.3.0" />
    <PackageVersion Include="FluentAssertions" Version="6.12.0" />
    <PackageVersion Include="FsCheck" Version="3.0.0-rc3" />
    <PackageVersion Include="Google.Protobuf" Version="3.27.4" />
    <PackageVersion Include="GraphQL.Server.Ui.Voyager" Version="8.0.2" />
    <PackageVersion Include="HotChocolate.AspNetCore" Version="13.9.11" />
    <PackageVersion Include="HotChocolate.Data.EntityFramework" Version="13.9.11" />
    <PackageVersion Include="IronCompress" Version="1.5.2" />
    <PackageVersion Include="Microsoft.ApplicationInsights.AspNetCore" Version="2.22.0" />
    <PackageVersion Include="Microsoft.ApplicationInsights.WorkerService" Version="2.22.0" />
    <PackageVersion Include="Microsoft.Azure.Devices" Version="1.39.1" />
    <PackageVersion Include="Microsoft.Azure.Devices.Client" Version="1.42.3" />
    <PackageVersion Include="Microsoft.Azure.Devices.Provisioning.Client" Version="1.19.4" />
    <PackageVersion Include="Microsoft.Azure.Devices.Provisioning.Service" Version="1.18.4" />
    <PackageVersion Include="Microsoft.Azure.Devices.Provisioning.Transport.Http" Version="1.15.4" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker" Version="1.23.0" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.ApplicationInsights" Version="1.3.0" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.Extensions.EventHubs" Version="6.3.5" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.Extensions.Http" Version="3.2.0" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.Extensions.Sql" Version="3.0.534" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.Extensions.Timer" Version="4.3.1" />
    <PackageVersion Include="Microsoft.Azure.Functions.Worker.Sdk" Version="1.17.4" />
    <PackageVersion Include="Microsoft.CodeAnalysis.Analyzers" Version="3.3.4" />
    <PackageVersion Include="Microsoft.CodeAnalysis.CSharp" Version="4.11.0" />
    <PackageVersion Include="Microsoft.CodeAnalysis.CSharp.Workspaces" Version="4.11.0" />
    <PackageVersion Include="Microsoft.Data.Analysis" Version="0.21.1" />
    <PackageVersion Include="Microsoft.Data.SqlClient" Version="5.2.2" />
    <PackageVersion Include="Microsoft.Data.Sqlite" Version="8.0.8" />
    <PackageVersion Include="Microsoft.EntityFrameworkCore" Version="8.0.8" />
    <PackageVersion Include="Microsoft.EntityFrameworkCore.SqlServer" Version="8.0.8" />
    <PackageVersion Include="Microsoft.EntityFrameworkCore.SqlServer.HierarchyId" Version="8.0.8" />
    <PackageVersion Include="Microsoft.Extensions.Caching.Abstractions" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.Caching.Memory" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.Configuration.Binder" Version="8.0.2" />
    <PackageVersion Include="Microsoft.Extensions.Configuration" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.Configuration.EnvironmentVariables" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.Configuration.Json" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.DependencyInjection" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.DependencyInjection.Abstractions" Version="8.0.1" />
    <PackageVersion Include="Microsoft.Extensions.Hosting" Version="8.0.0" />
    <PackageVersion Include="Microsoft.Extensions.Logging.Abstractions" Version="8.0.1" />
    <PackageVersion Include="Microsoft.IO.RecyclableMemoryStream" Version="3.0.1" />
    <PackageVersion Include="Microsoft.NET.Test.Sdk" Version="17.11.0" />
    <PackageVersion Include="MSTest.TestAdapter" Version="3.5.2" />
    <PackageVersion Include="MSTest.TestFramework" Version="3.5.2" />
    <PackageVersion Include="Polly" Version="8.4.1" />
    <PackageVersion Include="Spectre.Console" Version="0.48" />
    <PackageVersion Include="Spectre.Console.Cli" Version="0.48" />
    <PackageVersion Include="StrawberryShake.Server" Version="13.9.11" />
    <PackageVersion Include="xunit" Version="2.9.0" />
    <PackageVersion Include="xunit.runner.visualstudio" Version="2.8.2">
      <PrivateAssets>all</PrivateAssets>
      <IncludeAssets>runtime; build; native; contentfiles; analyzers; buildtransitive</IncludeAssets>
    </PackageVersion>
  </ItemGroup>
  <ItemGroup Label="Transitive packages pinned to certain versions due to security vulnerabilites">
    <PackageVersion Include="Azure.Identity" Version="1.11.4" />
    <PackageVersion Include="Microsoft.Identity.Client" Version="4.61.3" />
    <PackageVersion Include="Microsoft.Rest.ClientRuntime" Version="2.3.24" />
    <PackageVersion Include="System.Net.Http" Version="4.3.4" />
    <PackageVersion Include="System.Text.RegularExpressions" Version="4.3.1" />
    <PackageVersion Include="System.Text.Json" Version="8.0.4" />
  </ItemGroup>
</Project>
@rhyskoedijk
Copy link
Contributor

@Ossiam it is difficult to say where the problem lies, I would need to see the full output logs to understand the problem better, if you are comfortable sharing? If not, could you find the part of the log where "MSTest.TestAdapter" starts doing the NuGet update, it will look something like this:

INFO <job_1726044198> Updating MSTest.TestAdapter...
running NuGet updater:
  ...

There should be some more info below this part of the log to explain why the NuGet update failed.
Problems I've seen before include package restore failed, the build failed, package version was downgraded, target framework conflicts, etc.

Post back with what you find and we can try dig in to it more.

@Ossiam
Copy link
Author

Ossiam commented Sep 12, 2024

Thank you for the reply @rhyskoedijk! I'll post a snippet of the logs that relates to MSTest.TestAdapter and the run command

2024-09-11T08:42:38.2697708Z [command]/usr/bin/docker run --rm -i -e DEPENDABOT_PACKAGE_MANAGER=nuget -e DEPENDABOT_OPEN_PULL_REQUESTS_LIMIT=5 -e DEPENDABOT_DIRECTORY=/src -e DEPENDABOT_IGNORE_CONDITIONS=[{"dependency-name":"*","update-types":["version-update:semver-major"]},{"dependency-name":"Spectre.Console"},{"dependency-name":"Spectre.Console.Cli"}] -e DEPENDABOT_DEPENDENCY_GROUPS={"myProject-dependencies":{"patterns":["*"],"update-types":["minor","patch"]}} -e DEPENDABOT_EXTRA_CREDENTIALS=[{"type":"nuget_feed","url":"https://api.nuget.org/v3/index.json"}] -e DEPENDABOT_FAIL_ON_EXCEPTION=true -e AZURE_ORGANIZATION=MyOrg-e AZURE_PROJECT=MyProject -e AZURE_REPOSITORY=MyProject -e AZURE_ACCESS_TOKEN=*** -e AZURE_MERGE_STRATEGY=4 -e AZURE_SET_AUTO_COMPLETE=true ghcr.io/tinglesoftware/dependabot-updater-nuget:1.33 update_script_vnext
2024-09-11T08:42:38.3596787Z Unable to find image 'ghcr.io/tinglesoftware/dependabot-updater-nuget:1.33' locally
2024-09-11T08:42:38.5752953Z 1.33: Pulling from tinglesoftware/dependabot-updater-nuget
2024/09/10 11:24:25 INFO <job_1725966929> Checking if MSTest.TestAdapter 3.0.2 needs updating
2024/09/10 11:24:25 INFO <job_1725966929> Ignored versions:
2024/09/10 11:24:25 INFO <job_1725966929>   version-update:semver-major - from 
🌍 --> GET https://api.nuget.org/v3/registration5-gz-semver2/mstest.testadapter/index.json
🌍 <-- 200 https://api.nuget.org/v3/registration5-gz-semver2/mstest.testadapter/index.json
2024/09/10 11:24:25 INFO <job_1725966929> Filtered out 36 pre-release versions
🌍 --> GET https://api.nuget.org/v3-flatcontainer/mstest.testadapter/3.0.2/mstest.testadapter.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/mstest.testadapter/3.0.2/mstest.testadapter.nuspec
2024/09/10 11:24:25 INFO <job_1725966929> Latest version is 3.5.2
2024/09/10 11:24:25 INFO <job_1725966929> Requirements to unlock all
2024/09/10 11:24:25 INFO <job_1725966929> Requirements update strategy 
Finding updated dependencies for MSTest.TestAdapter.
🌍 --> GET https://api.nuget.org/v3-flatcontainer/mstest.testadapter/3.5.2/mstest.testadapter.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/mstest.testadapter/3.5.2/mstest.testadapter.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.vstestbridge/1.3.2/microsoft.testing.extensions.vstestbridge.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.vstestbridge/1.3.2/microsoft.testing.extensions.vstestbridge.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.telemetry/1.3.2/microsoft.testing.extensions.telemetry.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.telemetry/1.3.2/microsoft.testing.extensions.telemetry.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testing.platform/1.3.2/microsoft.testing.platform.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testing.platform/1.3.2/microsoft.testing.platform.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.applicationinsights/2.22.0/microsoft.applicationinsights.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.applicationinsights/2.22.0/microsoft.applicationinsights.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/system.diagnostics.diagnosticsource/5.0.0/system.diagnostics.diagnosticsource.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/system.diagnostics.diagnosticsource/5.0.0/system.diagnostics.diagnosticsource.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/system.runtime.compilerservices.unsafe/5.0.0/system.runtime.compilerservices.unsafe.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/system.runtime.compilerservices.unsafe/5.0.0/system.runtime.compilerservices.unsafe.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.trxreport.abstractions/1.3.2/microsoft.testing.extensions.trxreport.abstractions.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testing.extensions.trxreport.abstractions/1.3.2/microsoft.testing.extensions.trxreport.abstractions.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testplatform.objectmodel/17.10.0/microsoft.testplatform.objectmodel.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testplatform.objectmodel/17.10.0/microsoft.testplatform.objectmodel.nuspec
🌍 --> GET https://api.nuget.org/v3-flatcontainer/microsoft.testing.platform.msbuild/1.3.2/microsoft.testing.platform.msbuild.nuspec
🌍 <-- 200 https://api.nuget.org/v3-flatcontainer/microsoft.testing.platform.msbuild/1.3.2/microsoft.testing.platform.msbuild.nuspec
2024/09/10 11:24:27 INFO <job_1725966929> Updating MSTest.TestAdapter from 3.0.2 to 3.5.2
2024/09/10 11:24:27 ERROR <job_1725966929> Error processing MSTest.TestAdapter (Dependabot::DependabotError)
2024/09/10 11:24:27 ERROR <job_1725966929> FileUpdater failed
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/dependency_change_builder.rb:69:in `run'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation_2_7.rb:59:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation_2_7.rb:59:in `block in create_validator_method_fast0'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/dependency_change_builder.rb:42:in `create_from'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:167:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:167:in `validate_call_skip_block_type'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:109:in `block in create_validator_slow_skip_block_type'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/group_update_creation.rb:162:in `create_change_for'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation_2_7.rb:1187:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation_2_7.rb:1187:in `block in create_validator_method_medium4'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/group_update_creation.rb:92:in `block in compile_all_dependency_changes_for'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/group_update_creation.rb:56:in `each'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/group_update_creation.rb:56:in `compile_all_dependency_changes_for'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/create_group_update_pull_request.rb:97:in `dependency_change'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/create_group_update_pull_request.rb:61:in `perform'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/group_update_all_versions.rb:137:in `run_grouped_update_for'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/group_update_all_versions.rb:123:in `block in run_grouped_dependency_updates'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/group_update_all_versions.rb:122:in `each'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/group_update_all_versions.rb:122:in `run_grouped_dependency_updates'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater/operations/group_update_all_versions.rb:67:in `perform'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11545/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/updater.rb:45:in `run'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/tinglesoftware/dependabot/commands/update_all_dependencies_synchronous_command.rb:171:in `run_updates_for'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/tinglesoftware/dependabot/commands/update_all_dependencies_synchronous_command.rb:155:in `update_all_dependencies'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/tinglesoftware/dependabot/commands/update_all_dependencies_synchronous_command.rb:59:in `block in perform_job'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/opentelemetry-api-1.3.0/lib/opentelemetry/trace/tracer.rb:37:in `block in in_span'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/opentelemetry-api-1.3.0/lib/opentelemetry/trace.rb:70:in `block in with_span'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/opentelemetry-api-1.3.0/lib/opentelemetry/context.rb:87:in `with_value'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/opentelemetry-api-1.3.0/lib/opentelemetry/trace.rb:70:in `with_span'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/opentelemetry-api-1.3.0/lib/opentelemetry/trace/tracer.rb:37:in `in_span'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/tinglesoftware/dependabot/commands/update_all_dependencies_synchronous_command.rb:48:in `perform_job'
2024/09/10 11:24:27 ERROR <job_1725966929> /home/dependabot/dependabot-updater/lib/dependabot/base_command.rb:37:in `run'
2024/09/10 11:24:27 ERROR <job_1725966929> bin/update_script_vnext.rb:19:in `<main>'


What's weird is that MS.TestAdapter is already at version 3.5.2 in master, so I don't understand why it's listed as 3.0.2 in the logs

@rhyskoedijk
Copy link
Contributor

rhyskoedijk commented Sep 13, 2024

Following the stack trace, it looks like the error is happening because "no files were changed". As you said, the package is probably already up to date or couldn't be updated due to a NuGet error.

Were there any logs between these two lines below?

2024/09/10 11:24:27 INFO <job_1725966929> Updating MSTest.TestAdapter from 3.0.2 to 3.5.2
   ...nuget updater logs?....
2024/09/10 11:24:27 ERROR <job_1725966929> Error processing MSTest.TestAdapter (Dependabot::DependabotError)

I was expecting to see the logs from native NuGet updater tool appear somewhere which would include the path to the project file it is trying to update and might help identify the file it thinks is out of date and is failing on.

@Ossiam
Copy link
Author

Ossiam commented Sep 16, 2024

After some investigation, I discovered that the cause of the error with MSTest.TestAdapter and MSTest.TestFramework was that they existed in Directory.Packages.props, but they were not actually in the .csproj. After removing the references the issue was resolved.

Now I'm trying to figure out why Dependabot in the second repo only outputs "unknown_error" at the end.
The only errors that are listed in the logs are these (Something you recognize?):

2024/09/13 09:29:07 INFO <job_1726218773> Creating pull request for 'Bump the project-dependencies group in /src with 12 updates'.

2024/09/13 09:29:07 ERROR <job_1726218773> Error while generating commit message: GET https://api.github.com/repos/ChilliCream/graphql-platform/contents/: 403 - API rate limit exceeded for 13.64.12.214. (But here's the good news: Authenticated requests get a higher rate limit. Check out the documentation for more details.) // See: https://docs.github.com/rest/overview/resources-in-the-rest-api#rate-limiting
2024/09/13 09:29:07 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/octokit-6.1.1/lib/octokit/response/raise_error.rb:14:in `on_complete'

2024/09/13 09:29:09 ERROR <job_1726218773> Error while generating commit message: GET https://api.github.com/repos/fluentassertions/fluentassertions/releases?per_page=100: 403 - API rate limit exceeded for 13.64.12.214. (But here's the good news: Authenticated requests get a higher rate limit. Check out the documentation for more details.) // See: https://docs.github.com/rest/overview/resources-in-the-rest-api#rate-limiting
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/octokit-6.1.1/lib/octokit/response/raise_error.rb:14:in `on_complete'

2024/09/13 09:29:09 ERROR <job_1726218773> Error processing job (Dependabot::Clients::Azure::Forbidden)
2024/09/13 09:29:09 ERROR <job_1726218773> Dependabot::Clients::Azure::Forbidden
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/dependabot-common-0.275.0/lib/dependabot/clients/azure.rb:388:in `post'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/dependabot-common-0.275.0/lib/dependabot/clients/azure.rb:207:in `create_commit'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/dependabot-common-0.275.0/lib/dependabot/pull_request_creator/azure.rb:140:in `create_commit'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/dependabot-common-0.275.0/lib/dependabot/pull_request_creator/azure.rb:101:in `create'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/dependabot-common-0.275.0/lib/dependabot/pull_request_creator.rb:246:in `create'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/_methods.rb:277:in `block in _on_method_added'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/lib/tinglesoftware/dependabot/api_clients/azure_api_client.rb:79:in `create_pull_request'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `bind_call'
2024/09/13 09:29:09 ERROR <job_1726218773> /home/dependabot/dependabot-updater/vendor/ruby/3.3.0/gems/sorbet-runtime-0.5.11558/lib/types/private/methods/call_validation.rb:270:in `validate_call'

@Ossiam
Copy link
Author

Ossiam commented Sep 18, 2024

I've resolved the issue. The cause was that the Project Collection Build Service user that Dependabot uses to publish PRs didn't have the correct permissions in the repo. After giving it the correct permissions the issue was resolved.

It would have been much easier to diagnose if Dependabot actually specified this in the logs. Is that something that could be investigated/added?

@Ossiam Ossiam closed this as completed Sep 18, 2024
@Ossiam
Copy link
Author

Ossiam commented Sep 18, 2024

@rhyskoedijk

@rhyskoedijk
Copy link
Contributor

It would have been much easier to diagnose if Dependabot actually specified this in the logs. Is that something that could be investigated/added?

I hear what you mean, that has been a pretty common problem in the issue history.
I will see if I can make permission errors more verbose in the new V2 task.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants