{"level":"warn","ts":"2026-06-12T01:22:39.591239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T01:22:39.591498Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-06-12T01:22:39.591604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T01:22:39.591622Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-12T01:22:39.591651Z","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-06-12T01:22:39.592529Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-06-12T01:22:39.592609Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-06-12T01:22:39.596899Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.086579ms"} {"level":"info","ts":"2026-06-12T01:22:39.60579Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-06-12T01:22:39.605943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-06-12T01:22:39.606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-06-12T01:22:39.606022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-12T01:22:39.60607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-06-12T01:22:39.606175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-06-12T01:22:39.611376Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-12T01:22:39.617146Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-12T01:22:39.619767Z","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-06-12T01:22:39.6222Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-12T01:22:39.622483Z","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-06-12T01:22:39.623796Z","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-06-12T01:22:39.623807Z","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-06-12T01:22:39.623808Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-12T01:22:39.624665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-06-12T01:22:39.624794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-06-12T01:22:39.627809Z","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-06-12T01:22:39.62803Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-12T01:22:39.628067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-12T01:22:39.628199Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-12T01:22:39.628217Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-06-12T01:22:40.10719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-06-12T01:22:40.107261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-06-12T01:22:40.10731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-06-12T01:22:40.107336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-06-12T01:22:40.107374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-12T01:22:40.107395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-06-12T01:22:40.107408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-06-12T01:22:40.207899Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-12T01:22:40.208391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T01:22:40.208449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T01:22:40.20849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T01:22:40.208653Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-12T01:22:40.208712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-12T01:22:40.210168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-12T01:22:40.212933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-06-12T01:22:40.215107Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T01:22:40.215306Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T01:22:40.215376Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-12T01:22:42.073032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.521908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-12T01:22:42.073191Z","caller":"traceutil/trace.go:171","msg":"trace[1961695861] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:73; }","duration":"220.740554ms","start":"2026-06-12T01:22:41.85243Z","end":"2026-06-12T01:22:42.073171Z","steps":["trace[1961695861] 'range keys from in-memory index tree' (duration: 220.434316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:22:42.07303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.030325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-12T01:22:42.073357Z","caller":"traceutil/trace.go:171","msg":"trace[1663317977] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:73; }","duration":"135.437896ms","start":"2026-06-12T01:22:41.937885Z","end":"2026-06-12T01:22:42.073323Z","steps":["trace[1663317977] 'range keys from in-memory index tree' (duration: 134.955784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:22:43.197729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.214525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-12T01:22:43.197846Z","caller":"traceutil/trace.go:171","msg":"trace[1433740878] linearizableReadLoop","detail":"{readStateIndex:85; appliedIndex:84; }","duration":"517.2249ms","start":"2026-06-12T01:22:42.680601Z","end":"2026-06-12T01:22:43.197826Z","steps":["trace[1433740878] 'read index received' (duration: 222.185733ms)","trace[1433740878] 'applied index is now lower than readState.Index' (duration: 295.037787ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:22:43.197992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.392974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-12T01:22:43.198043Z","caller":"traceutil/trace.go:171","msg":"trace[890787338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:81; }","duration":"517.471687ms","start":"2026-06-12T01:22:42.680554Z","end":"2026-06-12T01:22:43.198026Z","steps":["trace[890787338] 'agreement among raft nodes before linearized reading' (duration: 517.326643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:22:43.198089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:22:42.680539Z","time spent":"517.538238ms","remote":"127.0.0.1:55432","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-06-12T01:22:43.198249Z","caller":"traceutil/trace.go:171","msg":"trace[1801870129] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"544.16812ms","start":"2026-06-12T01:22:42.653891Z","end":"2026-06-12T01:22:43.198059Z","steps":["trace[1801870129] 'process raft request' (duration: 248.975968ms)","trace[1801870129] 'compare' (duration: 294.046871ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:22:43.198441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:22:42.653872Z","time spent":"544.488728ms","remote":"127.0.0.1:58540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T01:22:43.67343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.942403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-12T01:22:43.673537Z","caller":"traceutil/trace.go:171","msg":"trace[2095979351] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"470.437431ms","start":"2026-06-12T01:22:43.203078Z","end":"2026-06-12T01:22:43.673515Z","steps":["trace[2095979351] 'process raft request' (duration: 356.342294ms)","trace[2095979351] 'compare' (duration: 113.787649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:22:43.674159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:22:43.203051Z","time spent":"471.067638ms","remote":"127.0.0.1:58540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T01:22:44.011812Z","caller":"traceutil/trace.go:171","msg":"trace[1734198328] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"330.787202ms","start":"2026-06-12T01:22:43.680985Z","end":"2026-06-12T01:22:44.011773Z","steps":["trace[1734198328] 'read index received' (duration: 323.692963ms)","trace[1734198328] 'applied index is now lower than readState.Index' (duration: 7.093219ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:22:44.01188Z","caller":"traceutil/trace.go:171","msg":"trace[993188539] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"332.801076ms","start":"2026-06-12T01:22:43.679044Z","end":"2026-06-12T01:22:44.011845Z","steps":["trace[993188539] 'process raft request' (duration: 325.716897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:22:44.01203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:22:43.679026Z","time spent":"332.936799ms","remote":"127.0.0.1:58540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T01:22:44.012318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.052108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-12T01:22:44.012338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.326436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-12T01:22:44.012402Z","caller":"traceutil/trace.go:171","msg":"trace[623194909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:83; }","duration":"286.16121ms","start":"2026-06-12T01:22:43.726218Z","end":"2026-06-12T01:22:44.012379Z","steps":["trace[623194909] 'agreement among raft nodes before linearized reading' (duration: 285.941674ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:22:44.012421Z","caller":"traceutil/trace.go:171","msg":"trace[1823293248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"331.45544ms","start":"2026-06-12T01:22:43.680942Z","end":"2026-06-12T01:22:44.012398Z","steps":["trace[1823293248] 'agreement among raft nodes before linearized reading' (duration: 331.029158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:22:44.012646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:22:43.680926Z","time spent":"331.701806ms","remote":"127.0.0.1:55432","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-06-12T01:22:44.012911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.479792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-12T01:22:44.013017Z","caller":"traceutil/trace.go:171","msg":"trace[71505774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:83; }","duration":"288.637836ms","start":"2026-06-12T01:22:43.724349Z","end":"2026-06-12T01:22:44.012987Z","steps":["trace[71505774] 'agreement among raft nodes before linearized reading' (duration: 287.66021ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:03.466692Z","caller":"traceutil/trace.go:171","msg":"trace[414656775] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"100.758ms","start":"2026-06-12T01:23:03.365885Z","end":"2026-06-12T01:23:03.466643Z","steps":["trace[414656775] 'process raft request' (duration: 99.667721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:04.312915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.575252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T01:23:04.31301Z","caller":"traceutil/trace.go:171","msg":"trace[1267911342] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"139.71061ms","start":"2026-06-12T01:23:04.17328Z","end":"2026-06-12T01:23:04.312991Z","steps":["trace[1267911342] 'process raft request' (duration: 31.8563ms)","trace[1267911342] 'compare' (duration: 107.408308ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:14.777407Z","caller":"traceutil/trace.go:171","msg":"trace[1708521682] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"117.120449ms","start":"2026-06-12T01:23:14.660256Z","end":"2026-06-12T01:23:14.777376Z","steps":["trace[1708521682] 'read index received' (duration: 116.853862ms)","trace[1708521682] 'applied index is now lower than readState.Index' (duration: 265.547µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:14.777481Z","caller":"traceutil/trace.go:171","msg":"trace[1403708439] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"149.618113ms","start":"2026-06-12T01:23:14.627838Z","end":"2026-06-12T01:23:14.777456Z","steps":["trace[1403708439] 'process raft request' (duration: 149.389387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:14.777582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.258252ms","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-06-12T01:23:14.777677Z","caller":"traceutil/trace.go:171","msg":"trace[360932018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"117.436977ms","start":"2026-06-12T01:23:14.660222Z","end":"2026-06-12T01:23:14.777659Z","steps":["trace[360932018] 'agreement among raft nodes before linearized reading' (duration: 117.259352ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:17.858688Z","caller":"traceutil/trace.go:171","msg":"trace[1325458005] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"104.639658ms","start":"2026-06-12T01:23:17.754022Z","end":"2026-06-12T01:23:17.858662Z","steps":["trace[1325458005] 'process raft request' (duration: 95.974926ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:17.925457Z","caller":"traceutil/trace.go:171","msg":"trace[72017073] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"157.149126ms","start":"2026-06-12T01:23:17.768282Z","end":"2026-06-12T01:23:17.925431Z","steps":["trace[72017073] 'process raft request' (duration: 156.978271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:18.317926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.757897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-12T01:23:18.31806Z","caller":"traceutil/trace.go:171","msg":"trace[266979541] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"366.279218ms","start":"2026-06-12T01:23:17.951757Z","end":"2026-06-12T01:23:18.318036Z","steps":["trace[266979541] 'process raft request' (duration: 40.308505ms)","trace[266979541] 'compare' (duration: 325.394938ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:23:18.318116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:17.951736Z","time spent":"366.358439ms","remote":"127.0.0.1:55466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T01:23:18.636564Z","caller":"traceutil/trace.go:171","msg":"trace[1552782230] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"405.225178ms","start":"2026-06-12T01:23:18.231306Z","end":"2026-06-12T01:23:18.636531Z","steps":["trace[1552782230] 'read index received' (duration: 399.208003ms)","trace[1552782230] 'applied index is now lower than readState.Index' (duration: 6.016265ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:23:18.637137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.560975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-12T01:23:18.637136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.830254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-12T01:23:18.637202Z","caller":"traceutil/trace.go:171","msg":"trace[1222849761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"264.674108ms","start":"2026-06-12T01:23:18.372511Z","end":"2026-06-12T01:23:18.637185Z","steps":["trace[1222849761] 'agreement among raft nodes before linearized reading' (duration: 264.539804ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:18.637352Z","caller":"traceutil/trace.go:171","msg":"trace[1689702699] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"177.301374ms","start":"2026-06-12T01:23:18.460042Z","end":"2026-06-12T01:23:18.637343Z","steps":["trace[1689702699] 'process raft request' (duration: 176.894873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:18.637452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.711672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:23:18.637474Z","caller":"traceutil/trace.go:171","msg":"trace[1091310473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"247.827525ms","start":"2026-06-12T01:23:18.389641Z","end":"2026-06-12T01:23:18.637468Z","steps":["trace[1091310473] 'agreement among raft nodes before linearized reading' (duration: 247.796504ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:18.637982Z","caller":"traceutil/trace.go:171","msg":"trace[1563197068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"405.959037ms","start":"2026-06-12T01:23:18.231234Z","end":"2026-06-12T01:23:18.637193Z","steps":["trace[1563197068] 'agreement among raft nodes before linearized reading' (duration: 405.70493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:18.63808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:18.231185Z","time spent":"406.86495ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-12T01:23:18.638353Z","caller":"traceutil/trace.go:171","msg":"trace[468567622] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"590.706432ms","start":"2026-06-12T01:23:18.047624Z","end":"2026-06-12T01:23:18.63833Z","steps":["trace[468567622] 'process raft request' (duration: 583.020645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:18.638435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:18.047579Z","time spent":"590.804825ms","remote":"127.0.0.1:55466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:23:20.984457Z","caller":"traceutil/trace.go:171","msg":"trace[673761478] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"334.216184ms","start":"2026-06-12T01:23:20.650215Z","end":"2026-06-12T01:23:20.984431Z","steps":["trace[673761478] 'process raft request' (duration: 334.06335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:20.984636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:20.650198Z","time spent":"334.344878ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:23:20.994573Z","caller":"traceutil/trace.go:171","msg":"trace[458558336] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"344.118249ms","start":"2026-06-12T01:23:20.650418Z","end":"2026-06-12T01:23:20.994536Z","steps":["trace[458558336] 'process raft request' (duration: 343.877842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:20.994836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:20.650395Z","time spent":"344.295743ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:23:20.995947Z","caller":"traceutil/trace.go:171","msg":"trace[1901526458] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:620; }","duration":"198.875278ms","start":"2026-06-12T01:23:20.797056Z","end":"2026-06-12T01:23:20.995932Z","steps":["trace[1901526458] 'read index received' (duration: 187.301711ms)","trace[1901526458] 'applied index is now lower than readState.Index' (duration: 11.572967ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:20.99603Z","caller":"traceutil/trace.go:171","msg":"trace[276794951] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"324.856214ms","start":"2026-06-12T01:23:20.671167Z","end":"2026-06-12T01:23:20.996023Z","steps":["trace[276794951] 'process raft request' (duration: 324.075504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:20.996091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:20.671058Z","time spent":"324.993377ms","remote":"127.0.0.1:58312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T01:23:20.996318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.533929ms","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-06-12T01:23:20.996351Z","caller":"traceutil/trace.go:171","msg":"trace[1565917148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"191.591691ms","start":"2026-06-12T01:23:20.80475Z","end":"2026-06-12T01:23:20.996342Z","steps":["trace[1565917148] 'agreement among raft nodes before linearized reading' (duration: 191.464768ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:20.996393Z","caller":"traceutil/trace.go:171","msg":"trace[2068577265] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"320.004378ms","start":"2026-06-12T01:23:20.676318Z","end":"2026-06-12T01:23:20.996323Z","steps":["trace[2068577265] 'process raft request' (duration: 319.559277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:20.99656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:20.676295Z","time spent":"320.192104ms","remote":"127.0.0.1:55466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T01:23:20.996628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.548485ms","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-06-12T01:23:20.996746Z","caller":"traceutil/trace.go:171","msg":"trace[1460754039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"199.709389ms","start":"2026-06-12T01:23:20.796997Z","end":"2026-06-12T01:23:20.996706Z","steps":["trace[1460754039] 'agreement among raft nodes before linearized reading' (duration: 199.378311ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:20.996951Z","caller":"traceutil/trace.go:171","msg":"trace[191954175] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"123.644446ms","start":"2026-06-12T01:23:20.873202Z","end":"2026-06-12T01:23:20.996846Z","steps":["trace[191954175] 'process raft request' (duration: 122.710472ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:21.635028Z","caller":"traceutil/trace.go:171","msg":"trace[976518939] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"103.345685ms","start":"2026-06-12T01:23:21.531666Z","end":"2026-06-12T01:23:21.635012Z","steps":["trace[976518939] 'compare' (duration: 96.129419ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:21.81811Z","caller":"traceutil/trace.go:171","msg":"trace[631288952] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"139.618256ms","start":"2026-06-12T01:23:21.678461Z","end":"2026-06-12T01:23:21.818079Z","steps":["trace[631288952] 'process raft request' (duration: 78.65466ms)","trace[631288952] 'compare' (duration: 60.823112ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:27.359669Z","caller":"traceutil/trace.go:171","msg":"trace[965635419] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"346.230032ms","start":"2026-06-12T01:23:27.01341Z","end":"2026-06-12T01:23:27.35964Z","steps":["trace[965635419] 'process raft request' (duration: 346.102499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:27.35982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.013387Z","time spent":"346.356186ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:23:27.528687Z","caller":"traceutil/trace.go:171","msg":"trace[1320597636] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"483.422136ms","start":"2026-06-12T01:23:27.045237Z","end":"2026-06-12T01:23:27.528659Z","steps":["trace[1320597636] 'read index received' (duration: 314.229081ms)","trace[1320597636] 'applied index is now lower than readState.Index' (duration: 169.192205ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:27.528742Z","caller":"traceutil/trace.go:171","msg":"trace[228998974] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"512.34604ms","start":"2026-06-12T01:23:27.016368Z","end":"2026-06-12T01:23:27.528714Z","steps":["trace[228998974] 'process raft request' (duration: 512.116744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:27.528945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.619132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T01:23:27.528964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.326003ms","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-06-12T01:23:27.529009Z","caller":"traceutil/trace.go:171","msg":"trace[1151111368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"140.716224ms","start":"2026-06-12T01:23:27.388279Z","end":"2026-06-12T01:23:27.528995Z","steps":["trace[1151111368] 'agreement among raft nodes before linearized reading' (duration: 140.629422ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:27.529025Z","caller":"traceutil/trace.go:171","msg":"trace[540210716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"483.401115ms","start":"2026-06-12T01:23:27.045609Z","end":"2026-06-12T01:23:27.52901Z","steps":["trace[540210716] 'agreement among raft nodes before linearized reading' (duration: 483.21707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:27.528977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.016339Z","time spent":"512.556034ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:23:27.529279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.030512ms","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-06-12T01:23:27.529327Z","caller":"traceutil/trace.go:171","msg":"trace[1193936834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"484.106134ms","start":"2026-06-12T01:23:27.045209Z","end":"2026-06-12T01:23:27.529315Z","steps":["trace[1193936834] 'agreement among raft nodes before linearized reading' (duration: 483.56998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:27.529397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.045197Z","time spent":"484.184516ms","remote":"127.0.0.1:58468","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-06-12T01:23:27.529071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.045592Z","time spent":"483.469687ms","remote":"127.0.0.1:58468","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-06-12T01:23:27.644857Z","caller":"traceutil/trace.go:171","msg":"trace[1473920901] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"110.515768ms","start":"2026-06-12T01:23:27.534312Z","end":"2026-06-12T01:23:27.644827Z","steps":["trace[1473920901] 'process raft request' (duration: 110.371594ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:27.645248Z","caller":"traceutil/trace.go:171","msg":"trace[738945304] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"102.837691ms","start":"2026-06-12T01:23:27.542369Z","end":"2026-06-12T01:23:27.645207Z","steps":["trace[738945304] 'process raft request' (duration: 102.41235ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:28.168378Z","caller":"traceutil/trace.go:171","msg":"trace[1498304816] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"327.969144ms","start":"2026-06-12T01:23:27.840379Z","end":"2026-06-12T01:23:28.168348Z","steps":["trace[1498304816] 'read index received' (duration: 324.021633ms)","trace[1498304816] 'applied index is now lower than readState.Index' (duration: 3.943071ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:28.168394Z","caller":"traceutil/trace.go:171","msg":"trace[1237863442] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"396.874693ms","start":"2026-06-12T01:23:27.771486Z","end":"2026-06-12T01:23:28.16836Z","steps":["trace[1237863442] 'process raft request' (duration: 392.84862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:28.168592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.771465Z","time spent":"397.004907ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:23:28.16859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.20174ms","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-06-12T01:23:28.168657Z","caller":"traceutil/trace.go:171","msg":"trace[911271138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"328.310683ms","start":"2026-06-12T01:23:27.840328Z","end":"2026-06-12T01:23:28.168638Z","steps":["trace[911271138] 'agreement among raft nodes before linearized reading' (duration: 328.099617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:28.168697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:27.840306Z","time spent":"328.381614ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-12T01:23:29.794039Z","caller":"traceutil/trace.go:171","msg":"trace[606546632] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"404.969751ms","start":"2026-06-12T01:23:29.389046Z","end":"2026-06-12T01:23:29.794016Z","steps":["trace[606546632] 'read index received' (duration: 404.823937ms)","trace[606546632] 'applied index is now lower than readState.Index' (duration: 144.784µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:29.794104Z","caller":"traceutil/trace.go:171","msg":"trace[954697628] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"424.80777ms","start":"2026-06-12T01:23:29.369254Z","end":"2026-06-12T01:23:29.794062Z","steps":["trace[954697628] 'process raft request' (duration: 424.636646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:29.794535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:29.369233Z","time spent":"425.237922ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:23:29.794174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.122776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:23:29.794675Z","caller":"traceutil/trace.go:171","msg":"trace[2095491797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"405.67631ms","start":"2026-06-12T01:23:29.388985Z","end":"2026-06-12T01:23:29.794661Z","steps":["trace[2095491797] 'agreement among raft nodes before linearized reading' (duration: 405.145166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:29.79471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:23:29.38897Z","time spent":"405.732991ms","remote":"127.0.0.1:55432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T01:23:29.794296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.816749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-12T01:23:29.794846Z","caller":"traceutil/trace.go:171","msg":"trace[620725788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"261.403824ms","start":"2026-06-12T01:23:29.533422Z","end":"2026-06-12T01:23:29.794825Z","steps":["trace[620725788] 'agreement among raft nodes before linearized reading' (duration: 260.759727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:29.794349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.078851ms","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-06-12T01:23:29.794979Z","caller":"traceutil/trace.go:171","msg":"trace[1275014894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"146.725328ms","start":"2026-06-12T01:23:29.648237Z","end":"2026-06-12T01:23:29.794962Z","steps":["trace[1275014894] 'agreement among raft nodes before linearized reading' (duration: 145.973399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:29.7944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.13144ms","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-06-12T01:23:29.795094Z","caller":"traceutil/trace.go:171","msg":"trace[421446302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"237.88348ms","start":"2026-06-12T01:23:29.557199Z","end":"2026-06-12T01:23:29.795083Z","steps":["trace[421446302] 'agreement among raft nodes before linearized reading' (duration: 237.021667ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:40.461596Z","caller":"traceutil/trace.go:171","msg":"trace[710882697] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"205.029786ms","start":"2026-06-12T01:23:40.256538Z","end":"2026-06-12T01:23:40.461568Z","steps":["trace[710882697] 'read index received' (duration: 204.876792ms)","trace[710882697] 'applied index is now lower than readState.Index' (duration: 151.854µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:23:40.461647Z","caller":"traceutil/trace.go:171","msg":"trace[1826516144] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"235.232892ms","start":"2026-06-12T01:23:40.226378Z","end":"2026-06-12T01:23:40.461611Z","steps":["trace[1826516144] 'process raft request' (duration: 235.053377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:40.461814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.267442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-12T01:23:40.461861Z","caller":"traceutil/trace.go:171","msg":"trace[1977911624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"205.357745ms","start":"2026-06-12T01:23:40.256492Z","end":"2026-06-12T01:23:40.46185Z","steps":["trace[1977911624] 'agreement among raft nodes before linearized reading' (duration: 205.197761ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:40.492098Z","caller":"traceutil/trace.go:171","msg":"trace[545680128] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"183.929534ms","start":"2026-06-12T01:23:40.308149Z","end":"2026-06-12T01:23:40.492079Z","steps":["trace[545680128] 'process raft request' (duration: 183.855022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:23:40.492287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.010677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:23:40.492326Z","caller":"traceutil/trace.go:171","msg":"trace[84693056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"105.094109ms","start":"2026-06-12T01:23:40.38722Z","end":"2026-06-12T01:23:40.492315Z","steps":["trace[84693056] 'agreement among raft nodes before linearized reading' (duration: 105.005417ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:23:45.780562Z","caller":"traceutil/trace.go:171","msg":"trace[531673153] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"186.439018ms","start":"2026-06-12T01:23:45.594095Z","end":"2026-06-12T01:23:45.780534Z","steps":["trace[531673153] 'process raft request' (duration: 186.281834ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:24:05.068283Z","caller":"traceutil/trace.go:171","msg":"trace[708215727] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"112.773376ms","start":"2026-06-12T01:24:04.955472Z","end":"2026-06-12T01:24:05.068245Z","steps":["trace[708215727] 'process raft request' (duration: 112.51969ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:24:20.31371Z","caller":"traceutil/trace.go:171","msg":"trace[2105897069] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"170.856476ms","start":"2026-06-12T01:24:20.142827Z","end":"2026-06-12T01:24:20.313683Z","steps":["trace[2105897069] 'read index received' (duration: 170.725173ms)","trace[2105897069] 'applied index is now lower than readState.Index' (duration: 130.223µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:20.313814Z","caller":"traceutil/trace.go:171","msg":"trace[1650378737] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"171.726679ms","start":"2026-06-12T01:24:20.142056Z","end":"2026-06-12T01:24:20.313782Z","steps":["trace[1650378737] 'process raft request' (duration: 171.39053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:20.313922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.074031ms","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-06-12T01:24:20.313958Z","caller":"traceutil/trace.go:171","msg":"trace[618156040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"171.144944ms","start":"2026-06-12T01:24:20.142803Z","end":"2026-06-12T01:24:20.313948Z","steps":["trace[618156040] 'agreement among raft nodes before linearized reading' (duration: 171.00189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:20.640935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.078749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-12T01:24:20.641155Z","caller":"traceutil/trace.go:171","msg":"trace[1832780828] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"327.349466ms","start":"2026-06-12T01:24:20.313779Z","end":"2026-06-12T01:24:20.641128Z","steps":["trace[1832780828] 'read index received' (duration: 48.969429ms)","trace[1832780828] 'applied index is now lower than readState.Index' (duration: 278.377527ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:20.641191Z","caller":"traceutil/trace.go:171","msg":"trace[363647662] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"323.166289ms","start":"2026-06-12T01:24:20.318002Z","end":"2026-06-12T01:24:20.641168Z","steps":["trace[363647662] 'process raft request' (duration: 323.035136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:20.641295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:20.317984Z","time spent":"323.247221ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:24:20.641412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.27297ms","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-06-12T01:24:20.641458Z","caller":"traceutil/trace.go:171","msg":"trace[13914663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"390.329571ms","start":"2026-06-12T01:24:20.251114Z","end":"2026-06-12T01:24:20.641444Z","steps":["trace[13914663] 'agreement among raft nodes before linearized reading' (duration: 390.129756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:20.641433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.96235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T01:24:20.641496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:20.251048Z","time spent":"390.438814ms","remote":"127.0.0.1:58468","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-06-12T01:24:20.641502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.325465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-12T01:24:20.641535Z","caller":"traceutil/trace.go:171","msg":"trace[1061494401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"469.382037ms","start":"2026-06-12T01:24:20.172141Z","end":"2026-06-12T01:24:20.641523Z","steps":["trace[1061494401] 'agreement among raft nodes before linearized reading' (duration: 469.268574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:20.641564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:20.172125Z","time spent":"469.432628ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-12T01:24:20.641561Z","caller":"traceutil/trace.go:171","msg":"trace[2145230916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"255.115364ms","start":"2026-06-12T01:24:20.38641Z","end":"2026-06-12T01:24:20.641502Z","steps":["trace[2145230916] 'agreement among raft nodes before linearized reading' (duration: 254.95193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:21.03856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.416319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T01:24:21.038671Z","caller":"traceutil/trace.go:171","msg":"trace[501313406] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"286.951926ms","start":"2026-06-12T01:24:20.7517Z","end":"2026-06-12T01:24:21.038652Z","steps":["trace[501313406] 'read index received' (duration: 69.942µs)","trace[501313406] 'applied index is now lower than readState.Index' (duration: 286.880534ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:21.03879Z","caller":"traceutil/trace.go:171","msg":"trace[243506933] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"392.959679ms","start":"2026-06-12T01:24:20.645815Z","end":"2026-06-12T01:24:21.038775Z","steps":["trace[243506933] 'process raft request' (duration: 41.245562ms)","trace[243506933] 'compare' (duration: 350.994028ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:24:21.038856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:20.6458Z","time spent":"393.023ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:24:21.039303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.609012ms","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-06-12T01:24:21.039385Z","caller":"traceutil/trace.go:171","msg":"trace[1346231603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"287.725755ms","start":"2026-06-12T01:24:20.751645Z","end":"2026-06-12T01:24:21.039371Z","steps":["trace[1346231603] 'agreement among raft nodes before linearized reading' (duration: 287.056328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:21.708183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.972573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T01:24:21.708268Z","caller":"traceutil/trace.go:171","msg":"trace[32440313] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"664.543811ms","start":"2026-06-12T01:24:21.043708Z","end":"2026-06-12T01:24:21.708251Z","steps":["trace[32440313] 'process raft request' (duration: 292.438794ms)","trace[32440313] 'compare' (duration: 371.864901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:24:21.708308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:21.043686Z","time spent":"664.603133ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:24:21.886813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368300470468222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-12T01:24:22.117847Z","caller":"traceutil/trace.go:171","msg":"trace[801562215] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"731.075078ms","start":"2026-06-12T01:24:21.38675Z","end":"2026-06-12T01:24:22.117825Z","steps":["trace[801562215] 'read index received' (duration: 730.915914ms)","trace[801562215] 'applied index is now lower than readState.Index' (duration: 158.584µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:22.117871Z","caller":"traceutil/trace.go:171","msg":"trace[1287786847] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"958.610218ms","start":"2026-06-12T01:24:21.159234Z","end":"2026-06-12T01:24:22.117845Z","steps":["trace[1287786847] 'process raft request' (duration: 958.470064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:22.117933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.19462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:24:22.117957Z","caller":"traceutil/trace.go:171","msg":"trace[68230054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"731.272682ms","start":"2026-06-12T01:24:21.386677Z","end":"2026-06-12T01:24:22.11795Z","steps":["trace[68230054] 'agreement among raft nodes before linearized reading' (duration: 731.216751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:22.11798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:21.386653Z","time spent":"731.321074ms","remote":"127.0.0.1:55432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T01:24:22.118008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:21.15921Z","time spent":"958.718881ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:24:42.236155Z","caller":"traceutil/trace.go:171","msg":"trace[1385117511] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"330.796524ms","start":"2026-06-12T01:24:41.905319Z","end":"2026-06-12T01:24:42.236116Z","steps":["trace[1385117511] 'process raft request' (duration: 330.686051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:42.236278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:41.9053Z","time spent":"330.909566ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:24:42.46991Z","caller":"traceutil/trace.go:171","msg":"trace[2145335010] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"288.073335ms","start":"2026-06-12T01:24:42.181808Z","end":"2026-06-12T01:24:42.469881Z","steps":["trace[2145335010] 'process raft request' (duration: 256.613573ms)","trace[2145335010] 'compare' (duration: 31.276827ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:43.529737Z","caller":"traceutil/trace.go:171","msg":"trace[1564737284] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"144.425033ms","start":"2026-06-12T01:24:43.385288Z","end":"2026-06-12T01:24:43.529713Z","steps":["trace[1564737284] 'read index received' (duration: 144.243338ms)","trace[1564737284] 'applied index is now lower than readState.Index' (duration: 180.755µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:24:43.529851Z","caller":"traceutil/trace.go:171","msg":"trace[643128154] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"368.71942ms","start":"2026-06-12T01:24:43.161121Z","end":"2026-06-12T01:24:43.52984Z","steps":["trace[643128154] 'process raft request' (duration: 368.456943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:43.529959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:24:43.1611Z","time spent":"368.779122ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:24:43.530028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.571461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:24:43.530108Z","caller":"traceutil/trace.go:171","msg":"trace[1197602282] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1064; }","duration":"114.710374ms","start":"2026-06-12T01:24:43.415378Z","end":"2026-06-12T01:24:43.530088Z","steps":["trace[1197602282] 'agreement among raft nodes before linearized reading' (duration: 114.584851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:43.530417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.080989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:24:43.530549Z","caller":"traceutil/trace.go:171","msg":"trace[578262369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"145.263623ms","start":"2026-06-12T01:24:43.385255Z","end":"2026-06-12T01:24:43.530518Z","steps":["trace[578262369] 'agreement among raft nodes before linearized reading' (duration: 144.995867ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:24:44.646815Z","caller":"traceutil/trace.go:171","msg":"trace[64332419] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"120.40749ms","start":"2026-06-12T01:24:44.526375Z","end":"2026-06-12T01:24:44.646782Z","steps":["trace[64332419] 'process raft request' (duration: 99.803765ms)","trace[64332419] 'compare' (duration: 20.491032ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:24:44.95451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.443888ms","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-06-12T01:24:44.954618Z","caller":"traceutil/trace.go:171","msg":"trace[163788512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1068; }","duration":"196.573982ms","start":"2026-06-12T01:24:44.758015Z","end":"2026-06-12T01:24:44.954589Z","steps":["trace[163788512] 'range keys from in-memory index tree' (duration: 196.314545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:44.954611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.33545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-12T01:24:44.954707Z","caller":"traceutil/trace.go:171","msg":"trace[5967619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1068; }","duration":"166.472754ms","start":"2026-06-12T01:24:44.78821Z","end":"2026-06-12T01:24:44.954683Z","steps":["trace[5967619] 'range keys from in-memory index tree' (duration: 166.162266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:24:44.954272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.819152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-12T01:24:44.95549Z","caller":"traceutil/trace.go:171","msg":"trace[212517233] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1068; }","duration":"164.067233ms","start":"2026-06-12T01:24:44.791395Z","end":"2026-06-12T01:24:44.955462Z","steps":["trace[212517233] 'range keys from in-memory index tree' (duration: 162.660188ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:24:58.213251Z","caller":"traceutil/trace.go:171","msg":"trace[59159683] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"132.724224ms","start":"2026-06-12T01:24:58.0805Z","end":"2026-06-12T01:24:58.213224Z","steps":["trace[59159683] 'process raft request' (duration: 132.603711ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:01.095246Z","caller":"traceutil/trace.go:171","msg":"trace[395164265] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"385.039366ms","start":"2026-06-12T01:25:00.710191Z","end":"2026-06-12T01:25:01.09523Z","steps":["trace[395164265] 'read index received' (duration: 384.897143ms)","trace[395164265] 'applied index is now lower than readState.Index' (duration: 141.703µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:01.095323Z","caller":"traceutil/trace.go:171","msg":"trace[989151995] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"535.600673ms","start":"2026-06-12T01:25:00.559716Z","end":"2026-06-12T01:25:01.095317Z","steps":["trace[989151995] 'process raft request' (duration: 535.407999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.095419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:00.559691Z","time spent":"535.667266ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:25:01.095545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.353854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-12T01:25:01.095563Z","caller":"traceutil/trace.go:171","msg":"trace[986842678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"385.393816ms","start":"2026-06-12T01:25:00.710165Z","end":"2026-06-12T01:25:01.095559Z","steps":["trace[986842678] 'agreement among raft nodes before linearized reading' (duration: 385.327624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.095577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:00.710152Z","time spent":"385.421556ms","remote":"127.0.0.1:58468","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-06-12T01:25:01.095733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.143471ms","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:7"} {"level":"info","ts":"2026-06-12T01:25:01.095817Z","caller":"traceutil/trace.go:171","msg":"trace[1267468573] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1171; }","duration":"360.254295ms","start":"2026-06-12T01:25:00.735541Z","end":"2026-06-12T01:25:01.095795Z","steps":["trace[1267468573] 'agreement among raft nodes before linearized reading' (duration: 360.130922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.095888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:00.735529Z","time spent":"360.346627ms","remote":"127.0.0.1:55260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-06-12T01:25:01.598371Z","caller":"traceutil/trace.go:171","msg":"trace[541202981] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"498.410007ms","start":"2026-06-12T01:25:01.099939Z","end":"2026-06-12T01:25:01.598349Z","steps":["trace[541202981] 'process raft request' (duration: 498.297304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.598508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:01.099921Z","time spent":"498.508009ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:25:01.598942Z","caller":"traceutil/trace.go:171","msg":"trace[627764402] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1217; }","duration":"214.219761ms","start":"2026-06-12T01:25:01.384706Z","end":"2026-06-12T01:25:01.598926Z","steps":["trace[627764402] 'read index received' (duration: 213.605875ms)","trace[627764402] 'applied index is now lower than readState.Index' (duration: 612.956µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:01.59902Z","caller":"traceutil/trace.go:171","msg":"trace[1633570697] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"496.932759ms","start":"2026-06-12T01:25:01.102063Z","end":"2026-06-12T01:25:01.598996Z","steps":["trace[1633570697] 'process raft request' (duration: 496.765024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.59905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.344915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:25:01.599089Z","caller":"traceutil/trace.go:171","msg":"trace[256704274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"214.421657ms","start":"2026-06-12T01:25:01.384658Z","end":"2026-06-12T01:25:01.599079Z","steps":["trace[256704274] 'agreement among raft nodes before linearized reading' (duration: 214.340465ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:01.599085Z","caller":"traceutil/trace.go:171","msg":"trace[1416967858] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"495.752959ms","start":"2026-06-12T01:25:01.103299Z","end":"2026-06-12T01:25:01.599052Z","steps":["trace[1416967858] 'process raft request' (duration: 495.593025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:01.599133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:01.102043Z","time spent":"497.038122ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:25:01.599266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:01.10329Z","time spent":"495.890362ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:25:18.816799Z","caller":"traceutil/trace.go:171","msg":"trace[1105850124] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"297.677458ms","start":"2026-06-12T01:25:18.519097Z","end":"2026-06-12T01:25:18.816775Z","steps":["trace[1105850124] 'process raft request' (duration: 297.554695ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:19.298021Z","caller":"traceutil/trace.go:171","msg":"trace[508209598] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"129.827202ms","start":"2026-06-12T01:25:19.168163Z","end":"2026-06-12T01:25:19.29799Z","steps":["trace[508209598] 'process raft request' (duration: 129.674008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:19.888574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334368300470468831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-12T01:25:20.335215Z","caller":"traceutil/trace.go:171","msg":"trace[1628800425] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"946.854096ms","start":"2026-06-12T01:25:19.388332Z","end":"2026-06-12T01:25:20.335186Z","steps":["trace[1628800425] 'read index received' (duration: 946.753914ms)","trace[1628800425] 'applied index is now lower than readState.Index' (duration: 99.232µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:20.335266Z","caller":"traceutil/trace.go:171","msg":"trace[2012387876] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"959.90732ms","start":"2026-06-12T01:25:19.375328Z","end":"2026-06-12T01:25:20.335235Z","steps":["trace[2012387876] 'process raft request' (duration: 959.715165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.335379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.99335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T01:25:20.335424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.375295Z","time spent":"960.032523ms","remote":"127.0.0.1:58378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T01:25:20.335421Z","caller":"traceutil/trace.go:171","msg":"trace[684830594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"947.114932ms","start":"2026-06-12T01:25:19.388294Z","end":"2026-06-12T01:25:20.335409Z","steps":["trace[684830594] 'agreement among raft nodes before linearized reading' (duration: 947.003939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.335475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.388287Z","time spent":"947.179224ms","remote":"127.0.0.1:55444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T01:25:20.53576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.975899ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-12T01:25:20.535878Z","caller":"traceutil/trace.go:171","msg":"trace[1025979144] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"200.587028ms","start":"2026-06-12T01:25:20.335274Z","end":"2026-06-12T01:25:20.535861Z","steps":["trace[1025979144] 'read index received' (duration: 88.408964ms)","trace[1025979144] 'applied index is now lower than readState.Index' (duration: 112.176514ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T01:25:20.535968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103482162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:25:20.536014Z","caller":"traceutil/trace.go:171","msg":"trace[1174688608] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1251; }","duration":"1.103563904s","start":"2026-06-12T01:25:19.432438Z","end":"2026-06-12T01:25:20.536002Z","steps":["trace[1174688608] 'agreement among raft nodes before linearized reading' (duration: 1.103485142s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.536031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.963155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-12T01:25:20.536049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.432424Z","time spent":"1.103614715s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-12T01:25:20.536035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.921822ms","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-06-12T01:25:20.536099Z","caller":"traceutil/trace.go:171","msg":"trace[1072416477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"803.031147ms","start":"2026-06-12T01:25:19.733027Z","end":"2026-06-12T01:25:20.536059Z","steps":["trace[1072416477] 'agreement among raft nodes before linearized reading' (duration: 802.890693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.536108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.056093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-12T01:25:20.536146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.733015Z","time spent":"803.122439ms","remote":"127.0.0.1:58468","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-06-12T01:25:20.536144Z","caller":"traceutil/trace.go:171","msg":"trace[971608233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"836.076976ms","start":"2026-06-12T01:25:19.700051Z","end":"2026-06-12T01:25:20.536128Z","steps":["trace[971608233] 'agreement among raft nodes before linearized reading' (duration: 835.82116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.536047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.424658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T01:25:20.536151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.163685ms","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-06-12T01:25:20.536177Z","caller":"traceutil/trace.go:171","msg":"trace[375641031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"796.203827ms","start":"2026-06-12T01:25:19.739955Z","end":"2026-06-12T01:25:20.536158Z","steps":["trace[375641031] 'agreement among raft nodes before linearized reading' (duration: 795.92331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.536184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.700031Z","time spent":"836.144379ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-12T01:25:20.536216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.739944Z","time spent":"796.263338ms","remote":"127.0.0.1:58468","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-06-12T01:25:20.536218Z","caller":"traceutil/trace.go:171","msg":"trace[744784588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"771.231577ms","start":"2026-06-12T01:25:19.764965Z","end":"2026-06-12T01:25:20.536196Z","steps":["trace[744784588] 'agreement among raft nodes before linearized reading' (duration: 771.019542ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:20.536239Z","caller":"traceutil/trace.go:171","msg":"trace[1644367649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"880.641733ms","start":"2026-06-12T01:25:19.655559Z","end":"2026-06-12T01:25:20.536201Z","steps":["trace[1644367649] 'agreement among raft nodes before linearized reading' (duration: 880.420108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:20.536264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.764913Z","time spent":"771.341141ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-12T01:25:20.536288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:19.655539Z","time spent":"880.737705ms","remote":"127.0.0.1:55432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-12T01:25:20.697558Z","caller":"traceutil/trace.go:171","msg":"trace[1713920975] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"156.897274ms","start":"2026-06-12T01:25:20.54064Z","end":"2026-06-12T01:25:20.697537Z","steps":["trace[1713920975] 'process raft request' (duration: 156.812822ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:20.697559Z","caller":"traceutil/trace.go:171","msg":"trace[175571428] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"157.325404ms","start":"2026-06-12T01:25:20.540205Z","end":"2026-06-12T01:25:20.697531Z","steps":["trace[175571428] 'process raft request' (duration: 101.435247ms)","trace[175571428] 'compare' (duration: 55.718643ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:20.697749Z","caller":"traceutil/trace.go:171","msg":"trace[278784439] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"156.534085ms","start":"2026-06-12T01:25:20.541207Z","end":"2026-06-12T01:25:20.697741Z","steps":["trace[278784439] 'process raft request' (duration: 156.303749ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:20.697806Z","caller":"traceutil/trace.go:171","msg":"trace[1732906448] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"111.803824ms","start":"2026-06-12T01:25:20.585994Z","end":"2026-06-12T01:25:20.697798Z","steps":["trace[1732906448] 'process raft request' (duration: 111.659561ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:21.441511Z","caller":"traceutil/trace.go:171","msg":"trace[1741752435] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"137.522132ms","start":"2026-06-12T01:25:21.303965Z","end":"2026-06-12T01:25:21.441487Z","steps":["trace[1741752435] 'process raft request' (duration: 137.348558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:21.871602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.876934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-12T01:25:21.87174Z","caller":"traceutil/trace.go:171","msg":"trace[371797424] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"142.29247ms","start":"2026-06-12T01:25:21.729432Z","end":"2026-06-12T01:25:21.871725Z","steps":["trace[371797424] 'process raft request' (duration: 142.253779ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:21.871805Z","caller":"traceutil/trace.go:171","msg":"trace[1699285900] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"261.912918ms","start":"2026-06-12T01:25:21.60986Z","end":"2026-06-12T01:25:21.871773Z","steps":["trace[1699285900] 'process raft request' (duration: 112.813759ms)","trace[1699285900] 'compare' (duration: 148.754301ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:24.728741Z","caller":"traceutil/trace.go:171","msg":"trace[2133425366] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"105.678243ms","start":"2026-06-12T01:25:24.62304Z","end":"2026-06-12T01:25:24.728718Z","steps":["trace[2133425366] 'read index received' (duration: 105.492918ms)","trace[2133425366] 'applied index is now lower than readState.Index' (duration: 184.475µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T01:25:24.728794Z","caller":"traceutil/trace.go:171","msg":"trace[78173890] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"136.816595ms","start":"2026-06-12T01:25:24.591949Z","end":"2026-06-12T01:25:24.728766Z","steps":["trace[78173890] 'process raft request' (duration: 136.647571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:24.728935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.848297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:25:24.729015Z","caller":"traceutil/trace.go:171","msg":"trace[839289796] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1279; }","duration":"106.023821ms","start":"2026-06-12T01:25:24.622975Z","end":"2026-06-12T01:25:24.728999Z","steps":["trace[839289796] 'agreement among raft nodes before linearized reading' (duration: 105.839496ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:31.637858Z","caller":"traceutil/trace.go:171","msg":"trace[1054046968] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"139.367178ms","start":"2026-06-12T01:25:31.498459Z","end":"2026-06-12T01:25:31.637826Z","steps":["trace[1054046968] 'process raft request' (duration: 139.215494ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:32.36174Z","caller":"traceutil/trace.go:171","msg":"trace[490287381] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"379.609449ms","start":"2026-06-12T01:25:31.982096Z","end":"2026-06-12T01:25:32.361705Z","steps":["trace[490287381] 'process raft request' (duration: 379.370534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:32.36194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:31.98207Z","time spent":"379.753003ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T01:25:33.400104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.179438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-06-12T01:25:33.400276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.823466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-12T01:25:33.40031Z","caller":"traceutil/trace.go:171","msg":"trace[2105197614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1337; }","duration":"483.905248ms","start":"2026-06-12T01:25:32.916395Z","end":"2026-06-12T01:25:33.4003Z","steps":["trace[2105197614] 'range keys from in-memory index tree' (duration: 483.735044ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.400263Z","caller":"traceutil/trace.go:171","msg":"trace[319354628] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"811.381402ms","start":"2026-06-12T01:25:32.588849Z","end":"2026-06-12T01:25:33.40023Z","steps":["trace[319354628] 'range keys from in-memory index tree' (duration: 811.034844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:33.400359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:32.91638Z","time spent":"483.952709ms","remote":"127.0.0.1:58468","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-06-12T01:25:33.400417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:32.588829Z","time spent":"811.543897ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-12T01:25:33.400521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.378188ms","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-06-12T01:25:33.400634Z","caller":"traceutil/trace.go:171","msg":"trace[1567759137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1337; }","duration":"619.559102ms","start":"2026-06-12T01:25:32.781044Z","end":"2026-06-12T01:25:33.400603Z","steps":["trace[1567759137] 'range keys from in-memory index tree' (duration: 619.199233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:33.400655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.451438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-12T01:25:33.400687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:32.781029Z","time spent":"619.644895ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-12T01:25:33.400701Z","caller":"traceutil/trace.go:171","msg":"trace[464262939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1337; }","duration":"473.51949ms","start":"2026-06-12T01:25:32.927173Z","end":"2026-06-12T01:25:33.400693Z","steps":["trace[464262939] 'range keys from in-memory index tree' (duration: 473.381457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:33.400801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:32.927163Z","time spent":"473.628783ms","remote":"127.0.0.1:58468","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-06-12T01:25:33.400815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.800927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-12T01:25:33.400848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.766189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T01:25:33.400889Z","caller":"traceutil/trace.go:171","msg":"trace[780735459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1337; }","duration":"311.905469ms","start":"2026-06-12T01:25:33.088967Z","end":"2026-06-12T01:25:33.400873Z","steps":["trace[780735459] 'range keys from in-memory index tree' (duration: 311.635933ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.400913Z","caller":"traceutil/trace.go:171","msg":"trace[474478183] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1337; }","duration":"378.880302ms","start":"2026-06-12T01:25:33.022011Z","end":"2026-06-12T01:25:33.400891Z","steps":["trace[474478183] 'count revisions from in-memory index tree' (duration: 378.660606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:33.400903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.565991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-12T01:25:33.400955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:33.021989Z","time spent":"378.958084ms","remote":"127.0.0.1:55284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-06-12T01:25:33.40099Z","caller":"traceutil/trace.go:171","msg":"trace[420092173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1337; }","duration":"473.707484ms","start":"2026-06-12T01:25:32.927259Z","end":"2026-06-12T01:25:33.400967Z","steps":["trace[420092173] 'range keys from in-memory index tree' (duration: 473.310155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T01:25:33.401045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:32.927237Z","time spent":"473.797477ms","remote":"127.0.0.1:58468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-12T01:25:33.400931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T01:25:33.088954Z","time spent":"311.969271ms","remote":"127.0.0.1:58468","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":"info","ts":"2026-06-12T01:25:33.543158Z","caller":"traceutil/trace.go:171","msg":"trace[96039221] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"138.089416ms","start":"2026-06-12T01:25:33.405041Z","end":"2026-06-12T01:25:33.54313Z","steps":["trace[96039221] 'process raft request' (duration: 137.934502ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.554067Z","caller":"traceutil/trace.go:171","msg":"trace[1908853329] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"148.895005ms","start":"2026-06-12T01:25:33.40515Z","end":"2026-06-12T01:25:33.554045Z","steps":["trace[1908853329] 'process raft request' (duration: 148.811913ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.554112Z","caller":"traceutil/trace.go:171","msg":"trace[1184469365] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"149.004538ms","start":"2026-06-12T01:25:33.405074Z","end":"2026-06-12T01:25:33.554079Z","steps":["trace[1184469365] 'process raft request' (duration: 148.743892ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.55431Z","caller":"traceutil/trace.go:171","msg":"trace[1363695026] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"148.31156ms","start":"2026-06-12T01:25:33.405985Z","end":"2026-06-12T01:25:33.554296Z","steps":["trace[1363695026] 'process raft request' (duration: 148.030953ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.554543Z","caller":"traceutil/trace.go:171","msg":"trace[708469714] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"147.89655ms","start":"2026-06-12T01:25:33.406634Z","end":"2026-06-12T01:25:33.554531Z","steps":["trace[708469714] 'process raft request' (duration: 147.597913ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:25:33.554866Z","caller":"traceutil/trace.go:171","msg":"trace[1060995112] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"145.377067ms","start":"2026-06-12T01:25:33.409473Z","end":"2026-06-12T01:25:33.55485Z","steps":["trace[1060995112] 'process raft request' (duration: 144.916196ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:26:07.977005Z","caller":"traceutil/trace.go:171","msg":"trace[1235494829] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"100.237917ms","start":"2026-06-12T01:26:07.876736Z","end":"2026-06-12T01:26:07.976974Z","steps":["trace[1235494829] 'process raft request' (duration: 100.041583ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T01:26:08.166778Z","caller":"traceutil/trace.go:171","msg":"trace[1401866529] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"108.931113ms","start":"2026-06-12T01:26:08.057811Z","end":"2026-06-12T01:26:08.166742Z","steps":["trace[1401866529] 'process raft request' (duration: 88.707211ms)","trace[1401866529] 'compare' (duration: 20.091369ms)"],"step_count":2}