{"level":"warn","ts":"2026-02-11T01:48:06.375463Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:48:06.375563Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-02-11T01:48:06.375678Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:48:06.375689Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-11T01:48:06.375707Z","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-02-11T01:48:06.376462Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-11T01:48:06.376572Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-02-11T01:48:06.383018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.168461ms"} {"level":"info","ts":"2026-02-11T01:48:06.391227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-11T01:48:06.391309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:48:06.391328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-11T01:48:06.391361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:48:06.391369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-11T01:48:06.391408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-11T01:48:06.421557Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:48:06.430379Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:48:06.445896Z","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-02-11T01:48:06.448159Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:48:06.448319Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:48:06.452068Z","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-02-11T01:48:06.452124Z","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-02-11T01:48:06.452134Z","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-02-11T01:48:06.453541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-11T01:48:06.453639Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-11T01:48:06.483738Z","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-02-11T01:48:06.484095Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:48:06.485055Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:48:06.485097Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-11T01:48:06.487118Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-11T01:48:07.291587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:48:07.291626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:48:07.291644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-11T01:48:07.291657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:48:07.291663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-11T01:48:07.291671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-11T01:48:07.291682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-11T01:48:07.293151Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:48:07.293186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:48:07.293411Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:48:07.296888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:48:07.296936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:48:07.296998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:48:07.297044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:48:07.297055Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:48:07.303045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-02-11T01:48:07.303311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:48:07.303335Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:48:20.172554Z","caller":"traceutil/trace.go:171","msg":"trace[217830277] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"148.979736ms","start":"2026-02-11T01:48:20.023559Z","end":"2026-02-11T01:48:20.172539Z","steps":["trace[217830277] 'process raft request' (duration: 148.664208ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:22.989587Z","caller":"traceutil/trace.go:171","msg":"trace[1331532932] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"142.743964ms","start":"2026-02-11T01:48:22.846809Z","end":"2026-02-11T01:48:22.989553Z","steps":["trace[1331532932] 'process raft request' (duration: 142.519258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:26.339348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.76969ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:48:26.339468Z","caller":"traceutil/trace.go:171","msg":"trace[328054186] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"150.219244ms","start":"2026-02-11T01:48:26.189214Z","end":"2026-02-11T01:48:26.339433Z","steps":["trace[328054186] 'read index received' (duration: 26.76µs)","trace[328054186] 'applied index is now lower than readState.Index' (duration: 150.191064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:26.339557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.330907ms","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-02-11T01:48:26.339588Z","caller":"traceutil/trace.go:171","msg":"trace[1281186502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"150.393829ms","start":"2026-02-11T01:48:26.189183Z","end":"2026-02-11T01:48:26.339577Z","steps":["trace[1281186502] 'agreement among raft nodes before linearized reading' (duration: 150.313387ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:28.585999Z","caller":"traceutil/trace.go:171","msg":"trace[1524657645] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"173.554895ms","start":"2026-02-11T01:48:28.412424Z","end":"2026-02-11T01:48:28.585979Z","steps":["trace[1524657645] 'process raft request' (duration: 173.453883ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:36.817198Z","caller":"traceutil/trace.go:171","msg":"trace[222812228] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"431.924595ms","start":"2026-02-11T01:48:36.385254Z","end":"2026-02-11T01:48:36.817178Z","steps":["trace[222812228] 'process raft request' (duration: 431.75757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:36.817783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:36.385241Z","time spent":"432.037287ms","remote":"127.0.0.1:44736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:48:36.817998Z","caller":"traceutil/trace.go:171","msg":"trace[42521544] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:525; }","duration":"187.938331ms","start":"2026-02-11T01:48:36.630047Z","end":"2026-02-11T01:48:36.817986Z","steps":["trace[42521544] 'read index received' (duration: 187.93333ms)","trace[42521544] 'applied index is now lower than readState.Index' (duration: 3.131µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:36.8181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.962631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:48:36.818137Z","caller":"traceutil/trace.go:171","msg":"trace[2000452496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"131.033023ms","start":"2026-02-11T01:48:36.687094Z","end":"2026-02-11T01:48:36.818127Z","steps":["trace[2000452496] 'agreement among raft nodes before linearized reading' (duration: 130.961141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:36.818456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.414883ms","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-02-11T01:48:36.818492Z","caller":"traceutil/trace.go:171","msg":"trace[2050674246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"188.451304ms","start":"2026-02-11T01:48:36.630031Z","end":"2026-02-11T01:48:36.818483Z","steps":["trace[2050674246] 'agreement among raft nodes before linearized reading' (duration: 188.353561ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:48:39.062406Z","caller":"traceutil/trace.go:171","msg":"trace[211119229] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"225.607934ms","start":"2026-02-11T01:48:38.836778Z","end":"2026-02-11T01:48:39.062386Z","steps":["trace[211119229] 'process raft request' (duration: 225.439779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:43.919096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.756453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:48:43.919215Z","caller":"traceutil/trace.go:171","msg":"trace[418429258] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"721.808559ms","start":"2026-02-11T01:48:43.197393Z","end":"2026-02-11T01:48:43.919202Z","steps":["trace[418429258] 'read index received' (duration: 491.81401ms)","trace[418429258] 'applied index is now lower than readState.Index' (duration: 229.993889ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:48:43.919398Z","caller":"traceutil/trace.go:171","msg":"trace[2140865909] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"724.067188ms","start":"2026-02-11T01:48:43.19532Z","end":"2026-02-11T01:48:43.919387Z","steps":["trace[2140865909] 'process raft request' (duration: 493.948645ms)","trace[2140865909] 'compare' (duration: 229.448135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:48:43.919465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.195307Z","time spent":"724.121139ms","remote":"127.0.0.1:44838","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-02-11T01:48:43.919583Z","caller":"traceutil/trace.go:171","msg":"trace[193856947] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"722.537128ms","start":"2026-02-11T01:48:43.19704Z","end":"2026-02-11T01:48:43.919577Z","steps":["trace[193856947] 'process raft request' (duration: 722.129817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:43.919614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.197027Z","time spent":"722.569568ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:48:43.919741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.345433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:48:43.919758Z","caller":"traceutil/trace.go:171","msg":"trace[1565770603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"722.374413ms","start":"2026-02-11T01:48:43.197378Z","end":"2026-02-11T01:48:43.919753Z","steps":["trace[1565770603] 'agreement among raft nodes before linearized reading' (duration: 722.301751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:43.919778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.197373Z","time spent":"722.400924ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:48:43.919919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.773107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qrknc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-11T01:48:43.91994Z","caller":"traceutil/trace.go:171","msg":"trace[1969974528] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qrknc; range_end:; response_count:1; response_revision:531; }","duration":"685.821848ms","start":"2026-02-11T01:48:43.234111Z","end":"2026-02-11T01:48:43.919933Z","steps":["trace[1969974528] 'agreement among raft nodes before linearized reading' (duration: 685.745636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:43.919957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.234097Z","time spent":"685.856199ms","remote":"127.0.0.1:44752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-qrknc\" "} {"level":"info","ts":"2026-02-11T01:48:44.33395Z","caller":"traceutil/trace.go:171","msg":"trace[125291822] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"404.19788ms","start":"2026-02-11T01:48:43.929727Z","end":"2026-02-11T01:48:44.333925Z","steps":["trace[125291822] 'process raft request' (duration: 403.931233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:44.334091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.929711Z","time spent":"404.315613ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:48:44.346194Z","caller":"traceutil/trace.go:171","msg":"trace[145414711] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"409.903259ms","start":"2026-02-11T01:48:43.93628Z","end":"2026-02-11T01:48:44.346183Z","steps":["trace[145414711] 'process raft request' (duration: 409.830187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:48:44.346259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:48:43.936262Z","time spent":"409.963781ms","remote":"127.0.0.1:44752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:48:46.652368Z","caller":"traceutil/trace.go:171","msg":"trace[1674377524] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"162.370522ms","start":"2026-02-11T01:48:46.489974Z","end":"2026-02-11T01:48:46.652345Z","steps":["trace[1674377524] 'process raft request' (duration: 162.244369ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:01.543047Z","caller":"traceutil/trace.go:171","msg":"trace[26005439] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"280.316453ms","start":"2026-02-11T01:49:01.2625Z","end":"2026-02-11T01:49:01.542817Z","steps":["trace[26005439] 'process raft request' (duration: 280.1957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.425257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.224438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:02.42531Z","caller":"traceutil/trace.go:171","msg":"trace[751679170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"614.31454ms","start":"2026-02-11T01:49:01.810983Z","end":"2026-02-11T01:49:02.425297Z","steps":["trace[751679170] 'range keys from in-memory index tree' (duration: 614.168156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.425331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:01.810965Z","time spent":"614.362401ms","remote":"127.0.0.1:44562","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-02-11T01:49:02.425618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.24537ms","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-02-11T01:49:02.425639Z","caller":"traceutil/trace.go:171","msg":"trace[976249659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"425.265721ms","start":"2026-02-11T01:49:02.000366Z","end":"2026-02-11T01:49:02.425631Z","steps":["trace[976249659] 'range keys from in-memory index tree' (duration: 425.130727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.425652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.000308Z","time spent":"425.341022ms","remote":"127.0.0.1:44838","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-02-11T01:49:02.425735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.669774ms","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-02-11T01:49:02.425748Z","caller":"traceutil/trace.go:171","msg":"trace[534384944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"419.696355ms","start":"2026-02-11T01:49:02.006047Z","end":"2026-02-11T01:49:02.425743Z","steps":["trace[534384944] 'range keys from in-memory index tree' (duration: 419.626463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.425759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.00604Z","time spent":"419.716526ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:49:02.425894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.924166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T01:49:02.425907Z","caller":"traceutil/trace.go:171","msg":"trace[1993625333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"263.968957ms","start":"2026-02-11T01:49:02.161934Z","end":"2026-02-11T01:49:02.425903Z","steps":["trace[1993625333] 'range keys from in-memory index tree' (duration: 263.659438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.425981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.533304ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:02.426014Z","caller":"traceutil/trace.go:171","msg":"trace[429789835] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:711; }","duration":"173.570135ms","start":"2026-02-11T01:49:02.25244Z","end":"2026-02-11T01:49:02.42601Z","steps":["trace[429789835] 'range keys from in-memory index tree' (duration: 173.525814ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:02.854845Z","caller":"traceutil/trace.go:171","msg":"trace[387390680] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"418.836523ms","start":"2026-02-11T01:49:02.435965Z","end":"2026-02-11T01:49:02.854802Z","steps":["trace[387390680] 'process raft request' (duration: 418.559605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.855309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.435955Z","time spent":"419.180342ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:49:02.89456Z","caller":"traceutil/trace.go:171","msg":"trace[1244682466] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"432.828159ms","start":"2026-02-11T01:49:02.461016Z","end":"2026-02-11T01:49:02.893844Z","steps":["trace[1244682466] 'read index received' (duration: 394.192339ms)","trace[1244682466] 'applied index is now lower than readState.Index' (duration: 38.63434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:49:02.89481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.768873ms","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-02-11T01:49:02.894851Z","caller":"traceutil/trace.go:171","msg":"trace[1137669974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"433.845056ms","start":"2026-02-11T01:49:02.460987Z","end":"2026-02-11T01:49:02.894832Z","steps":["trace[1137669974] 'agreement among raft nodes before linearized reading' (duration: 433.6501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.89494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.460973Z","time spent":"433.952348ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:49:02.895843Z","caller":"traceutil/trace.go:171","msg":"trace[2027325857] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"457.102863ms","start":"2026-02-11T01:49:02.438726Z","end":"2026-02-11T01:49:02.895829Z","steps":["trace[2027325857] 'process raft request' (duration: 454.921746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.895956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.438717Z","time spent":"457.166404ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:49:02.896146Z","caller":"traceutil/trace.go:171","msg":"trace[313417663] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"457.331179ms","start":"2026-02-11T01:49:02.438802Z","end":"2026-02-11T01:49:02.896133Z","steps":["trace[313417663] 'process raft request' (duration: 454.983807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:02.896223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:02.438796Z","time spent":"457.39168ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:49:05.687929Z","caller":"traceutil/trace.go:171","msg":"trace[191614686] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"134.898635ms","start":"2026-02-11T01:49:05.553001Z","end":"2026-02-11T01:49:05.6879Z","steps":["trace[191614686] 'read index received' (duration: 62.705958ms)","trace[191614686] 'applied index is now lower than readState.Index' (duration: 72.191177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:49:05.688184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.150751ms","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-02-11T01:49:05.688231Z","caller":"traceutil/trace.go:171","msg":"trace[636120893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"135.245044ms","start":"2026-02-11T01:49:05.55297Z","end":"2026-02-11T01:49:05.688215Z","steps":["trace[636120893] 'agreement among raft nodes before linearized reading' (duration: 135.053419ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:19.193096Z","caller":"traceutil/trace.go:171","msg":"trace[102507969] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"258.603927ms","start":"2026-02-11T01:49:18.934473Z","end":"2026-02-11T01:49:19.193077Z","steps":["trace[102507969] 'process raft request' (duration: 258.489264ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:19.193492Z","caller":"traceutil/trace.go:171","msg":"trace[434602377] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:788; }","duration":"229.335473ms","start":"2026-02-11T01:49:18.964147Z","end":"2026-02-11T01:49:19.193482Z","steps":["trace[434602377] 'read index received' (duration: 229.332433ms)","trace[434602377] 'applied index is now lower than readState.Index' (duration: 2.59µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:49:19.193603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.902516ms","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-02-11T01:49:19.193622Z","caller":"traceutil/trace.go:171","msg":"trace[507064749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"224.944657ms","start":"2026-02-11T01:49:18.968672Z","end":"2026-02-11T01:49:19.193616Z","steps":["trace[507064749] 'agreement among raft nodes before linearized reading' (duration: 224.858505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:19.193754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.60835ms","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-02-11T01:49:19.193773Z","caller":"traceutil/trace.go:171","msg":"trace[1983271847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"229.65074ms","start":"2026-02-11T01:49:18.964117Z","end":"2026-02-11T01:49:19.193768Z","steps":["trace[1983271847] 'agreement among raft nodes before linearized reading' (duration: 229.598129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:19.193893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.069244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T01:49:19.193911Z","caller":"traceutil/trace.go:171","msg":"trace[281419718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"224.112776ms","start":"2026-02-11T01:49:18.969793Z","end":"2026-02-11T01:49:19.193906Z","steps":["trace[281419718] 'agreement among raft nodes before linearized reading' (duration: 224.040844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:23.487696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:49:23.48776Z","caller":"traceutil/trace.go:171","msg":"trace[1231734603] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"216.887126ms","start":"2026-02-11T01:49:23.270861Z","end":"2026-02-11T01:49:23.487748Z","steps":["trace[1231734603] 'process raft request' (duration: 86.867569ms)","trace[1231734603] 'compare' (duration: 129.685288ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:49:23.577732Z","caller":"traceutil/trace.go:171","msg":"trace[926275516] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"305.924293ms","start":"2026-02-11T01:49:23.27179Z","end":"2026-02-11T01:49:23.577715Z","steps":["trace[926275516] 'process raft request' (duration: 305.900222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:23.577833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:23.271787Z","time spent":"305.993224ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:49:23.578115Z","caller":"traceutil/trace.go:171","msg":"trace[508047863] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"306.378335ms","start":"2026-02-11T01:49:23.271731Z","end":"2026-02-11T01:49:23.578109Z","steps":["trace[508047863] 'process raft request' (duration: 305.862101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:23.578144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:23.271725Z","time spent":"306.404265ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:49:23.932411Z","caller":"traceutil/trace.go:171","msg":"trace[348478260] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"160.333779ms","start":"2026-02-11T01:49:23.772057Z","end":"2026-02-11T01:49:23.932391Z","steps":["trace[348478260] 'process raft request' (duration: 160.205566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:27.700786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.405761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T01:49:27.70087Z","caller":"traceutil/trace.go:171","msg":"trace[973616182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"201.531235ms","start":"2026-02-11T01:49:27.499322Z","end":"2026-02-11T01:49:27.700853Z","steps":["trace[973616182] 'range keys from in-memory index tree' (duration: 201.245878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:27.701099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.979818ms","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-02-11T01:49:27.701133Z","caller":"traceutil/trace.go:171","msg":"trace[804707145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"101.0302ms","start":"2026-02-11T01:49:27.600094Z","end":"2026-02-11T01:49:27.701124Z","steps":["trace[804707145] 'range keys from in-memory index tree' (duration: 100.899976ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:27.939919Z","caller":"traceutil/trace.go:171","msg":"trace[179777849] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"124.322218ms","start":"2026-02-11T01:49:27.815575Z","end":"2026-02-11T01:49:27.939897Z","steps":["trace[179777849] 'read index received' (duration: 89.267093ms)","trace[179777849] 'applied index is now lower than readState.Index' (duration: 35.054365ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:49:27.940184Z","caller":"traceutil/trace.go:171","msg":"trace[65591164] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"232.167106ms","start":"2026-02-11T01:49:27.708003Z","end":"2026-02-11T01:49:27.94017Z","steps":["trace[65591164] 'process raft request' (duration: 196.903914ms)","trace[65591164] 'compare' (duration: 34.571213ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:49:27.940355Z","caller":"traceutil/trace.go:171","msg":"trace[2029558592] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"231.223941ms","start":"2026-02-11T01:49:27.709122Z","end":"2026-02-11T01:49:27.940346Z","steps":["trace[2029558592] 'process raft request' (duration: 230.727208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:27.941876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.261599ms","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-02-11T01:49:27.941993Z","caller":"traceutil/trace.go:171","msg":"trace[1538048253] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:789; }","duration":"126.393553ms","start":"2026-02-11T01:49:27.81555Z","end":"2026-02-11T01:49:27.941944Z","steps":["trace[1538048253] 'agreement among raft nodes before linearized reading' (duration: 124.928105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:27.942292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.768334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:27.942324Z","caller":"traceutil/trace.go:171","msg":"trace[1814746094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"123.809705ms","start":"2026-02-11T01:49:27.818504Z","end":"2026-02-11T01:49:27.942314Z","steps":["trace[1814746094] 'agreement among raft nodes before linearized reading' (duration: 123.754083ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:39.765212Z","caller":"traceutil/trace.go:171","msg":"trace[1135309757] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"380.883677ms","start":"2026-02-11T01:49:39.384307Z","end":"2026-02-11T01:49:39.765191Z","steps":["trace[1135309757] 'process raft request' (duration: 380.771374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:39.765328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:39.384298Z","time spent":"380.967239ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:49:40.435794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.990449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:49:40.435887Z","caller":"traceutil/trace.go:171","msg":"trace[2046901646] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"627.327124ms","start":"2026-02-11T01:49:39.808545Z","end":"2026-02-11T01:49:40.435872Z","steps":["trace[2046901646] 'read index received' (duration: 184.110979ms)","trace[2046901646] 'applied index is now lower than readState.Index' (duration: 443.213215ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:49:40.436062Z","caller":"traceutil/trace.go:171","msg":"trace[549540878] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"654.316854ms","start":"2026-02-11T01:49:39.781733Z","end":"2026-02-11T01:49:40.43605Z","steps":["trace[549540878] 'process raft request' (duration: 210.992707ms)","trace[549540878] 'compare' (duration: 442.67386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:49:40.436118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:39.781712Z","time spent":"654.376225ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:49:40.43631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.761745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:40.436348Z","caller":"traceutil/trace.go:171","msg":"trace[1217268079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"627.822056ms","start":"2026-02-11T01:49:39.808515Z","end":"2026-02-11T01:49:40.436337Z","steps":["trace[1217268079] 'agreement among raft nodes before linearized reading' (duration: 627.769344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.436375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:39.808499Z","time spent":"627.870298ms","remote":"127.0.0.1:44562","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-02-11T01:49:40.724048Z","caller":"traceutil/trace.go:171","msg":"trace[1337037203] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"288.120559ms","start":"2026-02-11T01:49:40.435912Z","end":"2026-02-11T01:49:40.724032Z","steps":["trace[1337037203] 'read index received' (duration: 287.992025ms)","trace[1337037203] 'applied index is now lower than readState.Index' (duration: 128.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:49:40.724233Z","caller":"traceutil/trace.go:171","msg":"trace[972939294] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"423.013941ms","start":"2026-02-11T01:49:40.301212Z","end":"2026-02-11T01:49:40.724226Z","steps":["trace[972939294] 'process raft request' (duration: 422.739364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.724292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:40.301194Z","time spent":"423.053012ms","remote":"127.0.0.1:44838","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-02-11T01:49:40.724428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.115784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:49:40.724443Z","caller":"traceutil/trace.go:171","msg":"trace[367999262] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:872; }","duration":"413.157094ms","start":"2026-02-11T01:49:40.311281Z","end":"2026-02-11T01:49:40.724438Z","steps":["trace[367999262] 'agreement among raft nodes before linearized reading' (duration: 413.097973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.724458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:40.311267Z","time spent":"413.186256ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-02-11T01:49:40.724539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.164599ms","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-02-11T01:49:40.724551Z","caller":"traceutil/trace.go:171","msg":"trace[1034195763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"652.197189ms","start":"2026-02-11T01:49:40.07235Z","end":"2026-02-11T01:49:40.724547Z","steps":["trace[1034195763] 'agreement among raft nodes before linearized reading' (duration: 652.161228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.724562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:40.072337Z","time spent":"652.222521ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:49:40.72464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.468012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:49:40.724655Z","caller":"traceutil/trace.go:171","msg":"trace[1208240364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:872; }","duration":"683.504843ms","start":"2026-02-11T01:49:40.041145Z","end":"2026-02-11T01:49:40.72465Z","steps":["trace[1208240364] 'agreement among raft nodes before linearized reading' (duration: 683.468442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.724666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:40.041132Z","time spent":"683.531723ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:49:40.724741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.714836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T01:49:40.724753Z","caller":"traceutil/trace.go:171","msg":"trace[1772930658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"738.748666ms","start":"2026-02-11T01:49:39.986001Z","end":"2026-02-11T01:49:40.724749Z","steps":["trace[1772930658] 'agreement among raft nodes before linearized reading' (duration: 738.714256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:40.724764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:49:39.985987Z","time spent":"738.774398ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:49:40.724821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.208409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:40.724832Z","caller":"traceutil/trace.go:171","msg":"trace[1267095785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"286.255571ms","start":"2026-02-11T01:49:40.438573Z","end":"2026-02-11T01:49:40.724828Z","steps":["trace[1267095785] 'agreement among raft nodes before linearized reading' (duration: 286.23889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:49:41.935895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.679261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:49:41.935983Z","caller":"traceutil/trace.go:171","msg":"trace[188575890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"128.797733ms","start":"2026-02-11T01:49:41.807165Z","end":"2026-02-11T01:49:41.935963Z","steps":["trace[188575890] 'range keys from in-memory index tree' (duration: 128.631129ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:49:51.077543Z","caller":"traceutil/trace.go:171","msg":"trace[1895962524] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"207.63681ms","start":"2026-02-11T01:49:50.869892Z","end":"2026-02-11T01:49:51.077529Z","steps":["trace[1895962524] 'process raft request' (duration: 207.140597ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:04.174004Z","caller":"traceutil/trace.go:171","msg":"trace[1603076530] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"163.800622ms","start":"2026-02-11T01:50:04.010187Z","end":"2026-02-11T01:50:04.173988Z","steps":["trace[1603076530] 'read index received' (duration: 163.646958ms)","trace[1603076530] 'applied index is now lower than readState.Index' (duration: 153.103µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:50:04.174158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.938465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:50:04.17418Z","caller":"traceutil/trace.go:171","msg":"trace[712464904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:958; }","duration":"164.021517ms","start":"2026-02-11T01:50:04.010152Z","end":"2026-02-11T01:50:04.174173Z","steps":["trace[712464904] 'agreement among raft nodes before linearized reading' (duration: 163.925415ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:04.174395Z","caller":"traceutil/trace.go:171","msg":"trace[993762523] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"324.032741ms","start":"2026-02-11T01:50:03.850355Z","end":"2026-02-11T01:50:04.174388Z","steps":["trace[993762523] 'process raft request' (duration: 323.525758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:04.174445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:03.850346Z","time spent":"324.063772ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:04.976787Z","caller":"traceutil/trace.go:171","msg":"trace[1282283221] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"214.874777ms","start":"2026-02-11T01:50:04.761898Z","end":"2026-02-11T01:50:04.976773Z","steps":["trace[1282283221] 'read index received' (duration: 214.814356ms)","trace[1282283221] 'applied index is now lower than readState.Index' (duration: 59.691µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:04.976854Z","caller":"traceutil/trace.go:171","msg":"trace[2020464891] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"305.778527ms","start":"2026-02-11T01:50:04.671067Z","end":"2026-02-11T01:50:04.976846Z","steps":["trace[2020464891] 'process raft request' (duration: 305.618843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:04.976915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.671047Z","time spent":"305.821888ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:50:04.977006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.091113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:50:04.977059Z","caller":"traceutil/trace.go:171","msg":"trace[1170223452] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:959; }","duration":"215.187975ms","start":"2026-02-11T01:50:04.761857Z","end":"2026-02-11T01:50:04.977045Z","steps":["trace[1170223452] 'agreement among raft nodes before linearized reading' (duration: 215.101083ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:05.166447Z","caller":"traceutil/trace.go:171","msg":"trace[292818455] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"189.597251ms","start":"2026-02-11T01:50:04.976827Z","end":"2026-02-11T01:50:05.166424Z","steps":["trace[292818455] 'read index received' (duration: 123.523966ms)","trace[292818455] 'applied index is now lower than readState.Index' (duration: 66.072265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:50:05.166586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.250408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:50:05.166619Z","caller":"traceutil/trace.go:171","msg":"trace[116727353] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:960; }","duration":"388.321759ms","start":"2026-02-11T01:50:04.778288Z","end":"2026-02-11T01:50:05.16661Z","steps":["trace[116727353] 'agreement among raft nodes before linearized reading' (duration: 388.215506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:05.16665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.778277Z","time spent":"388.364681ms","remote":"127.0.0.1:45028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:50:05.166652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.330537ms","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-02-11T01:50:05.166733Z","caller":"traceutil/trace.go:171","msg":"trace[2019508609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"297.41775ms","start":"2026-02-11T01:50:04.869297Z","end":"2026-02-11T01:50:05.166715Z","steps":["trace[2019508609] 'agreement among raft nodes before linearized reading' (duration: 297.169103ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:05.16706Z","caller":"traceutil/trace.go:171","msg":"trace[1234816400] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"405.076954ms","start":"2026-02-11T01:50:04.761965Z","end":"2026-02-11T01:50:05.167042Z","steps":["trace[1234816400] 'process raft request' (duration: 338.410803ms)","trace[1234816400] 'compare' (duration: 65.507921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:50:05.167145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.761937Z","time spent":"405.151446ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:50:05.167464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.248001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T01:50:05.167513Z","caller":"traceutil/trace.go:171","msg":"trace[1055656893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"303.321293ms","start":"2026-02-11T01:50:04.86418Z","end":"2026-02-11T01:50:05.167502Z","steps":["trace[1055656893] 'agreement among raft nodes before linearized reading' (duration: 302.715747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:05.167547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.864171Z","time spent":"303.369014ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:50:05.167729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.920173ms","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-02-11T01:50:05.16777Z","caller":"traceutil/trace.go:171","msg":"trace[989774585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"334.982965ms","start":"2026-02-11T01:50:04.832775Z","end":"2026-02-11T01:50:05.167758Z","steps":["trace[989774585] 'agreement among raft nodes before linearized reading' (duration: 334.850081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:05.167801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.832764Z","time spent":"335.030906ms","remote":"127.0.0.1:44838","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-02-11T01:50:05.167922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.780946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:50:05.167955Z","caller":"traceutil/trace.go:171","msg":"trace[1893356561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"325.832628ms","start":"2026-02-11T01:50:04.842113Z","end":"2026-02-11T01:50:05.167946Z","steps":["trace[1893356561] 'agreement among raft nodes before linearized reading' (duration: 325.783076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:05.167981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:04.842103Z","time spent":"325.872148ms","remote":"127.0.0.1:44562","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-02-11T01:50:11.500885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197611877203873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:50:11.610254Z","caller":"traceutil/trace.go:171","msg":"trace[994029256] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"609.652496ms","start":"2026-02-11T01:50:11.000586Z","end":"2026-02-11T01:50:11.610238Z","steps":["trace[994029256] 'read index received' (duration: 609.526513ms)","trace[994029256] 'applied index is now lower than readState.Index' (duration: 125.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:11.610549Z","caller":"traceutil/trace.go:171","msg":"trace[1034019543] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"652.288723ms","start":"2026-02-11T01:50:10.958246Z","end":"2026-02-11T01:50:11.610535Z","steps":["trace[1034019543] 'process raft request' (duration: 651.904493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:11.610718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:10.95821Z","time spent":"652.445607ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:50:11.611007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.419806ms","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-02-11T01:50:11.611137Z","caller":"traceutil/trace.go:171","msg":"trace[411850998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"610.59463ms","start":"2026-02-11T01:50:11.000518Z","end":"2026-02-11T01:50:11.611112Z","steps":["trace[411850998] 'agreement among raft nodes before linearized reading' (duration: 610.372744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:11.611222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:11.000506Z","time spent":"610.707324ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:50:11.611475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.73772ms","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-02-11T01:50:11.611578Z","caller":"traceutil/trace.go:171","msg":"trace[616703688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"408.860044ms","start":"2026-02-11T01:50:11.20271Z","end":"2026-02-11T01:50:11.61157Z","steps":["trace[616703688] 'agreement among raft nodes before linearized reading' (duration: 408.673329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:11.611694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:11.202701Z","time spent":"408.969166ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:50:11.612412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.14031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:50:11.61253Z","caller":"traceutil/trace.go:171","msg":"trace[45872924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"335.286684ms","start":"2026-02-11T01:50:11.277236Z","end":"2026-02-11T01:50:11.612522Z","steps":["trace[45872924] 'agreement among raft nodes before linearized reading' (duration: 335.008106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:11.612866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:11.27722Z","time spent":"335.639833ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:50:11.612664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.256425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T01:50:11.613128Z","caller":"traceutil/trace.go:171","msg":"trace[1853821935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"337.743537ms","start":"2026-02-11T01:50:11.275375Z","end":"2026-02-11T01:50:11.613119Z","steps":["trace[1853821935] 'agreement among raft nodes before linearized reading' (duration: 337.234914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:11.61325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:11.275363Z","time spent":"337.877161ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T01:53:11.652497Z","caller":"traceutil/trace.go:171","msg":"trace[1937478297] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"156.483258ms","start":"2026-02-11T01:53:11.495987Z","end":"2026-02-11T01:53:11.65247Z","steps":["trace[1937478297] 'process raft request' (duration: 156.359765ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:19.95518Z","caller":"traceutil/trace.go:171","msg":"trace[125540625] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"131.514848ms","start":"2026-02-11T01:53:19.823639Z","end":"2026-02-11T01:53:19.955154Z","steps":["trace[125540625] 'process raft request' (duration: 53.898126ms)","trace[125540625] 'compare' (duration: 77.409336ms)"],"step_count":2}