1 /* SPDX-License-Identifier: GPL-2.0+ */
15 #include "alloc-util.h"
16 #include "device-private.h"
17 #include "device-util.h"
20 #include "format-util.h"
21 #include "libudev-util.h"
22 #include "netlink-util.h"
23 #include "path-util.h"
24 #include "process-util.h"
25 #include "rlimit-util.h"
26 #include "signal-util.h"
27 #include "stdio-util.h"
28 #include "string-util.h"
31 #include "udev-builtin.h"
32 #include "udev-event.h"
33 #include "udev-node.h"
34 #include "udev-util.h"
35 #include "udev-watch.h"
36 #include "user-util.h"
38 typedef struct Spawn
{
41 usec_t timeout_warn_usec
;
43 usec_t event_birth_usec
;
52 UdevEvent
*udev_event_new(sd_device
*dev
, usec_t exec_delay_usec
, sd_netlink
*rtnl
) {
57 event
= new(UdevEvent
, 1);
61 *event
= (UdevEvent
) {
62 .dev
= sd_device_ref(dev
),
63 .birth_usec
= now(CLOCK_MONOTONIC
),
64 .exec_delay_usec
= exec_delay_usec
,
65 .rtnl
= sd_netlink_ref(rtnl
),
74 UdevEvent
*udev_event_free(UdevEvent
*event
) {
78 sd_device_unref(event
->dev
);
79 sd_device_unref(event
->dev_db_clone
);
80 sd_netlink_unref(event
->rtnl
);
81 ordered_hashmap_free_free_key(event
->run_list
);
82 ordered_hashmap_free_free_free(event
->seclabel_list
);
83 free(event
->program_result
);
108 struct subst_map_entry
{
111 enum subst_type type
;
114 static const struct subst_map_entry map
[] = {
115 { .name
= "devnode", .fmt
= 'N', .type
= SUBST_DEVNODE
},
116 { .name
= "tempnode", .fmt
= 'N', .type
= SUBST_DEVNODE
},
117 { .name
= "attr", .fmt
= 's', .type
= SUBST_ATTR
},
118 { .name
= "sysfs", .fmt
= 's', .type
= SUBST_ATTR
},
119 { .name
= "env", .fmt
= 'E', .type
= SUBST_ENV
},
120 { .name
= "kernel", .fmt
= 'k', .type
= SUBST_KERNEL
},
121 { .name
= "number", .fmt
= 'n', .type
= SUBST_KERNEL_NUMBER
},
122 { .name
= "driver", .fmt
= 'd', .type
= SUBST_DRIVER
},
123 { .name
= "devpath", .fmt
= 'p', .type
= SUBST_DEVPATH
},
124 { .name
= "id", .fmt
= 'b', .type
= SUBST_ID
},
125 { .name
= "major", .fmt
= 'M', .type
= SUBST_MAJOR
},
126 { .name
= "minor", .fmt
= 'm', .type
= SUBST_MINOR
},
127 { .name
= "result", .fmt
= 'c', .type
= SUBST_RESULT
},
128 { .name
= "parent", .fmt
= 'P', .type
= SUBST_PARENT
},
129 { .name
= "name", .fmt
= 'D', .type
= SUBST_NAME
},
130 { .name
= "links", .fmt
= 'L', .type
= SUBST_LINKS
},
131 { .name
= "root", .fmt
= 'r', .type
= SUBST_ROOT
},
132 { .name
= "sys", .fmt
= 'S', .type
= SUBST_SYS
},
135 static ssize_t
subst_format_var(UdevEvent
*event
,
136 const struct subst_map_entry
*entry
, char *attr
,
137 char *dest
, size_t l
) {
138 sd_device
*parent
, *dev
= event
->dev
;
139 const char *val
= NULL
;
146 switch (entry
->type
) {
148 r
= sd_device_get_devpath(dev
, &val
);
151 l
= strpcpy(&s
, l
, val
);
154 r
= sd_device_get_sysname(dev
, &val
);
157 l
= strpcpy(&s
, l
, val
);
159 case SUBST_KERNEL_NUMBER
:
160 r
= sd_device_get_sysnum(dev
, &val
);
165 l
= strpcpy(&s
, l
, val
);
168 if (!event
->dev_parent
)
170 r
= sd_device_get_sysname(event
->dev_parent
, &val
);
173 l
= strpcpy(&s
, l
, val
);
176 if (!event
->dev_parent
)
178 r
= sd_device_get_driver(event
->dev_parent
, &val
);
183 l
= strpcpy(&s
, l
, val
);
187 char buf
[DECIMAL_STR_MAX(unsigned)];
189 r
= sd_device_get_devnum(dev
, &devnum
);
190 if (r
< 0 && r
!= -ENOENT
)
192 xsprintf(buf
, "%u", r
< 0 ? 0 : entry
->type
== SUBST_MAJOR
? major(devnum
) : minor(devnum
));
193 l
= strpcpy(&s
, l
, buf
);
200 if (!event
->program_result
)
203 /* get part of the result string */
206 i
= strtoul(attr
, &rest
, 10);
208 char result
[UTIL_PATH_SIZE
], tmp
[UTIL_PATH_SIZE
], *cpos
;
210 strscpy(result
, sizeof(result
), event
->program_result
);
213 while (cpos
[0] != '\0' && !isspace(cpos
[0]))
215 while (isspace(cpos
[0]))
221 log_error("requested part of result string not found");
224 strscpy(tmp
, sizeof(tmp
), cpos
);
225 /* %{2+}c copies the whole string from the second part on */
226 if (rest
[0] != '+') {
227 cpos
= strchr(tmp
, ' ');
231 l
= strpcpy(&s
, l
, tmp
);
233 l
= strpcpy(&s
, l
, event
->program_result
);
237 char vbuf
[UTIL_NAME_SIZE
];
244 /* try to read the value specified by "[dmi/id]product_name" */
245 if (util_resolve_subsys_kernel(attr
, vbuf
, sizeof(vbuf
), true) == 0)
248 /* try to read the attribute the device */
250 (void) sd_device_get_sysattr_value(dev
, attr
, &val
);
252 /* try to read the attribute of the parent device, other matches have selected */
253 if (!val
&& event
->dev_parent
&& event
->dev_parent
!= dev
)
254 (void) sd_device_get_sysattr_value(event
->dev_parent
, attr
, &val
);
259 /* strip trailing whitespace, and replace unwanted characters */
261 strscpy(vbuf
, sizeof(vbuf
), val
);
263 while (len
> 0 && isspace(vbuf
[--len
]))
265 count
= util_replace_chars(vbuf
, UDEV_ALLOWED_CHARS_INPUT
);
267 log_device_debug(dev
, "%i character(s) replaced", count
);
268 l
= strpcpy(&s
, l
, vbuf
);
272 r
= sd_device_get_parent(dev
, &parent
);
277 r
= sd_device_get_devname(parent
, &val
);
282 l
= strpcpy(&s
, l
, val
+ STRLEN("/dev/"));
285 r
= sd_device_get_devname(dev
, &val
);
290 l
= strpcpy(&s
, l
, val
);
294 l
= strpcpy(&s
, l
, event
->name
);
295 else if (sd_device_get_devname(dev
, &val
) >= 0)
296 l
= strpcpy(&s
, l
, val
+ STRLEN("/dev/"));
298 r
= sd_device_get_sysname(dev
, &val
);
301 l
= strpcpy(&s
, l
, val
);
305 FOREACH_DEVICE_DEVLINK(dev
, val
)
307 l
= strpcpy(&s
, l
, val
+ STRLEN("/dev/"));
309 l
= strpcpyl(&s
, l
, " ", val
+ STRLEN("/dev/"), NULL
);
314 l
= strpcpy(&s
, l
, "/dev");
317 l
= strpcpy(&s
, l
, "/sys");
322 r
= sd_device_get_property_value(dev
, attr
, &val
);
327 l
= strpcpy(&s
, l
, val
);
330 assert_not_reached("Unknown format substitution type");
340 ssize_t
udev_event_apply_format(UdevEvent
*event
,
341 const char *src
, char *dest
, size_t size
,
342 bool replace_whitespace
) {
358 const struct subst_map_entry
*entry
= NULL
;
359 char attrbuf
[UTIL_PATH_SIZE
], *attr
;
360 bool format_dollar
= false;
363 while (from
[0] != '\0') {
364 if (from
[0] == '$') {
365 /* substitute named variable */
368 if (from
[1] == '$') {
373 for (i
= 0; i
< ELEMENTSOF(map
); i
++) {
374 if (startswith(&from
[1], map
[i
].name
)) {
376 from
+= strlen(map
[i
].name
)+1;
377 format_dollar
= true;
381 } else if (from
[0] == '%') {
382 /* substitute format char */
385 if (from
[1] == '%') {
390 for (i
= 0; i
< ELEMENTSOF(map
); i
++) {
391 if (from
[1] == map
[i
].fmt
) {
400 if (l
< 2) /* need space for this char and the terminating NUL */
410 /* extract possible $format{attr} */
411 if (from
[0] == '{') {
415 for (i
= 0; from
[i
] != '}'; i
++)
416 if (from
[i
] == '\0') {
417 log_error("missing closing brace for format '%s'", src
);
421 if (i
>= sizeof(attrbuf
))
423 memcpy(attrbuf
, from
, i
);
430 subst_len
= subst_format_var(event
, entry
, attr
, s
, l
);
433 log_device_warning_errno(event
->dev
, subst_len
, "Failed to substitute variable '$%s', ignoring: %m", entry
->name
);
435 log_device_warning_errno(event
->dev
, subst_len
, "Failed to apply format '%%%c', ignoring: %m", entry
->fmt
);
440 /* SUBST_RESULT handles spaces itself */
441 if (replace_whitespace
&& entry
->type
!= SUBST_RESULT
)
442 /* util_replace_whitespace can replace in-place,
443 * and does nothing if subst_len == 0
445 subst_len
= util_replace_whitespace(s
, s
, subst_len
);
457 static int on_spawn_io(sd_event_source
*s
, int fd
, uint32_t revents
, void *userdata
) {
458 Spawn
*spawn
= userdata
;
464 assert(fd
== spawn
->fd_stdout
|| fd
== spawn
->fd_stderr
);
465 assert(!spawn
->result
|| spawn
->result_len
< spawn
->result_size
);
467 if (fd
== spawn
->fd_stdout
&& spawn
->result
) {
468 p
= spawn
->result
+ spawn
->result_len
;
469 size
= spawn
->result_size
- spawn
->result_len
;
475 l
= read(fd
, p
, size
- 1);
478 log_error_errno(errno
, "Failed to read stdout of '%s': %m", spawn
->cmd
);
484 if (fd
== spawn
->fd_stdout
&& spawn
->result
)
485 spawn
->result_len
+= l
;
487 /* Log output only if we watch stderr. */
488 if (l
> 0 && spawn
->fd_stderr
>= 0) {
489 _cleanup_strv_free_
char **v
= NULL
;
492 v
= strv_split_newlines(p
);
497 log_debug("'%s'(%s) '%s'", spawn
->cmd
,
498 fd
== spawn
->fd_stdout
? "out" : "err", *q
);
504 static int on_spawn_timeout(sd_event_source
*s
, uint64_t usec
, void *userdata
) {
505 Spawn
*spawn
= userdata
;
506 char timeout
[FORMAT_TIMESPAN_MAX
];
510 kill_and_sigcont(spawn
->pid
, SIGKILL
);
512 log_error("Spawned process '%s' ["PID_FMT
"] timed out after %s, killing", spawn
->cmd
, spawn
->pid
,
513 format_timespan(timeout
, sizeof(timeout
), spawn
->timeout_usec
, USEC_PER_SEC
));
518 static int on_spawn_timeout_warning(sd_event_source
*s
, uint64_t usec
, void *userdata
) {
519 Spawn
*spawn
= userdata
;
520 char timeout
[FORMAT_TIMESPAN_MAX
];
524 log_warning("Spawned process '%s' ["PID_FMT
"] is taking longer than %s to complete", spawn
->cmd
, spawn
->pid
,
525 format_timespan(timeout
, sizeof(timeout
), spawn
->timeout_warn_usec
, USEC_PER_SEC
));
530 static int on_spawn_sigchld(sd_event_source
*s
, const siginfo_t
*si
, void *userdata
) {
531 Spawn
*spawn
= userdata
;
536 switch (si
->si_code
) {
538 if (si
->si_status
== 0)
539 log_debug("Process '%s' succeeded.", spawn
->cmd
);
541 log_full(spawn
->accept_failure
? LOG_DEBUG
: LOG_WARNING
,
542 "Process '%s' failed with exit code %i.", spawn
->cmd
, si
->si_status
);
547 log_error("Process '%s' terminated by signal %s.", spawn
->cmd
, signal_to_string(si
->si_status
));
550 log_error("Process '%s' failed due to unknown reason.", spawn
->cmd
);
553 sd_event_exit(sd_event_source_get_event(s
), ret
);
557 static int spawn_wait(Spawn
*spawn
) {
558 _cleanup_(sd_event_unrefp
) sd_event
*e
= NULL
;
563 r
= sd_event_new(&e
);
567 if (spawn
->timeout_usec
> 0) {
568 usec_t usec
, age_usec
;
570 usec
= now(CLOCK_MONOTONIC
);
571 age_usec
= usec
- spawn
->event_birth_usec
;
572 if (age_usec
< spawn
->timeout_usec
) {
573 if (spawn
->timeout_warn_usec
> 0 &&
574 spawn
->timeout_warn_usec
< spawn
->timeout_usec
&&
575 spawn
->timeout_warn_usec
> age_usec
) {
576 spawn
->timeout_warn_usec
-= age_usec
;
578 r
= sd_event_add_time(e
, NULL
, CLOCK_MONOTONIC
,
579 usec
+ spawn
->timeout_warn_usec
, USEC_PER_SEC
,
580 on_spawn_timeout_warning
, spawn
);
585 spawn
->timeout_usec
-= age_usec
;
587 r
= sd_event_add_time(e
, NULL
, CLOCK_MONOTONIC
,
588 usec
+ spawn
->timeout_usec
, USEC_PER_SEC
, on_spawn_timeout
, spawn
);
594 if (spawn
->fd_stdout
>= 0) {
595 r
= sd_event_add_io(e
, NULL
, spawn
->fd_stdout
, EPOLLIN
, on_spawn_io
, spawn
);
600 if (spawn
->fd_stderr
>= 0) {
601 r
= sd_event_add_io(e
, NULL
, spawn
->fd_stderr
, EPOLLIN
, on_spawn_io
, spawn
);
606 r
= sd_event_add_child(e
, NULL
, spawn
->pid
, WEXITED
, on_spawn_sigchld
, spawn
);
610 return sd_event_loop(e
);
613 int udev_event_spawn(UdevEvent
*event
,
617 char *result
, size_t ressize
) {
618 _cleanup_close_pair_
int outpipe
[2] = {-1, -1}, errpipe
[2] = {-1, -1};
619 _cleanup_strv_free_
char **argv
= NULL
;
627 assert(result
|| ressize
== 0);
629 /* pipes from child to parent */
630 if (result
|| log_get_max_level() >= LOG_INFO
)
631 if (pipe2(outpipe
, O_NONBLOCK
|O_CLOEXEC
) != 0)
632 return log_error_errno(errno
, "Failed to create pipe for command '%s': %m", cmd
);
634 if (log_get_max_level() >= LOG_INFO
)
635 if (pipe2(errpipe
, O_NONBLOCK
|O_CLOEXEC
) != 0)
636 return log_error_errno(errno
, "Failed to create pipe for command '%s': %m", cmd
);
638 argv
= strv_split_full(cmd
, NULL
, SPLIT_QUOTES
|SPLIT_RELAX
);
642 if (isempty(argv
[0]))
643 return log_error_errno(SYNTHETIC_ERRNO(EINVAL
),
644 "Invalid command '%s'", cmd
);
646 /* allow programs in /usr/lib/udev/ to be called without the path */
647 if (!path_is_absolute(argv
[0])) {
650 program
= path_join(UDEVLIBEXECDIR
, argv
[0]);
654 free_and_replace(argv
[0], program
);
657 r
= device_get_properties_strv(event
->dev
, &envp
);
659 return log_device_error_errno(event
->dev
, r
, "Failed to get device properties");
661 log_debug("Starting '%s'", cmd
);
663 r
= safe_fork("(spawn)", FORK_RESET_SIGNALS
|FORK_DEATHSIG
|FORK_LOG
, &pid
);
665 return log_error_errno(r
, "Failed to fork() to execute command '%s': %m", cmd
);
667 if (rearrange_stdio(-1, outpipe
[WRITE_END
], errpipe
[WRITE_END
]) < 0)
670 (void) close_all_fds(NULL
, 0);
671 (void) rlimit_nofile_safe();
673 execve(argv
[0], argv
, envp
);
677 /* parent closed child's ends of pipes */
678 outpipe
[WRITE_END
] = safe_close(outpipe
[WRITE_END
]);
679 errpipe
[WRITE_END
] = safe_close(errpipe
[WRITE_END
]);
684 .accept_failure
= accept_failure
,
685 .timeout_warn_usec
= udev_warn_timeout(timeout_usec
),
686 .timeout_usec
= timeout_usec
,
687 .event_birth_usec
= event
->birth_usec
,
688 .fd_stdout
= outpipe
[READ_END
],
689 .fd_stderr
= errpipe
[READ_END
],
691 .result_size
= ressize
,
693 r
= spawn_wait(&spawn
);
695 return log_error_errno(r
, "Failed to wait for spawned command '%s': %m", cmd
);
698 result
[spawn
.result_len
] = '\0';
700 return r
; /* 0 for success, and positive if the program failed */
703 static int rename_netif(UdevEvent
*event
) {
704 sd_device
*dev
= event
->dev
;
709 return 0; /* No new name is requested. */
711 r
= sd_device_get_sysname(dev
, &oldname
);
713 return log_device_error_errno(dev
, r
, "Failed to get sysname: %m");
715 if (streq(event
->name
, oldname
))
716 return 0; /* The interface name is already requested name. */
718 if (!device_for_action(dev
, DEVICE_ACTION_ADD
))
719 return 0; /* Rename the interface only when it is added. */
721 r
= sd_device_get_ifindex(dev
, &ifindex
);
723 return 0; /* Device is not a network interface. */
725 return log_device_error_errno(dev
, r
, "Failed to get ifindex: %m");
727 r
= rtnl_set_link_name(&event
->rtnl
, ifindex
, event
->name
);
729 return log_device_error_errno(dev
, r
, "Failed to rename network interface %i from '%s' to '%s': %m",
730 ifindex
, oldname
, event
->name
);
732 /* Set ID_RENAMING boolean property here, and drop it in the corresponding move uevent later. */
733 r
= device_add_property(dev
, "ID_RENAMING", "1");
735 log_device_warning_errno(dev
, r
, "Failed to add 'ID_RENAMING' property: %m");
737 r
= device_rename(dev
, event
->name
);
739 log_device_warning_errno(dev
, r
, "Failed to update properties with new name '%s': %m", event
->name
);
741 log_device_debug(dev
, "Network interface %i is renamed from '%s' to '%s'", ifindex
, oldname
, event
->name
);
746 static int update_devnode(UdevEvent
*event
) {
747 sd_device
*dev
= event
->dev
;
751 r
= sd_device_get_devnum(dev
, NULL
);
755 return log_device_error_errno(dev
, r
, "Failed to get devnum: %m");
757 /* remove/update possible left-over symlinks from old database entry */
758 if (event
->dev_db_clone
)
759 (void) udev_node_update_old_links(dev
, event
->dev_db_clone
);
761 if (!uid_is_valid(event
->uid
)) {
762 r
= device_get_devnode_uid(dev
, &event
->uid
);
766 return log_device_error_errno(dev
, r
, "Failed to get devnode UID: %m");
769 if (!gid_is_valid(event
->gid
)) {
770 r
= device_get_devnode_gid(dev
, &event
->gid
);
774 return log_device_error_errno(dev
, r
, "Failed to get devnode GID: %m");
777 if (event
->mode
== MODE_INVALID
) {
778 r
= device_get_devnode_mode(dev
, &event
->mode
);
779 if (r
< 0 && r
!= -ENOENT
)
780 return log_device_error_errno(dev
, r
, "Failed to get devnode mode: %m");
783 /* default 0660 if a group is assigned */
791 apply
= device_for_action(dev
, DEVICE_ACTION_ADD
) ||
792 uid_is_valid(event
->uid
) ||
793 gid_is_valid(event
->gid
) ||
794 event
->mode
!= MODE_INVALID
;
795 return udev_node_add(dev
, apply
, event
->mode
, event
->uid
, event
->gid
, event
->seclabel_list
);
798 static void event_execute_rules_on_remove(
801 Hashmap
*properties_list
,
804 sd_device
*dev
= event
->dev
;
807 r
= device_read_db_internal(dev
, true);
809 log_device_debug_errno(dev
, r
, "Failed to read database under /run/udev/data/: %m");
811 r
= device_tag_index(dev
, NULL
, false);
813 log_device_debug_errno(dev
, r
, "Failed to remove corresponding tag files under /run/udev/tag/, ignoring: %m");
815 r
= device_delete_db(dev
);
817 log_device_debug_errno(dev
, r
, "Failed to delete database under /run/udev/data/, ignoring: %m");
819 if (sd_device_get_devnum(dev
, NULL
) >= 0)
820 (void) udev_watch_end(dev
);
822 (void) udev_rules_apply_to_event(rules
, event
, timeout_usec
, properties_list
);
824 if (sd_device_get_devnum(dev
, NULL
) >= 0)
825 (void) udev_node_remove(dev
);
828 static int udev_event_on_move(UdevEvent
*event
) {
829 sd_device
*dev
= event
->dev
;
832 if (event
->dev_db_clone
&&
833 sd_device_get_devnum(dev
, NULL
) < 0) {
834 r
= device_copy_properties(dev
, event
->dev_db_clone
);
836 log_device_debug_errno(dev
, r
, "Failed to copy properties from cloned sd_device object, ignoring: %m");
839 /* Drop previously added property */
840 r
= device_add_property(dev
, "ID_RENAMING", NULL
);
842 return log_device_debug_errno(dev
, r
, "Failed to remove 'ID_RENAMING' property, ignoring: %m");
847 int udev_event_execute_rules(UdevEvent
*event
,
849 Hashmap
*properties_list
,
851 const char *subsystem
;
861 r
= sd_device_get_subsystem(dev
, &subsystem
);
863 return log_device_error_errno(dev
, r
, "Failed to get subsystem: %m");
865 r
= device_get_action(dev
, &action
);
867 return log_device_error_errno(dev
, r
, "Failed to get ACTION: %m");
869 if (action
== DEVICE_ACTION_REMOVE
) {
870 event_execute_rules_on_remove(event
, timeout_usec
, properties_list
, rules
);
874 r
= device_clone_with_db(dev
, &event
->dev_db_clone
);
876 log_device_debug_errno(dev
, r
, "Failed to clone sd_device object, ignoring: %m");
878 if (event
->dev_db_clone
&& sd_device_get_devnum(dev
, NULL
) >= 0)
879 /* Disable watch during event processing. */
880 (void) udev_watch_end(event
->dev_db_clone
);
882 if (action
== DEVICE_ACTION_MOVE
)
883 (void) udev_event_on_move(event
);
885 (void) udev_rules_apply_to_event(rules
, event
, timeout_usec
, properties_list
);
887 (void) rename_netif(event
);
888 (void) update_devnode(event
);
890 /* preserve old, or get new initialization timestamp */
891 r
= device_ensure_usec_initialized(dev
, event
->dev_db_clone
);
893 log_device_debug_errno(dev
, r
, "Failed to set initialization timestamp, ignoring: %m");
895 /* (re)write database file */
896 r
= device_tag_index(dev
, event
->dev_db_clone
, true);
898 log_device_debug_errno(dev
, r
, "Failed to update tags under /run/udev/tag/, ignoring: %m");
900 r
= device_update_db(dev
);
902 log_device_debug_errno(dev
, r
, "Failed to update database under /run/udev/data/, ignoring: %m");
904 device_set_is_initialized(dev
);
906 event
->dev_db_clone
= sd_device_unref(event
->dev_db_clone
);
911 void udev_event_execute_run(UdevEvent
*event
, usec_t timeout_usec
) {
917 ORDERED_HASHMAP_FOREACH_KEY(val
, cmd
, event
->run_list
, i
) {
918 UdevBuiltinCommand builtin_cmd
= PTR_TO_UDEV_BUILTIN_CMD(val
);
919 char command
[UTIL_PATH_SIZE
];
921 (void) udev_event_apply_format(event
, cmd
, command
, sizeof(command
), false);
923 if (builtin_cmd
!= _UDEV_BUILTIN_INVALID
) {
924 log_device_debug(event
->dev
, "Running built-in command \"%s\"", command
);
925 r
= udev_builtin_run(event
->dev
, builtin_cmd
, command
, false);
927 log_device_debug_errno(event
->dev
, r
, "Failed to run built-in command \"%s\", ignoring: %m", command
);
929 if (event
->exec_delay_usec
> 0) {
930 char buf
[FORMAT_TIMESPAN_MAX
];
932 log_device_debug(event
->dev
, "Delaying execution of \"%s\" for %s.",
933 command
, format_timespan(buf
, sizeof(buf
), event
->exec_delay_usec
, USEC_PER_SEC
));
934 (void) usleep(event
->exec_delay_usec
);
937 log_device_debug(event
->dev
, "Running command \"%s\"", command
);
938 r
= udev_event_spawn(event
, timeout_usec
, false, command
, NULL
, 0);
939 if (r
> 0) /* returned value is positive when program fails */
940 log_device_debug(event
->dev
, "Command \"%s\" returned %d (error), ignoring.", command
, r
);