{"level":"warn","ts":"2026-10-27T20:50:57.70421Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T20:50:57.704308Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.211: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.211:2380","--initial-cluster=instance=https://162.253.55.211:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.211:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.211: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-10-27T20:50:57.704473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T20:50:57.704491Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-10-27T20:50:57.704575Z","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-10-27T20:50:57.705487Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"]} {"level":"info","ts":"2026-10-27T20:50:57.705694Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.211: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-10-27T20:50:57.712597Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.516858ms"} {"level":"info","ts":"2026-10-27T20:50:57.724499Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"931f6a422b4c426f","cluster-id":"8a95cf5058d193ed"} {"level":"info","ts":"2026-10-27T20:50:57.724604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T20:50:57.724643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 0"} {"level":"info","ts":"2026-10-27T20:50:57.724654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 931f6a422b4c426f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T20:50:57.72468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became follower at term 1"} {"level":"info","ts":"2026-10-27T20:50:57.724718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"warn","ts":"2026-10-27T20:50:57.731942Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T20:50:57.73749Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T20:50:57.73943Z","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-10-27T20:50:57.742133Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"931f6a422b4c426f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T20:50:57.742708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"931f6a422b4c426f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T20:50:57.742932Z","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-10-27T20:50:57.742982Z","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-10-27T20:50:57.742991Z","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-10-27T20:50:57.743609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f switched to configuration voters=(10601308880280240751)"} {"level":"info","ts":"2026-10-27T20:50:57.743732Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","added-peer-id":"931f6a422b4c426f","added-peer-peer-urls":["https://162.253.55.211:2380"]} {"level":"info","ts":"2026-10-27T20:50:57.748975Z","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-10-27T20:50:57.749346Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-10-27T20:50:57.749425Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.211:2380"} {"level":"info","ts":"2026-10-27T20:50:57.749212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"931f6a422b4c426f","initial-advertise-peer-urls":["https://162.253.55.211:2380"],"listen-peer-urls":["https://162.253.55.211:2380"],"advertise-client-urls":["https://162.253.55.211:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.211:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T20:50:57.750171Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T20:50:58.225313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T20:50:58.225352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T20:50:58.225564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgPreVoteResp from 931f6a422b4c426f at term 1"} {"level":"info","ts":"2026-10-27T20:50:58.225576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became candidate at term 2"} {"level":"info","ts":"2026-10-27T20:50:58.225585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f received MsgVoteResp from 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-10-27T20:50:58.225592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"931f6a422b4c426f became leader at term 2"} {"level":"info","ts":"2026-10-27T20:50:58.2256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 931f6a422b4c426f elected leader 931f6a422b4c426f at term 2"} {"level":"info","ts":"2026-10-27T20:50:58.236705Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"931f6a422b4c426f","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.211:2379]}","request-path":"/0/members/931f6a422b4c426f/attributes","cluster-id":"8a95cf5058d193ed","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T20:50:58.23673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T20:50:58.236742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:50:58.236932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T20:50:58.23902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.211:2379"} {"level":"info","ts":"2026-10-27T20:50:58.239406Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T20:50:58.23946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T20:50:58.2409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T20:50:58.252709Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a95cf5058d193ed","local-member-id":"931f6a422b4c426f","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:50:58.252914Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:50:58.25298Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-27T20:51:00.625321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.040388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-10-27T20:51:00.625442Z","caller":"traceutil/trace.go:171","msg":"trace[666703354] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"182.656182ms","start":"2026-10-27T20:51:00.442771Z","end":"2026-10-27T20:51:00.625427Z","steps":["trace[666703354] 'read index received' (duration: 30.825729ms)","trace[666703354] 'applied index is now lower than readState.Index' (duration: 151.829223ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:00.625507Z","caller":"traceutil/trace.go:171","msg":"trace[914384619] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"275.494145ms","start":"2026-10-27T20:51:00.349974Z","end":"2026-10-27T20:51:00.625469Z","steps":["trace[914384619] 'process raft request' (duration: 123.683592ms)","trace[914384619] 'compare' (duration: 150.914526ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:51:00.625572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.802074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:00.625603Z","caller":"traceutil/trace.go:171","msg":"trace[352429044] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:87; }","duration":"182.858035ms","start":"2026-10-27T20:51:00.442736Z","end":"2026-10-27T20:51:00.625594Z","steps":["trace[352429044] 'agreement among raft nodes before linearized reading' (duration: 182.785044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:00.625958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.516718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:00.626114Z","caller":"traceutil/trace.go:171","msg":"trace[1561169354] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:87; }","duration":"182.741662ms","start":"2026-10-27T20:51:00.443351Z","end":"2026-10-27T20:51:00.626092Z","steps":["trace[1561169354] 'agreement among raft nodes before linearized reading' (duration: 182.492517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:00.626037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.177171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:00.626947Z","caller":"traceutil/trace.go:171","msg":"trace[589464086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:87; }","duration":"130.168779ms","start":"2026-10-27T20:51:00.49675Z","end":"2026-10-27T20:51:00.626919Z","steps":["trace[589464086] 'agreement among raft nodes before linearized reading' (duration: 129.119221ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:00.769004Z","caller":"traceutil/trace.go:171","msg":"trace[1831454023] linearizableReadLoop","detail":"{readStateIndex:93; appliedIndex:92; }","duration":"139.609191ms","start":"2026-10-27T20:51:00.62937Z","end":"2026-10-27T20:51:00.768979Z","steps":["trace[1831454023] 'read index received' (duration: 132.037733ms)","trace[1831454023] 'applied index is now lower than readState.Index' (duration: 7.570518ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:51:00.769194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.796984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:00.769235Z","caller":"traceutil/trace.go:171","msg":"trace[161974809] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:88; }","duration":"139.870905ms","start":"2026-10-27T20:51:00.629353Z","end":"2026-10-27T20:51:00.769224Z","steps":["trace[161974809] 'agreement among raft nodes before linearized reading' (duration: 139.730992ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:00.769239Z","caller":"traceutil/trace.go:171","msg":"trace[338078852] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"140.251962ms","start":"2026-10-27T20:51:00.628959Z","end":"2026-10-27T20:51:00.769211Z","steps":["trace[338078852] 'process raft request' (duration: 132.362819ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:00.784107Z","caller":"traceutil/trace.go:171","msg":"trace[1417538191] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"153.066754ms","start":"2026-10-27T20:51:00.631014Z","end":"2026-10-27T20:51:00.784081Z","steps":["trace[1417538191] 'process raft request' (duration: 152.935892ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:01.033371Z","caller":"traceutil/trace.go:171","msg":"trace[1633721515] linearizableReadLoop","detail":"{readStateIndex:95; appliedIndex:94; }","duration":"242.927323ms","start":"2026-10-27T20:51:00.790423Z","end":"2026-10-27T20:51:01.03335Z","steps":["trace[1633721515] 'read index received' (duration: 178.886162ms)","trace[1633721515] 'applied index is now lower than readState.Index' (duration: 64.040231ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:51:01.033531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.102407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:01.033515Z","caller":"traceutil/trace.go:171","msg":"trace[581691143] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"246.289124ms","start":"2026-10-27T20:51:00.78716Z","end":"2026-10-27T20:51:01.033449Z","steps":["trace[581691143] 'process raft request' (duration: 182.223713ms)","trace[581691143] 'compare' (duration: 63.845817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:51:01.033549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.933672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18e27e1b341373bd\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-10-27T20:51:01.033612Z","caller":"traceutil/trace.go:171","msg":"trace[101683512] range","detail":"{range_begin:/registry/events/default/instance.18e27e1b341373bd; range_end:; response_count:1; response_revision:90; }","duration":"243.011193ms","start":"2026-10-27T20:51:00.790589Z","end":"2026-10-27T20:51:01.0336Z","steps":["trace[101683512] 'agreement among raft nodes before linearized reading' (duration: 242.916382ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:01.03356Z","caller":"traceutil/trace.go:171","msg":"trace[1468690569] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:90; }","duration":"243.176568ms","start":"2026-10-27T20:51:00.790376Z","end":"2026-10-27T20:51:01.033553Z","steps":["trace[1468690569] 'agreement among raft nodes before linearized reading' (duration: 243.074966ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:01.262366Z","caller":"traceutil/trace.go:171","msg":"trace[1926297024] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:96; }","duration":"186.293646ms","start":"2026-10-27T20:51:01.076047Z","end":"2026-10-27T20:51:01.262341Z","steps":["trace[1926297024] 'read index received' (duration: 180.9825ms)","trace[1926297024] 'applied index is now lower than readState.Index' (duration: 5.309816ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:01.262441Z","caller":"traceutil/trace.go:171","msg":"trace[1466000394] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"187.208902ms","start":"2026-10-27T20:51:01.075202Z","end":"2026-10-27T20:51:01.262411Z","steps":["trace[1466000394] 'process raft request' (duration: 181.814745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:01.262512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.441639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T20:51:01.262549Z","caller":"traceutil/trace.go:171","msg":"trace[144513335] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:92; }","duration":"186.52703ms","start":"2026-10-27T20:51:01.076012Z","end":"2026-10-27T20:51:01.26254Z","steps":["trace[144513335] 'agreement among raft nodes before linearized reading' (duration: 186.421708ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:40.137531Z","caller":"traceutil/trace.go:171","msg":"trace[187820639] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"100.72127ms","start":"2026-10-27T20:51:40.036783Z","end":"2026-10-27T20:51:40.137504Z","steps":["trace[187820639] 'read index received' (duration: 100.431525ms)","trace[187820639] 'applied index is now lower than readState.Index' (duration: 241.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:40.137894Z","caller":"traceutil/trace.go:171","msg":"trace[593402585] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"207.156251ms","start":"2026-10-27T20:51:39.930726Z","end":"2026-10-27T20:51:40.137882Z","steps":["trace[593402585] 'process raft request' (duration: 206.560241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.138199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.395832ms","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-10-27T20:51:40.138246Z","caller":"traceutil/trace.go:171","msg":"trace[644944320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"101.501104ms","start":"2026-10-27T20:51:40.036734Z","end":"2026-10-27T20:51:40.138235Z","steps":["trace[644944320] 'agreement among raft nodes before linearized reading' (duration: 101.336031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.593545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.740046ms","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-10-27T20:51:40.593717Z","caller":"traceutil/trace.go:171","msg":"trace[1913491610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"397.929229ms","start":"2026-10-27T20:51:40.195726Z","end":"2026-10-27T20:51:40.593655Z","steps":["trace[1913491610] 'range keys from in-memory index tree' (duration: 397.500342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.593786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:51:40.195706Z","time spent":"398.064661ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T20:51:40.593434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.05779ms","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-10-27T20:51:40.593946Z","caller":"traceutil/trace.go:171","msg":"trace[1366446101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"394.615851ms","start":"2026-10-27T20:51:40.19927Z","end":"2026-10-27T20:51:40.593886Z","steps":["trace[1366446101] 'range keys from in-memory index tree' (duration: 393.859197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.594108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:51:40.19926Z","time spent":"394.716592ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T20:51:40.826937Z","caller":"traceutil/trace.go:171","msg":"trace[2075120327] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"498.594017ms","start":"2026-10-27T20:51:40.328309Z","end":"2026-10-27T20:51:40.826903Z","steps":["trace[2075120327] 'read index received' (duration: 497.637521ms)","trace[2075120327] 'applied index is now lower than readState.Index' (duration: 955.356µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:40.82704Z","caller":"traceutil/trace.go:171","msg":"trace[1364278827] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"548.420117ms","start":"2026-10-27T20:51:40.278567Z","end":"2026-10-27T20:51:40.826987Z","steps":["trace[1364278827] 'process raft request' (duration: 547.525101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.82715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.807621ms","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-10-27T20:51:40.827185Z","caller":"traceutil/trace.go:171","msg":"trace[1973112735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"498.894903ms","start":"2026-10-27T20:51:40.32828Z","end":"2026-10-27T20:51:40.827175Z","steps":["trace[1973112735] 'agreement among raft nodes before linearized reading' (duration: 498.73752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.827249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:51:40.278538Z","time spent":"548.5822ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:51:40.827249Z","caller":"traceutil/trace.go:171","msg":"trace[943327353] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"228.364259ms","start":"2026-10-27T20:51:40.598855Z","end":"2026-10-27T20:51:40.827219Z","steps":["trace[943327353] 'process raft request' (duration: 228.310018ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:40.827363Z","caller":"traceutil/trace.go:171","msg":"trace[1878732587] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"238.587372ms","start":"2026-10-27T20:51:40.588766Z","end":"2026-10-27T20:51:40.827353Z","steps":["trace[1878732587] 'process raft request' (duration: 238.186925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:40.827306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:51:40.32826Z","time spent":"498.952624ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T20:51:40.827621Z","caller":"traceutil/trace.go:171","msg":"trace[190203928] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"229.066312ms","start":"2026-10-27T20:51:40.598511Z","end":"2026-10-27T20:51:40.827578Z","steps":["trace[190203928] 'process raft request' (duration: 228.610133ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:42.262109Z","caller":"traceutil/trace.go:171","msg":"trace[1827272495] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"103.501308ms","start":"2026-10-27T20:51:42.158586Z","end":"2026-10-27T20:51:42.262087Z","steps":["trace[1827272495] 'read index received' (duration: 103.236213ms)","trace[1827272495] 'applied index is now lower than readState.Index' (duration: 263.885µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:42.262133Z","caller":"traceutil/trace.go:171","msg":"trace[1915554412] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"164.227702ms","start":"2026-10-27T20:51:42.097851Z","end":"2026-10-27T20:51:42.262078Z","steps":["trace[1915554412] 'process raft request' (duration: 164.025459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:42.262412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.779323ms","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-10-27T20:51:42.262417Z","caller":"traceutil/trace.go:171","msg":"trace[277449289] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"164.532308ms","start":"2026-10-27T20:51:42.097848Z","end":"2026-10-27T20:51:42.26238Z","steps":["trace[277449289] 'process raft request' (duration: 164.148541ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:42.262453Z","caller":"traceutil/trace.go:171","msg":"trace[1765010618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"103.921336ms","start":"2026-10-27T20:51:42.158521Z","end":"2026-10-27T20:51:42.262442Z","steps":["trace[1765010618] 'agreement among raft nodes before linearized reading' (duration: 103.664741ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:42.262643Z","caller":"traceutil/trace.go:171","msg":"trace[765703329] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"164.171562ms","start":"2026-10-27T20:51:42.098461Z","end":"2026-10-27T20:51:42.262633Z","steps":["trace[765703329] 'process raft request' (duration: 163.571272ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:51:56.180521Z","caller":"traceutil/trace.go:171","msg":"trace[994366397] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"115.097486ms","start":"2026-10-27T20:51:56.065403Z","end":"2026-10-27T20:51:56.1805Z","steps":["trace[994366397] 'read index received' (duration: 114.895903ms)","trace[994366397] 'applied index is now lower than readState.Index' (duration: 200.683µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:51:56.180661Z","caller":"traceutil/trace.go:171","msg":"trace[1913769393] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"134.801787ms","start":"2026-10-27T20:51:56.045841Z","end":"2026-10-27T20:51:56.180643Z","steps":["trace[1913769393] 'process raft request' (duration: 134.522412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:56.180727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.30828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:51:56.180761Z","caller":"traceutil/trace.go:171","msg":"trace[560171054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"115.388862ms","start":"2026-10-27T20:51:56.065363Z","end":"2026-10-27T20:51:56.180752Z","steps":["trace[560171054] 'agreement among raft nodes before linearized reading' (duration: 115.26348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:51:56.372574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.368909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:51:56.372766Z","caller":"traceutil/trace.go:171","msg":"trace[176123419] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"186.446716ms","start":"2026-10-27T20:51:56.186291Z","end":"2026-10-27T20:51:56.372738Z","steps":["trace[176123419] 'process raft request' (duration: 73.812342ms)","trace[176123419] 'compare' (duration: 112.212667ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:52:40.867023Z","caller":"traceutil/trace.go:171","msg":"trace[746542478] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"219.317703ms","start":"2026-10-27T20:52:40.647681Z","end":"2026-10-27T20:52:40.866998Z","steps":["trace[746542478] 'read index received' (duration: 219.189218ms)","trace[746542478] 'applied index is now lower than readState.Index' (duration: 127.195µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:52:40.867138Z","caller":"traceutil/trace.go:171","msg":"trace[577682406] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"221.776349ms","start":"2026-10-27T20:52:40.645319Z","end":"2026-10-27T20:52:40.867096Z","steps":["trace[577682406] 'process raft request' (duration: 221.515119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:52:40.867228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.546281ms","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-10-27T20:52:40.867259Z","caller":"traceutil/trace.go:171","msg":"trace[1748850667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"219.628835ms","start":"2026-10-27T20:52:40.647622Z","end":"2026-10-27T20:52:40.867251Z","steps":["trace[1748850667] 'agreement among raft nodes before linearized reading' (duration: 219.472299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:01.219422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.813728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:53:01.2195Z","caller":"traceutil/trace.go:171","msg":"trace[733318076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"155.922712ms","start":"2026-10-27T20:53:01.063562Z","end":"2026-10-27T20:53:01.219484Z","steps":["trace[733318076] 'range keys from in-memory index tree' (duration: 155.765926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:01.219802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.010286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-27T20:53:01.219822Z","caller":"traceutil/trace.go:171","msg":"trace[2032347823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"160.11294ms","start":"2026-10-27T20:53:01.059703Z","end":"2026-10-27T20:53:01.219816Z","steps":["trace[2032347823] 'range keys from in-memory index tree' (duration: 159.82279ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:01.362Z","caller":"traceutil/trace.go:171","msg":"trace[1280219667] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"134.890597ms","start":"2026-10-27T20:53:01.227059Z","end":"2026-10-27T20:53:01.361949Z","steps":["trace[1280219667] 'process raft request' (duration: 133.648049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.669818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.67427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T20:53:02.669924Z","caller":"traceutil/trace.go:171","msg":"trace[787574007] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1190; }","duration":"403.797306ms","start":"2026-10-27T20:53:02.266106Z","end":"2026-10-27T20:53:02.669904Z","steps":["trace[787574007] 'count revisions from in-memory index tree' (duration: 403.598708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.669976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:02.266085Z","time spent":"403.874849ms","remote":"127.0.0.1:46648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-27T20:53:02.670255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.197642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:53:02.670295Z","caller":"traceutil/trace.go:171","msg":"trace[118171696] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1190; }","duration":"683.270565ms","start":"2026-10-27T20:53:01.98701Z","end":"2026-10-27T20:53:02.67028Z","steps":["trace[118171696] 'range keys from in-memory index tree' (duration: 683.050607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.67033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:01.98699Z","time spent":"683.330427ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-10-27T20:53:02.670657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.233996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:53:02.67077Z","caller":"traceutil/trace.go:171","msg":"trace[512915657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"605.35593ms","start":"2026-10-27T20:53:02.065393Z","end":"2026-10-27T20:53:02.670749Z","steps":["trace[512915657] 'range keys from in-memory index tree' (duration: 605.146982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.670823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:02.065385Z","time spent":"605.425173ms","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T20:53:02.671036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.609718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.211\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T20:53:02.671104Z","caller":"traceutil/trace.go:171","msg":"trace[1287678120] range","detail":"{range_begin:/registry/masterleases/162.253.55.211; range_end:; response_count:1; response_revision:1190; }","duration":"606.713001ms","start":"2026-10-27T20:53:02.064373Z","end":"2026-10-27T20:53:02.671086Z","steps":["trace[1287678120] 'range keys from in-memory index tree' (duration: 606.456862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.671144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:02.064354Z","time spent":"606.778764ms","remote":"127.0.0.1:46082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.211\" "} {"level":"info","ts":"2026-10-27T20:53:02.797214Z","caller":"traceutil/trace.go:171","msg":"trace[507038741] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"202.801471ms","start":"2026-10-27T20:53:02.594364Z","end":"2026-10-27T20:53:02.797165Z","steps":["trace[507038741] 'process raft request' (duration: 202.564042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:02.797425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.489301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:53:02.797177Z","caller":"traceutil/trace.go:171","msg":"trace[208790338] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"125.235902ms","start":"2026-10-27T20:53:02.671908Z","end":"2026-10-27T20:53:02.797144Z","steps":["trace[208790338] 'read index received' (duration: 124.955801ms)","trace[208790338] 'applied index is now lower than readState.Index' (duration: 278.71µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:53:02.797498Z","caller":"traceutil/trace.go:171","msg":"trace[370198432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"125.603045ms","start":"2026-10-27T20:53:02.671879Z","end":"2026-10-27T20:53:02.797482Z","steps":["trace[370198432] 'agreement among raft nodes before linearized reading' (duration: 125.397057ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:02.955767Z","caller":"traceutil/trace.go:171","msg":"trace[1581361896] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"155.789746ms","start":"2026-10-27T20:53:02.799952Z","end":"2026-10-27T20:53:02.955742Z","steps":["trace[1581361896] 'process raft request' (duration: 155.751694ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:02.956035Z","caller":"traceutil/trace.go:171","msg":"trace[828903828] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"274.868514ms","start":"2026-10-27T20:53:02.681148Z","end":"2026-10-27T20:53:02.956016Z","steps":["trace[828903828] 'process raft request' (duration: 274.431657ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:03.164474Z","caller":"traceutil/trace.go:171","msg":"trace[1615963598] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"198.410085ms","start":"2026-10-27T20:53:02.965995Z","end":"2026-10-27T20:53:03.164405Z","steps":["trace[1615963598] 'process raft request' (duration: 174.482152ms)","trace[1615963598] 'compare' (duration: 23.742326ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:53:03.164396Z","caller":"traceutil/trace.go:171","msg":"trace[431308882] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"166.28958ms","start":"2026-10-27T20:53:02.998075Z","end":"2026-10-27T20:53:03.164364Z","steps":["trace[431308882] 'read index received' (duration: 142.41338ms)","trace[431308882] 'applied index is now lower than readState.Index' (duration: 23.87523ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:53:03.164784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.34996ms","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-10-27T20:53:03.164868Z","caller":"traceutil/trace.go:171","msg":"trace[1135606381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"185.476995ms","start":"2026-10-27T20:53:02.979365Z","end":"2026-10-27T20:53:03.164842Z","steps":["trace[1135606381] 'agreement among raft nodes before linearized reading' (duration: 185.119802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:03.239511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.250703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-27T20:53:03.239609Z","caller":"traceutil/trace.go:171","msg":"trace[1598958293] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1196; }","duration":"239.467241ms","start":"2026-10-27T20:53:03.000121Z","end":"2026-10-27T20:53:03.239588Z","steps":["trace[1598958293] 'agreement among raft nodes before linearized reading' (duration: 239.281414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:03.240477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.186522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:53:03.240599Z","caller":"traceutil/trace.go:171","msg":"trace[207671400] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1196; }","duration":"146.412481ms","start":"2026-10-27T20:53:03.094161Z","end":"2026-10-27T20:53:03.240573Z","steps":["trace[207671400] 'agreement among raft nodes before linearized reading' (duration: 146.185932ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:03.240129Z","caller":"traceutil/trace.go:171","msg":"trace[1828299012] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"236.238998ms","start":"2026-10-27T20:53:03.003875Z","end":"2026-10-27T20:53:03.240114Z","steps":["trace[1828299012] 'process raft request' (duration: 235.380746ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:03.810886Z","caller":"traceutil/trace.go:171","msg":"trace[822459129] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"214.504839ms","start":"2026-10-27T20:53:03.596333Z","end":"2026-10-27T20:53:03.810838Z","steps":["trace[822459129] 'process raft request' (duration: 214.316402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:16.13986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.751547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:53:16.139982Z","caller":"traceutil/trace.go:171","msg":"trace[441049375] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"569.946703ms","start":"2026-10-27T20:53:15.570009Z","end":"2026-10-27T20:53:16.139955Z","steps":["trace[441049375] 'process raft request' (duration: 299.018209ms)","trace[441049375] 'compare' (duration: 270.144925ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:53:16.140066Z","caller":"traceutil/trace.go:171","msg":"trace[1451463103] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"281.196743ms","start":"2026-10-27T20:53:15.858846Z","end":"2026-10-27T20:53:16.140043Z","steps":["trace[1451463103] 'process raft request' (duration: 281.12287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:16.140117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:15.569983Z","time spent":"570.065197ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:53:16.140278Z","caller":"traceutil/trace.go:171","msg":"trace[1180874976] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"566.749753ms","start":"2026-10-27T20:53:15.573519Z","end":"2026-10-27T20:53:16.140269Z","steps":["trace[1180874976] 'read index received' (duration: 295.525659ms)","trace[1180874976] 'applied index is now lower than readState.Index' (duration: 271.223344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:53:16.140453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.9326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-27T20:53:16.140478Z","caller":"traceutil/trace.go:171","msg":"trace[81226443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"566.994492ms","start":"2026-10-27T20:53:15.573476Z","end":"2026-10-27T20:53:16.14047Z","steps":["trace[81226443] 'agreement among raft nodes before linearized reading' (duration: 566.871728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:16.140496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:15.573459Z","time spent":"567.032934ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T20:53:16.140585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.68815ms","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-10-27T20:53:16.14061Z","caller":"traceutil/trace.go:171","msg":"trace[655842989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"560.754092ms","start":"2026-10-27T20:53:15.57985Z","end":"2026-10-27T20:53:16.140604Z","steps":["trace[655842989] 'agreement among raft nodes before linearized reading' (duration: 560.69618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:16.140627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:15.57983Z","time spent":"560.792804ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-27T20:53:16.140867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.031442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-10-27T20:53:16.140896Z","caller":"traceutil/trace.go:171","msg":"trace[134257088] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1277; }","duration":"542.082544ms","start":"2026-10-27T20:53:15.598807Z","end":"2026-10-27T20:53:16.14089Z","steps":["trace[134257088] 'agreement among raft nodes before linearized reading' (duration: 542.012911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:53:16.140917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:53:15.598795Z","time spent":"542.117124ms","remote":"127.0.0.1:46362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-10-27T20:53:16.427624Z","caller":"traceutil/trace.go:171","msg":"trace[198142605] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"280.944233ms","start":"2026-10-27T20:53:16.146644Z","end":"2026-10-27T20:53:16.427588Z","steps":["trace[198142605] 'process raft request' (duration: 280.817579ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:53:16.427706Z","caller":"traceutil/trace.go:171","msg":"trace[2050305666] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"281.37907ms","start":"2026-10-27T20:53:16.14626Z","end":"2026-10-27T20:53:16.427639Z","steps":["trace[2050305666] 'process raft request' (duration: 251.006942ms)","trace[2050305666] 'compare' (duration: 29.886359ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:58:07.35999Z","caller":"traceutil/trace.go:171","msg":"trace[2030992426] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"170.072282ms","start":"2026-10-27T20:58:07.189889Z","end":"2026-10-27T20:58:07.359961Z","steps":["trace[2030992426] 'process raft request' (duration: 169.701223ms)"],"step_count":1}