View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0004573secusharesocialpublic2016-06-09 14:132019-02-12 09:19
ReporterlynX Assigned Tolurchi  
PrioritynormalSeveritymajorReproducibilityalways
Status closedResolutionfixed 
Summary0004573: gnunet-social segfaults after a combination of commands
DescriptionThe last command in the series gave this output:

=> gnunet-social --guest-talk -m _notice_testing_psycit -d 'bar bar barrrr!!!! ' -e 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXG -p RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG -i TVV54YYR29KRDKM30ZC3014JXHDGPVT7KFQGA44CEV2YQSEVFDAG
Jun 09 13:59:26-859431 gnunet-social-32650 WARNING Ego: 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXGvonlynX
Jun 09 13:59:26-861640 gnunet-social-32650 WARNING Host: A0X7WPYWHBP2YEBZW8NRJ6F94Q5MRV1YSS85JFH42X5PXDWYB30G
Jun 09 13:59:26-862562 gnunet-social-32650 WARNING Host: JTWFK8BPAYK4NFV2F58WS0KSG7Y5FDF04HN8SB0J2TB5ZCTCH150
Jun 09 13:59:26-863452 gnunet-social-32650 WARNING Host: J7VFNKEZBTT137EYA30TBG7H3CWAQVMSXR1TVBNBK7GS28TG26M0
Jun 09 13:59:26-864396 gnunet-social-32650 WARNING Host: VHWGYFWXAYFXVVP0A3NMCXT7XB0RTPMP11NMJXRNFYZE1HW6RXKG
Jun 09 13:59:26-865350 gnunet-social-32650 WARNING Host: SQVPFMVCB3G1W5ACWPNV1XJGVYKXTMWWPMHYJA7VY73K5J9X7VF0
Jun 09 13:59:26-866274 gnunet-social-32650 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:59:26-866409 gnunet-social-32650 ERROR Assertion failed at social_api.c:962.
Jun 09 13:59:26-867301 gnunet-social-32650 WARNING Host: MVT6DTHGGG606MWGS9AQ1A5SMKVYTS8K0FH38W9461MM728PDJ7G
Jun 09 13:59:26-868216 gnunet-social-32650 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:59:26-868337 gnunet-social-32650 ERROR Assertion failed at social_api.c:962.
Jun 09 13:59:26-869216 gnunet-social-32650 WARNING Host: Z9EXJC8ET45JYN8834AB4E5QQJARYRHY544N6KSA43GF4737YN60
Jun 09 13:59:26-870098 gnunet-social-32650 WARNING Guest: RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG
child died with signal 11, without coredump
Steps To ReproduceFirst I launch gnunet-arm and pipe it into a logread:

/usr/depot/lib/gnunet/libexec/gnunet-service-arm |& gnunet-logread -x util- -n main
Jun 09 13:52:43-852621 nat-32465 WARNING Failed to resolve STUN server (are we online?)
Jun 09 13:52:43-896664 gns-32450 DEBUG Time window for zone iteration: 0 ms
Jun 09 13:52:43-896718 gns-32450 DEBUG Number of allowed parallel background queries: 1000
Jun 09 13:52:43-929445 gns-32450 DEBUG Looking for gns-intercept ego
Jun 09 13:52:43-979552 core-api-32457 DEBUG Connecting to CORE service
Jun 09 13:52:43-987419 core-api-32457 INFO (Re)connecting to CORE service, monitoring messages of type UNKNOWN (0)
Jun 09 13:52:43-987733 core-api-32457 DEBUG Transmitting control message with 8 bytes of type CORE_INIT (64) to core.
Jun 09 13:52:43-987866 core-api-32457 DEBUG Core connection down, not processing queue
Jun 09 13:52:43-997099 identity-api-32450 DEBUG Connecting to identity service.
Jun 09 13:52:44-001485 core-api-32457 DEBUG Processing message of type CORE_INIT_REPLY (65) and size 40 from core service
Jun 09 13:52:44-001525 core-api-32457 DEBUG Request queue empty, not processing queue
Jun 09 13:52:44-001584 core-api-32457 DEBUG Connected to core service of peer `29VW'.
Jun 09 13:52:44-001676 cadet-api-32457 DEBUG GNUNET_CADET_connect()
Jun 09 13:52:44-001720 cadet-api-32457 DEBUG addr 0xb778b528
Jun 09 13:52:44-002822 identity-api-32450 DEBUG Sending message of type IDENTITY_START (624) to identity service
Jun 09 13:52:44-009340 cadet-api-32457 DEBUG port 26
Jun 09 13:52:44-009381 cadet-api-32457 DEBUG Sending 8 bytes long message with 1 ports
Jun 09 13:52:44-009442 cadet-api-32457 DEBUG Sending message to service: {LOC_CONNCT}
Jun 09 13:52:44-009499 cadet-api-32457 DEBUG calling ntfy tmt rdy for 8 bytes
Jun 09 13:52:44-009638 cadet-api-32457 DEBUG GNUNET_CADET_connect() END
Jun 09 13:52:44-010015 cadet-api-32457 DEBUG
Jun 09 13:52:44-010052 cadet-api-32457 DEBUG # Send callback, buffer 8
Jun 09 13:52:44-010094 cadet-api-32457 DEBUG # cadet internal traffic, type {LOC_CONNCT}
Jun 09 13:52:44-010166 cadet-api-32457 DEBUG # total size: 8
Jun 09 13:52:44-010209 cadet-api-32457 DEBUG # nothing left to transmit
Jun 09 13:52:44-010246 cadet-api-32457 DEBUG # start receiving from service
Jun 09 13:52:44-010367 cadet-api-32457 DEBUG # Send callback() END
Jun 09 13:52:44-015389 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA temp_store=MEMORY
Jun 09 13:52:44-019376 psycstore-sqlite-32455 DEBUG Executed `PRAGMA temp_store=MEMORY' / 0
Jun 09 13:52:44-021498 identity-api-32450 DEBUG Sending message of type IDENTITY_GET_DEFAULT (627) to identity service
Jun 09 13:52:44-022390 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA synchronous=NORMAL
Jun 09 13:52:44-022498 psycstore-sqlite-32455 DEBUG Executed `PRAGMA synchronous=NORMAL' / 0
Jun 09 13:52:44-022597 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA legacy_file_format=OFF
Jun 09 13:52:44-022666 psycstore-sqlite-32455 DEBUG Executed `PRAGMA legacy_file_format=OFF' / 0
Jun 09 13:52:44-022761 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA auto_vacuum=INCREMENTAL
Jun 09 13:52:44-047166 psycstore-api-32452 DEBUG Connecting to PSYCstore service.
Jun 09 13:52:44-273408 psycstore-sqlite-32455 DEBUG Executed `PRAGMA auto_vacuum=INCREMENTAL' / 0
Jun 09 13:52:44-273575 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA encoding="UTF-8"
Jun 09 13:52:44-273628 psycstore-sqlite-32455 DEBUG Executed `PRAGMA encoding="UTF-8"' / 0
Jun 09 13:52:44-273699 psycstore-sqlite-32455 DEBUG SQL query:
PRAGMA page_size=4096
Jun 09 13:52:44-273749 psycstore-sqlite-32455 DEBUG Executed `PRAGMA page_size=4096' / 0
Jun 09 13:52:44-273877 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS channels (
  id INTEGER PRIMARY KEY,
  pub_key BLOB UNIQUE,
  max_state_message_id INTEGER,
  state_hash_message_id INTEGER
);
Jun 09 13:52:44-274019 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS channels (
  id INTEGER PRIMARY KEY,
  pub_key BLOB UNIQUE,
  max_state_message_id INTEGER,
  state_hash_message_id INTEGER
);' / 0
Jun 09 13:52:44-274113 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS slaves (
  id INTEGER PRIMARY KEY,
  pub_key BLOB UNIQUE
);
Jun 09 13:52:44-277365 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS slaves (
  id INTEGER PRIMARY KEY,
  pub_key BLOB UNIQUE
);' / 0
Jun 09 13:52:44-277550 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS membership (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  slave_id INTEGER NOT NULL REFERENCES slaves(id),
  did_join INTEGER NOT NULL,
  announced_at INTEGER NOT NULL,
  effective_since INTEGER NOT NULL,
  group_generation INTEGER NOT NULL
);
Jun 09 13:52:44-277715 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS membership (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  slave_id INTEGER NOT NULL REFERENCES slaves(id),
  did_join INTEGER NOT NULL,
  announced_at INTEGER NOT NULL,
  effective_since INTEGER NOT NULL,
  group_generation INTEGER NOT NULL
);' / 0
Jun 09 13:52:44-277856 psycstore-sqlite-32455 DEBUG SQL query:
CREATE INDEX IF NOT EXISTS idx_membership_channel_id_slave_id ON membership (channel_id, slave_id);
Jun 09 13:52:44-277981 psycstore-sqlite-32455 DEBUG Executed `CREATE INDEX IF NOT EXISTS idx_membership_channel_id_slave_id ON membership (channel_id, slave_id);' / 0
Jun 09 13:52:44-278233 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS messages (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  hop_counter INTEGER NOT NULL,
  signature BLOB,
  purpose BLOB,
  fragment_id INTEGER NOT NULL,
  fragment_offset INTEGER NOT NULL,
  message_id INTEGER NOT NULL,
  group_generation INTEGER NOT NULL,
  multicast_flags INTEGER NOT NULL,
  psycstore_flags INTEGER NOT NULL,
  data BLOB,
  PRIMARY KEY (channel_id, fragment_id),
  UNIQUE (channel_id, message_id, fragment_offset)
);
Jun 09 13:52:44-278405 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS messages (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  hop_counter INTEGER NOT NULL,
  signature BLOB,
  purpose BLOB,
  fragment_id INTEGER NOT NULL,
  fragment_offset INTEGER NOT NULL,
  message_id INTEGER NOT NULL,
  group_generation INTEGER NOT NULL,
  multicast_flags INTEGER NOT NULL,
  psycstore_flags INTEGER NOT NULL,
  data BLOB,
  PRIMARY KEY (channel_id, fragment_id),
  UNIQUE (channel_id, message_id, fragment_offset)
);' / 0
Jun 09 13:52:44-278568 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS state (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  name TEXT NOT NULL,
  value_current BLOB,
  value_signed BLOB,
  PRIMARY KEY (channel_id, name)
);
Jun 09 13:52:44-278712 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS state (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  name TEXT NOT NULL,
  value_current BLOB,
  value_signed BLOB,
  PRIMARY KEY (channel_id, name)
);' / 0
Jun 09 13:52:44-278866 psycstore-sqlite-32455 DEBUG SQL query:
CREATE TABLE IF NOT EXISTS state_sync (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  name TEXT NOT NULL,
  value BLOB,
  PRIMARY KEY (channel_id, name)
);
Jun 09 13:52:44-279009 psycstore-sqlite-32455 DEBUG Executed `CREATE TABLE IF NOT EXISTS state_sync (
  channel_id INTEGER NOT NULL REFERENCES channels(id),
  name TEXT NOT NULL,
  value BLOB,
  PRIMARY KEY (channel_id, name)
);' / 0
Jun 09 13:52:44-280319 psycstore-sqlite-32455 DEBUG Prepared `BEGIN;' / 0xb8f95ae8: 0
Jun 09 13:52:44-280449 psycstore-sqlite-32455 DEBUG Prepared `COMMIT;' / 0xb8f96218: 0
Jun 09 13:52:44-280517 psycstore-sqlite-32455 DEBUG Prepared `ROLLBACK;' / 0xb8f96308: 0
Jun 09 13:52:44-280649 psycstore-sqlite-32455 DEBUG Prepared `INSERT OR IGNORE INTO channels (pub_key) VALUES (?);' / 0xb8f986e8: 0
Jun 09 13:52:44-280754 psycstore-sqlite-32455 DEBUG Prepared `INSERT OR IGNORE INTO slaves (pub_key) VALUES (?);' / 0xb8f94278: 0
Jun 09 13:52:44-281053 psycstore-sqlite-32455 DEBUG Prepared `INSERT INTO membership
 (channel_id, slave_id, did_join, announced_at,
  effective_since, group_generation)
VALUES ((SELECT id FROM channels WHERE pub_key = ?),
        (SELECT id FROM slaves WHERE pub_key = ?),
        ?, ?, ?, ?);' / 0xb8f98188: 0
Jun 09 13:52:44-283574 psycstore-sqlite-32455 DEBUG Prepared `SELECT did_join FROM membership
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND slave_id = (SELECT id FROM slaves WHERE pub_key = ?)
      AND effective_since <= ? AND did_join = 1
ORDER BY announced_at DESC LIMIT 1;' / 0xb8f94938: 0
Jun 09 13:52:44-283908 psycstore-sqlite-32455 DEBUG Prepared `INSERT OR IGNORE INTO messages
 (channel_id, hop_counter, signature, purpose,
  fragment_id, fragment_offset, message_id,
  group_generation, multicast_flags, psycstore_flags, data)
VALUES ((SELECT id FROM channels WHERE pub_key = ?),
        ?, ?, ?, ?, ?, ?, ?, ?, ?, ?);' / 0xb8fd0610: 0
Jun 09 13:52:44-284310 psycstore-sqlite-32455 DEBUG Prepared `UPDATE messages
SET psycstore_flags = psycstore_flags | ?
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND message_id = ? AND fragment_offset = 0;' / 0xb8fd0b48: 0
Jun 09 13:52:44-284692 psycstore-sqlite-32455 DEBUG Prepared `SELECT hop_counter, signature, purpose, fragment_id,
       fragment_offset, message_id, group_generation,
       multicast_flags, psycstore_flags, data
FROM messages
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND ? <= fragment_id AND fragment_id <= ?;' / 0xb8fd0850: 0
Jun 09 13:52:44-285073 psycstore-sqlite-32455 DEBUG Prepared `SELECT hop_counter, signature, purpose, fragment_id,
       fragment_offset, message_id, group_generation,
       multicast_flags, psycstore_flags, data
FROM messages
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND ? <= message_id AND message_id <= ?LIMIT ?;' / 0xb8fd3c28: 0
Jun 09 13:52:44-285644 psycstore-sqlite-32455 DEBUG Prepared `SELECT * FROM
(SELECT hop_counter, signature, purpose, fragment_id,
        fragment_offset, message_id, group_generation,
        multicast_flags, psycstore_flags, data
 FROM messages
 WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
 ORDER BY fragment_id DESC
 LIMIT ?)
ORDER BY fragment_id;' / 0xb8fd5458: 0
Jun 09 13:52:44-289356 psycstore-sqlite-32455 DEBUG Prepared `SELECT hop_counter, signature, purpose, fragment_id,
       fragment_offset, message_id, group_generation,
        multicast_flags, psycstore_flags, data
FROM messages
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND message_id IN
      (SELECT message_id
       FROM messages
       WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
       GROUP BY message_id
       ORDER BY message_id
       DESC LIMIT ?)
ORDER BY fragment_id;' / 0xb8fd5548: 0
Jun 09 13:52:44-289793 psycstore-sqlite-32455 DEBUG Prepared `SELECT hop_counter, signature, purpose, fragment_id,
       fragment_offset, message_id, group_generation,
       multicast_flags, psycstore_flags, data
FROM messages
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND message_id = ? AND fragment_offset = ?;' / 0xb8fd7680: 0
Jun 09 13:52:44-290087 psycstore-sqlite-32455 DEBUG Prepared `SELECT fragment_id, message_id, group_generation
FROM messages
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
ORDER BY fragment_id DESC LIMIT 1;' / 0xb8fd7b80: 0
Jun 09 13:52:44-290313 psycstore-sqlite-32455 DEBUG Prepared `SELECT max_state_message_id
FROM channels
WHERE pub_key = ? AND max_state_message_id IS NOT NULL;' / 0xb8fdac18: 0
Jun 09 13:52:44-290454 psycstore-sqlite-32455 DEBUG Prepared `UPDATE channels
SET max_state_message_id = ?
WHERE pub_key = ?;' / 0xb8fdb1f0: 0
Jun 09 13:52:44-290599 psycstore-sqlite-32455 DEBUG Prepared `UPDATE channels
SET state_hash_message_id = ?
WHERE pub_key = ?;' / 0xb8fdb7c0: 0
Jun 09 13:52:44-291079 psycstore-sqlite-32455 DEBUG Prepared `INSERT OR REPLACE INTO state
  (channel_id, name, value_current, value_signed)
SELECT new.channel_id, new.name,
       new.value_current, old.value_signed
FROM (SELECT (SELECT id FROM channels WHERE pub_key = ?)
             AS channel_id,
             ? AS name, ? AS value_current) AS new
LEFT JOIN (SELECT channel_id, name, value_signed
           FROM state) AS old
ON new.channel_id = old.channel_id AND new.name = old.name;' / 0xb8fcf5d0: 0
Jun 09 13:52:44-308512 psycstore-sqlite-32455 DEBUG Prepared `DELETE FROM state
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND (value_current IS NULL OR length(value_current) = 0)
      AND (value_signed IS NULL OR length(value_signed) = 0);' / 0xb8fcf448: 0
Jun 09 13:52:44-308775 psycstore-sqlite-32455 DEBUG Prepared `UPDATE state
SET value_signed = value_current
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?);' / 0xb8fdc800: 0
Jun 09 13:52:44-308988 psycstore-sqlite-32455 DEBUG Prepared `DELETE FROM state
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?);' / 0xb8fdc8f0: 0
Jun 09 13:52:44-309215 psycstore-sqlite-32455 DEBUG Prepared `INSERT INTO state_sync (channel_id, name, value)
VALUES ((SELECT id FROM channels WHERE pub_key = ?), ?, ?);' / 0xb8fdc9e0: 0
Jun 09 13:52:44-309498 psycstore-sqlite-32455 DEBUG Prepared `INSERT INTO state
 (channel_id, name, value_current, value_signed)
SELECT channel_id, name, value, value
FROM state_sync
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?);' / 0xb8fdbe60: 0
Jun 09 13:52:44-309706 psycstore-sqlite-32455 DEBUG Prepared `DELETE FROM state_sync
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?);' / 0xb8fdc168: 0
Jun 09 13:52:44-309905 psycstore-sqlite-32455 DEBUG Prepared `SELECT value_current
FROM state
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND name = ?;' / 0xb8fe0b48: 0
Jun 09 13:52:44-310276 psycstore-sqlite-32455 DEBUG Prepared `SELECT name, value_current
FROM state
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?)
      AND (name = ? OR substr(name, 1, ?) = ? || '_');' / 0xb8fdf7e0: 0
Jun 09 13:52:44-310544 psycstore-sqlite-32455 DEBUG Prepared `SELECT name, value_signed
FROM state
WHERE channel_id = (SELECT id FROM channels WHERE pub_key = ?) AND value_signed IS NOT NULL;' / 0xb8fe1e10: 0
Jun 09 13:52:44-310626 psycstore-sqlite-32455 INFO SQLite database running

It stops at this point. I check what IPC has collected:

] gnunet-logread /tmp/gnunet-logread-ipc.sock
Jun 09 13:52:43-962513 main -> namestore NAMESTORE_MONITOR_START (40)
Jun 09 13:52:43-987905 main -> core CORE_INIT (8)
Jun 09 13:52:44-001436 core -> main CORE_INIT_REPLY (40)
Jun 09 13:52:44-003012 main -> identity IDENTITY_START (4)
Jun 09 13:52:44-010407 main -> cadet CADET_LOCAL_CONNECT (8)
Jun 09 13:52:44-021542 main -> identity IDENTITY_GET_DEFAULT (22)
Jun 09 13:52:44-508500 identity -> main IDENTITY_UPDATE (48)
Jun 09 13:52:44-609067 identity -> main IDENTITY_UPDATE (40)
Jun 09 13:52:44-609300 identity -> main IDENTITY_RESULT_CODE (25)
Jun 09 13:52:44-807616 namestore -> main NAMESTORE_MONITOR_SYNC (4)
Jun 09 13:52:44-808308 main -> namestore NAMESTORE_ZONE_ITERATION_START (40)
Jun 09 13:52:44-819894 namestore -> main NAMESTORE_RECORD_RESULT (48)
Jun 09 13:52:44-828723 main -> statistics STATISTICS_SET (60)
Jun 09 13:52:44-829386 main -> statistics STATISTICS_SET (46)
Jun 09 13:52:44-829877 main -> statistics STATISTICS_SET (52)

o
=> gnunet-social --guest-talk -m _notice_testing_psycit -d 'foo ' -e 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXG -p RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG -i TVV54YYR29KRDKM30ZC3014JXHDGPVT7KFQGA44CEV2YQSEVFDAG
Jun 09 13:58:02-802960 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-803558 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-803732 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-803805 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-912979 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-913077 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-916848 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-916945 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-919356 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-919456 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-923074 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-923199 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
[…]

Then I tried talking to a channel before entering it:

=> gnunet-social --guest-talk -m _notice_testing_psycit -d 'foo ' -e 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXG -p RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG -i TVV54YYR29KRDKM30ZC3014JXHDGPVT7KFQGA44CEV2YQSEVFDAG
Jun 09 13:58:02-802960 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-803558 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-803732 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-803805 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-912979 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-913077 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-916848 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-916945 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-919356 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-919456 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-923074 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-923199 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-926134 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-926260 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-928569 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-928666 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-930979 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-931076 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:02-933401 gnunet-social-32626 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:02-933501 gnunet-social-32626 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:32-554721 gnunet-social-32626 ERROR Timeout.
child exited with value 1

Then I tried subscribing the channel:

=> gnunet-social --guest-enter --follow -e 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXG -p RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG -i TVV54YYR29KRDKM30ZC3014JXHDGPVT7KFQGA44CEV2YQSEVFDAG
Jun 09 13:58:45-501006 gnunet-social-32638 WARNING Ego: 0A9RPQ1VDZB3M67XEBP9MVF6ZHKA4TP3YS99FMVG9JF4TQ3C9SXGvonlynX
Jun 09 13:58:45-503267 gnunet-social-32638 WARNING Host: A0X7WPYWHBP2YEBZW8NRJ6F94Q5MRV1YSS85JFH42X5PXDWYB30G
Jun 09 13:58:45-504212 gnunet-social-32638 WARNING Host: JTWFK8BPAYK4NFV2F58WS0KSG7Y5FDF04HN8SB0J2TB5ZCTCH150
Jun 09 13:58:45-505074 gnunet-social-32638 WARNING Host: J7VFNKEZBTT137EYA30TBG7H3CWAQVMSXR1TVBNBK7GS28TG26M0
Jun 09 13:58:45-506066 gnunet-social-32638 WARNING Host: VHWGYFWXAYFXVVP0A3NMCXT7XB0RTPMP11NMJXRNFYZE1HW6RXKG
Jun 09 13:58:45-507006 gnunet-social-32638 WARNING Host: SQVPFMVCB3G1W5ACWPNV1XJGVYKXTMWWPMHYJA7VY73K5J9X7VF0
Jun 09 13:58:45-507917 gnunet-social-32638 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:45-508042 gnunet-social-32638 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:45-508912 gnunet-social-32638 WARNING Host: MVT6DTHGGG606MWGS9AQ1A5SMKVYTS8K0FH38W9461MM728PDJ7G
Jun 09 13:58:45-509875 gnunet-social-32638 ERROR Failure to obtain ego. Is identity service running?
Jun 09 13:58:45-509986 gnunet-social-32638 ERROR Assertion failed at social_api.c:962.
Jun 09 13:58:45-510816 gnunet-social-32638 WARNING Host: Z9EXJC8ET45JYN8834AB4E5QQJARYRHY544N6KSA43GF4737YN60
Jun 09 13:58:45-511739 gnunet-social-32638 WARNING Guest: RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG
Jun 09 13:58:45-552608 gnunet-social-32638 WARNING Guest entered to local place: RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG, max_message_id: 0
Jun 09 13:59:26-917185 gnunet-social-32638 WARNING Guest entered to local place: RG3RY7FTTT6128V1HGZQCQ89Q5APTHSP2JD9WKGH4T2N7KQZFVQG, max_message_id: 0

This command still hasn't terminated.
The third one is the one that crashed.
Additional InformationThe IPC log continues as follows:


Jun 09 13:58:02-739753 main -> core CORE_INIT (8)
Jun 09 13:58:02-784071 main -> identity IDENTITY_START (4)
Jun 09 13:58:02-786209 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-798647 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-799099 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-799585 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-799988 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-800480 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-800907 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-801369 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-801790 main -> psyc PSYC_MASTER_START (40)
Jun 09 13:58:02-801955 core -> main CORE_INIT_REPLY (40)
Jun 09 13:58:02-803507 identity -> main IDENTITY_UPDATE (48)
Jun 09 13:58:02-914336 identity -> main IDENTITY_UPDATE (40)
Jun 09 13:58:03-081889 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-084314 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-086600 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-088405 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-089284 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-091663 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-095602 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-096470 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-098602 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-108700 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-114389 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-114833 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-114973 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-115697 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-116335 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-119219 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-119910 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-121794 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-122445 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-126441 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-127220 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:03-129184 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-129735 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-133426 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-133587 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-129735 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-133426 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-133587 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-134604 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-141216 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-142033 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-142904 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-146179 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-147010 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-147693 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-150598 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:03-151578 psyc -> main PSYC_MASTER_START_ACK (16)
Jun 09 13:58:03-164011 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:03-166401 main -> multicast MULTICAST_ORIGIN_START (48)
Jun 09 13:58:45-521458 main -> psyc PSYC_SLAVE_JOIN (181)
Jun 09 13:58:45-546766 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:58:45-550975 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:58:45-551958 psyc -> main PSYC_SLAVE_JOIN_ACK (16)
Jun 09 13:58:45-562906 main -> multicast MULTICAST_MEMBER_JOIN (177)
Jun 09 13:58:45-625529 main -> cadet CADET_CHANNEL_CREATE (48)
Jun 09 13:59:26-877849 main -> psyc PSYC_MESSAGE (94)
Jun 09 13:59:26-886856 main -> psyc PSYC_SLAVE_JOIN (181)
Jun 09 13:59:26-912728 main -> psycstore PSYCSTORE_COUNTERS_GET (48)
Jun 09 13:59:26-914805 psycstore -> main PSYCSTORE_RESULT_COUNTERS (48)
Jun 09 13:59:26-915807 psyc -> main PSYC_SLAVE_JOIN_ACK (16)
Jun 09 13:59:26-922729 main -> multicast MULTICAST_MEMBER_JOIN (177)
Jun 09 13:59:26-994807 main -> cadet CADET_CHANNEL_CREATE (48)
TagsNo tags attached.

Activities

lynX

2016-06-23 16:40

manager   ~0010926

Here's a more simple scenario that makes it segfault. I ran it without arguments.

] gdb gnunet-social
(gdb) run
Starting program: /usr/depot/bin/gnunet-social
Jun 23 16:24:24-171873 gnunet-social-32561 WARNING Ego: EPYRKKFCNMJ2K24X80A2AYYZK9AKG78XWJY3A5N72WQPG6B6MNWG vonlynX

Program received signal SIGSEGV, Segmentation fault.
GNUNET_CLIENT_MANAGER_drop_queue (mgr=0x8000b950) at client_manager.c:557
557 next = cur->next;
(gdb) bt
#0 GNUNET_CLIENT_MANAGER_drop_queue (mgr=0x8000b950) at client_manager.c:557
#1 0xb7f36b19 in GNUNET_CLIENT_MANAGER_disconnect (mgr=0x8000b950, transmit_queue=0,
    disconnect_cb=0x80002680 <app_disconnected>, cls=0x0) at client_manager.c:434
#2 0xb7fd7491 in GNUNET_SOCIAL_app_disconnect (app=0x8001b598, disconnect_cb=0x80002680 <app_disconnected>, disconnect_cls=0x0)
    at social_api.c:2516
#3 0x800024b5 in disconnect () at gnunet-social.c:203
#4 0xb7f72657 in run_ready (ws=0x8001bd80, rs=0x8001bcf8) at scheduler.c:620
#5 GNUNET_SCHEDULER_run (task=0xb7f6c1b0 <program_main>, task_cls=0xbfffef2c) at scheduler.c:887
#6 0xb7f6ca07 in GNUNET_PROGRAM_run2 (argc=<optimized out>, argv=<optimized out>,
    binaryName=0x80004d6c "gnunet-social - Interact with the social service: enter/leave, send/receive messages, access history and state.\n",
    binaryHelp=0x80004de0 "gnunet-social [--status]\n\ngnunet-social --host-enter --ego <NAME or PUBKEY> [--follow] [--welcome | --deny]\ngnunet-social --host-reconnect --place <PUBKEY> [--follow] [--welcome | --deny]\ngnunet-socia"...,
    options=0x80008b40 <options>, task=0x80002070 <run>, task_cls=0x0, run_without_scheduler=0) at program.c:300
#7 0xb7f6cd0e in GNUNET_PROGRAM_run (argc=1, argv=0x8000a658,
    binaryName=0x80004d6c "gnunet-social - Interact with the social service: enter/leave, send/receive messages, access history and state.\n",
    binaryHelp=0x80004de0 "gnunet-social [--status]\n\ngnunet-social --host-enter --ego <NAME or PUBKEY> [--follow] [--welcome | --deny]\ngnunet-social --host-reconnect --place <PUBKEY> [--follow] [--welcome | --deny]\ngnunet-socia"...,
    options=0x80008b40 <options>, task=0x80002070 <run>, task_cls=0x0) at program.c:339
#8 0x800019fa in main (argc=1, argv=0xbffff134) at gnunet-social.c:1342

arm debug log shows:

Jun 23 16:22:50-967783 social-32541 DEBUG 0xb804bfd8 Sending ego notification to client: vonlynX
Jun 23 16:22:50-993673 social-32541 DEBUG 0xb804bfd8 Sending end of ego list notification to client
Jun 23 16:22:50-994471 social-32541 DEBUG 0xb804bfd8 Sending end of place list notification to client
Jun 23 16:22:50-995304 social-32541 DEBUG 0xb804b140 Application cli connected.

Prior to starting it I removed .local/share/gnunet/social and .local/share/gnunet/identity, then generated a new ego.

Tried again after mkdir .local/share/gnunet/social but result is the same.

Tried also removing the identity directory, but the only difference is that the "WARNING Ego" is no longer shown.

lynX

2016-07-30 07:35

manager   ~0010996

Resolved by counting the times disconnect() is being called. Do not know if the problem is actually that disconnect() should only be called once, so the solution may be suboptimal.

Thanks to xrs and t3sserakt for patiently gdb'ing this one out.

lynX

2017-02-08 01:25

manager   ~0011706

Various segfaults have been fixed by lurchi. There are still some unstable conditions when parameters like --place are omitted.

Issue History

Date Modified Username Field Change
2016-06-09 14:13 lynX New Issue
2016-06-09 14:13 lynX Status new => assigned
2016-06-09 14:13 lynX Assigned To => tg
2016-06-23 16:40 lynX Note Added: 0010926
2016-07-30 07:35 lynX Note Added: 0010996
2016-07-30 07:36 lynX Reproducibility have not tried => always
2016-07-30 07:36 lynX Status assigned => feedback
2016-07-30 07:36 lynX Resolution open => fixed
2017-02-08 01:25 lynX Assigned To tg => lurchi
2017-02-08 01:25 lynX Status feedback => assigned
2017-02-08 01:25 lynX Fixed in Version => Git master
2017-02-08 01:25 lynX Note Added: 0011706
2017-02-08 01:27 lynX Status assigned => resolved
2017-02-26 02:17 Christian Grothoff Product Version => Git master
2017-02-26 02:17 Christian Grothoff Fixed in Version Git master => 0.11.0pre66
2017-02-26 02:17 Christian Grothoff Target Version => 0.11.0pre66
2018-06-07 00:24 Christian Grothoff Status resolved => closed
2019-02-12 09:17 Christian Grothoff Project GNUnet => secushare
2019-02-12 09:17 Christian Grothoff Category social service => General
2019-02-12 09:19 Christian Grothoff Category General => social