[Bug]: Exception OCA\Provisioning_API\Controller\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in lib/private/AppFramework/Http/Dispatcher.php on line 208 in file lib/Controller/UsersController.php line 893

[jendrikw]

⚠️ This issue respects the following points: ⚠️

• This is a bug, not a question or a configuration/webserver/proxy issue.
• This issue is not already reported on Github OR Nextcloud Community Forum (I've searched it).
• Nextcloud Server is up to date. See Maintenance and Release Schedule for supported versions.
• I agree to follow Nextcloud's Code of Conduct.

Bug description

There's an Error in the log:

Exception OCA\Provisioning_API\Controller\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208 in file '/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php' line 893

Steps to reproduce

I'm not sure

Expected behavior

No errors in log

Nextcloud Server version

30

Operating system

RHEL/CentOS

PHP engine version

PHP 8.3

Web server

Apache (supported)

Database engine version

MySQL

Is this bug present after an update or on a fresh install?

Fresh Nextcloud Server install

Are you using the Nextcloud Server Encryption module?

Encryption is Disabled

What user-backends are you using?

• Default user-backend (database)
• LDAP/ Active Directory
• SSO - SAML
• Other

Configuration report

{
    "system": {
        "passwordsalt": "***REMOVED SENSITIVE VALUE***",
        "secret": "***REMOVED SENSITIVE VALUE***",
        "trusted_domains": [
            "cloud.jendrik.eu"
        ],
        "datadirectory": "***REMOVED SENSITIVE VALUE***",
        "dbtype": "mysql",
        "version": "30.0.6.2",
        "overwrite.cli.url": "https:\/\/cloud.jendrik.eu",
        "dbname": "***REMOVED SENSITIVE VALUE***",
        "dbhost": "***REMOVED SENSITIVE VALUE***",
        "dbport": "",
        "dbtableprefix": "oc_",
        "mysql.utf8mb4": true,
        "dbuser": "***REMOVED SENSITIVE VALUE***",
        "dbpassword": "***REMOVED SENSITIVE VALUE***",
        "installed": true,
        "instanceid": "***REMOVED SENSITIVE VALUE***",
        "maintenance": false,
        "mail_domain": "***REMOVED SENSITIVE VALUE***",
        "mail_from_address": "***REMOVED SENSITIVE VALUE***",
        "mail_smtpmode": "sendmail",
        "mail_sendmailmode": "pipe",
        "htaccess.RewriteBase": "\/",
        "memcache.local": "\\OC\\Memcache\\APCu",
        "redis": {
            "host": "***REMOVED SENSITIVE VALUE***",
            "port": "0",
            "timeout": "1.5"
        },
        "filelocking.enabled": "true",
        "memcache.locking": "\\OC\\Memcache\\Redis",
        "memcache.distributed": "\\OC\\Memcache\\Redis",
        "default_phone_region": "DE",
        "maintenance_window_start": 0,
        "skeletondirectory": ""
    }
}

List of activated Apps

Enabled:
  - activity: 3.0.0
  - bruteforcesettings: 3.0.0
  - calendar: 5.1.3
  - circles: 30.0.0
  - cloud_federation_api: 1.13.0
  - contactsinteraction: 1.11.0
  - dashboard: 7.10.0
  - dav: 1.31.1
  - federatedfilesharing: 1.20.0
  - federation: 1.20.0
  - files: 2.2.0
  - files_downloadlimit: 3.0.0
  - files_pdfviewer: 3.0.0
  - files_sharing: 1.22.0
  - files_trashbin: 1.20.1
  - files_versions: 1.23.0
  - firstrunwizard: 3.0.0
  - logreader: 3.0.0
  - lookup_server_connector: 1.18.0
  - nextcloud_announcements: 2.0.0
  - notifications: 3.0.0
  - oauth2: 1.18.1
  - password_policy: 2.0.0
  - privacy: 2.0.0
  - provisioning_api: 1.20.0
  - related_resources: 1.5.0
  - serverinfo: 2.0.0
  - settings: 1.13.0
  - text: 4.1.0
  - theming: 2.6.0
  - twofactor_backupcodes: 1.19.0
  - updatenotification: 1.20.0
  - user_status: 1.10.0
  - viewer: 3.0.0
  - workflowengine: 2.12.0
Disabled:
  - admin_audit: 1.20.0
  - app_api: 4.0.6 (installed 4.0.6)
  - comments: 1.20.1 (installed 1.20.1)
  - encryption: 2.18.0
  - files_external: 1.22.0
  - files_reminders: 1.3.0 (installed 1.3.0)
  - photos: 3.0.2 (installed 3.0.2)
  - recommendations: 3.0.0 (installed 3.0.0)
  - sharebymail: 1.20.0 (installed 1.20.0)
  - support: 2.0.0 (installed 2.0.0)
  - survey_client: 2.0.0 (installed 2.0.0)
  - suspicious_login: 8.0.0
  - systemtags: 1.20.0 (installed 1.20.0)
  - twofactor_nextcloud_notification: 4.0.0
  - twofactor_totp: 12.0.0-dev
  - user_ldap: 1.21.0
  - weather_status: 1.10.0 (installed 1.10.0)
  - webhook_listeners: 1.1.0-dev (installed 1.1.0-dev)

Nextcloud Signing status

No errors have been found.

Nextcloud Logs

{
  "reqId": "Z8_fJp63c72YtzczKQ8pTAAAAAg",
  "level": 3,
  "time": "2025-03-11T06:58:46+00:00",
  "remoteAddr": "2a02:560:558b:c300:db7c:aa40:7ccb:fe54",
  "user": "jonas",
  "app": "no app in context",
  "method": "PUT",
  "url": "/ocs/v2.php/cloud/users/jonas",
  "message": "OCA\\Provisioning_API\\Controller\\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208 in file '/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php' line 893",
  "userAgent": "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0",
  "version": "30.0.6.2",
  "exception": {
    "Exception": "Exception",
    "Message": "OCA\\Provisioning_API\\Controller\\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208 in file '/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php' line 893",
    "Code": 0,
    "Trace": [
      {
        "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/App.php",
        "line": 161,
        "function": "dispatch",
        "class": "OC\\AppFramework\\Http\\Dispatcher",
        "type": "->",
        "args": [
          {
            "__class__": "OCA\\Provisioning_API\\Controller\\UsersController"
          },
          "editUser"
        ]
      },
      {
        "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/Route/Router.php",
        "line": 302,
        "function": "main",
        "class": "OC\\AppFramework\\App",
        "type": "::",
        "args": [
          "OCA\\Provisioning_API\\Controller\\UsersController",
          "editUser",
          {
            "__class__": "OC\\AppFramework\\DependencyInjection\\DIContainer"
          },
          {
            "userId": "jonas",
            "_route": "ocs.provisioning_api.users.edituser"
          }
        ]
      },
      {
        "file": "/var/www/virtual/jendrik/SERVERNAME/ocs/v1.php",
        "line": 43,
        "function": "match",
        "class": "OC\\Route\\Router",
        "type": "->",
        "args": [
          "/ocsapp/cloud/users/jonas"
        ]
      },
      {
        "file": "/var/www/virtual/jendrik/SERVERNAME/ocs/v2.php",
        "line": 7,
        "args": [
          "/var/www/virtual/jendrik/SERVERNAME/ocs/v1.php"
        ],
        "function": "require_once"
      }
    ],
    "File": "/var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php",
    "Line": 146,
    "Previous": {
      "Exception": "TypeError",
      "Message": "OCA\\Provisioning_API\\Controller\\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208",
      "Code": 0,
      "Trace": [
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php",
          "line": 208,
          "function": "editUser",
          "class": "OCA\\Provisioning_API\\Controller\\UsersController",
          "type": "->",
          "args": [
            "jonas",
            "birthdate",
            null
          ]
        },
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php",
          "line": 114,
          "function": "executeController",
          "class": "OC\\AppFramework\\Http\\Dispatcher",
          "type": "->",
          "args": [
            {
              "__class__": "OCA\\Provisioning_API\\Controller\\UsersController"
            },
            "editUser"
          ]
        },
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/App.php",
          "line": 161,
          "function": "dispatch",
          "class": "OC\\AppFramework\\Http\\Dispatcher",
          "type": "->",
          "args": [
            {
              "__class__": "OCA\\Provisioning_API\\Controller\\UsersController"
            },
            "editUser"
          ]
        },
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/lib/private/Route/Router.php",
          "line": 302,
          "function": "main",
          "class": "OC\\AppFramework\\App",
          "type": "::",
          "args": [
            "OCA\\Provisioning_API\\Controller\\UsersController",
            "editUser",
            {
              "__class__": "OC\\AppFramework\\DependencyInjection\\DIContainer"
            },
            {
              "userId": "jonas",
              "_route": "ocs.provisioning_api.users.edituser"
            }
          ]
        },
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/ocs/v1.php",
          "line": 43,
          "function": "match",
          "class": "OC\\Route\\Router",
          "type": "->",
          "args": [
            "/ocsapp/cloud/users/jonas"
          ]
        },
        {
          "file": "/var/www/virtual/jendrik/SERVERNAME/ocs/v2.php",
          "line": 7,
          "args": [
            "/var/www/virtual/jendrik/SERVERNAME/ocs/v1.php"
          ],
          "function": "require_once"
        }
      ],
      "File": "/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php",
      "Line": 893
    },
    "message": "OCA\\Provisioning_API\\Controller\\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208 in file '/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php' line 893",
    "exception": [],
    "CustomMessage": "OCA\\Provisioning_API\\Controller\\UsersController::editUser(): Argument #3 ($value) must be of type string, null given, called in /var/www/virtual/jendrik/SERVERNAME/lib/private/AppFramework/Http/Dispatcher.php on line 208 in file '/var/www/virtual/jendrik/SERVERNAME/apps/provisioning_api/lib/Controller/UsersController.php' line 893"
  },
  "id": "67d29989d7a74"
}

Additional info

No response

[joshtrichards]

It looks like something is trying to set the birthdate to a null value:

          "function": "editUser",
          "class": "OCA\\Provisioning_API\\Controller\\UsersController",
          "type": "->",
          "args": [
            "jonas",
            "birthdate",
            null
          ]
        },

[joshtrichards]

Is it a recurring log entry or just a one-time event?

Happen to have the web server log from the same time period to determine what page it was triggered from?

I can't reproduce this so far from the Personal Settings page where the birthday is typically set.

[jendrikw]

This is what I found, I'm not sure which hour it is because of time zone differences:

logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:15 +0100] "POST /login/confirm HTTP/2.0" 200 44 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:17 +0100] "PUT /ocs/v2.php/cloud/users/jonas HTTP/2.0" 200 81 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:17 +0100] "GET /avatar/jonas/512?v=1741676297068 HTTP/2.0" 200 4016 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:17 +0100] "GET /avatar/jonas/64?v=1741676297068 HTTP/2.0" 200 597 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:19 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 200 461 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:27 +0100] "PUT /ocs/v2.php/cloud/users/jonas HTTP/2.0" 200 81 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:36 +0100] "PUT /ocs/v2.php/cloud/users/jonas HTTP/2.0" 200 81 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:46 +0100] "PUT /ocs/v2.php/cloud/users/jonas HTTP/2.0" 500 1250 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:48 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 304 0 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:07:58:52 +0100] "PUT /ocs/v2.php/cloud/users/jonas HTTP/2.0" 200 81 "https://SERVERNAME/settings/user" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:08:58:08 +0100] "PROPFIND /remote.php/dav/calendars/jonas/ HTTP/2.0" 207 1652 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:08:58:10 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 304 0 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:08:58:38 +0100] "PROPFIND /remote.php/dav/calendars/jonas/ HTTP/2.0" 207 1652 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:08:58:40 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 304 0 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:09:58:25 +0100] "PROPFIND /remote.php/dav/calendars/jonas/ HTTP/2.0" 207 1652 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:09:58:28 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 304 0 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:09:58:55 +0100] "PROPFIND /remote.php/dav/calendars/jonas/ HTTP/2.0" 207 1652 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"
logs/webserver/access_log.5:SERVERNAME:443 2a02:560:: - - [11/Mar/2025:09:58:58 +0100] "GET /ocs/v2.php/apps/notifications/api/v2/notifications HTTP/2.0" 304 0 "https://SERVERNAME/apps/calendar/timeGridWeek/now" "Mozilla/5.0 (X11; Linux x86_64; rv:128.0) Gecko/20100101 Firefox/128.0"

[jendrikw]

The user assured me they triggered it from the profile settings UI, but I can reproduce it with curl by PUTting '{"key":"birthdate","value":null}' to 'https://SERVERNAME/ocs/v2.php/cloud/users/USERNAME', which returns http 500 and logs an exception. An invalid input like this should be caught and result in 422 or 400.

[sorbaugh]

Thanks for reporting @jendrikw ! A "null" value being passed shouldn't happen in the first place. The exception being thrown is therefore correct in your curl example. Did you have any luck reproducing from the UI or are there any other details you can share?