{"level":"warn","ts":"2026-04-21T17:12:28.486205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:12:28.48632Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.37:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.37:2380","--initial-cluster=instance=https://199.19.213.37:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.37:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.37: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-04-21T17:12:28.486395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:12:28.486414Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-04-21T17:12:28.486434Z","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-04-21T17:12:28.487314Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"]} {"level":"info","ts":"2026-04-21T17:12:28.48748Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.37: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-04-21T17:12:28.500705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.546767ms"} {"level":"info","ts":"2026-04-21T17:12:28.514517Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7252794153f4f3cd","cluster-id":"2552855285ac01f"} {"level":"info","ts":"2026-04-21T17:12:28.51462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T17:12:28.514652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 0"} {"level":"info","ts":"2026-04-21T17:12:28.514666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7252794153f4f3cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T17:12:28.514681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 1"} {"level":"info","ts":"2026-04-21T17:12:28.514731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"warn","ts":"2026-04-21T17:12:28.531194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T17:12:28.534256Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T17:12:28.535953Z","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-04-21T17:12:28.538301Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7252794153f4f3cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T17:12:28.538445Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7252794153f4f3cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T17:12:28.53873Z","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-04-21T17:12:28.53879Z","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-04-21T17:12:28.5388Z","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-04-21T17:12:28.540626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"info","ts":"2026-04-21T17:12:28.541035Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","added-peer-id":"7252794153f4f3cd","added-peer-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-04-21T17:12:28.545813Z","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-04-21T17:12:28.54645Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7252794153f4f3cd","initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T17:12:28.546067Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-04-21T17:12:28.547641Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-04-21T17:12:28.546701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T17:12:29.214931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T17:12:29.214974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T17:12:29.215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgPreVoteResp from 7252794153f4f3cd at term 1"} {"level":"info","ts":"2026-04-21T17:12:29.215011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became candidate at term 2"} {"level":"info","ts":"2026-04-21T17:12:29.215017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgVoteResp from 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-04-21T17:12:29.215025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became leader at term 2"} {"level":"info","ts":"2026-04-21T17:12:29.215032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7252794153f4f3cd elected leader 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-04-21T17:12:29.21641Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7252794153f4f3cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.37:2379]}","request-path":"/0/members/7252794153f4f3cd/attributes","cluster-id":"2552855285ac01f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T17:12:29.216434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:12:29.216446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:12:29.216443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:12:29.216761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T17:12:29.216805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T17:12:29.217519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:12:29.217675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:12:29.2178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:12:29.218128Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.37:2379"} {"level":"info","ts":"2026-04-21T17:12:29.220108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T17:12:35.216871Z","caller":"traceutil/trace.go:171","msg":"trace[843056119] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"246.65987ms","start":"2026-04-21T17:12:34.970169Z","end":"2026-04-21T17:12:35.216829Z","steps":["trace[843056119] 'process raft request' (duration: 197.361582ms)","trace[843056119] 'compare' (duration: 48.628202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:12:35.217127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.643636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T17:12:35.217181Z","caller":"traceutil/trace.go:171","msg":"trace[812984295] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"211.78262ms","start":"2026-04-21T17:12:35.00539Z","end":"2026-04-21T17:12:35.217172Z","steps":["trace[812984295] 'agreement among raft nodes before linearized reading' (duration: 211.602585ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:35.216827Z","caller":"traceutil/trace.go:171","msg":"trace[265260902] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:319; }","duration":"211.38849ms","start":"2026-04-21T17:12:35.005398Z","end":"2026-04-21T17:12:35.216786Z","steps":["trace[265260902] 'read index received' (duration: 162.168783ms)","trace[265260902] 'applied index is now lower than readState.Index' (duration: 49.218087ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:35.424266Z","caller":"traceutil/trace.go:171","msg":"trace[786533744] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"118.463613ms","start":"2026-04-21T17:12:35.305763Z","end":"2026-04-21T17:12:35.424226Z","steps":["trace[786533744] 'read index received' (duration: 34.84454ms)","trace[786533744] 'applied index is now lower than readState.Index' (duration: 83.617793ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:35.424479Z","caller":"traceutil/trace.go:171","msg":"trace[2126071683] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"162.239424ms","start":"2026-04-21T17:12:35.262223Z","end":"2026-04-21T17:12:35.424463Z","steps":["trace[2126071683] 'process raft request' (duration: 78.452196ms)","trace[2126071683] 'compare' (duration: 83.395269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:12:35.424643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.835932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T17:12:35.424745Z","caller":"traceutil/trace.go:171","msg":"trace[185301626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:314; }","duration":"119.031919ms","start":"2026-04-21T17:12:35.30569Z","end":"2026-04-21T17:12:35.424722Z","steps":["trace[185301626] 'agreement among raft nodes before linearized reading' (duration: 118.848873ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:35.59173Z","caller":"traceutil/trace.go:171","msg":"trace[331190316] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:325; }","duration":"129.800406ms","start":"2026-04-21T17:12:35.461902Z","end":"2026-04-21T17:12:35.591702Z","steps":["trace[331190316] 'read index received' (duration: 114.492464ms)","trace[331190316] 'applied index is now lower than readState.Index' (duration: 15.306842ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:35.59188Z","caller":"traceutil/trace.go:171","msg":"trace[539156520] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"162.122183ms","start":"2026-04-21T17:12:35.429745Z","end":"2026-04-21T17:12:35.591867Z","steps":["trace[539156520] 'process raft request' (duration: 146.705899ms)","trace[539156520] 'compare' (duration: 15.049275ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:35.592212Z","caller":"traceutil/trace.go:171","msg":"trace[1636811532] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"155.495347ms","start":"2026-04-21T17:12:35.436662Z","end":"2026-04-21T17:12:35.592157Z","steps":["trace[1636811532] 'process raft request' (duration: 154.977044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:35.592378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.462603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-21T17:12:35.592668Z","caller":"traceutil/trace.go:171","msg":"trace[403544920] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:317; }","duration":"130.76967ms","start":"2026-04-21T17:12:35.461847Z","end":"2026-04-21T17:12:35.592616Z","steps":["trace[403544920] 'agreement among raft nodes before linearized reading' (duration: 130.071893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:35.831467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.556665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T17:12:35.831565Z","caller":"traceutil/trace.go:171","msg":"trace[894962702] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"138.697319ms","start":"2026-04-21T17:12:35.692841Z","end":"2026-04-21T17:12:35.831539Z","steps":["trace[894962702] 'range keys from in-memory index tree' (duration: 138.458613ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:38.677517Z","caller":"traceutil/trace.go:171","msg":"trace[1723054616] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"119.959491ms","start":"2026-04-21T17:12:38.557525Z","end":"2026-04-21T17:12:38.677484Z","steps":["trace[1723054616] 'process raft request' (duration: 73.83446ms)","trace[1723054616] 'compare' (duration: 45.956467ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:39.89598Z","caller":"traceutil/trace.go:171","msg":"trace[1001385769] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"133.454298ms","start":"2026-04-21T17:12:39.762472Z","end":"2026-04-21T17:12:39.895926Z","steps":["trace[1001385769] 'process raft request' (duration: 77.325538ms)","trace[1001385769] 'compare' (duration: 55.909364ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:46.120576Z","caller":"traceutil/trace.go:171","msg":"trace[661207248] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"407.211275ms","start":"2026-04-21T17:12:45.713337Z","end":"2026-04-21T17:12:46.120549Z","steps":["trace[661207248] 'read index received' (duration: 406.944337ms)","trace[661207248] 'applied index is now lower than readState.Index' (duration: 265.378µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:46.120663Z","caller":"traceutil/trace.go:171","msg":"trace[253712275] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"414.375083ms","start":"2026-04-21T17:12:45.70625Z","end":"2026-04-21T17:12:46.120625Z","steps":["trace[253712275] 'process raft request' (duration: 414.142117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:46.120912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.417746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T17:12:46.120929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.380265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-21T17:12:46.121044Z","caller":"traceutil/trace.go:171","msg":"trace[830040549] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:410; }","duration":"137.535469ms","start":"2026-04-21T17:12:45.983487Z","end":"2026-04-21T17:12:46.121023Z","steps":["trace[830040549] 'agreement among raft nodes before linearized reading' (duration: 137.364105ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:46.121043Z","caller":"traceutil/trace.go:171","msg":"trace[1819541071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"376.55793ms","start":"2026-04-21T17:12:45.744436Z","end":"2026-04-21T17:12:46.120994Z","steps":["trace[1819541071] 'agreement among raft nodes before linearized reading' (duration: 376.390145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:46.121337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:45.744423Z","time spent":"376.889767ms","remote":"127.0.0.1:39538","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-04-21T17:12:46.121299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:45.706234Z","time spent":"414.512106ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:12:46.121642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.42648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:12:46.121697Z","caller":"traceutil/trace.go:171","msg":"trace[1178734619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"408.388464ms","start":"2026-04-21T17:12:45.713294Z","end":"2026-04-21T17:12:46.121682Z","steps":["trace[1178734619] 'agreement among raft nodes before linearized reading' (duration: 407.43033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:46.12178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:45.713277Z","time spent":"408.492406ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:12:46.588964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.294344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:12:46.589081Z","caller":"traceutil/trace.go:171","msg":"trace[1994243] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"462.8312ms","start":"2026-04-21T17:12:46.126232Z","end":"2026-04-21T17:12:46.589063Z","steps":["trace[1994243] 'process raft request' (duration: 167.184198ms)","trace[1994243] 'compare' (duration: 294.823642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:12:46.589158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:46.126221Z","time spent":"462.900152ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:12:46.654547Z","caller":"traceutil/trace.go:171","msg":"trace[565117844] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"291.844267ms","start":"2026-04-21T17:12:46.362679Z","end":"2026-04-21T17:12:46.654524Z","steps":["trace[565117844] 'read index received' (duration: 291.651953ms)","trace[565117844] 'applied index is now lower than readState.Index' (duration: 191.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:46.6547Z","caller":"traceutil/trace.go:171","msg":"trace[1424118478] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"524.890598ms","start":"2026-04-21T17:12:46.129781Z","end":"2026-04-21T17:12:46.654672Z","steps":["trace[1424118478] 'process raft request' (duration: 524.612951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:46.654808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.119144ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:12:46.654865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:46.129772Z","time spent":"525.008791ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:12:46.654876Z","caller":"traceutil/trace.go:171","msg":"trace[1948377086] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:412; }","duration":"292.193936ms","start":"2026-04-21T17:12:46.362671Z","end":"2026-04-21T17:12:46.654865Z","steps":["trace[1948377086] 'agreement among raft nodes before linearized reading' (duration: 292.104034ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:46.782014Z","caller":"traceutil/trace.go:171","msg":"trace[327223054] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"123.309084ms","start":"2026-04-21T17:12:46.658686Z","end":"2026-04-21T17:12:46.781996Z","steps":["trace[327223054] 'process raft request' (duration: 123.194692ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:46.787271Z","caller":"traceutil/trace.go:171","msg":"trace[115551270] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"120.088494ms","start":"2026-04-21T17:12:46.667134Z","end":"2026-04-21T17:12:46.787223Z","steps":["trace[115551270] 'process raft request' (duration: 119.972421ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:52.652947Z","caller":"traceutil/trace.go:171","msg":"trace[2139364122] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"370.915178ms","start":"2026-04-21T17:12:52.282014Z","end":"2026-04-21T17:12:52.652929Z","steps":["trace[2139364122] 'read index received' (duration: 370.769424ms)","trace[2139364122] 'applied index is now lower than readState.Index' (duration: 145.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:52.653187Z","caller":"traceutil/trace.go:171","msg":"trace[184152321] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"505.462044ms","start":"2026-04-21T17:12:52.147717Z","end":"2026-04-21T17:12:52.65318Z","steps":["trace[184152321] 'process raft request' (duration: 505.111044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:52.653265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.1477Z","time spent":"505.507715ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:12:52.653426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.413871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T17:12:52.653448Z","caller":"traceutil/trace.go:171","msg":"trace[267297627] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:456; }","duration":"371.435932ms","start":"2026-04-21T17:12:52.282005Z","end":"2026-04-21T17:12:52.653441Z","steps":["trace[267297627] 'agreement among raft nodes before linearized reading' (duration: 371.394691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:52.653467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.281944Z","time spent":"371.518694ms","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-04-21T17:12:53.159016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567871105101882865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:12:53.87556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.690419ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-04-21T17:12:53.875664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.654517Z","time spent":"1.221143808s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T17:12:54.041103Z","caller":"traceutil/trace.go:171","msg":"trace[604246369] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:471; }","duration":"1.38241317s","start":"2026-04-21T17:12:52.658656Z","end":"2026-04-21T17:12:54.041069Z","steps":["trace[604246369] 'read index received' (duration: 857.172473ms)","trace[604246369] 'applied index is now lower than readState.Index' (duration: 525.239467ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:12:54.041277Z","caller":"traceutil/trace.go:171","msg":"trace[1856624246] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.384281156s","start":"2026-04-21T17:12:52.656983Z","end":"2026-04-21T17:12:54.041264Z","steps":["trace[1856624246] 'process raft request' (duration: 1.328450424s)","trace[1856624246] 'compare' (duration: 55.471544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:12:54.041423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.656964Z","time spent":"1.384352038s","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:12:54.04155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382877082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:12:54.041667Z","caller":"traceutil/trace.go:171","msg":"trace[171030523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"1.383019625s","start":"2026-04-21T17:12:52.658622Z","end":"2026-04-21T17:12:54.041641Z","steps":["trace[171030523] 'agreement among raft nodes before linearized reading' (duration: 1.382860091s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:54.04173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.658613Z","time spent":"1.383109278s","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:12:54.063937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069810615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T17:12:54.064019Z","caller":"traceutil/trace.go:171","msg":"trace[1101120925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"1.069899327s","start":"2026-04-21T17:12:52.9941Z","end":"2026-04-21T17:12:54.063999Z","steps":["trace[1101120925] 'agreement among raft nodes before linearized reading' (duration: 1.069685162s)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:12:54.064019Z","caller":"traceutil/trace.go:171","msg":"trace[1445252663] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"187.383812ms","start":"2026-04-21T17:12:53.876617Z","end":"2026-04-21T17:12:54.064001Z","steps":["trace[1445252663] 'process raft request' (duration: 186.992472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:54.064081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.994007Z","time spent":"1.070061001s","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:12:54.063955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261301419s","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-04-21T17:12:54.064348Z","caller":"traceutil/trace.go:171","msg":"trace[1412904435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:458; }","duration":"1.26173334s","start":"2026-04-21T17:12:52.802591Z","end":"2026-04-21T17:12:54.064324Z","steps":["trace[1412904435] 'agreement among raft nodes before linearized reading' (duration: 1.261243238s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:54.064441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.802574Z","time spent":"1.261851883s","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T17:12:54.063941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:12:54.064574Z","caller":"traceutil/trace.go:171","msg":"trace[1085218210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"1.351047087s","start":"2026-04-21T17:12:52.713506Z","end":"2026-04-21T17:12:54.064553Z","steps":["trace[1085218210] 'agreement among raft nodes before linearized reading' (duration: 1.350264437s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:12:54.064638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:12:52.713453Z","time spent":"1.35116852s","remote":"127.0.0.1:39272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:13:03.906823Z","caller":"traceutil/trace.go:171","msg":"trace[1886078638] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"152.724748ms","start":"2026-04-21T17:13:03.754076Z","end":"2026-04-21T17:13:03.906801Z","steps":["trace[1886078638] 'process raft request' (duration: 145.696993ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:05.080898Z","caller":"traceutil/trace.go:171","msg":"trace[323748701] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"368.382726ms","start":"2026-04-21T17:13:04.712494Z","end":"2026-04-21T17:13:05.080876Z","steps":["trace[323748701] 'read index received' (duration: 368.192821ms)","trace[323748701] 'applied index is now lower than readState.Index' (duration: 189.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:13:05.080998Z","caller":"traceutil/trace.go:171","msg":"trace[1833227873] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"372.610741ms","start":"2026-04-21T17:13:04.708379Z","end":"2026-04-21T17:13:05.08099Z","steps":["trace[1833227873] 'process raft request' (duration: 372.377565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.081077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:04.708351Z","time spent":"372.667942ms","remote":"127.0.0.1:39438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4558,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:13:05.081188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.689573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:13:05.081247Z","caller":"traceutil/trace.go:171","msg":"trace[256730078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"368.766855ms","start":"2026-04-21T17:13:04.712464Z","end":"2026-04-21T17:13:05.081231Z","steps":["trace[256730078] 'agreement among raft nodes before linearized reading' (duration: 368.687134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.081281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:04.712454Z","time spent":"368.820307ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:13:05.095Z","caller":"traceutil/trace.go:171","msg":"trace[1315083692] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"141.567791ms","start":"2026-04-21T17:13:04.953297Z","end":"2026-04-21T17:13:05.094865Z","steps":["trace[1315083692] 'process raft request' (duration: 141.505449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.095015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.466076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xlvxv\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-04-21T17:13:05.095095Z","caller":"traceutil/trace.go:171","msg":"trace[87089620] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xlvxv; range_end:; response_count:1; response_revision:620; }","duration":"165.60525ms","start":"2026-04-21T17:13:04.929471Z","end":"2026-04-21T17:13:05.095076Z","steps":["trace[87089620] 'agreement among raft nodes before linearized reading' (duration: 165.425386ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:05.095154Z","caller":"traceutil/trace.go:171","msg":"trace[111110948] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"343.178848ms","start":"2026-04-21T17:13:04.751967Z","end":"2026-04-21T17:13:05.095146Z","steps":["trace[111110948] 'process raft request' (duration: 342.732607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.095224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:04.751951Z","time spent":"343.221688ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:13:05.095515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.425212ms","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-04-21T17:13:05.095714Z","caller":"traceutil/trace.go:171","msg":"trace[1132654506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"355.574236ms","start":"2026-04-21T17:13:04.740041Z","end":"2026-04-21T17:13:05.095616Z","steps":["trace[1132654506] 'agreement among raft nodes before linearized reading' (duration: 355.353211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.095885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:04.74003Z","time spent":"355.772561ms","remote":"127.0.0.1:39538","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-04-21T17:13:05.670613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.434079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:13:05.670706Z","caller":"traceutil/trace.go:171","msg":"trace[1684871033] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"320.541932ms","start":"2026-04-21T17:13:05.350145Z","end":"2026-04-21T17:13:05.670687Z","steps":["trace[1684871033] 'range keys from in-memory index tree' (duration: 320.356908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:05.670744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.822873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:13:05.670747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:05.350087Z","time spent":"320.650215ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-21T17:13:05.670774Z","caller":"traceutil/trace.go:171","msg":"trace[1925878641] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"120.886615ms","start":"2026-04-21T17:13:05.549876Z","end":"2026-04-21T17:13:05.670763Z","steps":["trace[1925878641] 'range keys from in-memory index tree' (duration: 120.760911ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.468698Z","caller":"traceutil/trace.go:171","msg":"trace[1469497141] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"515.994175ms","start":"2026-04-21T17:13:11.952674Z","end":"2026-04-21T17:13:12.468668Z","steps":["trace[1469497141] 'read index received' (duration: 446.707201ms)","trace[1469497141] 'applied index is now lower than readState.Index' (duration: 69.285724ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:13:12.468769Z","caller":"traceutil/trace.go:171","msg":"trace[985253006] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"677.238029ms","start":"2026-04-21T17:13:11.791468Z","end":"2026-04-21T17:13:12.468706Z","steps":["trace[985253006] 'process raft request' (duration: 607.831172ms)","trace[985253006] 'compare' (duration: 69.083379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:13:12.468939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:11.791439Z","time spent":"677.414633ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:13:12.468968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.695313ms","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-04-21T17:13:12.469004Z","caller":"traceutil/trace.go:171","msg":"trace[1900113025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"453.788556ms","start":"2026-04-21T17:13:12.015206Z","end":"2026-04-21T17:13:12.468994Z","steps":["trace[1900113025] 'agreement among raft nodes before linearized reading' (duration: 453.625472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:12.469041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:12.015178Z","time spent":"453.851728ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T17:13:12.469017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.306092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lgtcc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-21T17:13:12.469119Z","caller":"traceutil/trace.go:171","msg":"trace[609300876] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lgtcc; range_end:; response_count:1; response_revision:683; }","duration":"516.466166ms","start":"2026-04-21T17:13:11.952628Z","end":"2026-04-21T17:13:12.469094Z","steps":["trace[609300876] 'agreement among raft nodes before linearized reading' (duration: 516.142978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:12.469178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:11.9526Z","time spent":"516.565589ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-lgtcc\" "} {"level":"warn","ts":"2026-04-21T17:13:12.469209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.927701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T17:13:12.469236Z","caller":"traceutil/trace.go:171","msg":"trace[289167071] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:683; }","duration":"185.979841ms","start":"2026-04-21T17:13:12.283249Z","end":"2026-04-21T17:13:12.469228Z","steps":["trace[289167071] 'agreement among raft nodes before linearized reading' (duration: 185.891439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:12.46942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.97157ms","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-04-21T17:13:12.469452Z","caller":"traceutil/trace.go:171","msg":"trace[1629801979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"190.029322ms","start":"2026-04-21T17:13:12.279415Z","end":"2026-04-21T17:13:12.469444Z","steps":["trace[1629801979] 'agreement among raft nodes before linearized reading' (duration: 189.95079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:12.469591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.537856ms","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-04-21T17:13:12.469598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.718761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T17:13:12.469637Z","caller":"traceutil/trace.go:171","msg":"trace[683644435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"315.615038ms","start":"2026-04-21T17:13:12.154007Z","end":"2026-04-21T17:13:12.469622Z","steps":["trace[683644435] 'agreement among raft nodes before linearized reading' (duration: 315.460944ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.469661Z","caller":"traceutil/trace.go:171","msg":"trace[1093013622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"278.811173ms","start":"2026-04-21T17:13:12.190833Z","end":"2026-04-21T17:13:12.469644Z","steps":["trace[1093013622] 'agreement among raft nodes before linearized reading' (duration: 278.645539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:13:12.469671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:13:12.153987Z","time spent":"315.6775ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T17:13:12.64104Z","caller":"traceutil/trace.go:171","msg":"trace[792138179] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"165.787076ms","start":"2026-04-21T17:13:12.475225Z","end":"2026-04-21T17:13:12.641012Z","steps":["trace[792138179] 'process raft request' (duration: 157.623456ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.641056Z","caller":"traceutil/trace.go:171","msg":"trace[311681888] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"165.744385ms","start":"2026-04-21T17:13:12.47528Z","end":"2026-04-21T17:13:12.641025Z","steps":["trace[311681888] 'process raft request' (duration: 165.642852ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.641298Z","caller":"traceutil/trace.go:171","msg":"trace[1350793310] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"165.231002ms","start":"2026-04-21T17:13:12.476057Z","end":"2026-04-21T17:13:12.641288Z","steps":["trace[1350793310] 'process raft request' (duration: 164.916684ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.641477Z","caller":"traceutil/trace.go:171","msg":"trace[1893986388] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"158.562878ms","start":"2026-04-21T17:13:12.482901Z","end":"2026-04-21T17:13:12.641464Z","steps":["trace[1893986388] 'process raft request' (duration: 158.508037ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.641669Z","caller":"traceutil/trace.go:171","msg":"trace[804503096] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"164.558415ms","start":"2026-04-21T17:13:12.476954Z","end":"2026-04-21T17:13:12.641512Z","steps":["trace[804503096] 'process raft request' (duration: 164.265058ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:12.658999Z","caller":"traceutil/trace.go:171","msg":"trace[1880547134] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"140.487494ms","start":"2026-04-21T17:13:12.518472Z","end":"2026-04-21T17:13:12.658959Z","steps":["trace[1880547134] 'process raft request' (duration: 140.31829ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:13:19.113391Z","caller":"traceutil/trace.go:171","msg":"trace[1394488123] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"102.994753ms","start":"2026-04-21T17:13:19.010368Z","end":"2026-04-21T17:13:19.113362Z","steps":["trace[1394488123] 'process raft request' (duration: 101.864576ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:14:04.380821Z","caller":"traceutil/trace.go:171","msg":"trace[49657424] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"150.673624ms","start":"2026-04-21T17:14:04.23012Z","end":"2026-04-21T17:14:04.380794Z","steps":["trace[49657424] 'process raft request' (duration: 150.51866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:14:30.857263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.308846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:14:30.85735Z","caller":"traceutil/trace.go:171","msg":"trace[1720414727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"144.404378ms","start":"2026-04-21T17:14:30.712923Z","end":"2026-04-21T17:14:30.857328Z","steps":["trace[1720414727] 'range keys from in-memory index tree' (duration: 144.156042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:14:42.213981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567871105101884474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:14:42.334157Z","caller":"traceutil/trace.go:171","msg":"trace[365058350] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"620.635091ms","start":"2026-04-21T17:14:41.7135Z","end":"2026-04-21T17:14:42.334135Z","steps":["trace[365058350] 'read index received' (duration: 620.392025ms)","trace[365058350] 'applied index is now lower than readState.Index' (duration: 242.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:14:42.334311Z","caller":"traceutil/trace.go:171","msg":"trace[1000147101] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"635.008327ms","start":"2026-04-21T17:14:41.699269Z","end":"2026-04-21T17:14:42.334278Z","steps":["trace[1000147101] 'process raft request' (duration: 634.72185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:14:42.334449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:14:41.699254Z","time spent":"635.12613ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:14:42.334443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.909017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:14:42.334536Z","caller":"traceutil/trace.go:171","msg":"trace[255636368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"621.055471ms","start":"2026-04-21T17:14:41.713458Z","end":"2026-04-21T17:14:42.334513Z","steps":["trace[255636368] 'agreement among raft nodes before linearized reading' (duration: 620.859696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:14:42.334592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:14:41.713439Z","time spent":"621.145014ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:14:42.601213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.163791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:14:42.601426Z","caller":"traceutil/trace.go:171","msg":"trace[1765135855] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"235.901517ms","start":"2026-04-21T17:14:42.365483Z","end":"2026-04-21T17:14:42.601385Z","steps":["trace[1765135855] 'process raft request' (duration: 20.480529ms)","trace[1765135855] 'compare' (duration: 214.789422ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:14:59.410078Z","caller":"traceutil/trace.go:171","msg":"trace[1399254794] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"142.904652ms","start":"2026-04-21T17:14:59.267135Z","end":"2026-04-21T17:14:59.410039Z","steps":["trace[1399254794] 'process raft request' (duration: 142.729267ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:15:00.944338Z","caller":"traceutil/trace.go:171","msg":"trace[1789784506] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"147.995968ms","start":"2026-04-21T17:15:00.796303Z","end":"2026-04-21T17:15:00.944299Z","steps":["trace[1789784506] 'process raft request' (duration: 61.423463ms)","trace[1789784506] 'compare' (duration: 86.37461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:15:02.00406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.316655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:15:02.004177Z","caller":"traceutil/trace.go:171","msg":"trace[131584201] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"564.691165ms","start":"2026-04-21T17:15:01.43947Z","end":"2026-04-21T17:15:02.004161Z","steps":["trace[131584201] 'read index received' (duration: 136.185685ms)","trace[131584201] 'applied index is now lower than readState.Index' (duration: 428.504269ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:15:02.004185Z","caller":"traceutil/trace.go:171","msg":"trace[922435315] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"569.136356ms","start":"2026-04-21T17:15:01.435034Z","end":"2026-04-21T17:15:02.00417Z","steps":["trace[922435315] 'process raft request' (duration: 140.614966ms)","trace[922435315] 'compare' (duration: 428.191961ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:15:02.00424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:01.435015Z","time spent":"569.200267ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:15:02.004347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.88035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T17:15:02.004391Z","caller":"traceutil/trace.go:171","msg":"trace[1132200821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"564.935491ms","start":"2026-04-21T17:15:01.439434Z","end":"2026-04-21T17:15:02.004369Z","steps":["trace[1132200821] 'agreement among raft nodes before linearized reading' (duration: 564.810108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:15:02.004447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:01.439414Z","time spent":"565.018083ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:15:02.004582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.726557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-21T17:15:02.004583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.097614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:15:02.004654Z","caller":"traceutil/trace.go:171","msg":"trace[2072342175] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1280; }","duration":"259.835719ms","start":"2026-04-21T17:15:01.7448Z","end":"2026-04-21T17:15:02.004636Z","steps":["trace[2072342175] 'agreement among raft nodes before linearized reading' (duration: 259.702726ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:15:02.004679Z","caller":"traceutil/trace.go:171","msg":"trace[1159625613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"293.234497ms","start":"2026-04-21T17:15:01.71141Z","end":"2026-04-21T17:15:02.004645Z","steps":["trace[1159625613] 'agreement among raft nodes before linearized reading' (duration: 293.099954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:15:02.005389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.982412ms","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-04-21T17:15:02.005477Z","caller":"traceutil/trace.go:171","msg":"trace[812706448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"565.797712ms","start":"2026-04-21T17:15:01.439655Z","end":"2026-04-21T17:15:02.005453Z","steps":["trace[812706448] 'agreement among raft nodes before linearized reading' (duration: 564.776297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:15:02.005535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:01.43965Z","time spent":"565.866574ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T17:15:13.94101Z","caller":"traceutil/trace.go:171","msg":"trace[191530065] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"163.655527ms","start":"2026-04-21T17:15:13.77733Z","end":"2026-04-21T17:15:13.940985Z","steps":["trace[191530065] 'process raft request' (duration: 163.487672ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:15:14.479141Z","caller":"traceutil/trace.go:171","msg":"trace[1641694822] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"304.937697ms","start":"2026-04-21T17:15:14.174178Z","end":"2026-04-21T17:15:14.479116Z","steps":["trace[1641694822] 'process raft request' (duration: 304.758233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:15:14.479297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:14.174148Z","time spent":"305.07244ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:15:14.662848Z","caller":"traceutil/trace.go:171","msg":"trace[468797649] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"488.297321ms","start":"2026-04-21T17:15:14.17452Z","end":"2026-04-21T17:15:14.662818Z","steps":["trace[468797649] 'process raft request' (duration: 416.856441ms)","trace[468797649] 'compare' (duration: 71.199604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:15:14.662991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:14.174479Z","time spent":"488.439414ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:15:14.663063Z","caller":"traceutil/trace.go:171","msg":"trace[421999714] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1415; }","duration":"138.327148ms","start":"2026-04-21T17:15:14.524709Z","end":"2026-04-21T17:15:14.663036Z","steps":["trace[421999714] 'read index received' (duration: 66.658463ms)","trace[421999714] 'applied index is now lower than readState.Index' (duration: 71.666915ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:15:14.663319Z","caller":"traceutil/trace.go:171","msg":"trace[31357592] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"488.66181ms","start":"2026-04-21T17:15:14.174619Z","end":"2026-04-21T17:15:14.663281Z","steps":["trace[31357592] 'process raft request' (duration: 488.346982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:15:14.663525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:15:14.174614Z","time spent":"488.760182ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:15:14.663707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.78915ms","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-04-21T17:15:14.663755Z","caller":"traceutil/trace.go:171","msg":"trace[986072847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"139.075207ms","start":"2026-04-21T17:15:14.524667Z","end":"2026-04-21T17:15:14.663742Z","steps":["trace[986072847] 'agreement among raft nodes before linearized reading' (duration: 138.679017ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:16:05.376536Z","caller":"traceutil/trace.go:171","msg":"trace[636859522] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"484.702088ms","start":"2026-04-21T17:16:04.891803Z","end":"2026-04-21T17:16:05.376505Z","steps":["trace[636859522] 'read index received' (duration: 483.913039ms)","trace[636859522] 'applied index is now lower than readState.Index' (duration: 787.669µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:16:05.376673Z","caller":"traceutil/trace.go:171","msg":"trace[555500542] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"647.739893ms","start":"2026-04-21T17:16:04.728897Z","end":"2026-04-21T17:16:05.376637Z","steps":["trace[555500542] 'process raft request' (duration: 647.370924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.376805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.978085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T17:16:05.376844Z","caller":"traceutil/trace.go:171","msg":"trace[1647308545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1594; }","duration":"485.074568ms","start":"2026-04-21T17:16:04.891758Z","end":"2026-04-21T17:16:05.376832Z","steps":["trace[1647308545] 'agreement among raft nodes before linearized reading' (duration: 484.910483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.376878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:04.891736Z","time spent":"485.134199ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:16:05.376901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:04.728869Z","time spent":"647.884307ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:16:05.383157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.448245ms","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-04-21T17:16:05.383201Z","caller":"traceutil/trace.go:171","msg":"trace[1183464752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1595; }","duration":"489.519386ms","start":"2026-04-21T17:16:04.89367Z","end":"2026-04-21T17:16:05.383189Z","steps":["trace[1183464752] 'agreement among raft nodes before linearized reading' (duration: 489.361813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.383225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:04.893658Z","time spent":"489.562867ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T17:16:05.383334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.849181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-21T17:16:05.383322Z","caller":"traceutil/trace.go:171","msg":"trace[2041020643] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"213.742694ms","start":"2026-04-21T17:16:05.169539Z","end":"2026-04-21T17:16:05.383281Z","steps":["trace[2041020643] 'process raft request' (duration: 213.365535ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:16:05.383389Z","caller":"traceutil/trace.go:171","msg":"trace[1732486763] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1595; }","duration":"281.954305ms","start":"2026-04-21T17:16:05.101419Z","end":"2026-04-21T17:16:05.383373Z","steps":["trace[1732486763] 'agreement among raft nodes before linearized reading' (duration: 281.799601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.383573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.900066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-21T17:16:05.383647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.809643ms","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-04-21T17:16:05.383797Z","caller":"traceutil/trace.go:171","msg":"trace[1008890284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1595; }","duration":"489.955037ms","start":"2026-04-21T17:16:04.893772Z","end":"2026-04-21T17:16:05.383727Z","steps":["trace[1008890284] 'agreement among raft nodes before linearized reading' (duration: 489.558058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.383853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:04.893764Z","time spent":"490.07834ms","remote":"127.0.0.1:39538","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-04-21T17:16:05.383688Z","caller":"traceutil/trace.go:171","msg":"trace[946952454] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1595; }","duration":"341.07533ms","start":"2026-04-21T17:16:05.042603Z","end":"2026-04-21T17:16:05.383678Z","steps":["trace[946952454] 'agreement among raft nodes before linearized reading' (duration: 340.565418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.383954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:05.042581Z","time spent":"341.333287ms","remote":"127.0.0.1:39538","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":"info","ts":"2026-04-21T17:16:05.839101Z","caller":"traceutil/trace.go:171","msg":"trace[1319928085] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"123.647296ms","start":"2026-04-21T17:16:05.715425Z","end":"2026-04-21T17:16:05.839072Z","steps":["trace[1319928085] 'read index received' (duration: 123.358928ms)","trace[1319928085] 'applied index is now lower than readState.Index' (duration: 287.138µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:16:05.839356Z","caller":"traceutil/trace.go:171","msg":"trace[436098781] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"447.106392ms","start":"2026-04-21T17:16:05.392237Z","end":"2026-04-21T17:16:05.839343Z","steps":["trace[436098781] 'process raft request' (duration: 446.64081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:05.839457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:16:05.392218Z","time spent":"447.173414ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:16:05.839655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.228011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:16:05.839695Z","caller":"traceutil/trace.go:171","msg":"trace[1309170421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1601; }","duration":"124.305833ms","start":"2026-04-21T17:16:05.715379Z","end":"2026-04-21T17:16:05.839685Z","steps":["trace[1309170421] 'agreement among raft nodes before linearized reading' (duration: 124.250101ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:16:07.994327Z","caller":"traceutil/trace.go:171","msg":"trace[1569434791] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"177.156553ms","start":"2026-04-21T17:16:07.817144Z","end":"2026-04-21T17:16:07.994301Z","steps":["trace[1569434791] 'process raft request' (duration: 176.993309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:07.994521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.220861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:16:07.994584Z","caller":"traceutil/trace.go:171","msg":"trace[49817605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1610; }","duration":"282.339044ms","start":"2026-04-21T17:16:07.712233Z","end":"2026-04-21T17:16:07.994572Z","steps":["trace[49817605] 'agreement among raft nodes before linearized reading' (duration: 282.15795ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:16:07.994331Z","caller":"traceutil/trace.go:171","msg":"trace[941092901] linearizableReadLoop","detail":"{readStateIndex:1672; appliedIndex:1671; }","duration":"282.014296ms","start":"2026-04-21T17:16:07.712281Z","end":"2026-04-21T17:16:07.994295Z","steps":["trace[941092901] 'read index received' (duration: 279.438393ms)","trace[941092901] 'applied index is now lower than readState.Index' (duration: 2.571173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:16:07.994919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.454556ms","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-04-21T17:16:07.994978Z","caller":"traceutil/trace.go:171","msg":"trace[1525019871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1610; }","duration":"150.600719ms","start":"2026-04-21T17:16:07.844362Z","end":"2026-04-21T17:16:07.994963Z","steps":["trace[1525019871] 'agreement among raft nodes before linearized reading' (duration: 150.386604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:16:07.995006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.193056ms","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-04-21T17:16:07.995067Z","caller":"traceutil/trace.go:171","msg":"trace[1239255992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1610; }","duration":"145.366961ms","start":"2026-04-21T17:16:07.849682Z","end":"2026-04-21T17:16:07.995049Z","steps":["trace[1239255992] 'agreement among raft nodes before linearized reading' (duration: 145.166316ms)"],"step_count":1}