{"level":"warn","ts":"2026-02-07T04:13:23.588496Z","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-07T04:13:23.588573Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-07T04:13:23.588831Z","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-07T04:13:23.58885Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-07T04:13:23.588868Z","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-07T04:13:23.589503Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-07T04:13:23.589572Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-07T04:13:23.59587Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.957566ms"} {"level":"info","ts":"2026-02-07T04:13:23.605637Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-07T04:13:23.605699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:13:23.605751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-07T04:13:23.605802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:13:23.605894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-07T04:13:23.605937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-07T04:13:23.615688Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:13:23.617884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:13:23.618807Z","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-07T04:13:23.620668Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:13:23.621668Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:13:23.621863Z","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-07T04:13:23.621906Z","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-07T04:13:23.621917Z","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-07T04:13:23.622134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-07T04:13:23.62274Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-07T04:13:23.624529Z","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-07T04:13:23.624746Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:13:23.624766Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:13:23.625293Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-07T04:13:23.625301Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-07T04:13:23.706647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:13:23.70689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:13:23.70691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-07T04:13:23.706921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:13:23.706927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-07T04:13:23.706934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-07T04:13:23.706948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-07T04:13:23.708716Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:13:23.708918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:13:23.709011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:13:23.709253Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:13:23.709422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:13:23.709438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:13:23.711077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-07T04:13:23.711139Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:13:23.711187Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:13:23.711204Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:13:23.71209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T04:13:38.623357Z","caller":"traceutil/trace.go:171","msg":"trace[1850165746] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"190.389472ms","start":"2026-02-07T04:13:38.432953Z","end":"2026-02-07T04:13:38.623343Z","steps":["trace[1850165746] 'read index received' (duration: 190.276079ms)","trace[1850165746] 'applied index is now lower than readState.Index' (duration: 112.843µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:38.623458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.489364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:13:38.623484Z","caller":"traceutil/trace.go:171","msg":"trace[1246340520] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:366; }","duration":"190.556736ms","start":"2026-02-07T04:13:38.432922Z","end":"2026-02-07T04:13:38.623479Z","steps":["trace[1246340520] 'agreement among raft nodes before linearized reading' (duration: 190.487045ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:38.623681Z","caller":"traceutil/trace.go:171","msg":"trace[1914426464] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"426.65184ms","start":"2026-02-07T04:13:38.197022Z","end":"2026-02-07T04:13:38.623674Z","steps":["trace[1914426464] 'process raft request' (duration: 426.242549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:38.624005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:38.197012Z","time spent":"426.701491ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:55.521987Z","caller":"traceutil/trace.go:171","msg":"trace[1084217556] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"101.117448ms","start":"2026-02-07T04:13:55.420852Z","end":"2026-02-07T04:13:55.52197Z","steps":["trace[1084217556] 'process raft request' (duration: 101.008725ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:57.649134Z","caller":"traceutil/trace.go:171","msg":"trace[2050384358] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"423.02616ms","start":"2026-02-07T04:13:57.226093Z","end":"2026-02-07T04:13:57.649119Z","steps":["trace[2050384358] 'read index received' (duration: 422.901637ms)","trace[2050384358] 'applied index is now lower than readState.Index' (duration: 124.023µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:57.649195Z","caller":"traceutil/trace.go:171","msg":"trace[386672903] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"425.684892ms","start":"2026-02-07T04:13:57.223506Z","end":"2026-02-07T04:13:57.649191Z","steps":["trace[386672903] 'process raft request' (duration: 425.531038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.64926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.223481Z","time spent":"425.729134ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:13:57.649333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.959014ms","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-07T04:13:57.64939Z","caller":"traceutil/trace.go:171","msg":"trace[1068839625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"124.078756ms","start":"2026-02-07T04:13:57.525298Z","end":"2026-02-07T04:13:57.649377Z","steps":["trace[1068839625] 'agreement among raft nodes before linearized reading' (duration: 123.990104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.649469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.37448ms","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-07T04:13:57.64948Z","caller":"traceutil/trace.go:171","msg":"trace[1452769592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"423.38548ms","start":"2026-02-07T04:13:57.226091Z","end":"2026-02-07T04:13:57.649476Z","steps":["trace[1452769592] 'agreement among raft nodes before linearized reading' (duration: 423.363839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.649493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.226074Z","time spent":"423.41456ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:13:58.936658Z","caller":"traceutil/trace.go:171","msg":"trace[1317659149] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"126.862333ms","start":"2026-02-07T04:13:58.809779Z","end":"2026-02-07T04:13:58.936641Z","steps":["trace[1317659149] 'process raft request' (duration: 126.77902ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:03.291853Z","caller":"traceutil/trace.go:171","msg":"trace[558193995] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"344.042148ms","start":"2026-02-07T04:14:02.947788Z","end":"2026-02-07T04:14:03.29183Z","steps":["trace[558193995] 'read index received' (duration: 343.866083ms)","trace[558193995] 'applied index is now lower than readState.Index' (duration: 175.185µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:03.292015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.210953ms","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-07T04:14:03.292046Z","caller":"traceutil/trace.go:171","msg":"trace[2073431742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"344.290595ms","start":"2026-02-07T04:14:02.947745Z","end":"2026-02-07T04:14:03.292036Z","steps":["trace[2073431742] 'agreement among raft nodes before linearized reading' (duration: 344.170672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:03.292091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:02.947721Z","time spent":"344.362326ms","remote":"127.0.0.1:33604","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":"info","ts":"2026-02-07T04:14:03.292368Z","caller":"traceutil/trace.go:171","msg":"trace[1264056607] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"736.47946ms","start":"2026-02-07T04:14:02.555879Z","end":"2026-02-07T04:14:03.292358Z","steps":["trace[1264056607] 'process raft request' (duration: 735.833532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:03.292471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:02.555864Z","time spent":"736.557371ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:14:03.877866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.808413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:14:03.878263Z","caller":"traceutil/trace.go:171","msg":"trace[131431204] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"578.532787ms","start":"2026-02-07T04:14:03.299711Z","end":"2026-02-07T04:14:03.878244Z","steps":["trace[131431204] 'process raft request' (duration: 185.151089ms)","trace[131431204] 'compare' (duration: 392.523865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:03.878359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:03.299696Z","time spent":"578.627349ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:03.87855Z","caller":"traceutil/trace.go:171","msg":"trace[496095177] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"211.15782ms","start":"2026-02-07T04:14:03.667382Z","end":"2026-02-07T04:14:03.87854Z","steps":["trace[496095177] 'read index received' (duration: 27.881µs)","trace[496095177] 'applied index is now lower than readState.Index' (duration: 211.128939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:03.880539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.05549ms","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-07T04:14:03.880596Z","caller":"traceutil/trace.go:171","msg":"trace[473477323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"134.159742ms","start":"2026-02-07T04:14:03.746425Z","end":"2026-02-07T04:14:03.880585Z","steps":["trace[473477323] 'agreement among raft nodes before linearized reading' (duration: 134.026508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:03.880875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.487244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:14:03.880905Z","caller":"traceutil/trace.go:171","msg":"trace[1941761311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"213.544066ms","start":"2026-02-07T04:14:03.667352Z","end":"2026-02-07T04:14:03.880896Z","steps":["trace[1941761311] 'agreement among raft nodes before linearized reading' (duration: 211.284314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:03.881037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.09694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:14:03.881062Z","caller":"traceutil/trace.go:171","msg":"trace[1905989890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"134.140222ms","start":"2026-02-07T04:14:03.746915Z","end":"2026-02-07T04:14:03.881055Z","steps":["trace[1905989890] 'agreement among raft nodes before linearized reading' (duration: 134.094931ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:04.660892Z","caller":"traceutil/trace.go:171","msg":"trace[1761141676] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"101.91907ms","start":"2026-02-07T04:14:04.558952Z","end":"2026-02-07T04:14:04.660871Z","steps":["trace[1761141676] 'process raft request' (duration: 101.732075ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:06.007439Z","caller":"traceutil/trace.go:171","msg":"trace[1900066408] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"245.13012ms","start":"2026-02-07T04:14:05.762291Z","end":"2026-02-07T04:14:06.007421Z","steps":["trace[1900066408] 'process raft request' (duration: 245.030998ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:06.007761Z","caller":"traceutil/trace.go:171","msg":"trace[713320462] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:537; }","duration":"118.317189ms","start":"2026-02-07T04:14:05.889437Z","end":"2026-02-07T04:14:06.007754Z","steps":["trace[713320462] 'read index received' (duration: 118.314419ms)","trace[713320462] 'applied index is now lower than readState.Index' (duration: 2.17µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:06.007851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.398412ms","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-07T04:14:06.007868Z","caller":"traceutil/trace.go:171","msg":"trace[1029523958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"118.462113ms","start":"2026-02-07T04:14:05.8894Z","end":"2026-02-07T04:14:06.007863Z","steps":["trace[1029523958] 'agreement among raft nodes before linearized reading' (duration: 118.393722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:06.015358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.696551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:14:06.015397Z","caller":"traceutil/trace.go:171","msg":"trace[827393974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"121.748932ms","start":"2026-02-07T04:14:05.893637Z","end":"2026-02-07T04:14:06.015386Z","steps":["trace[827393974] 'agreement among raft nodes before linearized reading' (duration: 121.66279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:06.015718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.271268ms","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-07T04:14:06.015752Z","caller":"traceutil/trace.go:171","msg":"trace[2126090856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"118.332659ms","start":"2026-02-07T04:14:05.897414Z","end":"2026-02-07T04:14:06.015747Z","steps":["trace[2126090856] 'agreement among raft nodes before linearized reading' (duration: 118.242297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:06.015834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.447164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:14:06.015853Z","caller":"traceutil/trace.go:171","msg":"trace[1004892871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"121.477795ms","start":"2026-02-07T04:14:05.89437Z","end":"2026-02-07T04:14:06.015848Z","steps":["trace[1004892871] 'agreement among raft nodes before linearized reading' (duration: 121.443634ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:08.896439Z","caller":"traceutil/trace.go:171","msg":"trace[937367195] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"108.903251ms","start":"2026-02-07T04:14:08.787514Z","end":"2026-02-07T04:14:08.896417Z","steps":["trace[937367195] 'process raft request' (duration: 108.589703ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:18.18074Z","caller":"traceutil/trace.go:171","msg":"trace[672917364] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"390.253662ms","start":"2026-02-07T04:14:17.79047Z","end":"2026-02-07T04:14:18.180723Z","steps":["trace[672917364] 'read index received' (duration: 390.124539ms)","trace[672917364] 'applied index is now lower than readState.Index' (duration: 128.603µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:18.180962Z","caller":"traceutil/trace.go:171","msg":"trace[196062044] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"555.429843ms","start":"2026-02-07T04:14:17.625525Z","end":"2026-02-07T04:14:18.180955Z","steps":["trace[196062044] 'process raft request' (duration: 555.114175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:18.181022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:17.625512Z","time spent":"555.464855ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:14:18.181167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.697594ms","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-02-07T04:14:18.181184Z","caller":"traceutil/trace.go:171","msg":"trace[358662543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:659; }","duration":"390.739466ms","start":"2026-02-07T04:14:17.790439Z","end":"2026-02-07T04:14:18.181178Z","steps":["trace[358662543] 'agreement among raft nodes before linearized reading' (duration: 390.667674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:18.181197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:17.790426Z","time spent":"390.768116ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:14:19.06967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.57665ms","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-07T04:14:19.069777Z","caller":"traceutil/trace.go:171","msg":"trace[541942384] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:659; }","duration":"707.690313ms","start":"2026-02-07T04:14:18.362069Z","end":"2026-02-07T04:14:19.069759Z","steps":["trace[541942384] 'range keys from in-memory index tree' (duration: 707.563479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.069837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.169527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:14:19.069951Z","caller":"traceutil/trace.go:171","msg":"trace[674655967] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"882.92978ms","start":"2026-02-07T04:14:18.187002Z","end":"2026-02-07T04:14:19.069931Z","steps":["trace[674655967] 'process raft request' (duration: 243.593109ms)","trace[674655967] 'compare' (duration: 638.978912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:19.070009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.186988Z","time spent":"882.993782ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.139301Z","caller":"traceutil/trace.go:171","msg":"trace[2061754958] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:681; }","duration":"937.281098ms","start":"2026-02-07T04:14:18.202004Z","end":"2026-02-07T04:14:19.139285Z","steps":["trace[2061754958] 'read index received' (duration: 228.595888ms)","trace[2061754958] 'applied index is now lower than readState.Index' (duration: 708.68457ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:19.139585Z","caller":"traceutil/trace.go:171","msg":"trace[1936480967] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"952.451913ms","start":"2026-02-07T04:14:18.18712Z","end":"2026-02-07T04:14:19.139572Z","steps":["trace[1936480967] 'process raft request' (duration: 951.95562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.139673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.187116Z","time spent":"952.514346ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.139823Z","caller":"traceutil/trace.go:171","msg":"trace[1718782450] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"947.523039ms","start":"2026-02-07T04:14:18.192294Z","end":"2026-02-07T04:14:19.139817Z","steps":["trace[1718782450] 'process raft request' (duration: 946.858471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.139875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.192283Z","time spent":"947.57435ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.140196Z","caller":"traceutil/trace.go:171","msg":"trace[16379055] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"937.951656ms","start":"2026-02-07T04:14:18.202239Z","end":"2026-02-07T04:14:19.14019Z","steps":["trace[16379055] 'process raft request' (duration: 936.930608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.140378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.202218Z","time spent":"938.141532ms","remote":"127.0.0.1:33480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4085,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.140603Z","caller":"traceutil/trace.go:171","msg":"trace[1423227601] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"934.0605ms","start":"2026-02-07T04:14:18.206537Z","end":"2026-02-07T04:14:19.140597Z","steps":["trace[1423227601] 'process raft request' (duration: 932.661952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.140681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.206526Z","time spent":"934.137392ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.140776Z","caller":"traceutil/trace.go:171","msg":"trace[2066720737] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"930.016499ms","start":"2026-02-07T04:14:18.210755Z","end":"2026-02-07T04:14:19.140771Z","steps":["trace[2066720737] 'process raft request' (duration: 928.480017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.140826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.210746Z","time spent":"930.064751ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.140944Z","caller":"traceutil/trace.go:171","msg":"trace[1864405478] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"929.32098ms","start":"2026-02-07T04:14:18.211617Z","end":"2026-02-07T04:14:19.140938Z","steps":["trace[1864405478] 'process raft request' (duration: 927.635434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.14122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.211612Z","time spent":"929.576867ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:14:19.141339Z","caller":"traceutil/trace.go:171","msg":"trace[146593151] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"898.070625ms","start":"2026-02-07T04:14:18.243262Z","end":"2026-02-07T04:14:19.141333Z","steps":["trace[146593151] 'process raft request' (duration: 896.004069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.141389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.243248Z","time spent":"898.124686ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:14:19.14153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.523659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-422wf\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-07T04:14:19.141595Z","caller":"traceutil/trace.go:171","msg":"trace[910103652] range","detail":"{range_begin:/registry/pods/kube-system/cilium-422wf; range_end:; response_count:1; response_revision:667; }","duration":"939.609632ms","start":"2026-02-07T04:14:18.20198Z","end":"2026-02-07T04:14:19.14159Z","steps":["trace[910103652] 'agreement among raft nodes before linearized reading' (duration: 939.495639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.141629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.201969Z","time spent":"939.652613ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18081,"request content":"key:\"/registry/pods/kube-system/cilium-422wf\" "} {"level":"info","ts":"2026-02-07T04:14:19.172705Z","caller":"traceutil/trace.go:171","msg":"trace[2120522973] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"531.708206ms","start":"2026-02-07T04:14:18.640983Z","end":"2026-02-07T04:14:19.172691Z","steps":["trace[2120522973] 'process raft request' (duration: 530.058961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.175578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.640966Z","time spent":"534.565414ms","remote":"127.0.0.1:33376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:14:19.176077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.120341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:14:19.177814Z","caller":"traceutil/trace.go:171","msg":"trace[6947634] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:668; }","duration":"741.884899ms","start":"2026-02-07T04:14:18.435919Z","end":"2026-02-07T04:14:19.177804Z","steps":["trace[6947634] 'agreement among raft nodes before linearized reading' (duration: 740.066759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:19.177902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:18.4359Z","time spent":"741.992122ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-02-07T04:14:24.170185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.871306ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:14:25.498253Z","caller":"traceutil/trace.go:171","msg":"trace[1650170765] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"338.398573ms","start":"2026-02-07T04:14:25.159832Z","end":"2026-02-07T04:14:25.49823Z","steps":["trace[1650170765] 'read index received' (duration: 338.192548ms)","trace[1650170765] 'applied index is now lower than readState.Index' (duration: 205.345µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:25.498477Z","caller":"traceutil/trace.go:171","msg":"trace[216929691] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"409.991683ms","start":"2026-02-07T04:14:25.088477Z","end":"2026-02-07T04:14:25.498469Z","steps":["trace[216929691] 'process raft request' (duration: 409.607232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.498581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:25.088467Z","time spent":"410.040175ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:14:25.498783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.954208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T04:14:25.498804Z","caller":"traceutil/trace.go:171","msg":"trace[1933722283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"339.036891ms","start":"2026-02-07T04:14:25.159761Z","end":"2026-02-07T04:14:25.498798Z","steps":["trace[1933722283] 'agreement among raft nodes before linearized reading' (duration: 338.954669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.498832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:25.159728Z","time spent":"339.100603ms","remote":"127.0.0.1:33604","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-07T04:14:25.498939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.466709ms","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-02-07T04:14:25.498955Z","caller":"traceutil/trace.go:171","msg":"trace[935232290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"333.500679ms","start":"2026-02-07T04:14:25.165449Z","end":"2026-02-07T04:14:25.49895Z","steps":["trace[935232290] 'agreement among raft nodes before linearized reading' (duration: 333.456558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.49897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:25.16544Z","time spent":"333.52699ms","remote":"127.0.0.1:33604","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-07T04:14:25.499079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.24559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:14:25.499097Z","caller":"traceutil/trace.go:171","msg":"trace[2025744694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"109.307982ms","start":"2026-02-07T04:14:25.389784Z","end":"2026-02-07T04:14:25.499092Z","steps":["trace[2025744694] 'agreement among raft nodes before linearized reading' (duration: 109.280471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.499224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.803038ms","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-07T04:14:25.49924Z","caller":"traceutil/trace.go:171","msg":"trace[1061686748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"329.84911ms","start":"2026-02-07T04:14:25.169386Z","end":"2026-02-07T04:14:25.499235Z","steps":["trace[1061686748] 'agreement among raft nodes before linearized reading' (duration: 329.797178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.499255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:25.169378Z","time spent":"329.8737ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:14:25.499485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.750284ms","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-07T04:14:25.499565Z","caller":"traceutil/trace.go:171","msg":"trace[1784682180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"330.887538ms","start":"2026-02-07T04:14:25.168657Z","end":"2026-02-07T04:14:25.499545Z","steps":["trace[1784682180] 'agreement among raft nodes before linearized reading' (duration: 330.625741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:25.49962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:25.168645Z","time spent":"330.96129ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:14:25.646146Z","caller":"traceutil/trace.go:171","msg":"trace[254414184] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"140.269959ms","start":"2026-02-07T04:14:25.505845Z","end":"2026-02-07T04:14:25.646115Z","steps":["trace[254414184] 'process raft request' (duration: 140.197917ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:25.64617Z","caller":"traceutil/trace.go:171","msg":"trace[577094804] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"137.813472ms","start":"2026-02-07T04:14:25.508337Z","end":"2026-02-07T04:14:25.646151Z","steps":["trace[577094804] 'process raft request' (duration: 137.73926ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:25.646394Z","caller":"traceutil/trace.go:171","msg":"trace[1859799066] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"141.859083ms","start":"2026-02-07T04:14:25.504523Z","end":"2026-02-07T04:14:25.646382Z","steps":["trace[1859799066] 'process raft request' (duration: 140.29876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:45.921295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.715577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:14:45.921463Z","caller":"traceutil/trace.go:171","msg":"trace[1741817661] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"175.516126ms","start":"2026-02-07T04:14:45.745935Z","end":"2026-02-07T04:14:45.921451Z","steps":["trace[1741817661] 'read index received' (duration: 23.50062ms)","trace[1741817661] 'applied index is now lower than readState.Index' (duration: 152.014596ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:45.921623Z","caller":"traceutil/trace.go:171","msg":"trace[1679536170] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"196.923557ms","start":"2026-02-07T04:14:45.724692Z","end":"2026-02-07T04:14:45.921616Z","steps":["trace[1679536170] 'process raft request' (duration: 44.810059ms)","trace[1679536170] 'compare' (duration: 151.557453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:45.921828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.891916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:14:45.921864Z","caller":"traceutil/trace.go:171","msg":"trace[1729168699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"175.966048ms","start":"2026-02-07T04:14:45.745893Z","end":"2026-02-07T04:14:45.921859Z","steps":["trace[1729168699] 'agreement among raft nodes before linearized reading' (duration: 175.867535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:45.922042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.617255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:14:45.92208Z","caller":"traceutil/trace.go:171","msg":"trace[1934240508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"168.672536ms","start":"2026-02-07T04:14:45.753401Z","end":"2026-02-07T04:14:45.922073Z","steps":["trace[1934240508] 'agreement among raft nodes before linearized reading' (duration: 168.589244ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:46.204454Z","caller":"traceutil/trace.go:171","msg":"trace[1534943538] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"276.439348ms","start":"2026-02-07T04:14:45.927995Z","end":"2026-02-07T04:14:46.204435Z","steps":["trace[1534943538] 'process raft request' (duration: 276.351715ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:46.204848Z","caller":"traceutil/trace.go:171","msg":"trace[915011490] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"278.593168ms","start":"2026-02-07T04:14:45.926242Z","end":"2026-02-07T04:14:46.204835Z","steps":["trace[915011490] 'process raft request' (duration: 196.61201ms)","trace[915011490] 'compare' (duration: 81.142435ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:47.884421Z","caller":"traceutil/trace.go:171","msg":"trace[569791953] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"226.037403ms","start":"2026-02-07T04:14:47.65836Z","end":"2026-02-07T04:14:47.884397Z","steps":["trace[569791953] 'read index received' (duration: 225.800437ms)","trace[569791953] 'applied index is now lower than readState.Index' (duration: 236.026µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:47.884883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.493636ms","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-07T04:14:47.884934Z","caller":"traceutil/trace.go:171","msg":"trace[760357547] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"227.192866ms","start":"2026-02-07T04:14:47.657725Z","end":"2026-02-07T04:14:47.884918Z","steps":["trace[760357547] 'process raft request' (duration: 226.500717ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:47.885481Z","caller":"traceutil/trace.go:171","msg":"trace[114861229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"227.121484ms","start":"2026-02-07T04:14:47.658342Z","end":"2026-02-07T04:14:47.885464Z","steps":["trace[114861229] 'agreement among raft nodes before linearized reading' (duration: 226.348152ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:48.135486Z","caller":"traceutil/trace.go:171","msg":"trace[102442101] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"209.315911ms","start":"2026-02-07T04:14:47.926146Z","end":"2026-02-07T04:14:48.135462Z","steps":["trace[102442101] 'read index received' (duration: 181.548563ms)","trace[102442101] 'applied index is now lower than readState.Index' (duration: 27.766358ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:14:48.135724Z","caller":"traceutil/trace.go:171","msg":"trace[1384681213] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"245.549343ms","start":"2026-02-07T04:14:47.890162Z","end":"2026-02-07T04:14:48.135712Z","steps":["trace[1384681213] 'process raft request' (duration: 217.466786ms)","trace[1384681213] 'compare' (duration: 27.373987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:48.135989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.823365ms","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-07T04:14:48.13603Z","caller":"traceutil/trace.go:171","msg":"trace[160205237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"209.901587ms","start":"2026-02-07T04:14:47.926119Z","end":"2026-02-07T04:14:48.13602Z","steps":["trace[160205237] 'agreement among raft nodes before linearized reading' (duration: 209.767593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:48.136222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.400694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:14:48.136257Z","caller":"traceutil/trace.go:171","msg":"trace[1697886797] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:751; }","duration":"106.476576ms","start":"2026-02-07T04:14:48.029773Z","end":"2026-02-07T04:14:48.136249Z","steps":["trace[1697886797] 'agreement among raft nodes before linearized reading' (duration: 106.375093ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:48.281906Z","caller":"traceutil/trace.go:171","msg":"trace[1471607112] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"140.051975ms","start":"2026-02-07T04:14:48.141832Z","end":"2026-02-07T04:14:48.281884Z","steps":["trace[1471607112] 'process raft request' (duration: 134.423419ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:00.604906Z","caller":"traceutil/trace.go:171","msg":"trace[44052767] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"604.348178ms","start":"2026-02-07T04:15:00.000535Z","end":"2026-02-07T04:15:00.604884Z","steps":["trace[44052767] 'process raft request' (duration: 604.235515ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:00.604954Z","caller":"traceutil/trace.go:171","msg":"trace[146651113] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:870; }","duration":"419.146535ms","start":"2026-02-07T04:15:00.185786Z","end":"2026-02-07T04:15:00.604932Z","steps":["trace[146651113] 'read index received' (duration: 419.139515ms)","trace[146651113] 'applied index is now lower than readState.Index' (duration: 5.41µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:00.605021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.000525Z","time spent":"604.43728ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:00.60517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.368881ms","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-02-07T04:15:00.605191Z","caller":"traceutil/trace.go:171","msg":"trace[1187757786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"419.444393ms","start":"2026-02-07T04:15:00.185739Z","end":"2026-02-07T04:15:00.605183Z","steps":["trace[1187757786] 'agreement among raft nodes before linearized reading' (duration: 419.282789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.605213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.185723Z","time spent":"419.483945ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-07T04:15:00.745007Z","caller":"traceutil/trace.go:171","msg":"trace[1279128266] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"139.970473ms","start":"2026-02-07T04:15:00.605008Z","end":"2026-02-07T04:15:00.744978Z","steps":["trace[1279128266] 'read index received' (duration: 139.85848ms)","trace[1279128266] 'applied index is now lower than readState.Index' (duration: 111.423µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:00.745104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.776893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:15:00.745121Z","caller":"traceutil/trace.go:171","msg":"trace[901845397] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:837; }","duration":"491.850876ms","start":"2026-02-07T04:15:00.253265Z","end":"2026-02-07T04:15:00.745116Z","steps":["trace[901845397] 'agreement among raft nodes before linearized reading' (duration: 491.783524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.533923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-07T04:15:00.745156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.253248Z","time spent":"491.903087ms","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:15:00.745156Z","caller":"traceutil/trace.go:171","msg":"trace[1441236958] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:837; }","duration":"352.625415ms","start":"2026-02-07T04:15:00.392518Z","end":"2026-02-07T04:15:00.745143Z","steps":["trace[1441236958] 'agreement among raft nodes before linearized reading' (duration: 352.470081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.392501Z","time spent":"352.718748ms","remote":"127.0.0.1:33522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:15:00.745367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.110848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:15:00.745395Z","caller":"traceutil/trace.go:171","msg":"trace[2141318517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:837; }","duration":"370.146119ms","start":"2026-02-07T04:15:00.375243Z","end":"2026-02-07T04:15:00.745389Z","steps":["trace[2141318517] 'agreement among raft nodes before linearized reading' (duration: 370.069107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.375241Z","time spent":"370.16744ms","remote":"127.0.0.1:33604","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-02-07T04:15:00.745443Z","caller":"traceutil/trace.go:171","msg":"trace[51930009] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"544.965287ms","start":"2026-02-07T04:15:00.200471Z","end":"2026-02-07T04:15:00.745436Z","steps":["trace[51930009] 'process raft request' (duration: 544.341469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.200437Z","time spent":"545.037449ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:00.745543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.305514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:15:00.745558Z","caller":"traceutil/trace.go:171","msg":"trace[1081579087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:837; }","duration":"370.332845ms","start":"2026-02-07T04:15:00.375221Z","end":"2026-02-07T04:15:00.745553Z","steps":["trace[1081579087] 'agreement among raft nodes before linearized reading' (duration: 370.271163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.375215Z","time spent":"370.353285ms","remote":"127.0.0.1:33604","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-02-07T04:15:00.745883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.344866ms","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-07T04:15:00.745899Z","caller":"traceutil/trace.go:171","msg":"trace[852918878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:837; }","duration":"382.397748ms","start":"2026-02-07T04:15:00.363497Z","end":"2026-02-07T04:15:00.745895Z","steps":["trace[852918878] 'agreement among raft nodes before linearized reading' (duration: 382.338127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:00.745913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.363482Z","time spent":"382.42767ms","remote":"127.0.0.1:33604","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":"info","ts":"2026-02-07T04:15:00.795162Z","caller":"traceutil/trace.go:171","msg":"trace[611910544] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"187.056825ms","start":"2026-02-07T04:15:00.608089Z","end":"2026-02-07T04:15:00.795145Z","steps":["trace[611910544] 'process raft request' (duration: 186.926891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.027706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.444186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:15:02.027898Z","caller":"traceutil/trace.go:171","msg":"trace[508479146] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:872; }","duration":"856.631649ms","start":"2026-02-07T04:15:01.171251Z","end":"2026-02-07T04:15:02.027883Z","steps":["trace[508479146] 'read index received' (duration: 338.885995ms)","trace[508479146] 'applied index is now lower than readState.Index' (duration: 517.744744ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:02.028557Z","caller":"traceutil/trace.go:171","msg":"trace[1702460846] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"1.279732974s","start":"2026-02-07T04:15:00.748803Z","end":"2026-02-07T04:15:02.028536Z","steps":["trace[1702460846] 'process raft request' (duration: 761.383344ms)","trace[1702460846] 'compare' (duration: 517.354982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:02.028632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.74879Z","time spent":"1.279802566s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:02.028796Z","caller":"traceutil/trace.go:171","msg":"trace[951057259] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"1.278079229s","start":"2026-02-07T04:15:00.750708Z","end":"2026-02-07T04:15:02.028787Z","steps":["trace[951057259] 'process raft request' (duration: 1.277073451s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.028841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.750681Z","time spent":"1.278136259s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:02.028956Z","caller":"traceutil/trace.go:171","msg":"trace[646888936] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"1.276820703s","start":"2026-02-07T04:15:00.752127Z","end":"2026-02-07T04:15:02.028948Z","steps":["trace[646888936] 'process raft request' (duration: 1.275719123s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.028999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:00.752121Z","time spent":"1.276853915s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:02.029143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.892684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:15:02.029174Z","caller":"traceutil/trace.go:171","msg":"trace[1379669102] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:841; }","duration":"857.946425ms","start":"2026-02-07T04:15:01.171216Z","end":"2026-02-07T04:15:02.029162Z","steps":["trace[1379669102] 'agreement among raft nodes before linearized reading' (duration: 857.886473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.029203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:01.171188Z","time spent":"858.007747ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:15:02.08968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.040305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:02.089733Z","caller":"traceutil/trace.go:171","msg":"trace[1773965525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"701.130317ms","start":"2026-02-07T04:15:01.388591Z","end":"2026-02-07T04:15:02.089722Z","steps":["trace[1773965525] 'agreement among raft nodes before linearized reading' (duration: 701.024214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.089807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:01.388571Z","time spent":"701.230409ms","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T04:15:02.090012Z","caller":"traceutil/trace.go:171","msg":"trace[39412430] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"269.222388ms","start":"2026-02-07T04:15:01.820783Z","end":"2026-02-07T04:15:02.090005Z","steps":["trace[39412430] 'process raft request' (duration: 268.743745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.090129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.199066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:15:02.090142Z","caller":"traceutil/trace.go:171","msg":"trace[319728410] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:842; }","duration":"325.238528ms","start":"2026-02-07T04:15:01.7649Z","end":"2026-02-07T04:15:02.090138Z","steps":["trace[319728410] 'agreement among raft nodes before linearized reading' (duration: 325.176226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:02.090156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:01.764884Z","time spent":"325.266708ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:15:03.180133Z","caller":"traceutil/trace.go:171","msg":"trace[1711085838] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"222.310851ms","start":"2026-02-07T04:15:02.957806Z","end":"2026-02-07T04:15:03.180117Z","steps":["trace[1711085838] 'read index received' (duration: 222.182257ms)","trace[1711085838] 'applied index is now lower than readState.Index' (duration: 128.074µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:03.180228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.405033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:03.180243Z","caller":"traceutil/trace.go:171","msg":"trace[926401429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"222.457825ms","start":"2026-02-07T04:15:02.95778Z","end":"2026-02-07T04:15:03.180238Z","steps":["trace[926401429] 'agreement among raft nodes before linearized reading' (duration: 222.392973ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:03.180485Z","caller":"traceutil/trace.go:171","msg":"trace[1922175710] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"378.819421ms","start":"2026-02-07T04:15:02.801658Z","end":"2026-02-07T04:15:03.180477Z","steps":["trace[1922175710] 'process raft request' (duration: 378.372388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:03.180534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:02.801647Z","time spent":"378.850941ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:04.324995Z","caller":"traceutil/trace.go:171","msg":"trace[1550248660] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"260.486666ms","start":"2026-02-07T04:15:04.06449Z","end":"2026-02-07T04:15:04.324977Z","steps":["trace[1550248660] 'read index received' (duration: 66.057558ms)","trace[1550248660] 'applied index is now lower than readState.Index' (duration: 194.428368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:04.325128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.62398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:15:04.325147Z","caller":"traceutil/trace.go:171","msg":"trace[909879875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"260.676582ms","start":"2026-02-07T04:15:04.064463Z","end":"2026-02-07T04:15:04.325139Z","steps":["trace[909879875] 'agreement among raft nodes before linearized reading' (duration: 260.573199ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:04.325292Z","caller":"traceutil/trace.go:171","msg":"trace[489353667] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"282.415493ms","start":"2026-02-07T04:15:04.042869Z","end":"2026-02-07T04:15:04.325284Z","steps":["trace[489353667] 'process raft request' (duration: 236.225365ms)","trace[489353667] 'compare' (duration: 45.804457ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:10.849276Z","caller":"traceutil/trace.go:171","msg":"trace[1981173310] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"357.370766ms","start":"2026-02-07T04:15:10.491887Z","end":"2026-02-07T04:15:10.849258Z","steps":["trace[1981173310] 'process raft request' (duration: 357.229783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:10.849425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:10.491877Z","time spent":"357.474959ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:10.874711Z","caller":"traceutil/trace.go:171","msg":"trace[1370779020] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"184.961385ms","start":"2026-02-07T04:15:10.689718Z","end":"2026-02-07T04:15:10.87468Z","steps":["trace[1370779020] 'read index received' (duration: 160.109948ms)","trace[1370779020] 'applied index is now lower than readState.Index' (duration: 24.850887ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:10.87489Z","caller":"traceutil/trace.go:171","msg":"trace[1333800789] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"382.390408ms","start":"2026-02-07T04:15:10.492494Z","end":"2026-02-07T04:15:10.874884Z","steps":["trace[1333800789] 'process raft request' (duration: 382.10317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:10.874956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:10.492485Z","time spent":"382.420599ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:10.875268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.548742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:10.87529Z","caller":"traceutil/trace.go:171","msg":"trace[1590733666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"185.591653ms","start":"2026-02-07T04:15:10.689691Z","end":"2026-02-07T04:15:10.875283Z","steps":["trace[1590733666] 'agreement among raft nodes before linearized reading' (duration: 185.559992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:10.875409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.094834ms","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-02-07T04:15:10.875427Z","caller":"traceutil/trace.go:171","msg":"trace[940432577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:863; }","duration":"145.136684ms","start":"2026-02-07T04:15:10.730286Z","end":"2026-02-07T04:15:10.875423Z","steps":["trace[940432577] 'agreement among raft nodes before linearized reading' (duration: 145.069352ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:13.272008Z","caller":"traceutil/trace.go:171","msg":"trace[1415838613] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"391.554053ms","start":"2026-02-07T04:15:12.88044Z","end":"2026-02-07T04:15:13.271994Z","steps":["trace[1415838613] 'read index received' (duration: 391.413359ms)","trace[1415838613] 'applied index is now lower than readState.Index' (duration: 140.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:13.272208Z","caller":"traceutil/trace.go:171","msg":"trace[1248181460] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"400.49245ms","start":"2026-02-07T04:15:12.87171Z","end":"2026-02-07T04:15:13.272202Z","steps":["trace[1248181460] 'process raft request' (duration: 400.191871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:13.272278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:12.871702Z","time spent":"400.52231ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:13.272438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.994265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:15:13.272456Z","caller":"traceutil/trace.go:171","msg":"trace[110726708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"392.020616ms","start":"2026-02-07T04:15:12.880428Z","end":"2026-02-07T04:15:13.272449Z","steps":["trace[110726708] 'agreement among raft nodes before linearized reading' (duration: 391.946814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:13.272469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:12.880422Z","time spent":"392.044266ms","remote":"127.0.0.1:33604","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-02-07T04:15:13.272581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.226443ms","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-02-07T04:15:13.272607Z","caller":"traceutil/trace.go:171","msg":"trace[663463816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"387.272614ms","start":"2026-02-07T04:15:12.885329Z","end":"2026-02-07T04:15:13.272602Z","steps":["trace[663463816] 'agreement among raft nodes before linearized reading' (duration: 387.210212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:13.272624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:12.885325Z","time spent":"387.295925ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:15:13.272832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.340608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:13.272849Z","caller":"traceutil/trace.go:171","msg":"trace[975562374] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:869; }","duration":"292.382589ms","start":"2026-02-07T04:15:12.980462Z","end":"2026-02-07T04:15:13.272845Z","steps":["trace[975562374] 'agreement among raft nodes before linearized reading' (duration: 292.355158ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:16.525154Z","caller":"traceutil/trace.go:171","msg":"trace[1943778398] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"136.950508ms","start":"2026-02-07T04:15:16.388191Z","end":"2026-02-07T04:15:16.525141Z","steps":["trace[1943778398] 'read index received' (duration: 136.832985ms)","trace[1943778398] 'applied index is now lower than readState.Index' (duration: 117.033µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:16.525237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.036562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:16.525254Z","caller":"traceutil/trace.go:171","msg":"trace[1208191319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"137.082782ms","start":"2026-02-07T04:15:16.388165Z","end":"2026-02-07T04:15:16.525248Z","steps":["trace[1208191319] 'agreement among raft nodes before linearized reading' (duration: 137.039131ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:16.525241Z","caller":"traceutil/trace.go:171","msg":"trace[418619708] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"361.771368ms","start":"2026-02-07T04:15:16.163444Z","end":"2026-02-07T04:15:16.525216Z","steps":["trace[418619708] 'process raft request' (duration: 361.625914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:16.525373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:16.163436Z","time spent":"361.867931ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:17.510841Z","caller":"traceutil/trace.go:171","msg":"trace[1357192772] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"189.122543ms","start":"2026-02-07T04:15:17.32169Z","end":"2026-02-07T04:15:17.510813Z","steps":["trace[1357192772] 'read index received' (duration: 188.557307ms)","trace[1357192772] 'applied index is now lower than readState.Index' (duration: 564.476µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:17.510933Z","caller":"traceutil/trace.go:171","msg":"trace[1798946990] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"205.561527ms","start":"2026-02-07T04:15:17.305367Z","end":"2026-02-07T04:15:17.510928Z","steps":["trace[1798946990] 'process raft request' (duration: 204.881278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:17.511063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.362549ms","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-07T04:15:17.511078Z","caller":"traceutil/trace.go:171","msg":"trace[313833253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"189.412301ms","start":"2026-02-07T04:15:17.321661Z","end":"2026-02-07T04:15:17.511074Z","steps":["trace[313833253] 'agreement among raft nodes before linearized reading' (duration: 189.336049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:17.511208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.471792ms","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-02-07T04:15:17.511251Z","caller":"traceutil/trace.go:171","msg":"trace[1904203777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"189.525273ms","start":"2026-02-07T04:15:17.321714Z","end":"2026-02-07T04:15:17.511239Z","steps":["trace[1904203777] 'agreement among raft nodes before linearized reading' (duration: 189.41538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:17.511262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.849087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:15:17.511278Z","caller":"traceutil/trace.go:171","msg":"trace[1895443311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"187.873928ms","start":"2026-02-07T04:15:17.323399Z","end":"2026-02-07T04:15:17.511273Z","steps":["trace[1895443311] 'agreement among raft nodes before linearized reading' (duration: 187.831287ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:17.744319Z","caller":"traceutil/trace.go:171","msg":"trace[1051487050] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"189.820402ms","start":"2026-02-07T04:15:17.554471Z","end":"2026-02-07T04:15:17.744291Z","steps":["trace[1051487050] 'process raft request' (duration: 96.950602ms)","trace[1051487050] 'compare' (duration: 92.766217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:18.254217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.182741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:15:18.254331Z","caller":"traceutil/trace.go:171","msg":"trace[1346214922] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"698.700179ms","start":"2026-02-07T04:15:17.555618Z","end":"2026-02-07T04:15:18.254318Z","steps":["trace[1346214922] 'process raft request' (duration: 698.660758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:18.254395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:17.555612Z","time spent":"698.76381ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:18.254601Z","caller":"traceutil/trace.go:171","msg":"trace[1707888786] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"700.008114ms","start":"2026-02-07T04:15:17.554585Z","end":"2026-02-07T04:15:18.254594Z","steps":["trace[1707888786] 'process raft request' (duration: 364.395371ms)","trace[1707888786] 'compare' (duration: 334.866973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:18.254659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:17.554581Z","time spent":"700.059617ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:18.776882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.562659ms","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-07T04:15:18.776962Z","caller":"traceutil/trace.go:171","msg":"trace[1294639614] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:882; }","duration":"414.652931ms","start":"2026-02-07T04:15:18.362298Z","end":"2026-02-07T04:15:18.776951Z","steps":["trace[1294639614] 'range keys from in-memory index tree' (duration: 414.550749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:18.777553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.107129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:18.777575Z","caller":"traceutil/trace.go:171","msg":"trace[1788382133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"353.15506ms","start":"2026-02-07T04:15:18.424415Z","end":"2026-02-07T04:15:18.77757Z","steps":["trace[1788382133] 'range keys from in-memory index tree' (duration: 353.055777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:18.777594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:18.424402Z","time spent":"353.187881ms","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:15:18.777807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.764757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:15:18.777826Z","caller":"traceutil/trace.go:171","msg":"trace[69389947] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:882; }","duration":"336.795897ms","start":"2026-02-07T04:15:18.441025Z","end":"2026-02-07T04:15:18.777821Z","steps":["trace[69389947] 'range keys from in-memory index tree' (duration: 336.688194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:18.777839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:18.44102Z","time spent":"336.816558ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-02-07T04:15:18.778127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.970916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T04:15:18.778152Z","caller":"traceutil/trace.go:171","msg":"trace[1989817232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"250.018286ms","start":"2026-02-07T04:15:18.528128Z","end":"2026-02-07T04:15:18.778146Z","steps":["trace[1989817232] 'range keys from in-memory index tree' (duration: 249.911474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:19.589008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.912661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:15:19.589446Z","caller":"traceutil/trace.go:171","msg":"trace[1437681561] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"804.96097ms","start":"2026-02-07T04:15:18.784465Z","end":"2026-02-07T04:15:19.589426Z","steps":["trace[1437681561] 'process raft request' (duration: 313.561915ms)","trace[1437681561] 'compare' (duration: 490.463789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:19.589508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:18.784458Z","time spent":"805.019051ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:15:19.589631Z","caller":"traceutil/trace.go:171","msg":"trace[1259190165] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"659.004881ms","start":"2026-02-07T04:15:18.930616Z","end":"2026-02-07T04:15:19.589621Z","steps":["trace[1259190165] 'process raft request' (duration: 658.471826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:19.58968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:18.930584Z","time spent":"659.069263ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:20.018297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.908997ms","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-02-07T04:15:20.018367Z","caller":"traceutil/trace.go:171","msg":"trace[1115445246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"270.01048ms","start":"2026-02-07T04:15:19.74834Z","end":"2026-02-07T04:15:20.01835Z","steps":["trace[1115445246] 'range keys from in-memory index tree' (duration: 269.758073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:22.053624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.06926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:15:22.05371Z","caller":"traceutil/trace.go:171","msg":"trace[1210175463] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"387.245743ms","start":"2026-02-07T04:15:21.666448Z","end":"2026-02-07T04:15:22.053694Z","steps":["trace[1210175463] 'read index received' (duration: 142.010119ms)","trace[1210175463] 'applied index is now lower than readState.Index' (duration: 245.234544ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:22.053871Z","caller":"traceutil/trace.go:171","msg":"trace[1976808197] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"452.826608ms","start":"2026-02-07T04:15:21.601033Z","end":"2026-02-07T04:15:22.05386Z","steps":["trace[1976808197] 'process raft request' (duration: 207.443249ms)","trace[1976808197] 'compare' (duration: 244.894025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:15:22.053949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:21.601016Z","time spent":"452.902349ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:15:22.054176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.738607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:15:22.054208Z","caller":"traceutil/trace.go:171","msg":"trace[1520704631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"387.795118ms","start":"2026-02-07T04:15:21.666401Z","end":"2026-02-07T04:15:22.054196Z","steps":["trace[1520704631] 'agreement among raft nodes before linearized reading' (duration: 387.698695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:22.054233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:15:21.666385Z","time spent":"387.84292ms","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:15:28.314901Z","caller":"traceutil/trace.go:171","msg":"trace[212975868] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"204.21144ms","start":"2026-02-07T04:15:28.110674Z","end":"2026-02-07T04:15:28.314885Z","steps":["trace[212975868] 'read index received' (duration: 204.091486ms)","trace[212975868] 'applied index is now lower than readState.Index' (duration: 119.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:28.315402Z","caller":"traceutil/trace.go:171","msg":"trace[1896601916] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"235.378432ms","start":"2026-02-07T04:15:28.080015Z","end":"2026-02-07T04:15:28.315393Z","steps":["trace[1896601916] 'process raft request' (duration: 234.790995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:28.31557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.890258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:15:28.315632Z","caller":"traceutil/trace.go:171","msg":"trace[160629774] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:926; }","duration":"205.018392ms","start":"2026-02-07T04:15:28.110606Z","end":"2026-02-07T04:15:28.315625Z","steps":["trace[160629774] 'agreement among raft nodes before linearized reading' (duration: 204.922119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:28.31538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.345042ms","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-07T04:15:28.315818Z","caller":"traceutil/trace.go:171","msg":"trace[138281561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"179.832655ms","start":"2026-02-07T04:15:28.135979Z","end":"2026-02-07T04:15:28.315811Z","steps":["trace[138281561] 'agreement among raft nodes before linearized reading' (duration: 179.254129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:28.315648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.59136ms","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-07T04:15:28.315937Z","caller":"traceutil/trace.go:171","msg":"trace[1372378305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"170.899358ms","start":"2026-02-07T04:15:28.145025Z","end":"2026-02-07T04:15:28.315925Z","steps":["trace[1372378305] 'agreement among raft nodes before linearized reading' (duration: 170.58178ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:36.584525Z","caller":"traceutil/trace.go:171","msg":"trace[917624445] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"215.391918ms","start":"2026-02-07T04:15:36.369105Z","end":"2026-02-07T04:15:36.584497Z","steps":["trace[917624445] 'read index received' (duration: 215.146252ms)","trace[917624445] 'applied index is now lower than readState.Index' (duration: 244.206µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:36.584857Z","caller":"traceutil/trace.go:171","msg":"trace[228254388] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"233.15325ms","start":"2026-02-07T04:15:36.35169Z","end":"2026-02-07T04:15:36.584843Z","steps":["trace[228254388] 'process raft request' (duration: 232.643716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.585211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.000288ms","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-02-07T04:15:36.585293Z","caller":"traceutil/trace.go:171","msg":"trace[814933564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"215.114501ms","start":"2026-02-07T04:15:36.370169Z","end":"2026-02-07T04:15:36.585283Z","steps":["trace[814933564] 'agreement among raft nodes before linearized reading' (duration: 214.935176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.585458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.725004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:15:36.58551Z","caller":"traceutil/trace.go:171","msg":"trace[285292383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"204.786835ms","start":"2026-02-07T04:15:36.38071Z","end":"2026-02-07T04:15:36.585497Z","steps":["trace[285292383] 'agreement among raft nodes before linearized reading' (duration: 204.671782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.585683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.371002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:15:36.585719Z","caller":"traceutil/trace.go:171","msg":"trace[229594494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"192.418693ms","start":"2026-02-07T04:15:36.393291Z","end":"2026-02-07T04:15:36.58571Z","steps":["trace[229594494] 'agreement among raft nodes before linearized reading' (duration: 192.371252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.585219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.095889ms","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-07T04:15:36.586053Z","caller":"traceutil/trace.go:171","msg":"trace[59382170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"216.984303ms","start":"2026-02-07T04:15:36.369056Z","end":"2026-02-07T04:15:36.58604Z","steps":["trace[59382170] 'agreement among raft nodes before linearized reading' (duration: 216.017856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.586949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.345577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:15:36.587032Z","caller":"traceutil/trace.go:171","msg":"trace[1917997765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"211.44393ms","start":"2026-02-07T04:15:36.37558Z","end":"2026-02-07T04:15:36.587024Z","steps":["trace[1917997765] 'agreement among raft nodes before linearized reading' (duration: 209.896907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:15:36.765507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.806803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:15:36.76602Z","caller":"traceutil/trace.go:171","msg":"trace[635963778] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"170.847516ms","start":"2026-02-07T04:15:36.595127Z","end":"2026-02-07T04:15:36.765974Z","steps":["trace[635963778] 'process raft request' (duration: 170.467066ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:36.766302Z","caller":"traceutil/trace.go:171","msg":"trace[433489852] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"170.184919ms","start":"2026-02-07T04:15:36.596095Z","end":"2026-02-07T04:15:36.76628Z","steps":["trace[433489852] 'process raft request' (duration: 169.690845ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:15:36.766328Z","caller":"traceutil/trace.go:171","msg":"trace[725980774] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"171.438332ms","start":"2026-02-07T04:15:36.594871Z","end":"2026-02-07T04:15:36.76631Z","steps":["trace[725980774] 'process raft request' (duration: 61.739298ms)","trace[725980774] 'compare' (duration: 107.701769ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:15:36.7671Z","caller":"traceutil/trace.go:171","msg":"trace[1526885860] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"113.621273ms","start":"2026-02-07T04:15:36.653464Z","end":"2026-02-07T04:15:36.767085Z","steps":["trace[1526885860] 'process raft request' (duration: 112.356048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:47.842782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674583940047850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:17:48.345802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674583940047850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:17:48.846516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674583940047850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:17:49.256219Z","caller":"traceutil/trace.go:171","msg":"trace[681077230] linearizableReadLoop","detail":"{readStateIndex:1591; appliedIndex:1590; }","duration":"1.914234388s","start":"2026-02-07T04:17:47.341969Z","end":"2026-02-07T04:17:49.256204Z","steps":["trace[681077230] 'read index received' (duration: 1.914097734s)","trace[681077230] 'applied index is now lower than readState.Index' (duration: 135.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:17:49.256427Z","caller":"traceutil/trace.go:171","msg":"trace[559752762] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"1.94680385s","start":"2026-02-07T04:17:47.309614Z","end":"2026-02-07T04:17:49.256418Z","steps":["trace[559752762] 'process raft request' (duration: 1.946492652s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.256511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.309591Z","time spent":"1.946854832s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:17:49.256663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914704931s","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-02-07T04:17:49.256684Z","caller":"traceutil/trace.go:171","msg":"trace[484630231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1524; }","duration":"1.914760223s","start":"2026-02-07T04:17:47.341917Z","end":"2026-02-07T04:17:49.256678Z","steps":["trace[484630231] 'agreement among raft nodes before linearized reading' (duration: 1.914690161s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.256698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.341899Z","time spent":"1.914796233s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:17:49.2571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903554372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T04:17:49.257434Z","caller":"traceutil/trace.go:171","msg":"trace[649833624] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1524; }","duration":"1.903924472s","start":"2026-02-07T04:17:47.353484Z","end":"2026-02-07T04:17:49.257408Z","steps":["trace[649833624] 'agreement among raft nodes before linearized reading' (duration: 1.903382457s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.257788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.353469Z","time spent":"1.904164659s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:17:49.258457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55415833s","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:532"} {"level":"info","ts":"2026-02-07T04:17:49.258482Z","caller":"traceutil/trace.go:171","msg":"trace[133326492] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1524; }","duration":"1.554219451s","start":"2026-02-07T04:17:47.704257Z","end":"2026-02-07T04:17:49.258476Z","steps":["trace[133326492] 'agreement among raft nodes before linearized reading' (duration: 1.55415955s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.258496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.704239Z","time spent":"1.554254242s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T04:17:49.258576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.433113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T04:17:49.258597Z","caller":"traceutil/trace.go:171","msg":"trace[185516693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1524; }","duration":"109.487585ms","start":"2026-02-07T04:17:49.149103Z","end":"2026-02-07T04:17:49.258591Z","steps":["trace[185516693] 'agreement among raft nodes before linearized reading' (duration: 109.443993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.259006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.098543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:17:49.259028Z","caller":"traceutil/trace.go:171","msg":"trace[916204887] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:1524; }","duration":"809.151334ms","start":"2026-02-07T04:17:48.449871Z","end":"2026-02-07T04:17:49.259023Z","steps":["trace[916204887] 'agreement among raft nodes before linearized reading' (duration: 809.096432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.259041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:48.449858Z","time spent":"809.179935ms","remote":"127.0.0.1:33326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-02-07T04:17:49.259181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.435712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:17:49.259199Z","caller":"traceutil/trace.go:171","msg":"trace[407035420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1524; }","duration":"866.486412ms","start":"2026-02-07T04:17:48.392708Z","end":"2026-02-07T04:17:49.259194Z","steps":["trace[407035420] 'agreement among raft nodes before linearized reading' (duration: 866.459312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.259212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:48.39269Z","time spent":"866.519174ms","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:17:49.259493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686171338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:17:49.259707Z","caller":"traceutil/trace.go:171","msg":"trace[1696164301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1524; }","duration":"1.686446025s","start":"2026-02-07T04:17:47.573248Z","end":"2026-02-07T04:17:49.259694Z","steps":["trace[1696164301] 'agreement among raft nodes before linearized reading' (duration: 1.686075885s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.263718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.573238Z","time spent":"1.690442437s","remote":"127.0.0.1:33604","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-02-07T04:17:49.260793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906054222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-07T04:17:49.263831Z","caller":"traceutil/trace.go:171","msg":"trace[547167147] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1524; }","duration":"1.909140617s","start":"2026-02-07T04:17:47.354679Z","end":"2026-02-07T04:17:49.26382Z","steps":["trace[547167147] 'agreement among raft nodes before linearized reading' (duration: 1.906072122s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.26385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.35467Z","time spent":"1.909174288s","remote":"127.0.0.1:33604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:17:49.262063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691428544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:17:49.263882Z","caller":"traceutil/trace.go:171","msg":"trace[1648768319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1524; }","duration":"1.693286555s","start":"2026-02-07T04:17:47.570592Z","end":"2026-02-07T04:17:49.263878Z","steps":["trace[1648768319] 'agreement among raft nodes before linearized reading' (duration: 1.691432154s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:49.263893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:47.570574Z","time spent":"1.693315946s","remote":"127.0.0.1:33604","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-02-07T04:18:39.599318Z","caller":"traceutil/trace.go:171","msg":"trace[464751571] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"190.103475ms","start":"2026-02-07T04:18:39.409177Z","end":"2026-02-07T04:18:39.599281Z","steps":["trace[464751571] 'process raft request' (duration: 189.973612ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.628918Z","caller":"traceutil/trace.go:171","msg":"trace[313682600] linearizableReadLoop","detail":"{readStateIndex:1836; appliedIndex:1835; }","duration":"145.801062ms","start":"2026-02-07T04:18:39.483095Z","end":"2026-02-07T04:18:39.628896Z","steps":["trace[313682600] 'read index received' (duration: 116.238833ms)","trace[313682600] 'applied index is now lower than readState.Index' (duration: 29.561419ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:18:39.629034Z","caller":"traceutil/trace.go:171","msg":"trace[1089795031] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"218.311158ms","start":"2026-02-07T04:18:39.410712Z","end":"2026-02-07T04:18:39.629023Z","steps":["trace[1089795031] 'process raft request' (duration: 218.057611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:18:39.629174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.057802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-07T04:18:39.629221Z","caller":"traceutil/trace.go:171","msg":"trace[121106195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1757; }","duration":"145.150885ms","start":"2026-02-07T04:18:39.484055Z","end":"2026-02-07T04:18:39.629206Z","steps":["trace[121106195] 'agreement among raft nodes before linearized reading' (duration: 145.014021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:18:39.629437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.631603ms","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:532"} {"level":"info","ts":"2026-02-07T04:18:39.629464Z","caller":"traceutil/trace.go:171","msg":"trace[1498000393] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1757; }","duration":"120.669754ms","start":"2026-02-07T04:18:39.508786Z","end":"2026-02-07T04:18:39.629455Z","steps":["trace[1498000393] 'agreement among raft nodes before linearized reading' (duration: 120.594291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:18:39.629576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.46191ms","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-07T04:18:39.629617Z","caller":"traceutil/trace.go:171","msg":"trace[616548107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1757; }","duration":"146.546923ms","start":"2026-02-07T04:18:39.483056Z","end":"2026-02-07T04:18:39.629603Z","steps":["trace[616548107] 'agreement among raft nodes before linearized reading' (duration: 146.45013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:18:39.62962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.263496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-07T04:18:39.629647Z","caller":"traceutil/trace.go:171","msg":"trace[1625720528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1757; }","duration":"142.318107ms","start":"2026-02-07T04:18:39.487321Z","end":"2026-02-07T04:18:39.629639Z","steps":["trace[1625720528] 'agreement among raft nodes before linearized reading' (duration: 142.243725ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.758188Z","caller":"traceutil/trace.go:171","msg":"trace[135576063] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"120.328944ms","start":"2026-02-07T04:18:39.637819Z","end":"2026-02-07T04:18:39.758148Z","steps":["trace[135576063] 'process raft request' (duration: 41.405423ms)","trace[135576063] 'compare' (duration: 78.837539ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:18:39.769237Z","caller":"traceutil/trace.go:171","msg":"trace[1693991574] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"131.247354ms","start":"2026-02-07T04:18:39.637979Z","end":"2026-02-07T04:18:39.769226Z","steps":["trace[1693991574] 'process raft request' (duration: 130.845443ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.770078Z","caller":"traceutil/trace.go:171","msg":"trace[714415388] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"131.210453ms","start":"2026-02-07T04:18:39.638859Z","end":"2026-02-07T04:18:39.770069Z","steps":["trace[714415388] 'process raft request' (duration: 130.01507ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.770635Z","caller":"traceutil/trace.go:171","msg":"trace[602163614] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"128.54444ms","start":"2026-02-07T04:18:39.642079Z","end":"2026-02-07T04:18:39.770624Z","steps":["trace[602163614] 'process raft request' (duration: 126.813973ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.770989Z","caller":"traceutil/trace.go:171","msg":"trace[181917697] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"128.808877ms","start":"2026-02-07T04:18:39.642147Z","end":"2026-02-07T04:18:39.770956Z","steps":["trace[181917697] 'process raft request' (duration: 126.763391ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:18:39.771037Z","caller":"traceutil/trace.go:171","msg":"trace[504472625] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"128.054606ms","start":"2026-02-07T04:18:39.642977Z","end":"2026-02-07T04:18:39.771031Z","steps":["trace[504472625] 'process raft request' (duration: 125.952269ms)"],"step_count":1}