{"level":"warn","ts":"2026-03-02T21:17:31.375218Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:17:31.375309Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186: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://199.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-03-02T21:17:31.375408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:17:31.375445Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-03-02T21:17:31.375482Z","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-03-02T21:17:31.376296Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-03-02T21:17:31.376468Z","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://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-03-02T21:17:31.401813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.051308ms"} {"level":"info","ts":"2026-03-02T21:17:31.413965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-03-02T21:17:31.414036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:17:31.414084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-03-02T21:17:31.414099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:17:31.414113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-03-02T21:17:31.414233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-03-02T21:17:31.426632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:17:31.474175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:17:31.522811Z","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-03-02T21:17:31.52709Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:17:31.52777Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:17:31.527826Z","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-03-02T21:17:31.527926Z","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-03-02T21:17:31.527937Z","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-03-02T21:17:31.542413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-03-02T21:17:31.542609Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-03-02T21:17:31.542889Z","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-03-02T21:17:31.542976Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-03-02T21:17:31.543029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-03-02T21:17:31.543088Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:17:31.543124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:17:32.416167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:17:32.416218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:17:32.416235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-03-02T21:17:32.416251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:17:32.416261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-03-02T21:17:32.416275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-03-02T21:17:32.416292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-03-02T21:17:32.437859Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:17:32.437926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:17:32.4381Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:32.438736Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:17:32.438818Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:17:32.438804Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:32.438965Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:32.439042Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:32.441808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:17:32.442107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:17:32.444609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"info","ts":"2026-03-02T21:17:35.436158Z","caller":"traceutil/trace.go:171","msg":"trace[620301641] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"102.66271ms","start":"2026-03-02T21:17:35.333461Z","end":"2026-03-02T21:17:35.436123Z","steps":["trace[620301641] 'process raft request' (duration: 58.19831ms)","trace[620301641] 'compare' (duration: 44.300275ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:56.395417Z","caller":"traceutil/trace.go:171","msg":"trace[488826973] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"189.490022ms","start":"2026-03-02T21:17:56.205905Z","end":"2026-03-02T21:17:56.395395Z","steps":["trace[488826973] 'process raft request' (duration: 189.364949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:56.990863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.033404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:17:56.990955Z","caller":"traceutil/trace.go:171","msg":"trace[1474743598] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"425.871208ms","start":"2026-03-02T21:17:56.565074Z","end":"2026-03-02T21:17:56.990945Z","steps":["trace[1474743598] 'read index received' (duration: 248.325902ms)","trace[1474743598] 'applied index is now lower than readState.Index' (duration: 177.544326ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:56.990996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.93267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T21:17:56.991008Z","caller":"traceutil/trace.go:171","msg":"trace[75247736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"425.989681ms","start":"2026-03-02T21:17:56.565014Z","end":"2026-03-02T21:17:56.991003Z","steps":["trace[75247736] 'agreement among raft nodes before linearized reading' (duration: 425.95703ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:56.991031Z","caller":"traceutil/trace.go:171","msg":"trace[2074137887] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"530.879596ms","start":"2026-03-02T21:17:56.460111Z","end":"2026-03-02T21:17:56.99099Z","steps":["trace[2074137887] 'process raft request' (duration: 353.314288ms)","trace[2074137887] 'compare' (duration: 176.928171ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:56.991067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:56.56499Z","time spent":"426.070033ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T21:17:56.991183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:56.460098Z","time spent":"531.008849ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:02.750832Z","caller":"traceutil/trace.go:171","msg":"trace[501539682] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"334.287257ms","start":"2026-03-02T21:18:02.416517Z","end":"2026-03-02T21:18:02.750804Z","steps":["trace[501539682] 'read index received' (duration: 333.826865ms)","trace[501539682] 'applied index is now lower than readState.Index' (duration: 459.472µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:02.750975Z","caller":"traceutil/trace.go:171","msg":"trace[450277377] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"861.289139ms","start":"2026-03-02T21:18:01.889652Z","end":"2026-03-02T21:18:02.750941Z","steps":["trace[450277377] 'process raft request' (duration: 860.815447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:02.751102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:01.88962Z","time spent":"861.427172ms","remote":"127.0.0.1:60496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:18:02.751112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.575004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-02T21:18:02.751226Z","caller":"traceutil/trace.go:171","msg":"trace[2001248256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"334.740448ms","start":"2026-03-02T21:18:02.416471Z","end":"2026-03-02T21:18:02.751212Z","steps":["trace[2001248256] 'agreement among raft nodes before linearized reading' (duration: 334.520393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:02.751291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:02.416451Z","time spent":"334.82919ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T21:18:02.770958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.241366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:18:02.771014Z","caller":"traceutil/trace.go:171","msg":"trace[199503445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"339.329127ms","start":"2026-03-02T21:18:02.431671Z","end":"2026-03-02T21:18:02.771001Z","steps":["trace[199503445] 'agreement among raft nodes before linearized reading' (duration: 339.224515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:02.771039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:02.43166Z","time spent":"339.373239ms","remote":"127.0.0.1:60380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T21:18:04.892379Z","caller":"traceutil/trace.go:171","msg":"trace[650153830] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"125.738731ms","start":"2026-03-02T21:18:04.766616Z","end":"2026-03-02T21:18:04.892354Z","steps":["trace[650153830] 'read index received' (duration: 125.513805ms)","trace[650153830] 'applied index is now lower than readState.Index' (duration: 224.126µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:04.892419Z","caller":"traceutil/trace.go:171","msg":"trace[268429655] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"193.447646ms","start":"2026-03-02T21:18:04.698956Z","end":"2026-03-02T21:18:04.892404Z","steps":["trace[268429655] 'process raft request' (duration: 193.262002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:04.89255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.155323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T21:18:04.892557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.919386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-02T21:18:04.892592Z","caller":"traceutil/trace.go:171","msg":"trace[1347551020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"118.225384ms","start":"2026-03-02T21:18:04.774348Z","end":"2026-03-02T21:18:04.892573Z","steps":["trace[1347551020] 'agreement among raft nodes before linearized reading' (duration: 118.142852ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:04.892638Z","caller":"traceutil/trace.go:171","msg":"trace[980256997] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:467; }","duration":"126.035138ms","start":"2026-03-02T21:18:04.766581Z","end":"2026-03-02T21:18:04.892616Z","steps":["trace[980256997] 'agreement among raft nodes before linearized reading' (duration: 125.887624ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:05.091662Z","caller":"traceutil/trace.go:171","msg":"trace[920794473] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"195.567406ms","start":"2026-03-02T21:18:04.896075Z","end":"2026-03-02T21:18:05.091642Z","steps":["trace[920794473] 'process raft request' (duration: 195.204848ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:05.096148Z","caller":"traceutil/trace.go:171","msg":"trace[848940222] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"195.244447ms","start":"2026-03-02T21:18:04.900882Z","end":"2026-03-02T21:18:05.096126Z","steps":["trace[848940222] 'process raft request' (duration: 195.086013ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:11.474149Z","caller":"traceutil/trace.go:171","msg":"trace[1492480591] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"347.608559ms","start":"2026-03-02T21:18:11.126515Z","end":"2026-03-02T21:18:11.474123Z","steps":["trace[1492480591] 'process raft request' (duration: 347.493797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:11.474391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:11.126501Z","time spent":"347.804223ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:11.546596Z","caller":"traceutil/trace.go:171","msg":"trace[2052126114] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"246.030771ms","start":"2026-03-02T21:18:11.300547Z","end":"2026-03-02T21:18:11.546578Z","steps":["trace[2052126114] 'read index received' (duration: 173.481332ms)","trace[2052126114] 'applied index is now lower than readState.Index' (duration: 72.546449ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:11.546747Z","caller":"traceutil/trace.go:171","msg":"trace[1186544355] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"420.078496ms","start":"2026-03-02T21:18:11.126642Z","end":"2026-03-02T21:18:11.54672Z","steps":["trace[1186544355] 'process raft request' (duration: 419.892702ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:11.54682Z","caller":"traceutil/trace.go:171","msg":"trace[867056655] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"420.132848ms","start":"2026-03-02T21:18:11.126524Z","end":"2026-03-02T21:18:11.546657Z","steps":["trace[867056655] 'process raft request' (duration: 419.888632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:11.547014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.474881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p244q\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-02T21:18:11.546851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:11.126603Z","time spent":"420.180868ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:11.547082Z","caller":"traceutil/trace.go:171","msg":"trace[1950054992] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p244q; range_end:; response_count:1; response_revision:510; }","duration":"246.569133ms","start":"2026-03-02T21:18:11.300498Z","end":"2026-03-02T21:18:11.547067Z","steps":["trace[1950054992] 'agreement among raft nodes before linearized reading' (duration: 246.399249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:11.546847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.673327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:18:11.54731Z","caller":"traceutil/trace.go:171","msg":"trace[116751251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"115.186197ms","start":"2026-03-02T21:18:11.4321Z","end":"2026-03-02T21:18:11.547286Z","steps":["trace[116751251] 'agreement among raft nodes before linearized reading' (duration: 114.644095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:11.547528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:11.126516Z","time spent":"420.658589ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:11.731083Z","caller":"traceutil/trace.go:171","msg":"trace[1362459674] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"147.887769ms","start":"2026-03-02T21:18:11.583179Z","end":"2026-03-02T21:18:11.731067Z","steps":["trace[1362459674] 'read index received' (duration: 147.766356ms)","trace[1362459674] 'applied index is now lower than readState.Index' (duration: 120.663µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:18:11.731236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.042672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T21:18:11.731254Z","caller":"traceutil/trace.go:171","msg":"trace[2026722006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"148.114234ms","start":"2026-03-02T21:18:11.583133Z","end":"2026-03-02T21:18:11.731247Z","steps":["trace[2026722006] 'agreement among raft nodes before linearized reading' (duration: 147.999081ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:11.731248Z","caller":"traceutil/trace.go:171","msg":"trace[400530043] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"174.2912ms","start":"2026-03-02T21:18:11.556927Z","end":"2026-03-02T21:18:11.731219Z","steps":["trace[400530043] 'process raft request' (duration: 174.031455ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:13.81764Z","caller":"traceutil/trace.go:171","msg":"trace[2128419737] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"267.064766ms","start":"2026-03-02T21:18:13.550555Z","end":"2026-03-02T21:18:13.81762Z","steps":["trace[2128419737] 'read index received' (duration: 266.749729ms)","trace[2128419737] 'applied index is now lower than readState.Index' (duration: 313.907µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:13.817764Z","caller":"traceutil/trace.go:171","msg":"trace[1028323194] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"335.22859ms","start":"2026-03-02T21:18:13.482497Z","end":"2026-03-02T21:18:13.817726Z","steps":["trace[1028323194] 'process raft request' (duration: 334.888163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:13.817914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:13.482481Z","time spent":"335.350512ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:18:13.818017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.389362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T21:18:13.818114Z","caller":"traceutil/trace.go:171","msg":"trace[1508666016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"267.564326ms","start":"2026-03-02T21:18:13.550539Z","end":"2026-03-02T21:18:13.818103Z","steps":["trace[1508666016] 'agreement among raft nodes before linearized reading' (duration: 267.202188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:13.818464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.713766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-02T21:18:13.819491Z","caller":"traceutil/trace.go:171","msg":"trace[264165129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"266.80046ms","start":"2026-03-02T21:18:13.552668Z","end":"2026-03-02T21:18:13.819469Z","steps":["trace[264165129] 'agreement among raft nodes before linearized reading' (duration: 265.266247ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:14.72785Z","caller":"traceutil/trace.go:171","msg":"trace[32030543] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"283.397534ms","start":"2026-03-02T21:18:14.444407Z","end":"2026-03-02T21:18:14.727805Z","steps":["trace[32030543] 'read index received' (duration: 205.093316ms)","trace[32030543] 'applied index is now lower than readState.Index' (duration: 78.303198ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:18:14.727973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.555019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:18:14.727963Z","caller":"traceutil/trace.go:171","msg":"trace[703057560] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"905.424053ms","start":"2026-03-02T21:18:13.822508Z","end":"2026-03-02T21:18:14.727932Z","steps":["trace[703057560] 'process raft request' (duration: 826.90552ms)","trace[703057560] 'compare' (duration: 77.796278ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:14.728014Z","caller":"traceutil/trace.go:171","msg":"trace[1034385505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"283.816324ms","start":"2026-03-02T21:18:14.444181Z","end":"2026-03-02T21:18:14.727998Z","steps":["trace[1034385505] 'agreement among raft nodes before linearized reading' (duration: 283.742712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:14.728111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:13.822477Z","time spent":"905.548716ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:14.728165Z","caller":"traceutil/trace.go:171","msg":"trace[1912471261] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"904.35329ms","start":"2026-03-02T21:18:13.823774Z","end":"2026-03-02T21:18:14.728127Z","steps":["trace[1912471261] 'process raft request' (duration: 903.996633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:14.728244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:13.82376Z","time spent":"904.443422ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:14.729055Z","caller":"traceutil/trace.go:171","msg":"trace[16802582] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"905.170118ms","start":"2026-03-02T21:18:13.82325Z","end":"2026-03-02T21:18:14.72842Z","steps":["trace[16802582] 'process raft request' (duration: 904.408031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:14.729204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:13.823222Z","time spent":"905.901064ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:18:19.676565Z","caller":"traceutil/trace.go:171","msg":"trace[183696175] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"117.381996ms","start":"2026-03-02T21:18:19.559157Z","end":"2026-03-02T21:18:19.676539Z","steps":["trace[183696175] 'process raft request' (duration: 115.830285ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:46.185213Z","caller":"traceutil/trace.go:171","msg":"trace[1768417535] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"212.353193ms","start":"2026-03-02T21:18:45.972838Z","end":"2026-03-02T21:18:46.185191Z","steps":["trace[1768417535] 'process raft request' (duration: 212.21654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:20:15.425208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.51054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:20:15.42542Z","caller":"traceutil/trace.go:171","msg":"trace[50705447] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"643.385767ms","start":"2026-03-02T21:20:14.782014Z","end":"2026-03-02T21:20:15.4254Z","steps":["trace[50705447] 'process raft request' (duration: 18.584363ms)","trace[50705447] 'compare' (duration: 624.303748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:20:15.425491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:20:14.781989Z","time spent":"643.471757ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}