{"level":"warn","ts":"2026-03-18T21:43:48.984889Z","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-18T21:43:48.98502Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-18T21:43:48.985117Z","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-18T21:43:48.985134Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-03-18T21:43:48.985159Z","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-18T21:43:48.986133Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-03-18T21:43:48.98622Z","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.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-18T21:43:48.992583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.073206ms"} {"level":"info","ts":"2026-03-18T21:43:49.001603Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-03-18T21:43:49.001729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T21:43:49.001771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-03-18T21:43:49.001797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T21:43:49.001812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-03-18T21:43:49.001879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-03-18T21:43:49.008994Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T21:43:49.010286Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T21:43:49.011386Z","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-18T21:43:49.013194Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T21:43:49.014617Z","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-18T21:43:49.01479Z","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-18T21:43:49.014807Z","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-18T21:43:49.014959Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T21:43:49.016857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-03-18T21:43:49.017499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-03-18T21:43:49.01951Z","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-18T21:43:49.019705Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-03-18T21:43:49.019723Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-03-18T21:43:49.019983Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T21:43:49.020024Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T21:43:49.702663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T21:43:49.702767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T21:43:49.702842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-03-18T21:43:49.702878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-03-18T21:43:49.7029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-03-18T21:43:49.702987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-03-18T21:43:49.703015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-03-18T21:43:49.704252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T21:43:49.704441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:43:49.704499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:43:49.704893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:43:49.706207Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:43:49.706454Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:43:49.706549Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:43:49.706852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T21:43:49.708275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-03-18T21:43:49.711778Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T21:43:49.711874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-18T21:44:01.854557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:02.355194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:02.373851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025448773s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T21:44:02.374507Z","caller":"traceutil/trace.go:171","msg":"trace[804931278] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"1.020232161s","start":"2026-03-18T21:44:01.354241Z","end":"2026-03-18T21:44:02.374473Z","steps":["trace[804931278] 'read index received' (duration: 1.019770747s)","trace[804931278] 'applied index is now lower than readState.Index' (duration: 460.074µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:44:02.374591Z","caller":"traceutil/trace.go:171","msg":"trace[197280644] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.026254707s","start":"2026-03-18T21:44:01.348278Z","end":"2026-03-18T21:44:02.374533Z","steps":["trace[197280644] 'process raft request' (duration: 1.025884247s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:02.374816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020511998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T21:44:02.374885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.863697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-18T21:44:02.374846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.993193ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:02.37486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.878033ms","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-18T21:44:02.374958Z","caller":"traceutil/trace.go:171","msg":"trace[1558119810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"513.97164ms","start":"2026-03-18T21:44:01.860955Z","end":"2026-03-18T21:44:02.374927Z","steps":["trace[1558119810] 'agreement among raft nodes before linearized reading' (duration: 513.846576ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:02.375012Z","caller":"traceutil/trace.go:171","msg":"trace[525380384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"488.045297ms","start":"2026-03-18T21:44:01.886945Z","end":"2026-03-18T21:44:02.37499Z","steps":["trace[525380384] 'agreement among raft nodes before linearized reading' (duration: 487.825581ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:02.375018Z","caller":"traceutil/trace.go:171","msg":"trace[1756618542] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:396; }","duration":"311.191999ms","start":"2026-03-18T21:44:02.063787Z","end":"2026-03-18T21:44:02.374979Z","steps":["trace[1756618542] 'agreement among raft nodes before linearized reading' (duration: 310.945782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:02.375069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:01.860933Z","time spent":"514.077113ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T21:44:02.37507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:01.886931Z","time spent":"488.12365ms","remote":"127.0.0.1:52604","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":"info","ts":"2026-03-18T21:44:02.374922Z","caller":"traceutil/trace.go:171","msg":"trace[290408780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"1.020712124s","start":"2026-03-18T21:44:01.354186Z","end":"2026-03-18T21:44:02.374898Z","steps":["trace[290408780] 'agreement among raft nodes before linearized reading' (duration: 1.020446446s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:02.375295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:01.354164Z","time spent":"1.021114656s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T21:44:02.37553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.993308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T21:44:02.375591Z","caller":"traceutil/trace.go:171","msg":"trace[1561492772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"797.096001ms","start":"2026-03-18T21:44:01.578477Z","end":"2026-03-18T21:44:02.375573Z","steps":["trace[1561492772] 'agreement among raft nodes before linearized reading' (duration: 796.197885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:02.375639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:01.578456Z","time spent":"797.171543ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T21:44:02.375803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:01.348249Z","time spent":"1.026460953s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:21.642992Z","caller":"traceutil/trace.go:171","msg":"trace[618637133] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"235.73008ms","start":"2026-03-18T21:44:21.407239Z","end":"2026-03-18T21:44:21.642969Z","steps":["trace[618637133] 'process raft request' (duration: 235.484844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:22.213771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:22.714469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:23.215129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:23.270113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.550756158s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T21:44:23.270577Z","caller":"traceutil/trace.go:171","msg":"trace[1274215105] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.737651155s","start":"2026-03-18T21:44:21.532899Z","end":"2026-03-18T21:44:23.27055Z","steps":["trace[1274215105] 'process raft request' (duration: 1.737477461s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.270737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:21.532876Z","time spent":"1.737784647s","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T21:44:23.275819Z","caller":"traceutil/trace.go:171","msg":"trace[550763859] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.626588386s","start":"2026-03-18T21:44:21.649214Z","end":"2026-03-18T21:44:23.275803Z","steps":["trace[550763859] 'process raft request' (duration: 1.626461873s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.276024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:21.649188Z","time spent":"1.626671668s","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:23.276131Z","caller":"traceutil/trace.go:171","msg":"trace[1753714148] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.540771315s","start":"2026-03-18T21:44:21.735338Z","end":"2026-03-18T21:44:23.276109Z","steps":["trace[1753714148] 'process raft request' (duration: 1.540430647s)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:23.276205Z","caller":"traceutil/trace.go:171","msg":"trace[396296841] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.343686214s","start":"2026-03-18T21:44:21.932506Z","end":"2026-03-18T21:44:23.276193Z","steps":["trace[396296841] 'process raft request' (duration: 1.34353735s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.276208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:21.735313Z","time spent":"1.540858747s","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T21:44:23.276258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:21.932486Z","time spent":"1.343746374s","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T21:44:23.27633Z","caller":"traceutil/trace.go:171","msg":"trace[1604125030] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"944.049901ms","start":"2026-03-18T21:44:22.332269Z","end":"2026-03-18T21:44:23.276318Z","steps":["trace[1604125030] 'process raft request' (duration: 943.910508ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:23.276311Z","caller":"traceutil/trace.go:171","msg":"trace[1283462674] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.14244695s","start":"2026-03-18T21:44:22.133824Z","end":"2026-03-18T21:44:23.276271Z","steps":["trace[1283462674] 'process raft request' (duration: 1.142325668s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.276365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.332219Z","time spent":"944.132594ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T21:44:23.276461Z","caller":"traceutil/trace.go:171","msg":"trace[1541113523] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"680.903283ms","start":"2026-03-18T21:44:22.595545Z","end":"2026-03-18T21:44:23.276448Z","steps":["trace[1541113523] 'process raft request' (duration: 680.694579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.276486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.133787Z","time spent":"1.142609104s","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T21:44:23.276527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.59552Z","time spent":"680.970514ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:23.2767Z","caller":"traceutil/trace.go:171","msg":"trace[425376160] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:542; }","duration":"1.563995302s","start":"2026-03-18T21:44:21.712665Z","end":"2026-03-18T21:44:23.276661Z","steps":["trace[425376160] 'read index received' (duration: 1.557740735s)","trace[425376160] 'applied index is now lower than readState.Index' (duration: 6.253867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:44:23.276901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564233568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xz7k7\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-03-18T21:44:23.276935Z","caller":"traceutil/trace.go:171","msg":"trace[772122882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xz7k7; range_end:; response_count:1; response_revision:535; }","duration":"1.56430303s","start":"2026-03-18T21:44:21.712623Z","end":"2026-03-18T21:44:23.276926Z","steps":["trace[772122882] 'agreement among raft nodes before linearized reading' (duration: 1.564170046s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.276958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:21.712603Z","time spent":"1.56435083s","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xz7k7\" "} {"level":"warn","ts":"2026-03-18T21:44:23.277011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.984164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:23.277064Z","caller":"traceutil/trace.go:171","msg":"trace[1106435089] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"546.065906ms","start":"2026-03-18T21:44:22.730983Z","end":"2026-03-18T21:44:23.277049Z","steps":["trace[1106435089] 'agreement among raft nodes before linearized reading' (duration: 545.950844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.730943Z","time spent":"546.163289ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-18T21:44:23.277195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.964312ms","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-03-18T21:44:23.277221Z","caller":"traceutil/trace.go:171","msg":"trace[1732342235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"601.034933ms","start":"2026-03-18T21:44:22.67618Z","end":"2026-03-18T21:44:23.277215Z","steps":["trace[1732342235] 'agreement among raft nodes before linearized reading' (duration: 600.986452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.676169Z","time spent":"601.064574ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T21:44:23.277286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.205508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-18T21:44:23.277333Z","caller":"traceutil/trace.go:171","msg":"trace[51904276] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:535; }","duration":"147.269089ms","start":"2026-03-18T21:44:23.130039Z","end":"2026-03-18T21:44:23.277308Z","steps":["trace[51904276] 'agreement among raft nodes before linearized reading' (duration: 147.189558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.600876ms","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-18T21:44:23.277376Z","caller":"traceutil/trace.go:171","msg":"trace[375326224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"577.674298ms","start":"2026-03-18T21:44:22.699696Z","end":"2026-03-18T21:44:23.277371Z","steps":["trace[375326224] 'agreement among raft nodes before linearized reading' (duration: 577.624877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.27739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.699669Z","time spent":"577.718338ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T21:44:23.276891Z","caller":"traceutil/trace.go:171","msg":"trace[1050858442] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"339.38769ms","start":"2026-03-18T21:44:22.937433Z","end":"2026-03-18T21:44:23.276821Z","steps":["trace[1050858442] 'process raft request' (duration: 339.174695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.465607ms","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-18T21:44:23.277489Z","caller":"traceutil/trace.go:171","msg":"trace[38263335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"578.526708ms","start":"2026-03-18T21:44:22.698958Z","end":"2026-03-18T21:44:23.277484Z","steps":["trace[38263335] 'agreement among raft nodes before linearized reading' (duration: 578.490167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.094674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-18T21:44:23.277503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.698942Z","time spent":"578.557329ms","remote":"127.0.0.1:52604","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":"info","ts":"2026-03-18T21:44:23.277525Z","caller":"traceutil/trace.go:171","msg":"trace[518550774] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:535; }","duration":"471.178125ms","start":"2026-03-18T21:44:22.80634Z","end":"2026-03-18T21:44:23.277518Z","steps":["trace[518550774] 'agreement among raft nodes before linearized reading' (duration: 471.112384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.806312Z","time spent":"471.233917ms","remote":"127.0.0.1:52362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-03-18T21:44:23.277698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.214808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T21:44:23.277726Z","caller":"traceutil/trace.go:171","msg":"trace[1897845320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"622.27769ms","start":"2026-03-18T21:44:22.65544Z","end":"2026-03-18T21:44:23.277717Z","steps":["trace[1897845320] 'agreement among raft nodes before linearized reading' (duration: 622.229169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.27773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.354484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:23.277797Z","caller":"traceutil/trace.go:171","msg":"trace[1729952820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"747.517767ms","start":"2026-03-18T21:44:22.530267Z","end":"2026-03-18T21:44:23.277785Z","steps":["trace[1729952820] 'agreement among raft nodes before linearized reading' (duration: 747.426065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.277437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.090427ms","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":"warn","ts":"2026-03-18T21:44:23.277852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.530243Z","time spent":"747.601239ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-18T21:44:23.277876Z","caller":"traceutil/trace.go:171","msg":"trace[1651997953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:535; }","duration":"340.558478ms","start":"2026-03-18T21:44:22.937301Z","end":"2026-03-18T21:44:23.277859Z","steps":["trace[1651997953] 'agreement among raft nodes before linearized reading' (duration: 340.106347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.2779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.335373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:23.277917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.937275Z","time spent":"340.62548ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T21:44:23.277745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.655393Z","time spent":"622.345151ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T21:44:23.277935Z","caller":"traceutil/trace.go:171","msg":"trace[450181426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"998.392525ms","start":"2026-03-18T21:44:22.279532Z","end":"2026-03-18T21:44:23.277925Z","steps":["trace[450181426] 'agreement among raft nodes before linearized reading' (duration: 998.245201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:23.278156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.279496Z","time spent":"998.651631ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:44:23.279163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:22.937412Z","time spent":"340.277512ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T21:44:25.241109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.773167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-03-18T21:44:25.241185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.261217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-18T21:44:25.241275Z","caller":"traceutil/trace.go:171","msg":"trace[1387506500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"310.001874ms","start":"2026-03-18T21:44:24.93122Z","end":"2026-03-18T21:44:25.241222Z","steps":["trace[1387506500] 'range keys from in-memory index tree' (duration: 309.548272ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:25.241299Z","caller":"traceutil/trace.go:171","msg":"trace[735292959] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"510.437683ms","start":"2026-03-18T21:44:24.730815Z","end":"2026-03-18T21:44:25.241253Z","steps":["trace[735292959] 'range keys from in-memory index tree' (duration: 510.029032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:25.241119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.739235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-03-18T21:44:25.24134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:24.931198Z","time spent":"310.130127ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-18T21:44:25.241366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:24.730789Z","time spent":"510.561925ms","remote":"127.0.0.1:52370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-03-18T21:44:25.241376Z","caller":"traceutil/trace.go:171","msg":"trace[1983513354] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:585; }","duration":"576.035033ms","start":"2026-03-18T21:44:24.665316Z","end":"2026-03-18T21:44:25.241351Z","steps":["trace[1983513354] 'range keys from in-memory index tree' (duration: 575.537991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:25.241439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:24.665298Z","time spent":"576.126355ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12777,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-03-18T21:44:25.294266Z","caller":"traceutil/trace.go:171","msg":"trace[170846099] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:602; }","duration":"163.830979ms","start":"2026-03-18T21:44:25.130422Z","end":"2026-03-18T21:44:25.294253Z","steps":["trace[170846099] 'read index received' (duration: 163.824459ms)","trace[170846099] 'applied index is now lower than readState.Index' (duration: 5.65µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:44:25.294671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.246939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-03-18T21:44:25.294718Z","caller":"traceutil/trace.go:171","msg":"trace[641134482] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"164.348871ms","start":"2026-03-18T21:44:25.130356Z","end":"2026-03-18T21:44:25.294705Z","steps":["trace[641134482] 'agreement among raft nodes before linearized reading' (duration: 164.085285ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:25.294212Z","caller":"traceutil/trace.go:171","msg":"trace[885419180] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"216.176478ms","start":"2026-03-18T21:44:25.078017Z","end":"2026-03-18T21:44:25.294193Z","steps":["trace[885419180] 'process raft request' (duration: 216.038355ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:26.340913Z","caller":"traceutil/trace.go:171","msg":"trace[803692692] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"202.661742ms","start":"2026-03-18T21:44:26.138228Z","end":"2026-03-18T21:44:26.340889Z","steps":["trace[803692692] 'read index received' (duration: 202.503849ms)","trace[803692692] 'applied index is now lower than readState.Index' (duration: 156.013µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:44:26.341037Z","caller":"traceutil/trace.go:171","msg":"trace[876272369] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"206.965352ms","start":"2026-03-18T21:44:26.134034Z","end":"2026-03-18T21:44:26.340999Z","steps":["trace[876272369] 'process raft request' (duration: 206.662035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.341158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.767855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:26.341184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.916539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341225Z","caller":"traceutil/trace.go:171","msg":"trace[552079012] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:593; }","duration":"202.934448ms","start":"2026-03-18T21:44:26.138274Z","end":"2026-03-18T21:44:26.341209Z","steps":["trace[552079012] 'agreement among raft nodes before linearized reading' (duration: 202.808936ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:26.341264Z","caller":"traceutil/trace.go:171","msg":"trace[1349067012] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:593; }","duration":"203.022031ms","start":"2026-03-18T21:44:26.138216Z","end":"2026-03-18T21:44:26.341238Z","steps":["trace[1349067012] 'agreement among raft nodes before linearized reading' (duration: 202.843757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.34141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.740578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341427Z","caller":"traceutil/trace.go:171","msg":"trace[4645518] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:593; }","duration":"131.758519ms","start":"2026-03-18T21:44:26.209662Z","end":"2026-03-18T21:44:26.341421Z","steps":["trace[4645518] 'agreement among raft nodes before linearized reading' (duration: 131.727359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.341407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.736509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341521Z","caller":"traceutil/trace.go:171","msg":"trace[1917598492] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:593; }","duration":"131.908542ms","start":"2026-03-18T21:44:26.209584Z","end":"2026-03-18T21:44:26.341492Z","steps":["trace[1917598492] 'agreement among raft nodes before linearized reading' (duration: 131.673947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.341528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.457196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341568Z","caller":"traceutil/trace.go:171","msg":"trace[647721741] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:593; }","duration":"174.532048ms","start":"2026-03-18T21:44:26.167025Z","end":"2026-03-18T21:44:26.341557Z","steps":["trace[647721741] 'agreement among raft nodes before linearized reading' (duration: 174.465197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.341704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.025315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:26.341761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.785684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:26.341709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.644452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341775Z","caller":"traceutil/trace.go:171","msg":"trace[332928403] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:593; }","duration":"132.109357ms","start":"2026-03-18T21:44:26.209633Z","end":"2026-03-18T21:44:26.341742Z","steps":["trace[332928403] 'agreement among raft nodes before linearized reading' (duration: 132.010634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:26.341837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.06026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:26.341853Z","caller":"traceutil/trace.go:171","msg":"trace[1393239132] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:593; }","duration":"175.147482ms","start":"2026-03-18T21:44:26.166702Z","end":"2026-03-18T21:44:26.341849Z","steps":["trace[1393239132] 'agreement among raft nodes before linearized reading' (duration: 175.114061ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:26.34184Z","caller":"traceutil/trace.go:171","msg":"trace[3685819] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:593; }","duration":"202.867897ms","start":"2026-03-18T21:44:26.13894Z","end":"2026-03-18T21:44:26.341808Z","steps":["trace[3685819] 'agreement among raft nodes before linearized reading' (duration: 202.658552ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:26.341785Z","caller":"traceutil/trace.go:171","msg":"trace[1522891289] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:593; }","duration":"174.828505ms","start":"2026-03-18T21:44:26.16695Z","end":"2026-03-18T21:44:26.341778Z","steps":["trace[1522891289] 'agreement among raft nodes before linearized reading' (duration: 174.791645ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:31.303654Z","caller":"traceutil/trace.go:171","msg":"trace[1442769287] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"551.692128ms","start":"2026-03-18T21:44:30.751924Z","end":"2026-03-18T21:44:31.303616Z","steps":["trace[1442769287] 'process raft request' (duration: 551.492184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:31.3039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:30.751877Z","time spent":"551.934753ms","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:31.985347Z","caller":"traceutil/trace.go:171","msg":"trace[1267168701] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"616.877365ms","start":"2026-03-18T21:44:31.368445Z","end":"2026-03-18T21:44:31.985322Z","steps":["trace[1267168701] 'process raft request' (duration: 522.904117ms)","trace[1267168701] 'compare' (duration: 93.59342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:44:31.985487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:31.368426Z","time spent":"616.991037ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:31.985883Z","caller":"traceutil/trace.go:171","msg":"trace[15064161] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"616.228841ms","start":"2026-03-18T21:44:31.369291Z","end":"2026-03-18T21:44:31.98552Z","steps":["trace[15064161] 'process raft request' (duration: 615.957996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:31.986081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:31.369281Z","time spent":"616.691451ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:44:32.773969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.816486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:32.774575Z","caller":"traceutil/trace.go:171","msg":"trace[1687193612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"495.480482ms","start":"2026-03-18T21:44:32.279067Z","end":"2026-03-18T21:44:32.774547Z","steps":["trace[1687193612] 'range keys from in-memory index tree' (duration: 494.632343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:32.774755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:32.279043Z","time spent":"495.632184ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:44:33.128116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:32.808079Z","time spent":"320.03281ms","remote":"127.0.0.1:52362","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-18T21:44:33.867849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614764,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:34.368593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614764,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:34.86952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614764,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:35.37065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614764,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T21:44:35.724892Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.363523028s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T21:44:35.725375Z","caller":"traceutil/trace.go:171","msg":"trace[740296445] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.36411126s","start":"2026-03-18T21:44:33.361234Z","end":"2026-03-18T21:44:35.725345Z","steps":["trace[740296445] 'process raft request' (duration: 2.363892826s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:35.725566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.361197Z","time spent":"2.364267644s","remote":"127.0.0.1:52604","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-18T21:44:36.079615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.191808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T21:44:36.079966Z","caller":"traceutil/trace.go:171","msg":"trace[60223906] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"2.712754063s","start":"2026-03-18T21:44:33.367193Z","end":"2026-03-18T21:44:36.079947Z","steps":["trace[60223906] 'read index received' (duration: 2.357951348s)","trace[60223906] 'applied index is now lower than readState.Index' (duration: 354.801605ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:44:36.080186Z","caller":"traceutil/trace.go:171","msg":"trace[1891068505] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.515199573s","start":"2026-03-18T21:44:33.564967Z","end":"2026-03-18T21:44:36.080167Z","steps":["trace[1891068505] 'process raft request' (duration: 2.277203768s)","trace[1891068505] 'compare' (duration: 236.785009ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:44:36.080794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.804475937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T21:44:36.080836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089794532s","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-03-18T21:44:36.080792Z","caller":"traceutil/trace.go:171","msg":"trace[1314675897] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"604.009931ms","start":"2026-03-18T21:44:35.476741Z","end":"2026-03-18T21:44:36.080751Z","steps":["trace[1314675897] 'process raft request' (duration: 603.137803ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:36.080889Z","caller":"traceutil/trace.go:171","msg":"trace[815857421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.804649781s","start":"2026-03-18T21:44:34.276219Z","end":"2026-03-18T21:44:36.080869Z","steps":["trace[815857421] 'agreement among raft nodes before linearized reading' (duration: 1.804445337s)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:36.080907Z","caller":"traceutil/trace.go:171","msg":"trace[1198063477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"2.089953506s","start":"2026-03-18T21:44:33.990936Z","end":"2026-03-18T21:44:36.08089Z","steps":["trace[1198063477] 'agreement among raft nodes before linearized reading' (duration: 2.08966778s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.080945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:34.276192Z","time spent":"1.804745263s","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:44:36.080954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.990893Z","time spent":"2.090048608s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T21:44:36.080978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.687672922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-18T21:44:36.080984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089970027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T21:44:36.081011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:35.476712Z","time spent":"604.195836ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:36.081025Z","caller":"traceutil/trace.go:171","msg":"trace[614808634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"2.687723583s","start":"2026-03-18T21:44:33.393288Z","end":"2026-03-18T21:44:36.081012Z","steps":["trace[614808634] 'agreement among raft nodes before linearized reading' (duration: 2.68710921s)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:36.081043Z","caller":"traceutil/trace.go:171","msg":"trace[1674868343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"2.090083639s","start":"2026-03-18T21:44:33.990943Z","end":"2026-03-18T21:44:36.081027Z","steps":["trace[1674868343] 'agreement among raft nodes before linearized reading' (duration: 2.089830564s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.081072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.393204Z","time spent":"2.687858557s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T21:44:36.081082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.990911Z","time spent":"2.09016339s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T21:44:36.081335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.56494Z","time spent":"2.516288165s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:44:36.081603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.714385286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T21:44:36.081667Z","caller":"traceutil/trace.go:171","msg":"trace[1824866933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"2.714520679s","start":"2026-03-18T21:44:33.367131Z","end":"2026-03-18T21:44:36.081652Z","steps":["trace[1824866933] 'agreement among raft nodes before linearized reading' (duration: 2.713071829s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.081716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:33.367103Z","time spent":"2.714602351s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T21:44:36.589877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409136063614770,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T21:44:36.808515Z","caller":"traceutil/trace.go:171","msg":"trace[237431480] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"721.881496ms","start":"2026-03-18T21:44:36.086596Z","end":"2026-03-18T21:44:36.808478Z","steps":["trace[237431480] 'process raft request' (duration: 699.406825ms)","trace[237431480] 'compare' (duration: 21.865887ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:44:36.808702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:36.086576Z","time spent":"722.018898ms","remote":"127.0.0.1:52604","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-18T21:44:36.815676Z","caller":"traceutil/trace.go:171","msg":"trace[1710063793] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"726.150235ms","start":"2026-03-18T21:44:36.089513Z","end":"2026-03-18T21:44:36.815663Z","steps":["trace[1710063793] 'read index received' (duration: 696.502924ms)","trace[1710063793] 'applied index is now lower than readState.Index' (duration: 29.646371ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:44:36.815704Z","caller":"traceutil/trace.go:171","msg":"trace[938193309] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"728.839071ms","start":"2026-03-18T21:44:36.08685Z","end":"2026-03-18T21:44:36.815689Z","steps":["trace[938193309] 'process raft request' (duration: 728.580426ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:36.815773Z","caller":"traceutil/trace.go:171","msg":"trace[1187012553] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"728.75619ms","start":"2026-03-18T21:44:36.086995Z","end":"2026-03-18T21:44:36.815752Z","steps":["trace[1187012553] 'process raft request' (duration: 728.598016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.815887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.353319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T21:44:36.815962Z","caller":"traceutil/trace.go:171","msg":"trace[835251681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"726.484152ms","start":"2026-03-18T21:44:36.089468Z","end":"2026-03-18T21:44:36.815952Z","steps":["trace[835251681] 'agreement among raft nodes before linearized reading' (duration: 726.264857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.816013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:36.086978Z","time spent":"728.928483ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T21:44:36.815996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:36.089455Z","time spent":"726.532252ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T21:44:36.816073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:36.086841Z","time spent":"728.991674ms","remote":"127.0.0.1:52604","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-18T21:44:36.815535Z","caller":"traceutil/trace.go:171","msg":"trace[2016303403] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"728.873332ms","start":"2026-03-18T21:44:36.086617Z","end":"2026-03-18T21:44:36.81549Z","steps":["trace[2016303403] 'process raft request' (duration: 728.669768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:44:36.816641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:44:36.086596Z","time spent":"729.877473ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:44:57.19711Z","caller":"traceutil/trace.go:171","msg":"trace[14597831] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"202.646242ms","start":"2026-03-18T21:44:56.994441Z","end":"2026-03-18T21:44:57.197088Z","steps":["trace[14597831] 'process raft request' (duration: 182.843372ms)","trace[14597831] 'compare' (duration: 19.617807ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:44:57.216956Z","caller":"traceutil/trace.go:171","msg":"trace[1249809221] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"220.249264ms","start":"2026-03-18T21:44:56.996673Z","end":"2026-03-18T21:44:57.216922Z","steps":["trace[1249809221] 'process raft request' (duration: 220.02449ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:44:57.217189Z","caller":"traceutil/trace.go:171","msg":"trace[859649405] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"209.581104ms","start":"2026-03-18T21:44:57.007574Z","end":"2026-03-18T21:44:57.217155Z","steps":["trace[859649405] 'process raft request' (duration: 209.296749ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:00.969172Z","caller":"traceutil/trace.go:171","msg":"trace[731904026] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"132.651983ms","start":"2026-03-18T21:46:00.836495Z","end":"2026-03-18T21:46:00.969147Z","steps":["trace[731904026] 'process raft request' (duration: 132.51285ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:01.774045Z","caller":"traceutil/trace.go:171","msg":"trace[1215418845] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"105.990994ms","start":"2026-03-18T21:46:01.668029Z","end":"2026-03-18T21:46:01.77402Z","steps":["trace[1215418845] 'process raft request' (duration: 105.936463ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:01.77423Z","caller":"traceutil/trace.go:171","msg":"trace[1621471461] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"108.174592ms","start":"2026-03-18T21:46:01.666021Z","end":"2026-03-18T21:46:01.774196Z","steps":["trace[1621471461] 'process raft request' (duration: 106.45349ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:03.811957Z","caller":"traceutil/trace.go:171","msg":"trace[778160056] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"139.347317ms","start":"2026-03-18T21:46:03.672575Z","end":"2026-03-18T21:46:03.811922Z","steps":["trace[778160056] 'process raft request' (duration: 139.077963ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:04.028415Z","caller":"traceutil/trace.go:171","msg":"trace[1654664114] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"210.473188ms","start":"2026-03-18T21:46:03.817922Z","end":"2026-03-18T21:46:04.028395Z","steps":["trace[1654664114] 'process raft request' (duration: 210.377446ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:04.028618Z","caller":"traceutil/trace.go:171","msg":"trace[612394722] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"211.46681ms","start":"2026-03-18T21:46:03.817113Z","end":"2026-03-18T21:46:04.02858Z","steps":["trace[612394722] 'process raft request' (duration: 209.174911ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:46:11.519698Z","caller":"traceutil/trace.go:171","msg":"trace[827543356] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"118.277704ms","start":"2026-03-18T21:46:11.401372Z","end":"2026-03-18T21:46:11.51965Z","steps":["trace[827543356] 'process raft request' (duration: 117.752338ms)"],"step_count":1}