{"level":"warn","ts":"2026-03-18T10:09:40.798267Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T10:09:40.798353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-18T10:09:40.798424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T10:09:40.798437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-18T10:09:40.798454Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T10:09:40.799103Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-03-18T10:09:40.799216Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-18T10:09:40.828363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"28.933631ms"} {"level":"info","ts":"2026-03-18T10:09:40.849908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-03-18T10:09:40.850011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T10:09:40.850052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-03-18T10:09:40.850067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T10:09:40.850092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-03-18T10:09:40.850155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-03-18T10:09:40.868147Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T10:09:40.876292Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T10:09:40.877572Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-18T10:09:40.884429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T10:09:40.884829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T10:09:40.885109Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T10:09:40.885163Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T10:09:40.885177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T10:09:40.895493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-03-18T10:09:40.895711Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-03-18T10:09:40.896274Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T10:09:40.896617Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-18T10:09:40.896665Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-03-18T10:09:40.897793Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T10:09:40.897842Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T10:09:41.050595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T10:09:41.050687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T10:09:41.050739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-03-18T10:09:41.050766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-03-18T10:09:41.050778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-18T10:09:41.050827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-03-18T10:09:41.050846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-03-18T10:09:41.054002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T10:09:41.05598Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T10:09:41.056199Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T10:09:41.056234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T10:09:41.056466Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T10:09:41.056612Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T10:09:41.060008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T10:09:41.060135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T10:09:41.060162Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T10:09:41.060242Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T10:09:41.060966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-03-18T10:09:42.713122Z","caller":"traceutil/trace.go:171","msg":"trace[763413248] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"109.735864ms","start":"2026-03-18T10:09:42.603364Z","end":"2026-03-18T10:09:42.7131Z","steps":["trace[763413248] 'process raft request' (duration: 52.55671ms)","trace[763413248] 'compare' (duration: 56.874518ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:42.716558Z","caller":"traceutil/trace.go:171","msg":"trace[637591439] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"112.977077ms","start":"2026-03-18T10:09:42.603561Z","end":"2026-03-18T10:09:42.716538Z","steps":["trace[637591439] 'process raft request' (duration: 112.773533ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716541Z","caller":"traceutil/trace.go:171","msg":"trace[1508714717] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"112.973647ms","start":"2026-03-18T10:09:42.603526Z","end":"2026-03-18T10:09:42.7165Z","steps":["trace[1508714717] 'process raft request' (duration: 112.671481ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716707Z","caller":"traceutil/trace.go:171","msg":"trace[119185721] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"112.939687ms","start":"2026-03-18T10:09:42.603758Z","end":"2026-03-18T10:09:42.716698Z","steps":["trace[119185721] 'process raft request' (duration: 112.695411ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716769Z","caller":"traceutil/trace.go:171","msg":"trace[1865058238] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"113.11597ms","start":"2026-03-18T10:09:42.603643Z","end":"2026-03-18T10:09:42.716759Z","steps":["trace[1865058238] 'process raft request' (duration: 112.782173ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716818Z","caller":"traceutil/trace.go:171","msg":"trace[1970763335] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"113.229393ms","start":"2026-03-18T10:09:42.603566Z","end":"2026-03-18T10:09:42.716796Z","steps":["trace[1970763335] 'read index received' (duration: 52.313656ms)","trace[1970763335] 'applied index is now lower than readState.Index' (duration: 60.914797ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:42.716911Z","caller":"traceutil/trace.go:171","msg":"trace[41406471] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"112.150339ms","start":"2026-03-18T10:09:42.604745Z","end":"2026-03-18T10:09:42.716895Z","steps":["trace[41406471] 'process raft request' (duration: 111.797861ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716974Z","caller":"traceutil/trace.go:171","msg":"trace[35613526] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"102.642345ms","start":"2026-03-18T10:09:42.614321Z","end":"2026-03-18T10:09:42.716964Z","steps":["trace[35613526] 'process raft request' (duration: 102.411119ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.716999Z","caller":"traceutil/trace.go:171","msg":"trace[1675231993] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"113.163332ms","start":"2026-03-18T10:09:42.603805Z","end":"2026-03-18T10:09:42.716968Z","steps":["trace[1675231993] 'process raft request' (duration: 112.711052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.71714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.521539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-18T10:09:42.717133Z","caller":"traceutil/trace.go:171","msg":"trace[1897470762] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"110.335178ms","start":"2026-03-18T10:09:42.606791Z","end":"2026-03-18T10:09:42.717126Z","steps":["trace[1897470762] 'process raft request' (duration: 109.902049ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.717225Z","caller":"traceutil/trace.go:171","msg":"trace[1971666231] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"113.682124ms","start":"2026-03-18T10:09:42.603529Z","end":"2026-03-18T10:09:42.717211Z","steps":["trace[1971666231] 'agreement among raft nodes before linearized reading' (duration: 113.478809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.73459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.626005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T10:09:42.734653Z","caller":"traceutil/trace.go:171","msg":"trace[108489452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"130.727466ms","start":"2026-03-18T10:09:42.603911Z","end":"2026-03-18T10:09:42.734638Z","steps":["trace[108489452] 'agreement among raft nodes before linearized reading' (duration: 130.582333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.734729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.619566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T10:09:42.734841Z","caller":"traceutil/trace.go:171","msg":"trace[972482367] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"102.804189ms","start":"2026-03-18T10:09:42.63201Z","end":"2026-03-18T10:09:42.734814Z","steps":["trace[972482367] 'agreement among raft nodes before linearized reading' (duration: 102.633435ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.938488Z","caller":"traceutil/trace.go:171","msg":"trace[559980141] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"216.697837ms","start":"2026-03-18T10:09:42.721761Z","end":"2026-03-18T10:09:42.938459Z","steps":["trace[559980141] 'process raft request' (duration: 216.580784ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.938487Z","caller":"traceutil/trace.go:171","msg":"trace[935118725] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"216.654385ms","start":"2026-03-18T10:09:42.721773Z","end":"2026-03-18T10:09:42.938428Z","steps":["trace[935118725] 'process raft request' (duration: 175.144894ms)","trace[935118725] 'compare' (duration: 41.213215ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:42.938641Z","caller":"traceutil/trace.go:171","msg":"trace[1005348297] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"216.073394ms","start":"2026-03-18T10:09:42.722562Z","end":"2026-03-18T10:09:42.938636Z","steps":["trace[1005348297] 'process raft request' (duration: 215.91093ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.938708Z","caller":"traceutil/trace.go:171","msg":"trace[1600044536] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"216.230308ms","start":"2026-03-18T10:09:42.722473Z","end":"2026-03-18T10:09:42.938704Z","steps":["trace[1600044536] 'process raft request' (duration: 215.968761ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.938732Z","caller":"traceutil/trace.go:171","msg":"trace[1868469307] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"201.481486ms","start":"2026-03-18T10:09:42.73723Z","end":"2026-03-18T10:09:42.938711Z","steps":["trace[1868469307] 'process raft request' (duration: 201.437515ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.93878Z","caller":"traceutil/trace.go:171","msg":"trace[1753340991] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"201.784532ms","start":"2026-03-18T10:09:42.736989Z","end":"2026-03-18T10:09:42.938774Z","steps":["trace[1753340991] 'process raft request' (duration: 201.633629ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.938783Z","caller":"traceutil/trace.go:171","msg":"trace[767110175] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:16; }","duration":"204.36807ms","start":"2026-03-18T10:09:42.734399Z","end":"2026-03-18T10:09:42.938767Z","steps":["trace[767110175] 'read index received' (duration: 162.487949ms)","trace[767110175] 'applied index is now lower than readState.Index' (duration: 41.878751ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:42.938903Z","caller":"traceutil/trace.go:171","msg":"trace[347972126] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"202.55142ms","start":"2026-03-18T10:09:42.736346Z","end":"2026-03-18T10:09:42.938898Z","steps":["trace[347972126] 'process raft request' (duration: 202.159121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.572585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-03-18T10:09:42.939056Z","caller":"traceutil/trace.go:171","msg":"trace[434049957] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"212.651156ms","start":"2026-03-18T10:09:42.726379Z","end":"2026-03-18T10:09:42.93903Z","steps":["trace[434049957] 'agreement among raft nodes before linearized reading' (duration: 212.485062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.939249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.225399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T10:09:42.939258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.655149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-03-18T10:09:42.939274Z","caller":"traceutil/trace.go:171","msg":"trace[1004318907] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"217.28031ms","start":"2026-03-18T10:09:42.721986Z","end":"2026-03-18T10:09:42.939266Z","steps":["trace[1004318907] 'agreement among raft nodes before linearized reading' (duration: 217.213498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.939262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.371487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-stvsc\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-18T10:09:42.939304Z","caller":"traceutil/trace.go:171","msg":"trace[2020413024] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"201.7238ms","start":"2026-03-18T10:09:42.737565Z","end":"2026-03-18T10:09:42.939289Z","steps":["trace[2020413024] 'agreement among raft nodes before linearized reading' (duration: 201.639499ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.939319Z","caller":"traceutil/trace.go:171","msg":"trace[1856802226] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-stvsc; range_end:; response_count:1; response_revision:21; }","duration":"219.455619ms","start":"2026-03-18T10:09:42.719848Z","end":"2026-03-18T10:09:42.939304Z","steps":["trace[1856802226] 'agreement among raft nodes before linearized reading' (duration: 219.337366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:42.939328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.032427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T10:09:42.939374Z","caller":"traceutil/trace.go:171","msg":"trace[1955006586] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:21; }","duration":"218.085908ms","start":"2026-03-18T10:09:42.721272Z","end":"2026-03-18T10:09:42.939357Z","steps":["trace[1955006586] 'agreement among raft nodes before linearized reading' (duration: 218.017736ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:42.939947Z","caller":"traceutil/trace.go:171","msg":"trace[1290873051] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"216.247127ms","start":"2026-03-18T10:09:42.722442Z","end":"2026-03-18T10:09:42.938689Z","steps":["trace[1290873051] 'process raft request' (duration: 215.96353ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.1244Z","caller":"traceutil/trace.go:171","msg":"trace[1205446001] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"140.364083ms","start":"2026-03-18T10:09:42.984012Z","end":"2026-03-18T10:09:43.124376Z","steps":["trace[1205446001] 'process raft request' (duration: 129.705733ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124567Z","caller":"traceutil/trace.go:171","msg":"trace[1417642112] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"139.255878ms","start":"2026-03-18T10:09:42.985282Z","end":"2026-03-18T10:09:43.124538Z","steps":["trace[1417642112] 'process raft request' (duration: 139.054534ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124602Z","caller":"traceutil/trace.go:171","msg":"trace[183871917] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"139.252398ms","start":"2026-03-18T10:09:42.985315Z","end":"2026-03-18T10:09:43.124567Z","steps":["trace[183871917] 'process raft request' (duration: 139.061284ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124721Z","caller":"traceutil/trace.go:171","msg":"trace[299490706] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"139.368581ms","start":"2026-03-18T10:09:42.985343Z","end":"2026-03-18T10:09:43.124711Z","steps":["trace[299490706] 'process raft request' (duration: 139.056414ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124633Z","caller":"traceutil/trace.go:171","msg":"trace[1391375252] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"139.437712ms","start":"2026-03-18T10:09:42.985182Z","end":"2026-03-18T10:09:43.124619Z","steps":["trace[1391375252] 'process raft request' (duration: 139.119125ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124873Z","caller":"traceutil/trace.go:171","msg":"trace[1849037435] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"138.947882ms","start":"2026-03-18T10:09:42.985915Z","end":"2026-03-18T10:09:43.124863Z","steps":["trace[1849037435] 'process raft request' (duration: 138.551693ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.124883Z","caller":"traceutil/trace.go:171","msg":"trace[305016378] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:34; }","duration":"112.395705ms","start":"2026-03-18T10:09:43.012427Z","end":"2026-03-18T10:09:43.124822Z","steps":["trace[305016378] 'read index received' (duration: 101.224794ms)","trace[305016378] 'applied index is now lower than readState.Index' (duration: 11.169351ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:09:43.125043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.612059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T10:09:43.125086Z","caller":"traceutil/trace.go:171","msg":"trace[1710315741] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:37; }","duration":"112.697871ms","start":"2026-03-18T10:09:43.012374Z","end":"2026-03-18T10:09:43.125072Z","steps":["trace[1710315741] 'agreement among raft nodes before linearized reading' (duration: 112.595899ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:43.306575Z","caller":"traceutil/trace.go:171","msg":"trace[1314366376] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"132.621068ms","start":"2026-03-18T10:09:43.173931Z","end":"2026-03-18T10:09:43.306552Z","steps":["trace[1314366376] 'process raft request' (duration: 61.339757ms)","trace[1314366376] 'compare' (duration: 70.964064ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:09:46.153177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.665009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-18T10:09:46.153278Z","caller":"traceutil/trace.go:171","msg":"trace[100281792] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:190; }","duration":"132.780442ms","start":"2026-03-18T10:09:46.020475Z","end":"2026-03-18T10:09:46.153256Z","steps":["trace[100281792] 'range keys from in-memory index tree' (duration: 132.577057ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:46.476847Z","caller":"traceutil/trace.go:171","msg":"trace[968507301] linearizableReadLoop","detail":"{readStateIndex:200; appliedIndex:199; }","duration":"106.109734ms","start":"2026-03-18T10:09:46.370711Z","end":"2026-03-18T10:09:46.476821Z","steps":["trace[968507301] 'read index received' (duration: 64.597391ms)","trace[968507301] 'applied index is now lower than readState.Index' (duration: 41.510473ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:46.476938Z","caller":"traceutil/trace.go:171","msg":"trace[492859783] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"106.564163ms","start":"2026-03-18T10:09:46.370332Z","end":"2026-03-18T10:09:46.476896Z","steps":["trace[492859783] 'process raft request' (duration: 64.962579ms)","trace[492859783] 'compare' (duration: 41.39455ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:09:46.477083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.324108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-18T10:09:46.477143Z","caller":"traceutil/trace.go:171","msg":"trace[1322396811] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:197; }","duration":"106.42561ms","start":"2026-03-18T10:09:46.370696Z","end":"2026-03-18T10:09:46.477121Z","steps":["trace[1322396811] 'agreement among raft nodes before linearized reading' (duration: 106.246256ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:46.47994Z","caller":"traceutil/trace.go:171","msg":"trace[91056919] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"103.589896ms","start":"2026-03-18T10:09:46.376326Z","end":"2026-03-18T10:09:46.479916Z","steps":["trace[91056919] 'process raft request' (duration: 103.456313ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.03586Z","caller":"traceutil/trace.go:171","msg":"trace[1685845670] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"157.339424ms","start":"2026-03-18T10:09:48.878495Z","end":"2026-03-18T10:09:49.035835Z","steps":["trace[1685845670] 'process raft request' (duration: 157.16363ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136652Z","caller":"traceutil/trace.go:171","msg":"trace[42989839] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"257.890072ms","start":"2026-03-18T10:09:48.878728Z","end":"2026-03-18T10:09:49.136618Z","steps":["trace[42989839] 'process raft request' (duration: 257.674127ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136855Z","caller":"traceutil/trace.go:171","msg":"trace[1403808185] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"142.977781ms","start":"2026-03-18T10:09:48.993862Z","end":"2026-03-18T10:09:49.13684Z","steps":["trace[1403808185] 'process raft request' (duration: 142.92737ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136927Z","caller":"traceutil/trace.go:171","msg":"trace[599178749] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"166.270614ms","start":"2026-03-18T10:09:48.970596Z","end":"2026-03-18T10:09:49.136867Z","steps":["trace[599178749] 'process raft request' (duration: 166.129601ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136705Z","caller":"traceutil/trace.go:171","msg":"trace[112386189] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"255.772924ms","start":"2026-03-18T10:09:48.880903Z","end":"2026-03-18T10:09:49.136675Z","steps":["trace[112386189] 'process raft request' (duration: 255.626101ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136721Z","caller":"traceutil/trace.go:171","msg":"trace[284470760] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"256.240395ms","start":"2026-03-18T10:09:48.880455Z","end":"2026-03-18T10:09:49.136696Z","steps":["trace[284470760] 'process raft request' (duration: 256.046711ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.13678Z","caller":"traceutil/trace.go:171","msg":"trace[452261277] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"255.694022ms","start":"2026-03-18T10:09:48.881066Z","end":"2026-03-18T10:09:49.13676Z","steps":["trace[452261277] 'process raft request' (duration: 255.507178ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136803Z","caller":"traceutil/trace.go:171","msg":"trace[997441816] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"242.597628ms","start":"2026-03-18T10:09:48.894194Z","end":"2026-03-18T10:09:49.136792Z","steps":["trace[997441816] 'process raft request' (duration: 242.486256ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.136841Z","caller":"traceutil/trace.go:171","msg":"trace[1355463571] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"166.136881ms","start":"2026-03-18T10:09:48.970696Z","end":"2026-03-18T10:09:49.136833Z","steps":["trace[1355463571] 'process raft request' (duration: 166.068559ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.315017Z","caller":"traceutil/trace.go:171","msg":"trace[390063422] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"166.354067ms","start":"2026-03-18T10:09:49.148628Z","end":"2026-03-18T10:09:49.314982Z","steps":["trace[390063422] 'process raft request' (duration: 158.630204ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.31521Z","caller":"traceutil/trace.go:171","msg":"trace[120280025] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"139.192697ms","start":"2026-03-18T10:09:49.175998Z","end":"2026-03-18T10:09:49.315191Z","steps":["trace[120280025] 'process raft request' (duration: 138.971312ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.315188Z","caller":"traceutil/trace.go:171","msg":"trace[1727026591] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"165.814344ms","start":"2026-03-18T10:09:49.149327Z","end":"2026-03-18T10:09:49.315142Z","steps":["trace[1727026591] 'process raft request' (duration: 165.583769ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:09:49.317325Z","caller":"traceutil/trace.go:171","msg":"trace[1444693494] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"140.606738ms","start":"2026-03-18T10:09:49.176689Z","end":"2026-03-18T10:09:49.317296Z","steps":["trace[1444693494] 'process raft request' (duration: 138.309857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:52.580548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.864577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:09:52.580651Z","caller":"traceutil/trace.go:171","msg":"trace[298897103] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"184.313859ms","start":"2026-03-18T10:09:52.396322Z","end":"2026-03-18T10:09:52.580636Z","steps":["trace[298897103] 'read index received' (duration: 69.682765ms)","trace[298897103] 'applied index is now lower than readState.Index' (duration: 114.630134ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:09:52.580717Z","caller":"traceutil/trace.go:171","msg":"trace[614012287] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"215.005358ms","start":"2026-03-18T10:09:52.365682Z","end":"2026-03-18T10:09:52.580687Z","steps":["trace[614012287] 'process raft request' (duration: 100.377154ms)","trace[614012287] 'compare' (duration: 113.366806ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:09:52.580803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.454562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-18T10:09:52.580838Z","caller":"traceutil/trace.go:171","msg":"trace[1111010790] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:323; }","duration":"135.523354ms","start":"2026-03-18T10:09:52.445306Z","end":"2026-03-18T10:09:52.580829Z","steps":["trace[1111010790] 'agreement among raft nodes before linearized reading' (duration: 135.475703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:09:52.580754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.441441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-18T10:09:52.580938Z","caller":"traceutil/trace.go:171","msg":"trace[787069979] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:323; }","duration":"184.667207ms","start":"2026-03-18T10:09:52.396258Z","end":"2026-03-18T10:09:52.580925Z","steps":["trace[787069979] 'agreement among raft nodes before linearized reading' (duration: 184.458222ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:01.746086Z","caller":"traceutil/trace.go:171","msg":"trace[463852151] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"169.373783ms","start":"2026-03-18T10:10:01.576687Z","end":"2026-03-18T10:10:01.746061Z","steps":["trace[463852151] 'process raft request' (duration: 169.203749ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:01.879065Z","caller":"traceutil/trace.go:171","msg":"trace[301521102] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"100.440896ms","start":"2026-03-18T10:10:01.77859Z","end":"2026-03-18T10:10:01.879031Z","steps":["trace[301521102] 'read index received' (duration: 668.835µs)","trace[301521102] 'applied index is now lower than readState.Index' (duration: 99.770371ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:01.879225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.61216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:01.879271Z","caller":"traceutil/trace.go:171","msg":"trace[599862923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"100.698531ms","start":"2026-03-18T10:10:01.778556Z","end":"2026-03-18T10:10:01.879254Z","steps":["trace[599862923] 'agreement among raft nodes before linearized reading' (duration: 100.598809ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:01.879351Z","caller":"traceutil/trace.go:171","msg":"trace[99346341] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"128.588858ms","start":"2026-03-18T10:10:01.750736Z","end":"2026-03-18T10:10:01.879325Z","steps":["trace[99346341] 'process raft request' (duration: 28.590072ms)","trace[99346341] 'compare' (duration: 99.531676ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:10:14.105268Z","caller":"traceutil/trace.go:171","msg":"trace[1778396967] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"327.480875ms","start":"2026-03-18T10:10:13.777766Z","end":"2026-03-18T10:10:14.105247Z","steps":["trace[1778396967] 'read index received' (duration: 327.276251ms)","trace[1778396967] 'applied index is now lower than readState.Index' (duration: 203.794µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:14.10547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.599757ms","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-03-18T10:10:14.105502Z","caller":"traceutil/trace.go:171","msg":"trace[1434887622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"160.664468ms","start":"2026-03-18T10:10:13.94483Z","end":"2026-03-18T10:10:14.105494Z","steps":["trace[1434887622] 'agreement among raft nodes before linearized reading' (duration: 160.598826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:14.105521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.236426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T10:10:14.105579Z","caller":"traceutil/trace.go:171","msg":"trace[1871706606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"281.339918ms","start":"2026-03-18T10:10:13.824225Z","end":"2026-03-18T10:10:14.105565Z","steps":["trace[1871706606] 'agreement among raft nodes before linearized reading' (duration: 281.203005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:14.105596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.47801ms","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-03-18T10:10:14.105683Z","caller":"traceutil/trace.go:171","msg":"trace[1556603891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"276.591723ms","start":"2026-03-18T10:10:13.829072Z","end":"2026-03-18T10:10:14.105664Z","steps":["trace[1556603891] 'agreement among raft nodes before linearized reading' (duration: 276.45803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:14.105391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.611058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:14.10581Z","caller":"traceutil/trace.go:171","msg":"trace[388202425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"328.069488ms","start":"2026-03-18T10:10:13.777728Z","end":"2026-03-18T10:10:14.105798Z","steps":["trace[388202425] 'agreement among raft nodes before linearized reading' (duration: 327.620869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:14.105843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:13.777711Z","time spent":"328.120929ms","remote":"127.0.0.1:60062","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-03-18T10:10:14.105454Z","caller":"traceutil/trace.go:171","msg":"trace[459441282] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"415.487291ms","start":"2026-03-18T10:10:13.68983Z","end":"2026-03-18T10:10:14.105317Z","steps":["trace[459441282] 'process raft request' (duration: 415.305767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:14.106528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:13.689804Z","time spent":"416.552035ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:10:14.794254Z","caller":"traceutil/trace.go:171","msg":"trace[1799075733] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"114.613224ms","start":"2026-03-18T10:10:14.679613Z","end":"2026-03-18T10:10:14.794226Z","steps":["trace[1799075733] 'process raft request' (duration: 114.400559ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:22.33668Z","caller":"traceutil/trace.go:171","msg":"trace[1074027614] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"445.025854ms","start":"2026-03-18T10:10:21.891627Z","end":"2026-03-18T10:10:22.336653Z","steps":["trace[1074027614] 'process raft request' (duration: 444.786169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:22.336864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:21.891479Z","time spent":"445.299581ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:10:23.100187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.942843ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T10:10:23.100267Z","caller":"traceutil/trace.go:171","msg":"trace[92008619] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"906.166412ms","start":"2026-03-18T10:10:22.194085Z","end":"2026-03-18T10:10:23.100252Z","steps":["trace[92008619] 'read index received' (duration: 142.314896ms)","trace[92008619] 'applied index is now lower than readState.Index' (duration: 763.850506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:23.100377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.295046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T10:10:23.100404Z","caller":"traceutil/trace.go:171","msg":"trace[1926938281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"906.360727ms","start":"2026-03-18T10:10:22.194035Z","end":"2026-03-18T10:10:23.100395Z","steps":["trace[1926938281] 'agreement among raft nodes before linearized reading' (duration: 906.287696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:23.100427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:22.193981Z","time spent":"906.439549ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T10:10:23.100535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.987011ms","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-03-18T10:10:23.100613Z","caller":"traceutil/trace.go:171","msg":"trace[1964616588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"903.102904ms","start":"2026-03-18T10:10:22.197488Z","end":"2026-03-18T10:10:23.100591Z","steps":["trace[1964616588] 'agreement among raft nodes before linearized reading' (duration: 902.92455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:23.100652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:22.197478Z","time spent":"903.166005ms","remote":"127.0.0.1:60322","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":"warn","ts":"2026-03-18T10:10:23.100656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.848691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:23.10068Z","caller":"traceutil/trace.go:171","msg":"trace[188866188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"322.905881ms","start":"2026-03-18T10:10:22.777768Z","end":"2026-03-18T10:10:23.100674Z","steps":["trace[188866188] 'agreement among raft nodes before linearized reading' (duration: 322.871061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:23.100697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:22.777748Z","time spent":"322.945713ms","remote":"127.0.0.1:60062","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-03-18T10:10:23.100787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.463741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T10:10:23.100803Z","caller":"traceutil/trace.go:171","msg":"trace[2129061630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"273.502042ms","start":"2026-03-18T10:10:22.827296Z","end":"2026-03-18T10:10:23.100798Z","steps":["trace[2129061630] 'agreement among raft nodes before linearized reading' (duration: 273.470071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:23.100932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.682264ms","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-03-18T10:10:23.101016Z","caller":"traceutil/trace.go:171","msg":"trace[487124489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"890.795847ms","start":"2026-03-18T10:10:22.210205Z","end":"2026-03-18T10:10:23.101Z","steps":["trace[487124489] 'agreement among raft nodes before linearized reading' (duration: 890.693004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:23.101042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:22.210186Z","time spent":"890.848438ms","remote":"127.0.0.1:60322","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":"warn","ts":"2026-03-18T10:10:24.571228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.497072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:10:24.571468Z","caller":"traceutil/trace.go:171","msg":"trace[909841613] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:524; }","duration":"793.062992ms","start":"2026-03-18T10:10:23.778385Z","end":"2026-03-18T10:10:24.571448Z","steps":["trace[909841613] 'read index received' (duration: 35.256763ms)","trace[909841613] 'applied index is now lower than readState.Index' (duration: 757.805149ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:24.571585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.199334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:24.571625Z","caller":"traceutil/trace.go:171","msg":"trace[1244360024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"793.244755ms","start":"2026-03-18T10:10:23.778371Z","end":"2026-03-18T10:10:24.571615Z","steps":["trace[1244360024] 'agreement among raft nodes before linearized reading' (duration: 793.151753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:24.571662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:23.778315Z","time spent":"793.336777ms","remote":"127.0.0.1:60062","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-03-18T10:10:24.571671Z","caller":"traceutil/trace.go:171","msg":"trace[708004133] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.46621063s","start":"2026-03-18T10:10:23.105419Z","end":"2026-03-18T10:10:24.57163Z","steps":["trace[708004133] 'process raft request' (duration: 708.209666ms)","trace[708004133] 'compare' (duration: 757.39563ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:10:24.571823Z","caller":"traceutil/trace.go:171","msg":"trace[543699919] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.466306182s","start":"2026-03-18T10:10:23.105484Z","end":"2026-03-18T10:10:24.57179Z","steps":["trace[543699919] 'process raft request' (duration: 1.465851232s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:24.571862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:23.1054Z","time spent":"1.466375274s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:10:24.571888Z","caller":"traceutil/trace.go:171","msg":"trace[273567996] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.466306002s","start":"2026-03-18T10:10:23.105568Z","end":"2026-03-18T10:10:24.571874Z","steps":["trace[273567996] 'process raft request' (duration: 1.465833632s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:24.571954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:23.10548Z","time spent":"1.466415905s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:10:24.571969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:23.105564Z","time spent":"1.466369053s","remote":"127.0.0.1:60322","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-03-18T10:10:24.890427Z","caller":"traceutil/trace.go:171","msg":"trace[2096400990] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"317.145963ms","start":"2026-03-18T10:10:24.573253Z","end":"2026-03-18T10:10:24.890399Z","steps":["trace[2096400990] 'read index received' (duration: 219.306685ms)","trace[2096400990] 'applied index is now lower than readState.Index' (duration: 97.838238ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:24.890688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.41258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:24.890728Z","caller":"traceutil/trace.go:171","msg":"trace[477033727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"317.505831ms","start":"2026-03-18T10:10:24.573211Z","end":"2026-03-18T10:10:24.890717Z","steps":["trace[477033727] 'agreement among raft nodes before linearized reading' (duration: 317.290846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:24.890767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:24.573193Z","time spent":"317.566803ms","remote":"127.0.0.1:60074","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-03-18T10:10:24.890926Z","caller":"traceutil/trace.go:171","msg":"trace[378429000] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"647.001672ms","start":"2026-03-18T10:10:24.243888Z","end":"2026-03-18T10:10:24.890889Z","steps":["trace[378429000] 'process raft request' (duration: 548.728674ms)","trace[378429000] 'compare' (duration: 97.670124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:24.891088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:24.243859Z","time spent":"647.150134ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:10:43.042356Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075615058s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-18T10:10:44.211905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125472909s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T10:10:44.212317Z","caller":"traceutil/trace.go:171","msg":"trace[728951363] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"1.552416157s","start":"2026-03-18T10:10:42.659876Z","end":"2026-03-18T10:10:44.212292Z","steps":["trace[728951363] 'read index received' (duration: 382.692416ms)","trace[728951363] 'applied index is now lower than readState.Index' (duration: 1.169722421s)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:10:44.212387Z","caller":"traceutil/trace.go:171","msg":"trace[386751837] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"2.138332166s","start":"2026-03-18T10:10:42.073998Z","end":"2026-03-18T10:10:44.21233Z","steps":["trace[386751837] 'process raft request' (duration: 2.138147832s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.212572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546708209s","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-03-18T10:10:44.212617Z","caller":"traceutil/trace.go:171","msg":"trace[824743413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.546787931s","start":"2026-03-18T10:10:42.665817Z","end":"2026-03-18T10:10:44.212604Z","steps":["trace[824743413] 'agreement among raft nodes before linearized reading' (duration: 1.546581647s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.21263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:42.073974Z","time spent":"2.138536101s","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T10:10:44.212656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:42.66581Z","time spent":"1.546837262s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T10:10:44.212658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.861764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T10:10:44.212761Z","caller":"traceutil/trace.go:171","msg":"trace[858484853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"958.009347ms","start":"2026-03-18T10:10:43.254728Z","end":"2026-03-18T10:10:44.212737Z","steps":["trace[858484853] 'agreement among raft nodes before linearized reading' (duration: 957.719651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.212816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:43.254713Z","time spent":"958.093919ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T10:10:44.212969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201546837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T10:10:44.213062Z","caller":"traceutil/trace.go:171","msg":"trace[366259270] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:682; }","duration":"1.201610929s","start":"2026-03-18T10:10:43.011382Z","end":"2026-03-18T10:10:44.212993Z","steps":["trace[366259270] 'agreement among raft nodes before linearized reading' (duration: 1.201554388s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.213097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:43.011364Z","time spent":"1.201724591s","remote":"127.0.0.1:60260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-03-18T10:10:44.213222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.343215ms","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-03-18T10:10:44.213284Z","caller":"traceutil/trace.go:171","msg":"trace[1959422540] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:682; }","duration":"458.415026ms","start":"2026-03-18T10:10:43.754856Z","end":"2026-03-18T10:10:44.213271Z","steps":["trace[1959422540] 'agreement among raft nodes before linearized reading' (duration: 458.298114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.21333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370932582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-18T10:10:44.213334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:43.7548Z","time spent":"458.524438ms","remote":"127.0.0.1:60528","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-03-18T10:10:44.213348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.983945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:44.213405Z","caller":"traceutil/trace.go:171","msg":"trace[344025510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"437.072467ms","start":"2026-03-18T10:10:43.776318Z","end":"2026-03-18T10:10:44.21339Z","steps":["trace[344025510] 'agreement among raft nodes before linearized reading' (duration: 436.997805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.213437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544858669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T10:10:44.21344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:43.776292Z","time spent":"437.140638ms","remote":"127.0.0.1:60062","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-03-18T10:10:44.213462Z","caller":"traceutil/trace.go:171","msg":"trace[975552382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"1.54492283s","start":"2026-03-18T10:10:42.668532Z","end":"2026-03-18T10:10:44.213455Z","steps":["trace[975552382] 'agreement among raft nodes before linearized reading' (duration: 1.544852739s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.213488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:42.668513Z","time spent":"1.544969281s","remote":"127.0.0.1:60322","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-03-18T10:10:44.213368Z","caller":"traceutil/trace.go:171","msg":"trace[860399941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.370996853s","start":"2026-03-18T10:10:42.842361Z","end":"2026-03-18T10:10:44.213358Z","steps":["trace[860399941] 'agreement among raft nodes before linearized reading' (duration: 1.370903501s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.213599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:42.842345Z","time spent":"1.371244209s","remote":"127.0.0.1:60322","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":"warn","ts":"2026-03-18T10:10:44.213972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554090745s","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-03-18T10:10:44.214013Z","caller":"traceutil/trace.go:171","msg":"trace[213222111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.554160957s","start":"2026-03-18T10:10:42.659838Z","end":"2026-03-18T10:10:44.213999Z","steps":["trace[213222111] 'agreement among raft nodes before linearized reading' (duration: 1.552551481s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.214043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:42.65982Z","time spent":"1.554215908s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T10:10:44.439389Z","caller":"traceutil/trace.go:171","msg":"trace[1688387100] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"208.630166ms","start":"2026-03-18T10:10:44.230669Z","end":"2026-03-18T10:10:44.439299Z","steps":["trace[1688387100] 'process raft request' (duration: 202.265963ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:44.456414Z","caller":"traceutil/trace.go:171","msg":"trace[1618662363] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"225.412403ms","start":"2026-03-18T10:10:44.230971Z","end":"2026-03-18T10:10:44.456383Z","steps":["trace[1618662363] 'process raft request' (duration: 225.190538ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:44.456419Z","caller":"traceutil/trace.go:171","msg":"trace[1156521577] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"225.373632ms","start":"2026-03-18T10:10:44.231026Z","end":"2026-03-18T10:10:44.4564Z","steps":["trace[1156521577] 'process raft request' (duration: 225.26733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:44.456609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.570103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T10:10:44.456433Z","caller":"traceutil/trace.go:171","msg":"trace[413330723] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:710; }","duration":"179.385558ms","start":"2026-03-18T10:10:44.277026Z","end":"2026-03-18T10:10:44.456411Z","steps":["trace[413330723] 'read index received' (duration: 155.898871ms)","trace[413330723] 'applied index is now lower than readState.Index' (duration: 23.485457ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:10:44.456662Z","caller":"traceutil/trace.go:171","msg":"trace[1789532685] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:687; }","duration":"179.694787ms","start":"2026-03-18T10:10:44.276957Z","end":"2026-03-18T10:10:44.456652Z","steps":["trace[1789532685] 'agreement among raft nodes before linearized reading' (duration: 179.528542ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:44.456468Z","caller":"traceutil/trace.go:171","msg":"trace[1955527173] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"225.397202ms","start":"2026-03-18T10:10:44.231056Z","end":"2026-03-18T10:10:44.456453Z","steps":["trace[1955527173] 'process raft request' (duration: 225.27821ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:44.456514Z","caller":"traceutil/trace.go:171","msg":"trace[1190357487] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"224.970342ms","start":"2026-03-18T10:10:44.231537Z","end":"2026-03-18T10:10:44.456507Z","steps":["trace[1190357487] 'process raft request' (duration: 224.826989ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:10:46.163506Z","caller":"traceutil/trace.go:171","msg":"trace[544109460] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"386.947843ms","start":"2026-03-18T10:10:45.776538Z","end":"2026-03-18T10:10:46.163486Z","steps":["trace[544109460] 'read index received' (duration: 386.81811ms)","trace[544109460] 'applied index is now lower than readState.Index' (duration: 128.903µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:10:46.163599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.049945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:10:46.163623Z","caller":"traceutil/trace.go:171","msg":"trace[957108950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"387.119127ms","start":"2026-03-18T10:10:45.776495Z","end":"2026-03-18T10:10:46.163614Z","steps":["trace[957108950] 'agreement among raft nodes before linearized reading' (duration: 387.065336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:46.163647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:45.776476Z","time spent":"387.165918ms","remote":"127.0.0.1:60074","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-03-18T10:10:46.163637Z","caller":"traceutil/trace.go:171","msg":"trace[566883724] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"963.758147ms","start":"2026-03-18T10:10:45.199846Z","end":"2026-03-18T10:10:46.163604Z","steps":["trace[566883724] 'process raft request' (duration: 963.519261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:10:46.163808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:10:45.199813Z","time spent":"963.89356ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:09.02Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.280853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:11:09.020148Z","caller":"traceutil/trace.go:171","msg":"trace[138011818] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"339.955844ms","start":"2026-03-18T10:11:08.680177Z","end":"2026-03-18T10:11:09.020133Z","steps":["trace[138011818] 'read index received' (duration: 132.893µs)","trace[138011818] 'applied index is now lower than readState.Index' (duration: 339.821851ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:09.02022Z","caller":"traceutil/trace.go:171","msg":"trace[1285741225] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"340.019076ms","start":"2026-03-18T10:11:08.680148Z","end":"2026-03-18T10:11:09.020167Z","steps":["trace[1285741225] 'process raft request' (duration: 235.488347ms)","trace[1285741225] 'compare' (duration: 103.974376ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:09.020306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.137618ms","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-03-18T10:11:09.020337Z","caller":"traceutil/trace.go:171","msg":"trace[1336057781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"340.19416ms","start":"2026-03-18T10:11:08.680132Z","end":"2026-03-18T10:11:09.020327Z","steps":["trace[1336057781] 'agreement among raft nodes before linearized reading' (duration: 340.079357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:09.020378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:08.680112Z","time spent":"340.257202ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T10:11:09.020414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:08.680117Z","time spent":"340.197999ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:09.020533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.958935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:09.020651Z","caller":"traceutil/trace.go:171","msg":"trace[1332241583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"242.149819ms","start":"2026-03-18T10:11:08.778478Z","end":"2026-03-18T10:11:09.020628Z","steps":["trace[1332241583] 'agreement among raft nodes before linearized reading' (duration: 241.895313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:09.020827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.410561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T10:11:09.020877Z","caller":"traceutil/trace.go:171","msg":"trace[1939922921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"308.479702ms","start":"2026-03-18T10:11:08.712384Z","end":"2026-03-18T10:11:09.020864Z","steps":["trace[1939922921] 'agreement among raft nodes before linearized reading' (duration: 308.293468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:09.02086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.230664ms","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-03-18T10:11:09.020911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:08.712372Z","time spent":"308.531643ms","remote":"127.0.0.1:60322","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-03-18T10:11:09.020952Z","caller":"traceutil/trace.go:171","msg":"trace[325902019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"331.345216ms","start":"2026-03-18T10:11:08.689583Z","end":"2026-03-18T10:11:09.020928Z","steps":["trace[325902019] 'agreement among raft nodes before linearized reading' (duration: 330.705132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:09.02102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:08.689575Z","time spent":"331.429678ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T10:11:11.278701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747811057318623407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T10:11:11.427637Z","caller":"traceutil/trace.go:171","msg":"trace[1059793265] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"743.621642ms","start":"2026-03-18T10:11:10.683995Z","end":"2026-03-18T10:11:11.427616Z","steps":["trace[1059793265] 'process raft request' (duration: 743.5064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:11.427946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:10.683977Z","time spent":"743.808316ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:11:11.602057Z","caller":"traceutil/trace.go:171","msg":"trace[1532720986] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"823.401354ms","start":"2026-03-18T10:11:10.778635Z","end":"2026-03-18T10:11:11.602037Z","steps":["trace[1532720986] 'read index received' (duration: 648.969289ms)","trace[1532720986] 'applied index is now lower than readState.Index' (duration: 174.431305ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:11.602169Z","caller":"traceutil/trace.go:171","msg":"trace[629642326] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"888.317335ms","start":"2026-03-18T10:11:10.713843Z","end":"2026-03-18T10:11:11.602161Z","steps":["trace[629642326] 'process raft request' (duration: 833.343003ms)","trace[629642326] 'compare' (duration: 54.738757ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:11.602255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:10.713814Z","time spent":"888.375867ms","remote":"127.0.0.1:60322","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-03-18T10:11:11.602333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.696241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:11.602403Z","caller":"traceutil/trace.go:171","msg":"trace[1943245845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"823.849734ms","start":"2026-03-18T10:11:10.778535Z","end":"2026-03-18T10:11:11.602385Z","steps":["trace[1943245845] 'agreement among raft nodes before linearized reading' (duration: 823.724511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:11.60244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:10.778511Z","time spent":"823.920065ms","remote":"127.0.0.1:60074","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-03-18T10:11:11.602811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.741896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-18T10:11:11.602813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.37302ms","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-03-18T10:11:11.602841Z","caller":"traceutil/trace.go:171","msg":"trace[645148100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"554.825857ms","start":"2026-03-18T10:11:11.048007Z","end":"2026-03-18T10:11:11.602833Z","steps":["trace[645148100] 'agreement among raft nodes before linearized reading' (duration: 554.730955ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:11.60285Z","caller":"traceutil/trace.go:171","msg":"trace[1838487544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"555.439632ms","start":"2026-03-18T10:11:11.0474Z","end":"2026-03-18T10:11:11.60284Z","steps":["trace[1838487544] 'agreement among raft nodes before linearized reading' (duration: 555.327469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:11.602874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:11.047387Z","time spent":"555.480582ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T10:11:11.602872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.489727ms","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-03-18T10:11:11.602867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:11.047993Z","time spent":"554.867109ms","remote":"127.0.0.1:60322","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-03-18T10:11:11.602932Z","caller":"traceutil/trace.go:171","msg":"trace[534569127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"563.591568ms","start":"2026-03-18T10:11:11.039328Z","end":"2026-03-18T10:11:11.602919Z","steps":["trace[534569127] 'agreement among raft nodes before linearized reading' (duration: 563.452415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:11.602968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:11.039302Z","time spent":"563.65643ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T10:11:11.60317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.253915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T10:11:11.603334Z","caller":"traceutil/trace.go:171","msg":"trace[315504159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"578.434959ms","start":"2026-03-18T10:11:11.024877Z","end":"2026-03-18T10:11:11.603312Z","steps":["trace[315504159] 'agreement among raft nodes before linearized reading' (duration: 577.817495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:11.603388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:11.024855Z","time spent":"578.522931ms","remote":"127.0.0.1:60322","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-03-18T10:11:20.794562Z","caller":"traceutil/trace.go:171","msg":"trace[1953653579] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"128.379807ms","start":"2026-03-18T10:11:20.666167Z","end":"2026-03-18T10:11:20.794547Z","steps":["trace[1953653579] 'process raft request' (duration: 128.292956ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.794744Z","caller":"traceutil/trace.go:171","msg":"trace[1112114750] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"132.035731ms","start":"2026-03-18T10:11:20.6627Z","end":"2026-03-18T10:11:20.794736Z","steps":["trace[1112114750] 'process raft request' (duration: 104.026218ms)","trace[1112114750] 'compare' (duration: 27.542702ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:20.794861Z","caller":"traceutil/trace.go:171","msg":"trace[1439163798] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"129.34183ms","start":"2026-03-18T10:11:20.665476Z","end":"2026-03-18T10:11:20.794818Z","steps":["trace[1439163798] 'process raft request' (duration: 128.92765ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.828258Z","caller":"traceutil/trace.go:171","msg":"trace[1688242190] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:856; }","duration":"111.702225ms","start":"2026-03-18T10:11:20.716525Z","end":"2026-03-18T10:11:20.828227Z","steps":["trace[1688242190] 'read index received' (duration: 50.293395ms)","trace[1688242190] 'applied index is now lower than readState.Index' (duration: 61.40736ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:20.828396Z","caller":"traceutil/trace.go:171","msg":"trace[60598416] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"117.069308ms","start":"2026-03-18T10:11:20.711314Z","end":"2026-03-18T10:11:20.828383Z","steps":["trace[60598416] 'process raft request' (duration: 116.786762ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.828423Z","caller":"traceutil/trace.go:171","msg":"trace[124283198] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"117.312823ms","start":"2026-03-18T10:11:20.711063Z","end":"2026-03-18T10:11:20.828376Z","steps":["trace[124283198] 'process raft request' (duration: 116.918394ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.828659Z","caller":"traceutil/trace.go:171","msg":"trace[640653361] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"122.309488ms","start":"2026-03-18T10:11:20.706296Z","end":"2026-03-18T10:11:20.828605Z","steps":["trace[640653361] 'process raft request' (duration: 121.632303ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.828659Z","caller":"traceutil/trace.go:171","msg":"trace[1318451129] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"112.956273ms","start":"2026-03-18T10:11:20.715666Z","end":"2026-03-18T10:11:20.828622Z","steps":["trace[1318451129] 'process raft request' (duration: 112.481602ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:20.828847Z","caller":"traceutil/trace.go:171","msg":"trace[398994602] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"122.713147ms","start":"2026-03-18T10:11:20.706122Z","end":"2026-03-18T10:11:20.828835Z","steps":["trace[398994602] 'process raft request' (duration: 121.645993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:20.828844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.284658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-03-18T10:11:20.82889Z","caller":"traceutil/trace.go:171","msg":"trace[1420010216] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:827; }","duration":"112.386049ms","start":"2026-03-18T10:11:20.716492Z","end":"2026-03-18T10:11:20.828878Z","steps":["trace[1420010216] 'agreement among raft nodes before linearized reading' (duration: 112.177635ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:25.725529Z","caller":"traceutil/trace.go:171","msg":"trace[1003296490] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"163.602276ms","start":"2026-03-18T10:11:25.561904Z","end":"2026-03-18T10:11:25.725506Z","steps":["trace[1003296490] 'process raft request' (duration: 163.392201ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:26.42122Z","caller":"traceutil/trace.go:171","msg":"trace[1980265775] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"629.127582ms","start":"2026-03-18T10:11:25.792062Z","end":"2026-03-18T10:11:26.42119Z","steps":["trace[1980265775] 'process raft request' (duration: 624.383463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:26.421424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:25.792006Z","time spent":"629.320596ms","remote":"127.0.0.1:60322","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-03-18T10:11:26.423132Z","caller":"traceutil/trace.go:171","msg":"trace[1039351059] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"630.679258ms","start":"2026-03-18T10:11:25.792436Z","end":"2026-03-18T10:11:26.423115Z","steps":["trace[1039351059] 'process raft request' (duration: 630.32143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:26.423228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:25.792421Z","time spent":"630.759829ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:11:26.423672Z","caller":"traceutil/trace.go:171","msg":"trace[2018682106] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"631.135788ms","start":"2026-03-18T10:11:25.79251Z","end":"2026-03-18T10:11:26.423646Z","steps":["trace[2018682106] 'process raft request' (duration: 630.469923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:26.425328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:25.79247Z","time spent":"631.304132ms","remote":"127.0.0.1:60322","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-03-18T10:11:38.952771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.147275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:38.952846Z","caller":"traceutil/trace.go:171","msg":"trace[2120472715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"156.250987ms","start":"2026-03-18T10:11:38.796581Z","end":"2026-03-18T10:11:38.952832Z","steps":["trace[2120472715] 'range keys from in-memory index tree' (duration: 156.085393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:38.952828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.212009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T10:11:38.952898Z","caller":"traceutil/trace.go:171","msg":"trace[428735038] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:891; }","duration":"336.29638ms","start":"2026-03-18T10:11:38.616584Z","end":"2026-03-18T10:11:38.95288Z","steps":["trace[428735038] 'count revisions from in-memory index tree' (duration: 336.132737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:38.952937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:38.616532Z","time spent":"336.395933ms","remote":"127.0.0.1:60152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-03-18T10:11:42.352157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747811057318623768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T10:11:42.852455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747811057318623768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T10:11:42.868741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075858949s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T10:11:42.892775Z","caller":"traceutil/trace.go:171","msg":"trace[1838809656] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.099993532s","start":"2026-03-18T10:11:41.792745Z","end":"2026-03-18T10:11:42.892738Z","steps":["trace[1838809656] 'process raft request' (duration: 1.078589702s)","trace[1838809656] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 21.254047ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:42.892935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:41.792717Z","time spent":"1.100140196s","remote":"127.0.0.1:60322","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-03-18T10:11:43.30763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.27736ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T10:11:43.307989Z","caller":"traceutil/trace.go:171","msg":"trace[1032064212] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:957; }","duration":"1.455867403s","start":"2026-03-18T10:11:41.852102Z","end":"2026-03-18T10:11:43.30797Z","steps":["trace[1032064212] 'read index received' (duration: 1.016846224s)","trace[1032064212] 'applied index is now lower than readState.Index' (duration: 439.019469ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:43.308042Z","caller":"traceutil/trace.go:171","msg":"trace[2059433707] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.113263118s","start":"2026-03-18T10:11:42.194724Z","end":"2026-03-18T10:11:43.307987Z","steps":["trace[2059433707] 'process raft request' (duration: 1.112995931s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:43.308211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:42.194703Z","time spent":"1.113413372s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:43.360489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.294374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:43.360578Z","caller":"traceutil/trace.go:171","msg":"trace[1299780041] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:918; }","duration":"334.447238ms","start":"2026-03-18T10:11:43.026108Z","end":"2026-03-18T10:11:43.360556Z","steps":["trace[1299780041] 'agreement among raft nodes before linearized reading' (duration: 282.046395ms)","trace[1299780041] 'count revisions from in-memory index tree' (duration: 52.202308ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:43.36058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.782411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T10:11:43.360628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:43.026088Z","time spent":"334.5282ms","remote":"127.0.0.1:43498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-18T10:11:43.360669Z","caller":"traceutil/trace.go:171","msg":"trace[1360692516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"850.926435ms","start":"2026-03-18T10:11:42.509721Z","end":"2026-03-18T10:11:43.360647Z","steps":["trace[1360692516] 'agreement among raft nodes before linearized reading' (duration: 798.450071ms)","trace[1360692516] 'range keys from in-memory index tree' (duration: 52.253629ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:43.360722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:42.509698Z","time spent":"851.012996ms","remote":"127.0.0.1:60322","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-03-18T10:11:43.360759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.883429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T10:11:43.360808Z","caller":"traceutil/trace.go:171","msg":"trace[280344732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"838.96237ms","start":"2026-03-18T10:11:42.521831Z","end":"2026-03-18T10:11:43.360793Z","steps":["trace[280344732] 'agreement among raft nodes before linearized reading' (duration: 786.333412ms)","trace[280344732] 'range keys from in-memory index tree' (duration: 52.487594ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:43.360842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:42.521817Z","time spent":"839.017462ms","remote":"127.0.0.1:60322","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":"warn","ts":"2026-03-18T10:11:43.360862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.971865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T10:11:43.360855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.466982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:43.360935Z","caller":"traceutil/trace.go:171","msg":"trace[146672910] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:918; }","duration":"232.038586ms","start":"2026-03-18T10:11:43.128846Z","end":"2026-03-18T10:11:43.360884Z","steps":["trace[146672910] 'agreement among raft nodes before linearized reading' (duration: 179.300606ms)","trace[146672910] 'count revisions from in-memory index tree' (duration: 52.694139ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:43.360967Z","caller":"traceutil/trace.go:171","msg":"trace[1773126526] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:918; }","duration":"227.613075ms","start":"2026-03-18T10:11:43.133324Z","end":"2026-03-18T10:11:43.360937Z","steps":["trace[1773126526] 'agreement among raft nodes before linearized reading' (duration: 174.815134ms)","trace[1773126526] 'count revisions from in-memory index tree' (duration: 52.636768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:43.361239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.997796ms","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-03-18T10:11:43.361257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509148196s","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-03-18T10:11:43.361283Z","caller":"traceutil/trace.go:171","msg":"trace[1205835891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"864.068678ms","start":"2026-03-18T10:11:42.497197Z","end":"2026-03-18T10:11:43.361266Z","steps":["trace[1205835891] 'agreement among raft nodes before linearized reading' (duration: 810.925938ms)","trace[1205835891] 'range keys from in-memory index tree' (duration: 52.272179ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:43.361291Z","caller":"traceutil/trace.go:171","msg":"trace[1983799667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.509195508s","start":"2026-03-18T10:11:41.852085Z","end":"2026-03-18T10:11:43.36128Z","steps":["trace[1983799667] 'agreement among raft nodes before linearized reading' (duration: 1.456016927s)","trace[1983799667] 'range keys from in-memory index tree' (duration: 53.088829ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:43.361314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:42.497177Z","time spent":"864.1275ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T10:11:43.361319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:41.852075Z","time spent":"1.509237449s","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T10:11:43.842234Z","caller":"traceutil/trace.go:171","msg":"trace[2073755424] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"353.115906ms","start":"2026-03-18T10:11:43.489092Z","end":"2026-03-18T10:11:43.842208Z","steps":["trace[2073755424] 'process raft request' (duration: 352.994953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:43.84237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:43.489068Z","time spent":"353.237009ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T10:11:45.465581Z","caller":"traceutil/trace.go:171","msg":"trace[1825420545] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"566.583508ms","start":"2026-03-18T10:11:44.898974Z","end":"2026-03-18T10:11:45.465557Z","steps":["trace[1825420545] 'process raft request' (duration: 566.398244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:45.465718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:44.898962Z","time spent":"566.68686ms","remote":"127.0.0.1:60322","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-03-18T10:11:46.01695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.209256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:11:46.017145Z","caller":"traceutil/trace.go:171","msg":"trace[211663127] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"546.945868ms","start":"2026-03-18T10:11:45.470177Z","end":"2026-03-18T10:11:46.017123Z","steps":["trace[211663127] 'process raft request' (duration: 407.480576ms)","trace[211663127] 'compare' (duration: 139.088992ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:46.017249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:45.470159Z","time spent":"547.05184ms","remote":"127.0.0.1:60322","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-03-18T10:11:46.233863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.376626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:11:46.234411Z","caller":"traceutil/trace.go:171","msg":"trace[1353932449] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"763.905728ms","start":"2026-03-18T10:11:45.470475Z","end":"2026-03-18T10:11:46.234381Z","steps":["trace[1353932449] 'process raft request' (duration: 658.941719ms)","trace[1353932449] 'compare' (duration: 104.297414ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:46.234568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:45.470461Z","time spent":"764.023371ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:11:46.234658Z","caller":"traceutil/trace.go:171","msg":"trace[1334636326] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"764.102861ms","start":"2026-03-18T10:11:45.470538Z","end":"2026-03-18T10:11:46.234641Z","steps":["trace[1334636326] 'process raft request' (duration: 763.692302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:46.234756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:45.470532Z","time spent":"764.183124ms","remote":"127.0.0.1:60322","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-03-18T10:11:46.234915Z","caller":"traceutil/trace.go:171","msg":"trace[589966758] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"764.186864ms","start":"2026-03-18T10:11:45.470708Z","end":"2026-03-18T10:11:46.234895Z","steps":["trace[589966758] 'process raft request' (duration: 763.56596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:46.236465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:45.4707Z","time spent":"765.73307ms","remote":"127.0.0.1:60322","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-03-18T10:11:46.235272Z","caller":"traceutil/trace.go:171","msg":"trace[574823640] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:966; }","duration":"456.768126ms","start":"2026-03-18T10:11:45.778492Z","end":"2026-03-18T10:11:46.23526Z","steps":["trace[574823640] 'read index received' (duration: 99.222028ms)","trace[574823640] 'applied index is now lower than readState.Index' (duration: 357.544758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:46.23533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.832669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:46.23671Z","caller":"traceutil/trace.go:171","msg":"trace[1243660513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"458.234311ms","start":"2026-03-18T10:11:45.778462Z","end":"2026-03-18T10:11:46.236696Z","steps":["trace[1243660513] 'agreement among raft nodes before linearized reading' (duration: 456.837439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:46.236773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:45.778446Z","time spent":"458.316612ms","remote":"127.0.0.1:60074","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-03-18T10:11:46.70595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.471472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:11:46.706069Z","caller":"traceutil/trace.go:171","msg":"trace[68228066] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"182.660284ms","start":"2026-03-18T10:11:46.523393Z","end":"2026-03-18T10:11:46.706053Z","steps":["trace[68228066] 'read index received' (duration: 34.901µs)","trace[68228066] 'applied index is now lower than readState.Index' (duration: 182.624193ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:46.706139Z","caller":"traceutil/trace.go:171","msg":"trace[1018352567] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"446.677106ms","start":"2026-03-18T10:11:46.259453Z","end":"2026-03-18T10:11:46.70613Z","steps":["trace[1018352567] 'process raft request' (duration: 137.983858ms)","trace[1018352567] 'compare' (duration: 308.273387ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:46.706199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.259438Z","time spent":"446.736617ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T10:11:46.706467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.076873ms","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-03-18T10:11:46.706504Z","caller":"traceutil/trace.go:171","msg":"trace[70569707] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:930; }","duration":"183.118084ms","start":"2026-03-18T10:11:46.523377Z","end":"2026-03-18T10:11:46.706495Z","steps":["trace[70569707] 'agreement among raft nodes before linearized reading' (duration: 183.012062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:47.207105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747811057318623796,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T10:11:47.607003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.328596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T10:11:47.607089Z","caller":"traceutil/trace.go:171","msg":"trace[2122985171] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.057033707s","start":"2026-03-18T10:11:46.550041Z","end":"2026-03-18T10:11:47.607075Z","steps":["trace[2122985171] 'process raft request' (duration: 786.577468ms)","trace[2122985171] 'compare' (duration: 270.06695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:47.607135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.550028Z","time spent":"1.057083108s","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T10:11:47.667547Z","caller":"traceutil/trace.go:171","msg":"trace[1446379674] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:972; }","duration":"961.421952ms","start":"2026-03-18T10:11:46.706101Z","end":"2026-03-18T10:11:47.667523Z","steps":["trace[1446379674] 'read index received' (duration: 630.501335ms)","trace[1446379674] 'applied index is now lower than readState.Index' (duration: 330.919787ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:47.667715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061653883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T10:11:47.667693Z","caller":"traceutil/trace.go:171","msg":"trace[1231138705] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"691.71964ms","start":"2026-03-18T10:11:46.975944Z","end":"2026-03-18T10:11:47.667663Z","steps":["trace[1231138705] 'process raft request' (duration: 691.526676ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:47.667755Z","caller":"traceutil/trace.go:171","msg":"trace[1340579982] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:933; }","duration":"1.061738895s","start":"2026-03-18T10:11:46.606004Z","end":"2026-03-18T10:11:47.667743Z","steps":["trace[1340579982] 'agreement among raft nodes before linearized reading' (duration: 1.061655263s)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:47.667771Z","caller":"traceutil/trace.go:171","msg":"trace[1971498106] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"956.839057ms","start":"2026-03-18T10:11:46.710894Z","end":"2026-03-18T10:11:47.667733Z","steps":["trace[1971498106] 'process raft request' (duration: 956.470989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:47.66781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122746546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T10:11:47.667851Z","caller":"traceutil/trace.go:171","msg":"trace[343061205] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:933; }","duration":"1.122804778s","start":"2026-03-18T10:11:46.545036Z","end":"2026-03-18T10:11:47.667841Z","steps":["trace[343061205] 'agreement among raft nodes before linearized reading' (duration: 1.122637654s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:47.66787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.544948Z","time spent":"1.12291677s","remote":"127.0.0.1:60100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-03-18T10:11:47.667875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.975916Z","time spent":"691.856424ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:47.667918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.710883Z","time spent":"956.97689ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T10:11:47.667798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.605991Z","time spent":"1.061796536s","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-03-18T10:11:47.694752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.346856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T10:11:47.694775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.970631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T10:11:47.694839Z","caller":"traceutil/trace.go:171","msg":"trace[467592907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"919.46444ms","start":"2026-03-18T10:11:46.775346Z","end":"2026-03-18T10:11:47.694811Z","steps":["trace[467592907] 'agreement among raft nodes before linearized reading' (duration: 919.328566ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:47.694861Z","caller":"traceutil/trace.go:171","msg":"trace[1088499357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"224.097705ms","start":"2026-03-18T10:11:47.470741Z","end":"2026-03-18T10:11:47.694839Z","steps":["trace[1088499357] 'agreement among raft nodes before linearized reading' (duration: 223.865648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:47.694889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.775331Z","time spent":"919.547461ms","remote":"127.0.0.1:60062","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-03-18T10:11:47.694755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.127445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-18T10:11:47.694986Z","caller":"traceutil/trace.go:171","msg":"trace[1541067100] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:934; }","duration":"985.384323ms","start":"2026-03-18T10:11:46.709586Z","end":"2026-03-18T10:11:47.69497Z","steps":["trace[1541067100] 'agreement among raft nodes before linearized reading' (duration: 984.960092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:47.695061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:46.709567Z","time spent":"985.478764ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-18T10:11:48.390213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.81522ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-18T10:11:48.39032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:47.668712Z","time spent":"721.598507ms","remote":"127.0.0.1:60100","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-18T10:11:48.390464Z","caller":"traceutil/trace.go:171","msg":"trace[890021606] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"690.928753ms","start":"2026-03-18T10:11:47.699519Z","end":"2026-03-18T10:11:48.390447Z","steps":["trace[890021606] 'process raft request' (duration: 690.891862ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:48.390458Z","caller":"traceutil/trace.go:171","msg":"trace[1773845086] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"694.597156ms","start":"2026-03-18T10:11:47.69583Z","end":"2026-03-18T10:11:48.390427Z","steps":["trace[1773845086] 'read index received' (duration: 64.570562ms)","trace[1773845086] 'applied index is now lower than readState.Index' (duration: 630.023404ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:48.390556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:47.699474Z","time spent":"691.027445ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:11:48.390621Z","caller":"traceutil/trace.go:171","msg":"trace[1327438107] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"692.228212ms","start":"2026-03-18T10:11:47.69837Z","end":"2026-03-18T10:11:48.390599Z","steps":["trace[1327438107] 'process raft request' (duration: 691.944976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.390664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.811801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T10:11:48.390698Z","caller":"traceutil/trace.go:171","msg":"trace[159090618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"694.891973ms","start":"2026-03-18T10:11:47.695795Z","end":"2026-03-18T10:11:48.390687Z","steps":["trace[159090618] 'agreement among raft nodes before linearized reading' (duration: 694.771591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.390733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:47.698349Z","time spent":"692.320854ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:48.390733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:47.695775Z","time spent":"694.950894ms","remote":"127.0.0.1:60074","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-03-18T10:11:48.390812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.636277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-18T10:11:48.390865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.247609ms","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-03-18T10:11:48.390864Z","caller":"traceutil/trace.go:171","msg":"trace[1754381637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:936; }","duration":"369.722248ms","start":"2026-03-18T10:11:48.021126Z","end":"2026-03-18T10:11:48.390849Z","steps":["trace[1754381637] 'agreement among raft nodes before linearized reading' (duration: 369.558864ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:48.390901Z","caller":"traceutil/trace.go:171","msg":"trace[1182769611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:936; }","duration":"150.300531ms","start":"2026-03-18T10:11:48.240589Z","end":"2026-03-18T10:11:48.39089Z","steps":["trace[1182769611] 'agreement among raft nodes before linearized reading' (duration: 150.185068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.3909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.552109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-18T10:11:48.390906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.02111Z","time spent":"369.788969ms","remote":"127.0.0.1:60322","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-03-18T10:11:48.390935Z","caller":"traceutil/trace.go:171","msg":"trace[1002459108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:936; }","duration":"151.622531ms","start":"2026-03-18T10:11:48.239303Z","end":"2026-03-18T10:11:48.390926Z","steps":["trace[1002459108] 'agreement among raft nodes before linearized reading' (duration: 151.494078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.391053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.313741ms","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-03-18T10:11:48.391081Z","caller":"traceutil/trace.go:171","msg":"trace[1947355459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"150.382383ms","start":"2026-03-18T10:11:48.24069Z","end":"2026-03-18T10:11:48.391072Z","steps":["trace[1947355459] 'agreement among raft nodes before linearized reading' (duration: 150.26424ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:48.813358Z","caller":"traceutil/trace.go:171","msg":"trace[2109581096] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"416.515672ms","start":"2026-03-18T10:11:48.396819Z","end":"2026-03-18T10:11:48.813334Z","steps":["trace[2109581096] 'process raft request' (duration: 416.443981ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:48.813433Z","caller":"traceutil/trace.go:171","msg":"trace[2115458001] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"417.599087ms","start":"2026-03-18T10:11:48.395798Z","end":"2026-03-18T10:11:48.813397Z","steps":["trace[2115458001] 'process raft request' (duration: 364.378365ms)","trace[2115458001] 'compare' (duration: 52.978016ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:11:48.813495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.396804Z","time spent":"416.621584ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:48.813604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.395774Z","time spent":"417.73707ms","remote":"127.0.0.1:60322","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-03-18T10:11:48.813829Z","caller":"traceutil/trace.go:171","msg":"trace[37634189] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"415.56249ms","start":"2026-03-18T10:11:48.398256Z","end":"2026-03-18T10:11:48.813818Z","steps":["trace[37634189] 'process raft request' (duration: 415.049969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.813906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.398243Z","time spent":"415.615091ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T10:11:48.814012Z","caller":"traceutil/trace.go:171","msg":"trace[1852408847] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:980; }","duration":"415.549749ms","start":"2026-03-18T10:11:48.398441Z","end":"2026-03-18T10:11:48.813991Z","steps":["trace[1852408847] 'read index received' (duration: 361.750514ms)","trace[1852408847] 'applied index is now lower than readState.Index' (duration: 53.797565ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:11:48.814022Z","caller":"traceutil/trace.go:171","msg":"trace[34415865] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"414.291331ms","start":"2026-03-18T10:11:48.39971Z","end":"2026-03-18T10:11:48.814002Z","steps":["trace[34415865] 'process raft request' (duration: 414.069726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.814194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.399704Z","time spent":"414.404384ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:11:48.814259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.903903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T10:11:48.814274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.761215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-18T10:11:48.814327Z","caller":"traceutil/trace.go:171","msg":"trace[326024298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"414.039485ms","start":"2026-03-18T10:11:48.400277Z","end":"2026-03-18T10:11:48.814316Z","steps":["trace[326024298] 'agreement among raft nodes before linearized reading' (duration: 413.887002ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:11:48.814326Z","caller":"traceutil/trace.go:171","msg":"trace[632909575] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:941; }","duration":"415.900797ms","start":"2026-03-18T10:11:48.398412Z","end":"2026-03-18T10:11:48.814313Z","steps":["trace[632909575] 'agreement among raft nodes before linearized reading' (duration: 415.731864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:11:48.814378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.398403Z","time spent":"415.956498ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-18T10:11:48.814363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:11:48.400272Z","time spent":"414.081006ms","remote":"127.0.0.1:60062","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-03-18T10:14:58.28923Z","caller":"traceutil/trace.go:171","msg":"trace[1953324952] linearizableReadLoop","detail":"{readStateIndex:1847; appliedIndex:1846; }","duration":"271.565256ms","start":"2026-03-18T10:14:58.017639Z","end":"2026-03-18T10:14:58.289204Z","steps":["trace[1953324952] 'read index received' (duration: 271.464124ms)","trace[1953324952] 'applied index is now lower than readState.Index' (duration: 100.222µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T10:14:58.289399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.746699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T10:14:58.289437Z","caller":"traceutil/trace.go:171","msg":"trace[1217472688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1765; }","duration":"271.840872ms","start":"2026-03-18T10:14:58.017584Z","end":"2026-03-18T10:14:58.289425Z","steps":["trace[1217472688] 'agreement among raft nodes before linearized reading' (duration: 271.705099ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:14:58.289409Z","caller":"traceutil/trace.go:171","msg":"trace[1668589728] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"359.362954ms","start":"2026-03-18T10:14:57.929935Z","end":"2026-03-18T10:14:58.289298Z","steps":["trace[1668589728] 'process raft request' (duration: 359.147139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:14:58.289647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:14:57.92991Z","time spent":"359.64554ms","remote":"127.0.0.1:60322","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-03-18T10:14:58.419904Z","caller":"traceutil/trace.go:171","msg":"trace[787299513] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"270.707836ms","start":"2026-03-18T10:14:58.149168Z","end":"2026-03-18T10:14:58.419876Z","steps":["trace[787299513] 'process raft request' (duration: 270.071332ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:14:58.426418Z","caller":"traceutil/trace.go:171","msg":"trace[1554185262] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"132.787507ms","start":"2026-03-18T10:14:58.293606Z","end":"2026-03-18T10:14:58.426393Z","steps":["trace[1554185262] 'process raft request' (duration: 132.680075ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T10:15:04.198925Z","caller":"traceutil/trace.go:171","msg":"trace[762287751] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"336.451425ms","start":"2026-03-18T10:15:03.862442Z","end":"2026-03-18T10:15:04.198893Z","steps":["trace[762287751] 'read index received' (duration: 336.155489ms)","trace[762287751] 'applied index is now lower than readState.Index' (duration: 294.376µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T10:15:04.199038Z","caller":"traceutil/trace.go:171","msg":"trace[515835288] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"401.52172ms","start":"2026-03-18T10:15:03.797476Z","end":"2026-03-18T10:15:04.198998Z","steps":["trace[515835288] 'process raft request' (duration: 401.255224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:15:04.199196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.727391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T10:15:04.199243Z","caller":"traceutil/trace.go:171","msg":"trace[1344834691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1793; }","duration":"336.825254ms","start":"2026-03-18T10:15:03.862407Z","end":"2026-03-18T10:15:04.199232Z","steps":["trace[1344834691] 'agreement among raft nodes before linearized reading' (duration: 336.65265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:15:04.199265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:15:03.797455Z","time spent":"401.683393ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T10:15:04.199284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:15:03.862392Z","time spent":"336.883305ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T10:15:04.199556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.624501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T10:15:04.199617Z","caller":"traceutil/trace.go:171","msg":"trace[2141135307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1793; }","duration":"328.711204ms","start":"2026-03-18T10:15:03.870891Z","end":"2026-03-18T10:15:04.199602Z","steps":["trace[2141135307] 'agreement among raft nodes before linearized reading' (duration: 328.305275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T10:15:04.199644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T10:15:03.87088Z","time spent":"328.758745ms","remote":"127.0.0.1:60322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}