2 * printk_safe.c - Safe printk for printk-deadlock-prone contexts
4 * This program is free software; you can redistribute it and/or
5 * modify it under the terms of the GNU General Public License
6 * as published by the Free Software Foundation; either version 2
7 * of the License, or (at your option) any later version.
9 * This program is distributed in the hope that it will be useful,
10 * but WITHOUT ANY WARRANTY; without even the implied warranty of
11 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
12 * GNU General Public License for more details.
14 * You should have received a copy of the GNU General Public License
15 * along with this program; if not, see <http://www.gnu.org/licenses/>.
18 #include <linux/preempt.h>
19 #include <linux/spinlock.h>
20 #include <linux/debug_locks.h>
21 #include <linux/smp.h>
22 #include <linux/cpumask.h>
23 #include <linux/irq_work.h>
24 #include <linux/printk.h>
29 * printk() could not take logbuf_lock in NMI context. Instead,
30 * it uses an alternative implementation that temporary stores
31 * the strings into a per-CPU buffer. The content of the buffer
32 * is later flushed into the main ring buffer via IRQ work.
34 * The alternative implementation is chosen transparently
35 * by examinig current printk() context mask stored in @printk_context
38 * The implementation allows to flush the strings also from another CPU.
39 * There are situations when we want to make sure that all buffers
40 * were handled or when IRQs are blocked.
42 static int printk_safe_irq_ready
;
44 #define SAFE_LOG_BUF_LEN ((1 << CONFIG_PRINTK_SAFE_LOG_BUF_SHIFT) - \
47 sizeof(struct irq_work))
49 struct printk_safe_seq_buf
{
50 atomic_t len
; /* length of written data */
51 atomic_t message_lost
;
52 struct irq_work work
; /* IRQ work that flushes the buffer */
53 unsigned char buffer
[SAFE_LOG_BUF_LEN
];
56 static DEFINE_PER_CPU(struct printk_safe_seq_buf
, safe_print_seq
);
57 static DEFINE_PER_CPU(int, printk_context
);
59 #ifdef CONFIG_PRINTK_NMI
60 static DEFINE_PER_CPU(struct printk_safe_seq_buf
, nmi_print_seq
);
63 /* Get flushed in a more safe context. */
64 static void queue_flush_work(struct printk_safe_seq_buf
*s
)
66 if (printk_safe_irq_ready
) {
67 /* Make sure that IRQ work is really initialized. */
69 irq_work_queue(&s
->work
);
74 * Add a message to per-CPU context-dependent buffer. NMI and printk-safe
75 * have dedicated buffers, because otherwise printk-safe preempted by
76 * NMI-printk would have overwritten the NMI messages.
78 * The messages are fushed from irq work (or from panic()), possibly,
79 * from other CPU, concurrently with printk_safe_log_store(). Should this
80 * happen, printk_safe_log_store() will notice the buffer->len mismatch
81 * and repeat the write.
83 static __printf(2, 0) int printk_safe_log_store(struct printk_safe_seq_buf
*s
,
84 const char *fmt
, va_list args
)
90 len
= atomic_read(&s
->len
);
92 /* The trailing '\0' is not counted into len. */
93 if (len
>= sizeof(s
->buffer
) - 1) {
94 atomic_inc(&s
->message_lost
);
100 * Make sure that all old data have been read before the buffer
101 * was reset. This is not needed when we just append data.
106 add
= vscnprintf(s
->buffer
+ len
, sizeof(s
->buffer
) - len
, fmt
, args
);
111 * Do it once again if the buffer has been flushed in the meantime.
112 * Note that atomic_cmpxchg() is an implicit memory barrier that
113 * makes sure that the data were written before updating s->len.
115 if (atomic_cmpxchg(&s
->len
, len
, len
+ add
) != len
)
122 static inline void printk_safe_flush_line(const char *text
, int len
)
125 * Avoid any console drivers calls from here, because we may be
126 * in NMI or printk_safe context (when in panic). The messages
127 * must go only into the ring buffer at this stage. Consoles will
128 * get explicitly called later when a crashdump is not generated.
130 printk_deferred("%.*s", len
, text
);
133 /* printk part of the temporary buffer line by line */
134 static int printk_safe_flush_buffer(const char *start
, size_t len
)
143 /* Print line by line. */
146 printk_safe_flush_line(start
, c
- start
+ 1);
152 /* Handle continuous lines or missing new line. */
153 if ((c
+ 1 < end
) && printk_get_level(c
)) {
155 c
= printk_skip_level(c
);
159 printk_safe_flush_line(start
, c
- start
);
169 /* Check if there was a partial line. Ignore pure header. */
170 if (start
< end
&& !header
) {
171 static const char newline
[] = KERN_CONT
"\n";
173 printk_safe_flush_line(start
, end
- start
);
174 printk_safe_flush_line(newline
, strlen(newline
));
180 static void report_message_lost(struct printk_safe_seq_buf
*s
)
182 int lost
= atomic_xchg(&s
->message_lost
, 0);
185 printk_deferred("Lost %d message(s)!\n", lost
);
189 * Flush data from the associated per-CPU buffer. The function
190 * can be called either via IRQ work or independently.
192 static void __printk_safe_flush(struct irq_work
*work
)
194 static raw_spinlock_t read_lock
=
195 __RAW_SPIN_LOCK_INITIALIZER(read_lock
);
196 struct printk_safe_seq_buf
*s
=
197 container_of(work
, struct printk_safe_seq_buf
, work
);
203 * The lock has two functions. First, one reader has to flush all
204 * available message to make the lockless synchronization with
205 * writers easier. Second, we do not want to mix messages from
206 * different CPUs. This is especially important when printing
209 raw_spin_lock_irqsave(&read_lock
, flags
);
213 len
= atomic_read(&s
->len
);
216 * This is just a paranoid check that nobody has manipulated
217 * the buffer an unexpected way. If we printed something then
218 * @len must only increase. Also it should never overflow the
221 if ((i
&& i
>= len
) || len
> sizeof(s
->buffer
)) {
222 const char *msg
= "printk_safe_flush: internal error\n";
224 printk_safe_flush_line(msg
, strlen(msg
));
229 goto out
; /* Someone else has already flushed the buffer. */
231 /* Make sure that data has been written up to the @len */
233 i
+= printk_safe_flush_buffer(s
->buffer
+ i
, len
- i
);
236 * Check that nothing has got added in the meantime and truncate
237 * the buffer. Note that atomic_cmpxchg() is an implicit memory
238 * barrier that makes sure that the data were copied before
241 if (atomic_cmpxchg(&s
->len
, len
, 0) != len
)
245 report_message_lost(s
);
246 raw_spin_unlock_irqrestore(&read_lock
, flags
);
250 * printk_safe_flush - flush all per-cpu nmi buffers.
252 * The buffers are flushed automatically via IRQ work. This function
253 * is useful only when someone wants to be sure that all buffers have
254 * been flushed at some point.
256 void printk_safe_flush(void)
260 for_each_possible_cpu(cpu
) {
261 #ifdef CONFIG_PRINTK_NMI
262 __printk_safe_flush(&per_cpu(nmi_print_seq
, cpu
).work
);
264 __printk_safe_flush(&per_cpu(safe_print_seq
, cpu
).work
);
269 * printk_safe_flush_on_panic - flush all per-cpu nmi buffers when the system
272 * Similar to printk_safe_flush() but it can be called even in NMI context when
273 * the system goes down. It does the best effort to get NMI messages into
274 * the main ring buffer.
276 * Note that it could try harder when there is only one CPU online.
278 void printk_safe_flush_on_panic(void)
281 * Make sure that we could access the main ring buffer.
282 * Do not risk a double release when more CPUs are up.
284 if (in_nmi() && raw_spin_is_locked(&logbuf_lock
)) {
285 if (num_online_cpus() > 1)
289 raw_spin_lock_init(&logbuf_lock
);
295 #ifdef CONFIG_PRINTK_NMI
297 * Safe printk() for NMI context. It uses a per-CPU buffer to
298 * store the message. NMIs are not nested, so there is always only
299 * one writer running. But the buffer might get flushed from another
300 * CPU, so we need to be careful.
302 static __printf(1, 0) int vprintk_nmi(const char *fmt
, va_list args
)
304 struct printk_safe_seq_buf
*s
= this_cpu_ptr(&nmi_print_seq
);
306 return printk_safe_log_store(s
, fmt
, args
);
309 void printk_nmi_enter(void)
312 * The size of the extra per-CPU buffer is limited. Use it only when
313 * the main one is locked. If this CPU is not in the safe context,
314 * the lock must be taken on another CPU and we could wait for it.
316 if ((this_cpu_read(printk_context
) & PRINTK_SAFE_CONTEXT_MASK
) &&
317 raw_spin_is_locked(&logbuf_lock
)) {
318 this_cpu_or(printk_context
, PRINTK_NMI_CONTEXT_MASK
);
320 this_cpu_or(printk_context
, PRINTK_NMI_DEFERRED_CONTEXT_MASK
);
324 void printk_nmi_exit(void)
326 this_cpu_and(printk_context
,
327 ~(PRINTK_NMI_CONTEXT_MASK
|
328 PRINTK_NMI_DEFERRED_CONTEXT_MASK
));
333 static __printf(1, 0) int vprintk_nmi(const char *fmt
, va_list args
)
338 #endif /* CONFIG_PRINTK_NMI */
341 * Lock-less printk(), to avoid deadlocks should the printk() recurse
342 * into itself. It uses a per-CPU buffer to store the message, just like
345 static __printf(1, 0) int vprintk_safe(const char *fmt
, va_list args
)
347 struct printk_safe_seq_buf
*s
= this_cpu_ptr(&safe_print_seq
);
349 return printk_safe_log_store(s
, fmt
, args
);
352 /* Can be preempted by NMI. */
353 void __printk_safe_enter(void)
355 this_cpu_inc(printk_context
);
358 /* Can be preempted by NMI. */
359 void __printk_safe_exit(void)
361 this_cpu_dec(printk_context
);
364 __printf(1, 0) int vprintk_func(const char *fmt
, va_list args
)
366 /* Use extra buffer in NMI when logbuf_lock is taken or in safe mode. */
367 if (this_cpu_read(printk_context
) & PRINTK_NMI_CONTEXT_MASK
)
368 return vprintk_nmi(fmt
, args
);
370 /* Use extra buffer to prevent a recursion deadlock in safe mode. */
371 if (this_cpu_read(printk_context
) & PRINTK_SAFE_CONTEXT_MASK
)
372 return vprintk_safe(fmt
, args
);
375 * Use the main logbuf when logbuf_lock is available in NMI.
376 * But avoid calling console drivers that might have their own locks.
378 if (this_cpu_read(printk_context
) & PRINTK_NMI_DEFERRED_CONTEXT_MASK
)
379 return vprintk_deferred(fmt
, args
);
382 return vprintk_default(fmt
, args
);
385 void __init
printk_safe_init(void)
389 for_each_possible_cpu(cpu
) {
390 struct printk_safe_seq_buf
*s
;
392 s
= &per_cpu(safe_print_seq
, cpu
);
393 init_irq_work(&s
->work
, __printk_safe_flush
);
395 #ifdef CONFIG_PRINTK_NMI
396 s
= &per_cpu(nmi_print_seq
, cpu
);
397 init_irq_work(&s
->work
, __printk_safe_flush
);
401 /* Make sure that IRQ works are initialized before enabling. */
403 printk_safe_irq_ready
= 1;
405 /* Flush pending messages that did not have scheduled IRQ works. */