126
123
def rename(self):
127
124
"""Derived from the Avahi example code"""
128
125
if self.rename_count >= self.max_renames:
129
logger.critical(u"No suitable Zeroconf service name found"
130
u" after %i retries, exiting.",
126
logger.critical(u"No suitable service name found after %i"
127
u" retries, exiting.", rename_count)
132
128
raise AvahiServiceError("Too many renames")
133
129
self.name = server.GetAlternativeServiceName(self.name)
134
logger.info(u"Changing Zeroconf service name to %r ...",
136
syslogger.setFormatter(logging.Formatter
130
logger.info(u"Changing name to %r ...", str(self.name))
131
syslogger.setFormatter(logging.Formatter\
137
132
('Mandos (%s): %%(levelname)s:'
138
' %%(message)s' % self.name))
133
' %%(message)s' % self.name))
141
136
self.rename_count += 1
170
165
# End of Avahi example code
173
def _datetime_to_dbus_struct(dt, variant_level=0):
174
"""Convert a UTC datetime.datetime() to a D-Bus struct.
175
The format is special to this application, since we could not find
176
any other standard way."""
177
return dbus.Struct((dbus.Int16(dt.year),
181
dbus.Byte(dt.minute),
182
dbus.Byte(dt.second),
183
dbus.UInt32(dt.microsecond)),
185
variant_level=variant_level)
188
class Client(dbus.service.Object):
168
class Client(object):
189
169
"""A representation of a client host served by this server.
191
name: string; from the config file, used in log messages
171
name: string; from the config file, used in log messages
192
172
fingerprint: string (40 or 32 hexadecimal digits); used to
193
173
uniquely identify the client
194
secret: bytestring; sent verbatim (over TLS) to client
195
host: string; available for use by the checker command
196
created: datetime.datetime(); (UTC) object creation
197
last_started: datetime.datetime(); (UTC)
199
last_checked_ok: datetime.datetime(); (UTC) or None
200
timeout: datetime.timedelta(); How long from last_checked_ok
201
until this client is invalid
202
interval: datetime.timedelta(); How often to start a new checker
203
stop_hook: If set, called by stop() as stop_hook(self)
204
checker: subprocess.Popen(); a running checker process used
205
to see if the client lives.
206
'None' if no process is running.
174
secret: bytestring; sent verbatim (over TLS) to client
175
host: string; available for use by the checker command
176
created: datetime.datetime(); object creation, not client host
177
last_checked_ok: datetime.datetime() or None if not yet checked OK
178
timeout: datetime.timedelta(); How long from last_checked_ok
179
until this client is invalid
180
interval: datetime.timedelta(); How often to start a new checker
181
stop_hook: If set, called by stop() as stop_hook(self)
182
checker: subprocess.Popen(); a running checker process used
183
to see if the client lives.
184
'None' if no process is running.
207
185
checker_initiator_tag: a gobject event source tag, or None
208
186
stop_initiator_tag: - '' -
209
187
checker_callback_tag: - '' -
219
196
_interval_milliseconds: - '' -
221
198
def _set_timeout(self, timeout):
222
"Setter function for the 'timeout' attribute"
199
"Setter function for 'timeout' attribute"
223
200
self._timeout = timeout
224
201
self._timeout_milliseconds = ((self.timeout.days
225
202
* 24 * 60 * 60 * 1000)
226
203
+ (self.timeout.seconds * 1000)
227
204
+ (self.timeout.microseconds
230
self.PropertyChanged(dbus.String(u"timeout"),
231
(dbus.UInt64(self._timeout_milliseconds,
233
timeout = property(lambda self: self._timeout, _set_timeout)
206
timeout = property(lambda self: self._timeout,
236
209
def _set_interval(self, interval):
237
"Setter function for the 'interval' attribute"
210
"Setter function for 'interval' attribute"
238
211
self._interval = interval
239
212
self._interval_milliseconds = ((self.interval.days
240
213
* 24 * 60 * 60 * 1000)
243
216
+ (self.interval.microseconds
246
self.PropertyChanged(dbus.String(u"interval"),
247
(dbus.UInt64(self._interval_milliseconds,
249
interval = property(lambda self: self._interval, _set_interval)
218
interval = property(lambda self: self._interval,
250
220
del _set_interval
252
def __init__(self, name = None, stop_hook=None, config=None):
221
def __init__(self, name = None, stop_hook=None, config={}):
253
222
"""Note: the 'checker' key in 'config' sets the
254
223
'checker_command' attribute and *not* the 'checker'
256
self.dbus_object_path = (dbus.ObjectPath
258
+ name.replace(".", "_")))
259
dbus.service.Object.__init__(self, bus,
260
self.dbus_object_path)
264
226
logger.debug(u"Creating client %r", self.name)
265
227
# Uppercase and remove spaces from fingerprint for later
266
228
# comparison purposes with return value from the fingerprint()
268
self.fingerprint = (config["fingerprint"].upper()
230
self.fingerprint = config["fingerprint"].upper()\
270
232
logger.debug(u" Fingerprint: %s", self.fingerprint)
271
233
if "secret" in config:
272
234
self.secret = config["secret"].decode(u"base64")
273
235
elif "secfile" in config:
274
with closing(open(os.path.expanduser
276
(config["secfile"])))) as secfile:
277
self.secret = secfile.read()
236
sf = open(config["secfile"])
237
self.secret = sf.read()
279
240
raise TypeError(u"No secret or secfile for client %s"
281
242
self.host = config.get("host", "")
282
self.created = datetime.datetime.utcnow()
284
self.last_started = None
243
self.created = datetime.datetime.now()
285
244
self.last_checked_ok = None
286
245
self.timeout = string_to_delta(config["timeout"])
287
246
self.interval = string_to_delta(config["interval"])
290
249
self.checker_initiator_tag = None
291
250
self.stop_initiator_tag = None
292
251
self.checker_callback_tag = None
293
self.checker_command = config["checker"]
252
self.check_command = config["checker"]
296
254
"""Start this client's checker and timeout hooks"""
297
self.last_started = datetime.datetime.utcnow()
298
255
# Schedule a new checker to be started an 'interval' from now,
299
256
# and every interval from then on.
300
self.checker_initiator_tag = (gobject.timeout_add
301
(self._interval_milliseconds,
257
self.checker_initiator_tag = gobject.timeout_add\
258
(self._interval_milliseconds,
303
260
# Also start a new checker *right now*.
304
261
self.start_checker()
305
262
# Schedule a stop() when 'timeout' has passed
306
self.stop_initiator_tag = (gobject.timeout_add
307
(self._timeout_milliseconds,
311
self.PropertyChanged(dbus.String(u"started"),
312
dbus.Boolean(True, variant_level=1))
313
self.PropertyChanged(dbus.String(u"last_started"),
314
(_datetime_to_dbus_struct
315
(self.last_started, variant_level=1)))
263
self.stop_initiator_tag = gobject.timeout_add\
264
(self._timeout_milliseconds,
318
"""Stop this client."""
319
if not getattr(self, "started", False):
268
The possibility that a client might be restarted is left open,
269
but not currently used."""
270
# If this client doesn't have a secret, it is already stopped.
271
if hasattr(self, "secret") and self.secret:
272
logger.info(u"Stopping client %s", self.name)
321
logger.info(u"Stopping client %s", self.name)
322
276
if getattr(self, "stop_initiator_tag", False):
323
277
gobject.source_remove(self.stop_initiator_tag)
324
278
self.stop_initiator_tag = None
328
282
self.stop_checker()
329
283
if self.stop_hook:
330
284
self.stop_hook(self)
333
self.PropertyChanged(dbus.String(u"started"),
334
dbus.Boolean(False, variant_level=1))
335
285
# Do not run this again if called by a gobject.timeout_add
338
287
def __del__(self):
339
288
self.stop_hook = None
342
def checker_callback(self, pid, condition, command):
290
def checker_callback(self, pid, condition):
343
291
"""The checker has completed, so take appropriate actions."""
292
now = datetime.datetime.now()
344
293
self.checker_callback_tag = None
345
294
self.checker = None
347
self.PropertyChanged(dbus.String(u"checker_running"),
348
dbus.Boolean(False, variant_level=1))
349
if (os.WIFEXITED(condition)
350
and (os.WEXITSTATUS(condition) == 0)):
295
if os.WIFEXITED(condition) \
296
and (os.WEXITSTATUS(condition) == 0):
351
297
logger.info(u"Checker for %(name)s succeeded",
354
self.CheckerCompleted(dbus.Boolean(True),
355
dbus.UInt16(condition),
356
dbus.String(command))
299
self.last_checked_ok = now
300
gobject.source_remove(self.stop_initiator_tag)
301
self.stop_initiator_tag = gobject.timeout_add\
302
(self._timeout_milliseconds,
358
304
elif not os.WIFEXITED(condition):
359
305
logger.warning(u"Checker for %(name)s crashed?",
362
self.CheckerCompleted(dbus.Boolean(False),
363
dbus.UInt16(condition),
364
dbus.String(command))
366
308
logger.info(u"Checker for %(name)s failed",
369
self.CheckerCompleted(dbus.Boolean(False),
370
dbus.UInt16(condition),
371
dbus.String(command))
373
def bump_timeout(self):
374
"""Bump up the timeout for this client.
375
This should only be called when the client has been seen,
378
self.last_checked_ok = datetime.datetime.utcnow()
379
gobject.source_remove(self.stop_initiator_tag)
380
self.stop_initiator_tag = (gobject.timeout_add
381
(self._timeout_milliseconds,
383
self.PropertyChanged(dbus.String(u"last_checked_ok"),
384
(_datetime_to_dbus_struct
385
(self.last_checked_ok,
388
310
def start_checker(self):
389
311
"""Start a new checker subprocess if one is not running.
390
312
If a checker already exists, leave it running and do
399
321
# is as it should be.
400
322
if self.checker is None:
402
# In case checker_command has exactly one % operator
403
command = self.checker_command % self.host
324
# In case check_command has exactly one % operator
325
command = self.check_command % self.host
404
326
except TypeError:
405
327
# Escape attributes for the shell
406
328
escaped_attrs = dict((key, re.escape(str(val)))
408
330
vars(self).iteritems())
410
command = self.checker_command % escaped_attrs
332
command = self.check_command % escaped_attrs
411
333
except TypeError, error:
412
334
logger.error(u'Could not format string "%s":'
413
u' %s', self.checker_command, error)
335
u' %s', self.check_command, error)
414
336
return True # Try again later
416
338
logger.info(u"Starting checker %r for %s",
417
339
command, self.name)
418
# We don't need to redirect stdout and stderr, since
419
# in normal mode, that is already done by daemon(),
420
# and in debug mode we don't want to. (Stdin is
421
# always replaced by /dev/null.)
422
340
self.checker = subprocess.Popen(command,
424
342
shell=True, cwd="/")
426
self.CheckerStarted(command)
427
self.PropertyChanged(dbus.String("checker_running"),
428
dbus.Boolean(True, variant_level=1))
429
self.checker_callback_tag = (gobject.child_watch_add
431
self.checker_callback,
433
except OSError, error:
343
self.checker_callback_tag = gobject.child_watch_add\
345
self.checker_callback)
346
except subprocess.OSError, error:
434
347
logger.error(u"Failed to start subprocess: %s",
436
349
# Re-run this periodically if run by gobject.timeout_add
439
351
def stop_checker(self):
440
352
"""Force the checker process, if any, to stop."""
441
353
if self.checker_callback_tag:
453
365
if error.errno != errno.ESRCH: # No such process
455
367
self.checker = None
456
self.PropertyChanged(dbus.String(u"checker_running"),
457
dbus.Boolean(False, variant_level=1))
459
368
def still_valid(self):
460
369
"""Has the timeout not yet passed for this client?"""
461
if not getattr(self, "started", False):
463
now = datetime.datetime.utcnow()
370
now = datetime.datetime.now()
464
371
if self.last_checked_ok is None:
465
372
return now < (self.created + self.timeout)
467
374
return now < (self.last_checked_ok + self.timeout)
469
## D-Bus methods & signals
470
_interface = u"org.mandos_system.Mandos.Client"
472
# BumpTimeout - method
473
BumpTimeout = dbus.service.method(_interface)(bump_timeout)
474
BumpTimeout.__name__ = "BumpTimeout"
476
# CheckerCompleted - signal
477
@dbus.service.signal(_interface, signature="bqs")
478
def CheckerCompleted(self, success, condition, command):
482
# CheckerStarted - signal
483
@dbus.service.signal(_interface, signature="s")
484
def CheckerStarted(self, command):
488
# GetAllProperties - method
489
@dbus.service.method(_interface, out_signature="a{sv}")
490
def GetAllProperties(self):
492
return dbus.Dictionary({
494
dbus.String(self.name, variant_level=1),
495
dbus.String("fingerprint"):
496
dbus.String(self.fingerprint, variant_level=1),
498
dbus.String(self.host, variant_level=1),
499
dbus.String("created"):
500
_datetime_to_dbus_struct(self.created,
502
dbus.String("last_started"):
503
(_datetime_to_dbus_struct(self.last_started,
505
if self.last_started is not None
506
else dbus.Boolean(False, variant_level=1)),
507
dbus.String("started"):
508
dbus.Boolean(self.started, variant_level=1),
509
dbus.String("last_checked_ok"):
510
(_datetime_to_dbus_struct(self.last_checked_ok,
512
if self.last_checked_ok is not None
513
else dbus.Boolean (False, variant_level=1)),
514
dbus.String("timeout"):
515
dbus.UInt64(self._timeout_milliseconds,
517
dbus.String("interval"):
518
dbus.UInt64(self._interval_milliseconds,
520
dbus.String("checker"):
521
dbus.String(self.checker_command,
523
dbus.String("checker_running"):
524
dbus.Boolean(self.checker is not None,
528
# IsStillValid - method
529
IsStillValid = (dbus.service.method(_interface, out_signature="b")
531
IsStillValid.__name__ = "IsStillValid"
533
# PropertyChanged - signal
534
@dbus.service.signal(_interface, signature="sv")
535
def PropertyChanged(self, property, value):
539
# SetChecker - method
540
@dbus.service.method(_interface, in_signature="s")
541
def SetChecker(self, checker):
542
"D-Bus setter method"
543
self.checker_command = checker
546
@dbus.service.method(_interface, in_signature="s")
547
def SetHost(self, host):
548
"D-Bus setter method"
551
# SetInterval - method
552
@dbus.service.method(_interface, in_signature="t")
553
def SetInterval(self, milliseconds):
554
self.interval = datetime.timdeelta(0, 0, 0, milliseconds)
557
@dbus.service.method(_interface, in_signature="ay",
559
def SetSecret(self, secret):
560
"D-Bus setter method"
561
self.secret = str(secret)
563
# SetTimeout - method
564
@dbus.service.method(_interface, in_signature="t")
565
def SetTimeout(self, milliseconds):
566
self.timeout = datetime.timedelta(0, 0, 0, milliseconds)
569
Start = dbus.service.method(_interface)(start)
570
Start.__name__ = "Start"
572
# StartChecker - method
573
@dbus.service.method(_interface)
574
def StartChecker(self):
579
@dbus.service.method(_interface)
584
# StopChecker - method
585
StopChecker = dbus.service.method(_interface)(stop_checker)
586
StopChecker.__name__ = "StopChecker"
591
377
def peer_certificate(session):
592
378
"Return the peer's OpenPGP certificate as a bytestring"
593
379
# If not an OpenPGP certificate...
594
if (gnutls.library.functions
595
.gnutls_certificate_type_get(session._c_object)
596
!= gnutls.library.constants.GNUTLS_CRT_OPENPGP):
380
if gnutls.library.functions.gnutls_certificate_type_get\
381
(session._c_object) \
382
!= gnutls.library.constants.GNUTLS_CRT_OPENPGP:
597
383
# ...do the normal thing
598
384
return session.peer_certificate
599
385
list_size = ctypes.c_uint()
600
cert_list = (gnutls.library.functions
601
.gnutls_certificate_get_peers
602
(session._c_object, ctypes.byref(list_size)))
386
cert_list = gnutls.library.functions.gnutls_certificate_get_peers\
387
(session._c_object, ctypes.byref(list_size))
603
388
if list_size.value == 0:
605
390
cert = cert_list[0]
609
394
def fingerprint(openpgp):
610
395
"Convert an OpenPGP bytestring to a hexdigit fingerprint string"
611
396
# New GnuTLS "datum" with the OpenPGP public key
612
datum = (gnutls.library.types
613
.gnutls_datum_t(ctypes.cast(ctypes.c_char_p(openpgp),
616
ctypes.c_uint(len(openpgp))))
397
datum = gnutls.library.types.gnutls_datum_t\
398
(ctypes.cast(ctypes.c_char_p(openpgp),
399
ctypes.POINTER(ctypes.c_ubyte)),
400
ctypes.c_uint(len(openpgp)))
617
401
# New empty GnuTLS certificate
618
402
crt = gnutls.library.types.gnutls_openpgp_crt_t()
619
(gnutls.library.functions
620
.gnutls_openpgp_crt_init(ctypes.byref(crt)))
403
gnutls.library.functions.gnutls_openpgp_crt_init\
621
405
# Import the OpenPGP public key into the certificate
622
(gnutls.library.functions
623
.gnutls_openpgp_crt_import(crt, ctypes.byref(datum),
624
gnutls.library.constants
625
.GNUTLS_OPENPGP_FMT_RAW))
626
# Verify the self signature in the key
627
crtverify = ctypes.c_uint()
628
(gnutls.library.functions
629
.gnutls_openpgp_crt_verify_self(crt, 0, ctypes.byref(crtverify)))
630
if crtverify.value != 0:
631
gnutls.library.functions.gnutls_openpgp_crt_deinit(crt)
632
raise gnutls.errors.CertificateSecurityError("Verify failed")
406
gnutls.library.functions.gnutls_openpgp_crt_import\
407
(crt, ctypes.byref(datum),
408
gnutls.library.constants.GNUTLS_OPENPGP_FMT_RAW)
633
409
# New buffer for the fingerprint
634
buf = ctypes.create_string_buffer(20)
635
buf_len = ctypes.c_size_t()
410
buffer = ctypes.create_string_buffer(20)
411
buffer_length = ctypes.c_size_t()
636
412
# Get the fingerprint from the certificate into the buffer
637
(gnutls.library.functions
638
.gnutls_openpgp_crt_get_fingerprint(crt, ctypes.byref(buf),
639
ctypes.byref(buf_len)))
413
gnutls.library.functions.gnutls_openpgp_crt_get_fingerprint\
414
(crt, ctypes.byref(buffer), ctypes.byref(buffer_length))
640
415
# Deinit the certificate
641
416
gnutls.library.functions.gnutls_openpgp_crt_deinit(crt)
642
417
# Convert the buffer to a Python bytestring
643
fpr = ctypes.string_at(buf, buf_len.value)
418
fpr = ctypes.string_at(buffer, buffer_length.value)
644
419
# Convert the bytestring to hexadecimal notation
645
420
hex_fpr = u''.join(u"%02X" % ord(char) for char in fpr)
649
class TCP_handler(SocketServer.BaseRequestHandler, object):
424
class tcp_handler(SocketServer.BaseRequestHandler, object):
650
425
"""A TCP request handler class.
651
426
Instantiated by IPv6_TCPServer for each request to handle it.
652
427
Note: This will run in its own forked process."""
654
429
def handle(self):
655
430
logger.info(u"TCP connection from: %s",
656
unicode(self.client_address))
657
session = (gnutls.connection
658
.ClientSession(self.request,
431
unicode(self.client_address))
432
session = gnutls.connection.ClientSession\
433
(self.request, gnutls.connection.X509Credentials())
662
435
line = self.request.makefile().readline()
663
436
logger.debug(u"Protocol version: %r", line)
798
563
datetime.timedelta(1)
799
564
>>> string_to_delta(u'1w')
800
565
datetime.timedelta(7)
801
>>> string_to_delta('5m 30s')
802
datetime.timedelta(0, 330)
804
timevalue = datetime.timedelta(0)
805
for s in interval.split():
807
suffix = unicode(s[-1])
810
delta = datetime.timedelta(value)
812
delta = datetime.timedelta(0, value)
814
delta = datetime.timedelta(0, 0, 0, 0, value)
816
delta = datetime.timedelta(0, 0, 0, 0, 0, value)
818
delta = datetime.timedelta(0, 0, 0, 0, 0, 0, value)
821
except (ValueError, IndexError):
568
suffix=unicode(interval[-1])
569
value=int(interval[:-1])
571
delta = datetime.timedelta(value)
573
delta = datetime.timedelta(0, value)
575
delta = datetime.timedelta(0, 0, 0, 0, value)
577
delta = datetime.timedelta(0, 0, 0, 0, 0, value)
579
delta = datetime.timedelta(0, 0, 0, 0, 0, 0, value)
582
except (ValueError, IndexError):
827
587
def server_state_changed(state):
828
588
"""Derived from the Avahi example code"""
829
589
if state == avahi.SERVER_COLLISION:
830
logger.error(u"Zeroconf server name collision")
590
logger.error(u"Server name collision")
832
592
elif state == avahi.SERVER_RUNNING:
967
733
# Parse config file with clients
968
734
client_defaults = { "timeout": "1h",
969
735
"interval": "5m",
970
"checker": "fping -q -- %(host)s",
736
"checker": "fping -q -- %%(host)s",
973
738
client_config = ConfigParser.SafeConfigParser(client_defaults)
974
739
client_config.read(os.path.join(server_settings["configdir"],
978
tcp_server = IPv6_TCPServer((server_settings["address"],
979
server_settings["port"]),
981
settings=server_settings,
983
pidfilename = "/var/run/mandos.pid"
985
pidfile = open(pidfilename, "w")
986
except IOError, error:
987
logger.error("Could not open file %r", pidfilename)
990
uid = pwd.getpwnam("_mandos").pw_uid
993
uid = pwd.getpwnam("mandos").pw_uid
996
uid = pwd.getpwnam("nobody").pw_uid
1000
gid = pwd.getpwnam("_mandos").pw_gid
1003
gid = pwd.getpwnam("mandos").pw_gid
1006
gid = pwd.getpwnam("nogroup").pw_gid
1012
except OSError, error:
1013
if error[0] != errno.EPERM:
1017
743
service = AvahiService(name = server_settings["servicename"],
1018
servicetype = "_mandos._tcp", )
744
type = "_mandos._tcp", );
1019
745
if server_settings["interface"]:
1020
service.interface = (if_nametoindex
1021
(server_settings["interface"]))
746
service.interface = if_nametoindex(server_settings["interface"])
1023
748
global main_loop
1041
773
logger.critical(u"No clients defined")
1045
# Redirect stdin so all checkers get /dev/null
1046
null = os.open(os.path.devnull, os.O_NOCTTY | os.O_RDWR)
1047
os.dup2(null, sys.stdin.fileno())
1051
# No console logging
1052
777
logger.removeHandler(console)
1053
# Close all input and output, do double fork, etc.
780
pidfilename = "/var/run/mandos/mandos.pid"
783
pidfile = open(pidfilename, "w")
1058
784
pidfile.write(str(pid) + "\n")
1062
logger.error(u"Could not write to file %r with PID %d",
1065
# "pidfile" was never created
788
logger.error(u"Could not write %s file with PID %d",
789
pidfilename, os.getpid())
1070
792
"Cleanup function; run on exit"
1087
809
signal.signal(signal.SIGHUP, lambda signum, frame: sys.exit())
1088
810
signal.signal(signal.SIGTERM, lambda signum, frame: sys.exit())
1090
class MandosServer(dbus.service.Object):
1091
"""A D-Bus proxy object"""
1093
dbus.service.Object.__init__(self, bus,
1095
_interface = u"org.mandos_system.Mandos"
1097
@dbus.service.signal(_interface, signature="oa{sv}")
1098
def ClientAdded(self, objpath, properties):
1102
@dbus.service.signal(_interface, signature="o")
1103
def ClientRemoved(self, objpath):
1107
@dbus.service.method(_interface, out_signature="ao")
1108
def GetAllClients(self):
1109
return dbus.Array(c.dbus_object_path for c in clients)
1111
@dbus.service.method(_interface, out_signature="a{oa{sv}}")
1112
def GetAllClientsWithProperties(self):
1113
return dbus.Dictionary(
1114
((c.dbus_object_path, c.GetAllProperties())
1118
@dbus.service.method(_interface, in_signature="o")
1119
def RemoveClient(self, object_path):
1121
if c.dbus_object_path == object_path:
1129
mandos_server = MandosServer()
1131
812
for client in clients:
1133
mandos_server.ClientAdded(client.dbus_object_path,
1134
client.GetAllProperties())
1138
tcp_server.server_activate()
815
tcp_server = IPv6_TCPServer((server_settings["address"],
816
server_settings["port"]),
818
settings=server_settings,
1140
820
# Find out what port we got
1141
821
service.port = tcp_server.socket.getsockname()[1]
1142
822
logger.info(u"Now listening on address %r, port %d, flowinfo %d,"