{"level":"warn","ts":"2026-10-14T17:36:27.65436Z","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-14T17:36:27.654481Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.69: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.69:2380","--initial-cluster=instance=https://162.253.55.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.69: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-14T17:36:27.65463Z","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-14T17:36:27.654648Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-14T17:36:27.6547Z","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-14T17:36:27.655604Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"]} {"level":"info","ts":"2026-10-14T17:36:27.655749Z","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.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.69: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-14T17:36:27.661029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.931559ms"} {"level":"info","ts":"2026-10-14T17:36:27.669996Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"905ccde8a677f143","cluster-id":"6fd07fe3af558460"} {"level":"info","ts":"2026-10-14T17:36:27.670088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:36:27.670114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:36:27.670131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 905ccde8a677f143 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:36:27.670147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:36:27.670192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"warn","ts":"2026-10-14T17:36:27.67724Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:36:27.67906Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:36:27.680309Z","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-14T17:36:27.686261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"905ccde8a677f143","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:36:27.686977Z","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-14T17:36:27.68713Z","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-14T17:36:27.687184Z","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-14T17:36:27.688417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 switched to configuration voters=(10402415638427988291)"} {"level":"info","ts":"2026-10-14T17:36:27.688803Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","added-peer-id":"905ccde8a677f143","added-peer-peer-urls":["https://162.253.55.69:2380"]} {"level":"info","ts":"2026-10-14T17:36:27.686505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"905ccde8a677f143","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:36:27.699606Z","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-14T17:36:27.699788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-14T17:36:27.699858Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.69:2380"} {"level":"info","ts":"2026-10-14T17:36:27.700107Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"905ccde8a677f143","initial-advertise-peer-urls":["https://162.253.55.69:2380"],"listen-peer-urls":["https://162.253.55.69:2380"],"advertise-client-urls":["https://162.253.55.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:36:27.700162Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:36:27.970451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:36:27.970619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:36:27.970656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgPreVoteResp from 905ccde8a677f143 at term 1"} {"level":"info","ts":"2026-10-14T17:36:27.970676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:36:27.970693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 received MsgVoteResp from 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-14T17:36:27.970715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"905ccde8a677f143 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:36:27.970767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 905ccde8a677f143 elected leader 905ccde8a677f143 at term 2"} {"level":"info","ts":"2026-10-14T17:36:27.971757Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"905ccde8a677f143","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.69:2379]}","request-path":"/0/members/905ccde8a677f143/attributes","cluster-id":"6fd07fe3af558460","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:36:27.971898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:36:27.972156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:36:27.972334Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:36:27.973678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6fd07fe3af558460","local-member-id":"905ccde8a677f143","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:36:27.973898Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:36:27.974224Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:36:27.974261Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:36:27.974355Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:36:27.974564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.69:2379"} {"level":"info","ts":"2026-10-14T17:36:27.976197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:36:46.270603Z","caller":"traceutil/trace.go:171","msg":"trace[946418684] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"115.072612ms","start":"2026-10-14T17:36:46.155493Z","end":"2026-10-14T17:36:46.270566Z","steps":["trace[946418684] 'process raft request' (duration: 114.811046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:36:46.627492Z","caller":"traceutil/trace.go:171","msg":"trace[1575782626] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"266.292271ms","start":"2026-10-14T17:36:46.361169Z","end":"2026-10-14T17:36:46.627461Z","steps":["trace[1575782626] 'read index received' (duration: 266.003794ms)","trace[1575782626] 'applied index is now lower than readState.Index' (duration: 287.347µs)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:36:46.627659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.467076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:36:46.627695Z","caller":"traceutil/trace.go:171","msg":"trace[1833272197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"266.589449ms","start":"2026-10-14T17:36:46.361094Z","end":"2026-10-14T17:36:46.627683Z","steps":["trace[1833272197] 'agreement among raft nodes before linearized reading' (duration: 266.473726ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:36:46.628029Z","caller":"traceutil/trace.go:171","msg":"trace[2020346934] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"300.961424ms","start":"2026-10-14T17:36:46.327052Z","end":"2026-10-14T17:36:46.628013Z","steps":["trace[2020346934] 'process raft request' (duration: 300.116143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:36:46.628448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:36:46.32703Z","time spent":"301.027566ms","remote":"127.0.0.1:33556","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-10-14T17:37:15.644858Z","caller":"traceutil/trace.go:171","msg":"trace[536833549] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"117.33188ms","start":"2026-10-14T17:37:15.527498Z","end":"2026-10-14T17:37:15.644829Z","steps":["trace[536833549] 'read index received' (duration: 73.130013ms)","trace[536833549] 'applied index is now lower than readState.Index' (duration: 44.200597ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:37:15.644957Z","caller":"traceutil/trace.go:171","msg":"trace[160915773] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"205.697842ms","start":"2026-10-14T17:37:15.439226Z","end":"2026-10-14T17:37:15.644923Z","steps":["trace[160915773] 'process raft request' (duration: 161.460465ms)","trace[160915773] 'compare' (duration: 43.966361ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:37:15.645049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.524993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-14T17:37:15.645092Z","caller":"traceutil/trace.go:171","msg":"trace[632511218] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:599; }","duration":"117.629696ms","start":"2026-10-14T17:37:15.527451Z","end":"2026-10-14T17:37:15.64508Z","steps":["trace[632511218] 'agreement among raft nodes before linearized reading' (duration: 117.494713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:37:16.401141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.170465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xwb9s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-14T17:37:16.401232Z","caller":"traceutil/trace.go:171","msg":"trace[622762578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xwb9s; range_end:; response_count:1; response_revision:601; }","duration":"322.317188ms","start":"2026-10-14T17:37:16.078892Z","end":"2026-10-14T17:37:16.401209Z","steps":["trace[622762578] 'range keys from in-memory index tree' (duration: 321.95586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:37:16.401274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:37:16.07887Z","time spent":"322.394239ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-xwb9s\" "} {"level":"info","ts":"2026-10-14T17:37:16.526895Z","caller":"traceutil/trace.go:171","msg":"trace[1430483987] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"165.150028ms","start":"2026-10-14T17:37:16.361718Z","end":"2026-10-14T17:37:16.526868Z","steps":["trace[1430483987] 'read index received' (duration: 164.554266ms)","trace[1430483987] 'applied index is now lower than readState.Index' (duration: 594.602µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:37:16.527072Z","caller":"traceutil/trace.go:171","msg":"trace[482658640] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"434.810369ms","start":"2026-10-14T17:37:16.092227Z","end":"2026-10-14T17:37:16.527037Z","steps":["trace[482658640] 'process raft request' (duration: 434.062494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:37:16.527244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:37:16.0922Z","time spent":"434.950352ms","remote":"127.0.0.1:33388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T17:37:16.527217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.482744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:37:16.527321Z","caller":"traceutil/trace.go:171","msg":"trace[975713550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"165.626076ms","start":"2026-10-14T17:37:16.361674Z","end":"2026-10-14T17:37:16.5273Z","steps":["trace[975713550] 'agreement among raft nodes before linearized reading' (duration: 165.408232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:37:16.563326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.584111ms","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-14T17:37:16.56339Z","caller":"traceutil/trace.go:171","msg":"trace[314624293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"116.683822ms","start":"2026-10-14T17:37:16.44669Z","end":"2026-10-14T17:37:16.563374Z","steps":["trace[314624293] 'agreement among raft nodes before linearized reading' (duration: 116.518499ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:37:16.563794Z","caller":"traceutil/trace.go:171","msg":"trace[1408530756] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"140.836003ms","start":"2026-10-14T17:37:16.422945Z","end":"2026-10-14T17:37:16.563781Z","steps":["trace[1408530756] 'process raft request' (duration: 140.13836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:37:32.498499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.303726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:37:32.498679Z","caller":"traceutil/trace.go:171","msg":"trace[979829880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"137.549069ms","start":"2026-10-14T17:37:32.361103Z","end":"2026-10-14T17:37:32.498652Z","steps":["trace[979829880] 'range keys from in-memory index tree' (duration: 137.115832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:38:03.536339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.17657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:38:03.536457Z","caller":"traceutil/trace.go:171","msg":"trace[1187306917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"174.350513ms","start":"2026-10-14T17:38:03.362084Z","end":"2026-10-14T17:38:03.536434Z","steps":["trace[1187306917] 'range keys from in-memory index tree' (duration: 174.096549ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:38:50.63287Z","caller":"traceutil/trace.go:171","msg":"trace[1991148948] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"330.681278ms","start":"2026-10-14T17:38:50.302162Z","end":"2026-10-14T17:38:50.632843Z","steps":["trace[1991148948] 'process raft request' (duration: 231.800334ms)","trace[1991148948] 'compare' (duration: 98.751213ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:38:50.633142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T17:38:50.302136Z","time spent":"330.931832ms","remote":"127.0.0.1:33556","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-10-14T17:38:50.632845Z","caller":"traceutil/trace.go:171","msg":"trace[1718203376] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"272.592283ms","start":"2026-10-14T17:38:50.360228Z","end":"2026-10-14T17:38:50.63282Z","steps":["trace[1718203376] 'read index received' (duration: 173.677899ms)","trace[1718203376] 'applied index is now lower than readState.Index' (duration: 98.913064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:38:50.633027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.786156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:38:50.633617Z","caller":"traceutil/trace.go:171","msg":"trace[321113868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1840; }","duration":"273.421495ms","start":"2026-10-14T17:38:50.36018Z","end":"2026-10-14T17:38:50.633601Z","steps":["trace[321113868] 'agreement among raft nodes before linearized reading' (duration: 272.796846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:38:50.633736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.690566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T17:38:50.633788Z","caller":"traceutil/trace.go:171","msg":"trace[584825771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1840; }","duration":"102.744637ms","start":"2026-10-14T17:38:50.53103Z","end":"2026-10-14T17:38:50.633774Z","steps":["trace[584825771] 'agreement among raft nodes before linearized reading' (duration: 102.588895ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:39:18.938316Z","caller":"traceutil/trace.go:171","msg":"trace[972846713] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2125; }","duration":"127.484439ms","start":"2026-10-14T17:39:18.810806Z","end":"2026-10-14T17:39:18.938291Z","steps":["trace[972846713] 'read index received' (duration: 29.930292ms)","trace[972846713] 'applied index is now lower than readState.Index' (duration: 97.553167ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:39:18.938638Z","caller":"traceutil/trace.go:171","msg":"trace[223645801] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"130.6853ms","start":"2026-10-14T17:39:18.807722Z","end":"2026-10-14T17:39:18.938408Z","steps":["trace[223645801] 'process raft request' (duration: 101.050222ms)","trace[223645801] 'compare' (duration: 29.336324ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:39:18.93882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.989315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T17:39:18.93891Z","caller":"traceutil/trace.go:171","msg":"trace[100730197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2047; }","duration":"128.116896ms","start":"2026-10-14T17:39:18.810776Z","end":"2026-10-14T17:39:18.938892Z","steps":["trace[100730197] 'agreement among raft nodes before linearized reading' (duration: 127.676791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:40:29.461345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.385212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-14T17:40:29.461334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.571433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:40:29.461397Z","caller":"traceutil/trace.go:171","msg":"trace[1846605401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2531; }","duration":"193.481416ms","start":"2026-10-14T17:40:29.267906Z","end":"2026-10-14T17:40:29.461387Z","steps":["trace[1846605401] 'range keys from in-memory index tree' (duration: 193.263207ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:40:29.461418Z","caller":"traceutil/trace.go:171","msg":"trace[335910125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2531; }","duration":"100.689778ms","start":"2026-10-14T17:40:29.36071Z","end":"2026-10-14T17:40:29.461399Z","steps":["trace[335910125] 'range keys from in-memory index tree' (duration: 100.503381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:41:02.065819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.958096ms","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-14T17:41:02.065964Z","caller":"traceutil/trace.go:171","msg":"trace[1862607140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2711; }","duration":"126.130102ms","start":"2026-10-14T17:41:01.939785Z","end":"2026-10-14T17:41:02.065915Z","steps":["trace[1862607140] 'range keys from in-memory index tree' (duration: 125.77344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:43:18.503834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.91978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T17:43:18.503913Z","caller":"traceutil/trace.go:171","msg":"trace[1706508633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3416; }","duration":"124.058934ms","start":"2026-10-14T17:43:18.379837Z","end":"2026-10-14T17:43:18.503896Z","steps":["trace[1706508633] 'range keys from in-memory index tree' (duration: 123.736706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:44:17.221994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.342639ms","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-14T17:44:17.222081Z","caller":"traceutil/trace.go:171","msg":"trace[1527456103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3823; }","duration":"131.477762ms","start":"2026-10-14T17:44:17.090586Z","end":"2026-10-14T17:44:17.222064Z","steps":["trace[1527456103] 'range keys from in-memory index tree' (duration: 131.148364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:26.741927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.146017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-14T17:45:26.742025Z","caller":"traceutil/trace.go:171","msg":"trace[901984817] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4787; }","duration":"112.251779ms","start":"2026-10-14T17:45:26.629753Z","end":"2026-10-14T17:45:26.742005Z","steps":["trace[901984817] 'range keys from in-memory index tree' (duration: 111.370143ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:28.508004Z","caller":"traceutil/trace.go:171","msg":"trace[904164242] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"105.971292ms","start":"2026-10-14T17:45:28.40201Z","end":"2026-10-14T17:45:28.507982Z","steps":["trace[904164242] 'process raft request' (duration: 105.86083ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:28.52211Z","caller":"traceutil/trace.go:171","msg":"trace[1045774910] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"119.836863ms","start":"2026-10-14T17:45:28.402246Z","end":"2026-10-14T17:45:28.522083Z","steps":["trace[1045774910] 'process raft request' (duration: 119.029359ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:28.522303Z","caller":"traceutil/trace.go:171","msg":"trace[1916009538] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"119.968896ms","start":"2026-10-14T17:45:28.402321Z","end":"2026-10-14T17:45:28.52229Z","steps":["trace[1916009538] 'process raft request' (duration: 119.08234ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:33.138729Z","caller":"traceutil/trace.go:171","msg":"trace[774370898] linearizableReadLoop","detail":"{readStateIndex:5081; appliedIndex:5080; }","duration":"147.196158ms","start":"2026-10-14T17:45:32.991506Z","end":"2026-10-14T17:45:33.138702Z","steps":["trace[774370898] 'read index received' (duration: 147.004334ms)","trace[774370898] 'applied index is now lower than readState.Index' (duration: 190.234µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:45:33.138923Z","caller":"traceutil/trace.go:171","msg":"trace[785645241] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"165.769633ms","start":"2026-10-14T17:45:32.973076Z","end":"2026-10-14T17:45:33.138845Z","steps":["trace[785645241] 'process raft request' (duration: 165.395077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:33.139033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.504703ms","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-10-14T17:45:33.13908Z","caller":"traceutil/trace.go:171","msg":"trace[1896499302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4894; }","duration":"147.611415ms","start":"2026-10-14T17:45:32.991455Z","end":"2026-10-14T17:45:33.139067Z","steps":["trace[1896499302] 'agreement among raft nodes before linearized reading' (duration: 147.403372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:33.139517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.67805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:45:33.139842Z","caller":"traceutil/trace.go:171","msg":"trace[1293916051] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4894; }","duration":"110.035166ms","start":"2026-10-14T17:45:33.029586Z","end":"2026-10-14T17:45:33.139621Z","steps":["trace[1293916051] 'agreement among raft nodes before linearized reading' (duration: 109.308883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:33.512394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.804671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:45:33.512484Z","caller":"traceutil/trace.go:171","msg":"trace[1464279229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4897; }","duration":"151.937013ms","start":"2026-10-14T17:45:33.360522Z","end":"2026-10-14T17:45:33.512459Z","steps":["trace[1464279229] 'range keys from in-memory index tree' (duration: 151.702369ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:46.612365Z","caller":"traceutil/trace.go:171","msg":"trace[1373605465] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"185.529473ms","start":"2026-10-14T17:45:46.426813Z","end":"2026-10-14T17:45:46.612342Z","steps":["trace[1373605465] 'read index received' (duration: 145.577929ms)","trace[1373605465] 'applied index is now lower than readState.Index' (duration: 39.950184ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:45:46.612584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.747378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-14T17:45:46.612615Z","caller":"traceutil/trace.go:171","msg":"trace[1564757609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5062; }","duration":"185.830309ms","start":"2026-10-14T17:45:46.426774Z","end":"2026-10-14T17:45:46.612605Z","steps":["trace[1564757609] 'agreement among raft nodes before linearized reading' (duration: 185.666856ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:46.647627Z","caller":"traceutil/trace.go:171","msg":"trace[79432714] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"217.128978ms","start":"2026-10-14T17:45:46.430479Z","end":"2026-10-14T17:45:46.647608Z","steps":["trace[79432714] 'process raft request' (duration: 216.918934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:45:46.647786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.443838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-14T17:45:46.647827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.302345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-14T17:45:46.64788Z","caller":"traceutil/trace.go:171","msg":"trace[937091473] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5063; }","duration":"112.584141ms","start":"2026-10-14T17:45:46.535271Z","end":"2026-10-14T17:45:46.647855Z","steps":["trace[937091473] 'agreement among raft nodes before linearized reading' (duration: 112.436559ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:46.64788Z","caller":"traceutil/trace.go:171","msg":"trace[1083614828] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5063; }","duration":"103.376326ms","start":"2026-10-14T17:45:46.544489Z","end":"2026-10-14T17:45:46.647865Z","steps":["trace[1083614828] 'agreement among raft nodes before linearized reading' (duration: 103.167213ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:45:47.009738Z","caller":"traceutil/trace.go:171","msg":"trace[1441723293] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"100.029136ms","start":"2026-10-14T17:45:46.909657Z","end":"2026-10-14T17:45:47.009686Z","steps":["trace[1441723293] 'process raft request' (duration: 56.779084ms)","trace[1441723293] 'compare' (duration: 43.12591ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:46:28.018228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2850} {"level":"info","ts":"2026-10-14T17:46:28.1019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2850,"took":"82.031883ms","hash":1911687707,"current-db-size-bytes":53100544,"current-db-size":"53 MB","current-db-size-in-use-bytes":28532736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T17:46:28.101966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1911687707,"revision":2850,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:51:28.025146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5447} {"level":"info","ts":"2026-10-14T17:51:28.115408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5447,"took":"88.796903ms","hash":2090348963,"current-db-size-bytes":53100544,"current-db-size":"53 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-14T17:51:28.115493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2090348963,"revision":5447,"compact-revision":2850}