Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Node time sync is not achieved #20

Open
gmag11 opened this issue Dec 18, 2016 · 2 comments
Open

Node time sync is not achieved #20

gmag11 opened this issue Dec 18, 2016 · 2 comments

Comments

@gmag11
Copy link

gmag11 commented Dec 18, 2016

I'm trying to do a simple setup with 2 esp8266 to. My intention is to have its LED blinking synchronously, so time sync is needed. I've used StatHere example.

I guess there is a bug while calculating adjustment value but I've been not able to find it.

My code is this:

//************************************************************
// this is a simple example that uses the easyMesh library
//
// 1. blinks led once for every node on the mesh
// 2. blink cycle repeats every BLINK_PERIOD
// 3. sends a silly message to every node on the mesh at a random time betweew 1 and 5 seconds
// 4. prints anything it recieves to Serial.print
// 
//
//************************************************************
#include <easyMesh.h>

// some gpio pin that is connected to an LED... 
// on my rig, this is 5, change to the right number of your LED.
#define   LED             2       // GPIO number of connected LED

#define   BLINK_PERIOD    1000000 // microseconds until cycle repeat
#define   BLINK_DURATION  100000  // microseconds LED is on for

#define   MESH_PREFIX     "whateverYouLike"
#define   MESH_PASSWORD   "somethingSneeky"
#define   MESH_PORT       5555

easyMesh  mesh;

uint32_t sendMessageTime = 0;

void setup() {
  Serial.begin(115200);
    
  pinMode( LED, OUTPUT );

//mesh.setDebugMsgTypes( ERROR | MESH_STATUS | CONNECTION | SYNC | COMMUNICATION | GENERAL | MSG_TYPES | REMOTE ); // all types on
  mesh.setDebugMsgTypes( ERROR | STARTUP | SYNC );  // set before init() so that you can see startup messages

  mesh.init( MESH_PREFIX, MESH_PASSWORD, MESH_PORT );
  mesh.setReceiveCallback( &receivedCallback );
  mesh.setNewConnectionCallback( &newConnectionCallback );

  randomSeed( analogRead( A0 ) );  
}

void loop() {
  mesh.update();

  // run the blinky
  bool  onFlag = true;
  uint32_t cycleTime = mesh.getNodeTime() % BLINK_PERIOD;
  for ( uint8_t i = 0; i < ( mesh.connectionCount() + 1); i++ ) {
    uint32_t onTime = BLINK_DURATION * i * 2;    

    if ( cycleTime > onTime && cycleTime < onTime + BLINK_DURATION )
      onFlag = false;
  }
  digitalWrite( LED, onFlag );

  // get next random time for send message
  if ( sendMessageTime == 0 ) {
    sendMessageTime = mesh.getNodeTime() + random( 1000000, 5000000 );
  }

  // if the time is ripe, send everyone a message!
  if ( sendMessageTime != 0 && sendMessageTime < mesh.getNodeTime() ){
    String msg = "Hello from node ";
    msg += mesh.getChipId();
    mesh.sendBroadcast( msg );
    sendMessageTime = 0;
  }
}

void receivedCallback( uint32_t from, String &msg ) {
  Serial.printf("startHere: Received from %d msg=%s\n", from, msg.c_str());
  Serial.printf("Node time: %u\n", mesh.getNodeTime());
}

void newConnectionCallback( bool adopt ) {
  Serial.printf("startHere: New Connection, adopt=%d\n", adopt);
}

They do time sync procedure but time is not actually syncd. Besides one of the two nodes keep asking time forever.

Here Serial output in node number 1:

setDebugTypes 0x13
0x2	init():
0x2	apInit(): Starting AP with SSID=whateverYouLike12617095 IP=192.168.135.1 GW=192.168.135.1 NM=255.255.255.0
0x2	DHCP server started
0x2	AP tcp server established on port 5555
0x2	stationInit():
0x10	startNodeSync(): with 0
0x10	handleNodeSync(): with 0
0x10	handleNodeSync(): conn->chipId updated from 0 to 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 1282846
0x10	startTimeSync(): with 1282846
0x10	buildTimeStamp(): num=0
0x10	buildTimeStamp(): timeStamp={"time":5661707,"num":0,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4249843,"num":0,"adopt":false}
0x10	processTimeStamp(): str={"time":4249843,"num":0,"adopt":false}
0x10	buildTimeStamp(): num=1
0x10	buildTimeStamp(): timeStamp={"time":5706159,"num":1,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5706159,"num":1,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4272217,"num":1,"adopt":true}
0x10	processTimeStamp(): str={"time":4272217,"num":1,"adopt":true}
0x10	buildTimeStamp(): num=2
0x10	buildTimeStamp(): timeStamp={"time":5751075,"num":2,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5751075,"num":2,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4317041,"num":2,"adopt":false}
0x10	processTimeStamp(): str={"time":4317041,"num":2,"adopt":false}
0x10	buildTimeStamp(): num=3
0x10	buildTimeStamp(): timeStamp={"time":5795597,"num":3,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5795597,"num":3,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4361691,"num":3,"adopt":true}
0x10	processTimeStamp(): str={"time":4361691,"num":3,"adopt":true}
0x10	buildTimeStamp(): num=4
0x10	buildTimeStamp(): timeStamp={"time":5840406,"num":4,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5840406,"num":4,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4406378,"num":4,"adopt":false}
0x10	processTimeStamp(): str={"time":4406378,"num":4,"adopt":false}
0x10	buildTimeStamp(): num=5
0x10	buildTimeStamp(): timeStamp={"time":5885137,"num":5,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5885137,"num":5,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4451127,"num":5,"adopt":true}
0x10	processTimeStamp(): str={"time":4451127,"num":5,"adopt":true}
0x10	buildTimeStamp(): num=6
0x10	buildTimeStamp(): timeStamp={"time":5930394,"num":6,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5930394,"num":6,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4495834,"num":6,"adopt":false}
0x10	processTimeStamp(): str={"time":4495834,"num":6,"adopt":false}
0x10	buildTimeStamp(): num=7
0x10	buildTimeStamp(): timeStamp={"time":5975167,"num":7,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":5975167,"num":7,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4541142,"num":7,"adopt":true}
0x10	processTimeStamp(): str={"time":4541142,"num":7,"adopt":true}
0x10	buildTimeStamp(): num=8
0x10	buildTimeStamp(): timeStamp={"time":6019944,"num":8,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":6019944,"num":8,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4585875,"num":8,"adopt":false}
0x10	processTimeStamp(): str={"time":4585875,"num":8,"adopt":false}
0x10	buildTimeStamp(): num=9
0x10	buildTimeStamp(): timeStamp={"time":6064542,"num":9,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":6064542,"num":9,"adopt":true}
startHere: New Connection, adopt=1
0x10	handleTimeSync(): with 1282846 in timestamp={"time":4630542,"num":9,"adopt":true}
0x10	processTimeStamp(): str={"time":4630542,"num":9,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":4630542,"num":9,"adopt":true}
0x10	calcAdjustment(): odd=0
0x10	best interval=67198, best index=0
0x10	new calc time=4272217, adoptedTime=4272217
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 6829478
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 10047099
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 14539637
0x10	handleNodeSync(): with 0
0x10	handleNodeSync(): conn->chipId updated from 0 to 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
0x10	manageConnections(): starting timeSync with 1282846
0x10	startTimeSync(): with 1282846
0x10	buildTimeStamp(): num=0
0x10	buildTimeStamp(): timeStamp={"time":19217694,"num":0,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":17817582,"num":0,"adopt":false}
0x10	processTimeStamp(): str={"time":17817582,"num":0,"adopt":false}
0x10	buildTimeStamp(): num=1
0x10	buildTimeStamp(): timeStamp={"time":19240242,"num":1,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19240242,"num":1,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":17862162,"num":1,"adopt":true}
0x10	processTimeStamp(): str={"time":17862162,"num":1,"adopt":true}
0x10	buildTimeStamp(): num=2
0x10	buildTimeStamp(): timeStamp={"time":19285246,"num":2,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19285246,"num":2,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":17907121,"num":2,"adopt":false}
0x10	processTimeStamp(): str={"time":17907121,"num":2,"adopt":false}
0x10	buildTimeStamp(): num=3
0x10	buildTimeStamp(): timeStamp={"time":19331121,"num":3,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19331121,"num":3,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":17953072,"num":3,"adopt":true}
0x10	processTimeStamp(): str={"time":17953072,"num":3,"adopt":true}
0x10	buildTimeStamp(): num=4
0x10	buildTimeStamp(): timeStamp={"time":19377963,"num":4,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19377963,"num":4,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":17999819,"num":4,"adopt":false}
0x10	processTimeStamp(): str={"time":17999819,"num":4,"adopt":false}
0x10	buildTimeStamp(): num=5
0x10	buildTimeStamp(): timeStamp={"time":19423722,"num":5,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19423722,"num":5,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":18045709,"num":5,"adopt":true}
0x10	processTimeStamp(): str={"time":18045709,"num":5,"adopt":true}
0x10	buildTimeStamp(): num=6
0x10	buildTimeStamp(): timeStamp={"time":19468829,"num":6,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19468829,"num":6,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":18091268,"num":6,"adopt":false}
0x10	processTimeStamp(): str={"time":18091268,"num":6,"adopt":false}
0x10	buildTimeStamp(): num=7
0x10	buildTimeStamp(): timeStamp={"time":19515793,"num":7,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19515793,"num":7,"adopt":true}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":18137766,"num":7,"adopt":true}
0x10	processTimeStamp(): str={"time":18137766,"num":7,"adopt":true}
0x10	buildTimeStamp(): num=8
0x10	buildTimeStamp(): timeStamp={"time":19560866,"num":8,"adopt":false}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19560866,"num":8,"adopt":false}
0x10	handleTimeSync(): with 1282846 in timestamp={"time":18182683,"num":8,"adopt":false}
0x10	processTimeStamp(): str={"time":18182683,"num":8,"adopt":false}
0x10	buildTimeStamp(): num=9
0x10	buildTimeStamp(): timeStamp={"time":19606042,"num":9,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":19606042,"num":9,"adopt":true}
startHere: New Connection, adopt=1
0x10	handleTimeSync(): with 1282846 in timestamp={"time":18227919,"num":9,"adopt":true}
0x10	processTimeStamp(): str={"time":18227919,"num":9,"adopt":true}
0x10	handleTimeSync(): with 1282846 out timestamp={"time":18227919,"num":9,"adopt":true}
0x10	calcAdjustment(): odd=0
0x10	best interval=89539, best index=0
0x10	new calc time=17862162, adoptedTime=17862162
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 20947325
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 1282846 sending NODE_SYNC_REPLY
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 22835867
0x10	manageConnections(): start nodeSync with 1282846
0x10	startNodeSync(): with 1282846
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 1282846
0x10	manageConnections(): start nodeSync with 1282846
0x10	startNodeSync(): with 1282846
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 1282846
0x10	manageConnections(): start nodeSync with 1282846
0x10	startNodeSync(): with 1282846
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 28343599
startHere: Received from 1282846 msg=Hello from node 1282846
Node time: 28456673
0x10	handleNodeSync(): with 1282846
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 1282846
0x10	manageConnections(): start nodeSync with 1282846
0x10	startNodeSync(): with 1282846

Same for node 2:

setDebugTypes 0x13
0x2	init():
0x2	apInit(): Starting AP with SSID=whateverYouLike1282846 IP=192.168.30.1 GW=192.168.30.1 NM=255.255.255.0
0x2	DHCP server started
0x2	AP tcp server established on port 5555
0x2	stationInit():
0x10	handleNodeSync(): with 0
0x10	handleNodeSync(): conn->chipId updated from 0 to 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 12617095 sending NODE_SYNC_REPLY
0x10	manageConnections(): starting timeSync with 12617095
0x10	startTimeSync(): with 12617095
0x10	buildTimeStamp(): num=0
0x10	buildTimeStamp(): timeStamp={"time":4249843,"num":0,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5661707,"num":0,"adopt":false}
0x10	processTimeStamp(): str={"time":5661707,"num":0,"adopt":false}
0x10	buildTimeStamp(): num=1
0x10	buildTimeStamp(): timeStamp={"time":4272217,"num":1,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4272217,"num":1,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5706159,"num":1,"adopt":true}
0x10	processTimeStamp(): str={"time":5706159,"num":1,"adopt":true}
0x10	buildTimeStamp(): num=2
0x10	buildTimeStamp(): timeStamp={"time":4317041,"num":2,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4317041,"num":2,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5751075,"num":2,"adopt":false}
0x10	processTimeStamp(): str={"time":5751075,"num":2,"adopt":false}
0x10	buildTimeStamp(): num=3
0x10	buildTimeStamp(): timeStamp={"time":4361691,"num":3,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4361691,"num":3,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5795597,"num":3,"adopt":true}
0x10	processTimeStamp(): str={"time":5795597,"num":3,"adopt":true}
0x10	buildTimeStamp(): num=4
0x10	buildTimeStamp(): timeStamp={"time":4406378,"num":4,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4406378,"num":4,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5840406,"num":4,"adopt":false}
0x10	processTimeStamp(): str={"time":5840406,"num":4,"adopt":false}
0x10	buildTimeStamp(): num=5
0x10	buildTimeStamp(): timeStamp={"time":4451127,"num":5,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4451127,"num":5,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5885137,"num":5,"adopt":true}
0x10	processTimeStamp(): str={"time":5885137,"num":5,"adopt":true}
0x10	buildTimeStamp(): num=6
0x10	buildTimeStamp(): timeStamp={"time":4495834,"num":6,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4495834,"num":6,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5930394,"num":6,"adopt":false}
0x10	processTimeStamp(): str={"time":5930394,"num":6,"adopt":false}
0x10	buildTimeStamp(): num=7
0x10	buildTimeStamp(): timeStamp={"time":4541142,"num":7,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4541142,"num":7,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":5975167,"num":7,"adopt":true}
0x10	processTimeStamp(): str={"time":5975167,"num":7,"adopt":true}
0x10	buildTimeStamp(): num=8
0x10	buildTimeStamp(): timeStamp={"time":4585875,"num":8,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4585875,"num":8,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":6019944,"num":8,"adopt":false}
0x10	processTimeStamp(): str={"time":6019944,"num":8,"adopt":false}
0x10	buildTimeStamp(): num=9
0x10	buildTimeStamp(): timeStamp={"time":4630542,"num":9,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":4630542,"num":9,"adopt":true}
startHere: New Connection, adopt=1
0x10	handleTimeSync(): with 12617095 in timestamp={"time":6064542,"num":9,"adopt":true}
0x10	processTimeStamp(): str={"time":6064542,"num":9,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":6064542,"num":9,"adopt":true}
0x10	calcAdjustment(): odd=0
0x10	best interval=89331, best index=2
0x10	new calc time=5795597, adoptedTime=5795597
startHere: Received from 12617095 msg=Hello from node 12617095
Node time: 5627037
0x10	manageConnections(): start nodeSync with 12617095
0x10	startNodeSync(): with 12617095
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 12617095
startHere: Received from 12617095 msg=Hello from node 12617095
Node time: 8071057
0x10	manageConnections(): start nodeSync with 12617095
0x10	startNodeSync(): with 12617095
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 12617095
startHere: Received from 12617095 msg=Hello from node 12617095
Node time: 9863930
0x10	manageConnections(): start nodeSync with 12617095
0x10	startNodeSync(): with 12617095
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 12617095
0x10	manageConnections(): start nodeSync with 12617095
0x10	startNodeSync(): with 12617095
0x10	startNodeSync(): with 0
0x10	handleNodeSync(): with 0
0x10	handleNodeSync(): conn->chipId updated from 0 to 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 12617095
0x10	startTimeSync(): with 12617095
0x10	buildTimeStamp(): num=0
0x10	buildTimeStamp(): timeStamp={"time":17817582,"num":0,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19217694,"num":0,"adopt":false}
0x10	processTimeStamp(): str={"time":19217694,"num":0,"adopt":false}
0x10	buildTimeStamp(): num=1
0x10	buildTimeStamp(): timeStamp={"time":17862162,"num":1,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":17862162,"num":1,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19240242,"num":1,"adopt":true}
0x10	processTimeStamp(): str={"time":19240242,"num":1,"adopt":true}
0x10	buildTimeStamp(): num=2
0x10	buildTimeStamp(): timeStamp={"time":17907121,"num":2,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":17907121,"num":2,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19285246,"num":2,"adopt":false}
0x10	processTimeStamp(): str={"time":19285246,"num":2,"adopt":false}
0x10	buildTimeStamp(): num=3
0x10	buildTimeStamp(): timeStamp={"time":17953072,"num":3,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":17953072,"num":3,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19331121,"num":3,"adopt":true}
0x10	processTimeStamp(): str={"time":19331121,"num":3,"adopt":true}
0x10	buildTimeStamp(): num=4
0x10	buildTimeStamp(): timeStamp={"time":17999819,"num":4,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":17999819,"num":4,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19377963,"num":4,"adopt":false}
0x10	processTimeStamp(): str={"time":19377963,"num":4,"adopt":false}
0x10	buildTimeStamp(): num=5
0x10	buildTimeStamp(): timeStamp={"time":18045709,"num":5,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":18045709,"num":5,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19423722,"num":5,"adopt":true}
0x10	processTimeStamp(): str={"time":19423722,"num":5,"adopt":true}
0x10	buildTimeStamp(): num=6
0x10	buildTimeStamp(): timeStamp={"time":18091268,"num":6,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":18091268,"num":6,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19468829,"num":6,"adopt":false}
0x10	processTimeStamp(): str={"time":19468829,"num":6,"adopt":false}
0x10	buildTimeStamp(): num=7
0x10	buildTimeStamp(): timeStamp={"time":18137766,"num":7,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":18137766,"num":7,"adopt":true}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19515793,"num":7,"adopt":true}
0x10	processTimeStamp(): str={"time":19515793,"num":7,"adopt":true}
0x10	buildTimeStamp(): num=8
0x10	buildTimeStamp(): timeStamp={"time":18182683,"num":8,"adopt":false}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":18182683,"num":8,"adopt":false}
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19560866,"num":8,"adopt":false}
0x10	processTimeStamp(): str={"time":19560866,"num":8,"adopt":false}
0x10	buildTimeStamp(): num=9
0x10	buildTimeStamp(): timeStamp={"time":18227919,"num":9,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":18227919,"num":9,"adopt":true}
startHere: New Connection, adopt=1
0x10	handleTimeSync(): with 12617095 in timestamp={"time":19606042,"num":9,"adopt":true}
0x10	processTimeStamp(): str={"time":19606042,"num":9,"adopt":true}
0x10	handleTimeSync(): with 12617095 out timestamp={"time":19606042,"num":9,"adopt":true}
0x10	calcAdjustment(): odd=0
0x10	best interval=67552, best index=0
0x10	new calc time=19240242, adoptedTime=19240242
0x10	manageConnections(): start nodeSync with 12617095
0x10	startNodeSync(): with 12617095
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REPLY from 12617095
startHere: Received from 12617095 msg=Hello from node 12617095
Node time: 21951040
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 12617095 sending NODE_SYNC_REPLY
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 12617095 sending NODE_SYNC_REPLY
startHere: Received from 12617095 msg=Hello from node 12617095
Node time: 25705848
0x10	handleNodeSync(): with 12617095
0x10	handleNodeSync(): valid NODE_SYNC_REQUEST 12617095 sending NODE_SYNC_REPLY
@ds1982
Copy link

ds1982 commented Jul 21, 2017 •
edited
Loading

I have exactly the same behaviour and NodeTimes are not in sync while that.
I am also using this with a slowly blinking LED and the NodeTimes differ ~1 second.

Anyone has a solution?

@sfranzyshen
Copy link

this code is dead ... try painlessmesh

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@ds1982 @sfranzyshen @gmag11