{"level":"warn","ts":"2026-09-22T01:56:39.541153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:56:39.541231Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-22T01:56:39.541285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:56:39.541313Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-22T01:56:39.54133Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T01:56:39.541907Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-09-22T01:56:39.541977Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-22T01:56:39.547267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.104895ms"} {"level":"info","ts":"2026-09-22T01:56:39.55534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-09-22T01:56:39.555398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:56:39.555419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-09-22T01:56:39.555436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:56:39.555447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-09-22T01:56:39.555482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-09-22T01:56:39.564993Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:56:39.56652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:56:39.569057Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-22T01:56:39.571692Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:56:39.571786Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:56:39.571834Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:56:39.571841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-22T01:56:39.572042Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:56:39.572788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-09-22T01:56:39.575141Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-22T01:56:39.576834Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-22T01:56:39.577034Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:56:39.577085Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:56:39.577366Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-22T01:56:39.577404Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-22T01:56:40.056108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:56:40.056167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:56:40.056229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-09-22T01:56:40.056254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:56:40.056271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-22T01:56:40.056291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-09-22T01:56:40.056325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-22T01:56:40.082037Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:56:40.082938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:56:40.083274Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:56:40.083321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:56:40.083427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:56:40.083451Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:56:40.08515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:56:40.086032Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:56:40.086105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:56:40.087366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:56:40.11059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"info","ts":"2026-09-22T01:56:41.773262Z","caller":"traceutil/trace.go:171","msg":"trace[237125152] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"199.934724ms","start":"2026-09-22T01:56:41.573301Z","end":"2026-09-22T01:56:41.773235Z","steps":["trace[237125152] 'process raft request' (duration: 198.607469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.773451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.28823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.77326Z","caller":"traceutil/trace.go:171","msg":"trace[16475827] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"198.077304ms","start":"2026-09-22T01:56:41.575126Z","end":"2026-09-22T01:56:41.773203Z","steps":["trace[16475827] 'read index received' (duration: 68.625937ms)","trace[16475827] 'applied index is now lower than readState.Index' (duration: 129.450277ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:56:41.773501Z","caller":"traceutil/trace.go:171","msg":"trace[844599242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:3; }","duration":"198.388352ms","start":"2026-09-22T01:56:41.575103Z","end":"2026-09-22T01:56:41.773491Z","steps":["trace[844599242] 'agreement among raft nodes before linearized reading' (duration: 198.241758ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.782984Z","caller":"traceutil/trace.go:171","msg":"trace[824896449] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"133.171985ms","start":"2026-09-22T01:56:41.649788Z","end":"2026-09-22T01:56:41.78296Z","steps":["trace[824896449] 'process raft request' (duration: 132.989511ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.783359Z","caller":"traceutil/trace.go:171","msg":"trace[1690352559] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"133.459994ms","start":"2026-09-22T01:56:41.649877Z","end":"2026-09-22T01:56:41.783337Z","steps":["trace[1690352559] 'process raft request' (duration: 133.001891ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.783461Z","caller":"traceutil/trace.go:171","msg":"trace[1838781178] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"133.34101ms","start":"2026-09-22T01:56:41.650102Z","end":"2026-09-22T01:56:41.783443Z","steps":["trace[1838781178] 'process raft request' (duration: 132.809026ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.783487Z","caller":"traceutil/trace.go:171","msg":"trace[157418388] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"130.712651ms","start":"2026-09-22T01:56:41.652757Z","end":"2026-09-22T01:56:41.78347Z","steps":["trace[157418388] 'process raft request' (duration: 130.253649ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.78355Z","caller":"traceutil/trace.go:171","msg":"trace[2055444128] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"132.831117ms","start":"2026-09-22T01:56:41.65071Z","end":"2026-09-22T01:56:41.783541Z","steps":["trace[2055444128] 'process raft request' (duration: 132.248991ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.783591Z","caller":"traceutil/trace.go:171","msg":"trace[1221444374] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"112.050637ms","start":"2026-09-22T01:56:41.671527Z","end":"2026-09-22T01:56:41.783577Z","steps":["trace[1221444374] 'process raft request' (duration: 111.515593ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.78364Z","caller":"traceutil/trace.go:171","msg":"trace[889790975] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"131.713737ms","start":"2026-09-22T01:56:41.651917Z","end":"2026-09-22T01:56:41.783631Z","steps":["trace[889790975] 'process raft request' (duration: 131.0656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.783526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.669479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.783812Z","caller":"traceutil/trace.go:171","msg":"trace[658184325] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"133.990938ms","start":"2026-09-22T01:56:41.649795Z","end":"2026-09-22T01:56:41.783786Z","steps":["trace[658184325] 'agreement among raft nodes before linearized reading' (duration: 133.610017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.783838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.648665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.78388Z","caller":"traceutil/trace.go:171","msg":"trace[90272415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"208.732557ms","start":"2026-09-22T01:56:41.575135Z","end":"2026-09-22T01:56:41.783868Z","steps":["trace[90272415] 'agreement among raft nodes before linearized reading' (duration: 207.942026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.783905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.000988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.783942Z","caller":"traceutil/trace.go:171","msg":"trace[548014966] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"134.050769ms","start":"2026-09-22T01:56:41.649877Z","end":"2026-09-22T01:56:41.783928Z","steps":["trace[548014966] 'agreement among raft nodes before linearized reading' (duration: 134.002208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.784032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.980769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.86\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.784059Z","caller":"traceutil/trace.go:171","msg":"trace[22198032] range","detail":"{range_begin:/registry/masterleases/162.253.55.86; range_end:; response_count:0; response_revision:11; }","duration":"142.029591ms","start":"2026-09-22T01:56:41.642021Z","end":"2026-09-22T01:56:41.78405Z","steps":["trace[22198032] 'agreement among raft nodes before linearized reading' (duration: 141.98799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.784186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.662233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.784225Z","caller":"traceutil/trace.go:171","msg":"trace[131105913] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"200.794816ms","start":"2026-09-22T01:56:41.583419Z","end":"2026-09-22T01:56:41.784214Z","steps":["trace[131105913] 'agreement among raft nodes before linearized reading' (duration: 200.718774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:56:41.784379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.335999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:56:41.784404Z","caller":"traceutil/trace.go:171","msg":"trace[1467871241] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"159.395191ms","start":"2026-09-22T01:56:41.625001Z","end":"2026-09-22T01:56:41.784396Z","steps":["trace[1467871241] 'agreement among raft nodes before linearized reading' (duration: 159.35781ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:41.783128Z","caller":"traceutil/trace.go:171","msg":"trace[1409913653] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"133.480894ms","start":"2026-09-22T01:56:41.649602Z","end":"2026-09-22T01:56:41.783083Z","steps":["trace[1409913653] 'process raft request' (duration: 133.112914ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:20.363968Z","caller":"traceutil/trace.go:171","msg":"trace[678807192] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"271.702084ms","start":"2026-09-22T01:57:20.092238Z","end":"2026-09-22T01:57:20.36394Z","steps":["trace[678807192] 'process raft request' (duration: 180.594822ms)","trace[678807192] 'compare' (duration: 90.946528ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:20.364155Z","caller":"traceutil/trace.go:171","msg":"trace[1765579548] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"271.660213ms","start":"2026-09-22T01:57:20.092473Z","end":"2026-09-22T01:57:20.364133Z","steps":["trace[1765579548] 'process raft request' (duration: 271.429247ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:20.389765Z","caller":"traceutil/trace.go:171","msg":"trace[1024927715] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"110.291121ms","start":"2026-09-22T01:57:20.279448Z","end":"2026-09-22T01:57:20.389739Z","steps":["trace[1024927715] 'process raft request' (duration: 109.848899ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:20.390059Z","caller":"traceutil/trace.go:171","msg":"trace[2096489422] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:512; }","duration":"103.374777ms","start":"2026-09-22T01:57:20.286664Z","end":"2026-09-22T01:57:20.390039Z","steps":["trace[2096489422] 'read index received' (duration: 77.124412ms)","trace[2096489422] 'applied index is now lower than readState.Index' (duration: 26.249285ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:20.390415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.710746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:57:20.390601Z","caller":"traceutil/trace.go:171","msg":"trace[1180661694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"103.841249ms","start":"2026-09-22T01:57:20.286615Z","end":"2026-09-22T01:57:20.390457Z","steps":["trace[1180661694] 'agreement among raft nodes before linearized reading' (duration: 103.708376ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:26.526454Z","caller":"traceutil/trace.go:171","msg":"trace[204385890] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"134.21508ms","start":"2026-09-22T01:57:26.392213Z","end":"2026-09-22T01:57:26.526428Z","steps":["trace[204385890] 'process raft request' (duration: 134.005035ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:26.816272Z","caller":"traceutil/trace.go:171","msg":"trace[1787305712] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"421.418872ms","start":"2026-09-22T01:57:26.394789Z","end":"2026-09-22T01:57:26.816208Z","steps":["trace[1787305712] 'process raft request' (duration: 421.056045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:26.817306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:26.394768Z","time spent":"421.586627ms","remote":"127.0.0.1:43556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:57:26.819457Z","caller":"traceutil/trace.go:171","msg":"trace[550972051] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"401.660528ms","start":"2026-09-22T01:57:26.417774Z","end":"2026-09-22T01:57:26.819435Z","steps":["trace[550972051] 'read index received' (duration: 108.507007ms)","trace[550972051] 'applied index is now lower than readState.Index' (duration: 293.152391ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:26.819794Z","caller":"traceutil/trace.go:171","msg":"trace[145027784] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"381.578257ms","start":"2026-09-22T01:57:26.438188Z","end":"2026-09-22T01:57:26.819766Z","steps":["trace[145027784] 'process raft request' (duration: 378.901434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:26.819969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:26.438159Z","time spent":"381.705971ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:57:26.819954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.190382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T01:57:26.820039Z","caller":"traceutil/trace.go:171","msg":"trace[24873824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"267.367676ms","start":"2026-09-22T01:57:26.552652Z","end":"2026-09-22T01:57:26.820019Z","steps":["trace[24873824] 'agreement among raft nodes before linearized reading' (duration: 267.162431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:26.820572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.781285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xm2d6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-22T01:57:26.820656Z","caller":"traceutil/trace.go:171","msg":"trace[817078107] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xm2d6; range_end:; response_count:1; response_revision:596; }","duration":"402.927849ms","start":"2026-09-22T01:57:26.417707Z","end":"2026-09-22T01:57:26.820635Z","steps":["trace[817078107] 'agreement among raft nodes before linearized reading' (duration: 402.637392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:26.820698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:26.417683Z","time spent":"403.00752ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-xm2d6\" "} {"level":"info","ts":"2026-09-22T01:57:27.002521Z","caller":"traceutil/trace.go:171","msg":"trace[383488749] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"171.436615ms","start":"2026-09-22T01:57:26.83106Z","end":"2026-09-22T01:57:27.002497Z","steps":["trace[383488749] 'process raft request' (duration: 167.178765ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:27.060697Z","caller":"traceutil/trace.go:171","msg":"trace[1436366222] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"221.436968ms","start":"2026-09-22T01:57:26.839207Z","end":"2026-09-22T01:57:27.060644Z","steps":["trace[1436366222] 'process raft request' (duration: 221.225093ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:27.060794Z","caller":"traceutil/trace.go:171","msg":"trace[540498760] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"164.705666ms","start":"2026-09-22T01:57:26.896065Z","end":"2026-09-22T01:57:27.060771Z","steps":["trace[540498760] 'process raft request' (duration: 164.508021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:27.61166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.294576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:57:27.612816Z","caller":"traceutil/trace.go:171","msg":"trace[1666111111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"326.459724ms","start":"2026-09-22T01:57:27.286279Z","end":"2026-09-22T01:57:27.612739Z","steps":["trace[1666111111] 'range keys from in-memory index tree' (duration: 325.172054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:27.61295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:27.286253Z","time spent":"326.654238ms","remote":"127.0.0.1:43288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T01:57:27.613185Z","caller":"traceutil/trace.go:171","msg":"trace[248087447] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"110.3064ms","start":"2026-09-22T01:57:27.502852Z","end":"2026-09-22T01:57:27.613159Z","steps":["trace[248087447] 'process raft request' (duration: 109.511091ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:45.079205Z","caller":"traceutil/trace.go:171","msg":"trace[1023175107] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"167.965843ms","start":"2026-09-22T01:57:44.911204Z","end":"2026-09-22T01:57:45.07917Z","steps":["trace[1023175107] 'process raft request' (duration: 167.780639ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:25.640478Z","caller":"traceutil/trace.go:171","msg":"trace[1553407185] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"274.33748ms","start":"2026-09-22T01:58:25.366116Z","end":"2026-09-22T01:58:25.640453Z","steps":["trace[1553407185] 'process raft request' (duration: 274.231797ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:25.640802Z","caller":"traceutil/trace.go:171","msg":"trace[986447733] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"275.967128ms","start":"2026-09-22T01:58:25.364804Z","end":"2026-09-22T01:58:25.640772Z","steps":["trace[986447733] 'process raft request' (duration: 271.999466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:46.9418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.453923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:58:46.941877Z","caller":"traceutil/trace.go:171","msg":"trace[2132596407] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1165; }","duration":"156.578127ms","start":"2026-09-22T01:58:46.785278Z","end":"2026-09-22T01:58:46.941856Z","steps":["trace[2132596407] 'range keys from in-memory index tree' (duration: 156.33079ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:47.059208Z","caller":"traceutil/trace.go:171","msg":"trace[177111243] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"112.139416ms","start":"2026-09-22T01:58:46.947045Z","end":"2026-09-22T01:58:47.059185Z","steps":["trace[177111243] 'process raft request' (duration: 111.5725ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:47.254011Z","caller":"traceutil/trace.go:171","msg":"trace[1058120431] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"190.703273ms","start":"2026-09-22T01:58:47.063276Z","end":"2026-09-22T01:58:47.25398Z","steps":["trace[1058120431] 'read index received' (duration: 154.55554ms)","trace[1058120431] 'applied index is now lower than readState.Index' (duration: 36.146653ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:47.254101Z","caller":"traceutil/trace.go:171","msg":"trace[63402781] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"267.222529ms","start":"2026-09-22T01:58:46.98685Z","end":"2026-09-22T01:58:47.254073Z","steps":["trace[63402781] 'process raft request' (duration: 230.964612ms)","trace[63402781] 'compare' (duration: 36.03237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:47.254267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.974871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-09-22T01:58:47.254296Z","caller":"traceutil/trace.go:171","msg":"trace[988950178] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1167; }","duration":"191.051363ms","start":"2026-09-22T01:58:47.063236Z","end":"2026-09-22T01:58:47.254288Z","steps":["trace[988950178] 'agreement among raft nodes before linearized reading' (duration: 190.904528ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:47.296521Z","caller":"traceutil/trace.go:171","msg":"trace[183341305] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"233.035463ms","start":"2026-09-22T01:58:47.063458Z","end":"2026-09-22T01:58:47.296494Z","steps":["trace[183341305] 'process raft request' (duration: 232.795706ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:47.296722Z","caller":"traceutil/trace.go:171","msg":"trace[1030967508] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"231.726065ms","start":"2026-09-22T01:58:47.06496Z","end":"2026-09-22T01:58:47.296686Z","steps":["trace[1030967508] 'process raft request' (duration: 231.489618ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:47.919622Z","caller":"traceutil/trace.go:171","msg":"trace[636791406] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"184.368401ms","start":"2026-09-22T01:58:47.735229Z","end":"2026-09-22T01:58:47.919597Z","steps":["trace[636791406] 'read index received' (duration: 184.296269ms)","trace[636791406] 'applied index is now lower than readState.Index' (duration: 71.182µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:47.919909Z","caller":"traceutil/trace.go:171","msg":"trace[2102516680] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"186.481361ms","start":"2026-09-22T01:58:47.733414Z","end":"2026-09-22T01:58:47.919895Z","steps":["trace[2102516680] 'process raft request' (duration: 185.988777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:47.92017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.933758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T01:58:47.920211Z","caller":"traceutil/trace.go:171","msg":"trace[1196603855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"185.01166ms","start":"2026-09-22T01:58:47.735187Z","end":"2026-09-22T01:58:47.920199Z","steps":["trace[1196603855] 'agreement among raft nodes before linearized reading' (duration: 184.886067ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:03:43.10123Z","caller":"traceutil/trace.go:171","msg":"trace[1762318588] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"150.67627ms","start":"2026-09-22T02:03:42.950532Z","end":"2026-09-22T02:03:43.101208Z","steps":["trace[1762318588] 'process raft request' (duration: 149.262256ms)"],"step_count":1}