Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15219236 kB
MemAvailable 30426980 kB
Buffers 0 kB
Cached 16509248 kB
SwapCached 11672 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-31 10:37:23
DTS_END                         2022-12-31 10:37:23.7310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37886
TRN_ID                          233215
ATT_ID                          673
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8880.88 20625 1065706
customer order: creation 186.19 465 22343
customer order: refuse 16.18 404 1942
order to supplier: creation 49.03 1250 5884
order to supplier: removal 3.31 158338 397
invoice (draft): creation 47.99 2952 5759
invoice (draft): removal 47.28 993 5673
invoice accept: apply 44.10 24927 5292
invoice accept: cancel 42.47 1191 5096
customer reserve: creation 3998.97 242 479876
customer reserve: removal 4145.84 99 497501
realization accept: apply 89.70 281 10764
realization accept: cancel 68.33 184 8199
payment from customer: creation 65.32 173 7838
payment from customer: removal 6.16 128 739
payment to supplier: creation 53.19 206 6383
payment to supplier: removal 3.93 170 471
service: total inventory turnovers 4.13 3755 495
service: total monetary turnovers 7.71 391 925
service: aggregate perf. data 1.04 14420 125
service: refresh index statistics 0.03 201927 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-12-2022 10:38:56 to 31-12-2022 10:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7222
TEST_TIME 2 5688
TEST_TIME 3 5928
TEST_TIME 4 6320
TEST_TIME 5 6876
TEST_TIME 6 6922
TEST_TIME 7 7551
TEST_TIME 8 7370
TEST_TIME 9 7463
TEST_TIME 10 7294
TEST_TIME 11 7254
TEST_TIME 12 7340
TEST_TIME 13 7403
TEST_TIME 14 7271
TEST_TIME 15 7280
TEST_TIME 16 7153
TEST_TIME 17 7538
TEST_TIME 18 7501
TEST_TIME 19 7656
TEST_TIME 20 8000
TEST_TIME 21 8032
TEST_TIME 22 8098
TEST_TIME 23 8120
TEST_TIME 24 8281
TEST_TIME 25 8534
TEST_TIME 26 8937
TEST_TIME 27 9203
TEST_TIME 28 9497
TEST_TIME 29 9700
TEST_TIME 30 9961
TEST_TIME 31 10436
TEST_TIME 32 10745
TEST_TIME 33 10932
TEST_TIME 34 10931
TEST_TIME 35 10755
TEST_TIME 36 10649
TEST_TIME 37 10609
TEST_TIME 38 10510
TEST_TIME 39 10440
TEST_TIME 40 10365
TEST_TIME 41 10209
TEST_TIME 42 10101
TEST_TIME 43 9999
TEST_TIME 44 10005
TEST_TIME 45 9973
TEST_TIME 46 9941
TEST_TIME 47 10016
TEST_TIME 48 9934
TEST_TIME 49 9836
TEST_TIME 50 9724
TEST_TIME 51 9664
TEST_TIME 52 9611
TEST_TIME 53 9609
TEST_TIME 54 9571
TEST_TIME 55 9510
TEST_TIME 56 9607
TEST_TIME 57 9542
TEST_TIME 58 9488
TEST_TIME 59 9572
TEST_TIME 60 9680
TEST_TIME 61 9654
TEST_TIME 62 9621
TEST_TIME 63 9592
TEST_TIME 64 9691
TEST_TIME 65 9738
TEST_TIME 66 9782
TEST_TIME 67 9697
TEST_TIME 68 9592
TEST_TIME 69 9495
TEST_TIME 70 9516
TEST_TIME 71 9435
TEST_TIME 72 9394
TEST_TIME 73 9351
TEST_TIME 74 9301
TEST_TIME 75 9247
TEST_TIME 76 9186
TEST_TIME 77 9194
TEST_TIME 78 9156
TEST_TIME 79 9168
TEST_TIME 80 9135
TEST_TIME 81 9129
TEST_TIME 82 9159
TEST_TIME 83 9120
TEST_TIME 84 9201
TEST_TIME 85 9313
TEST_TIME 86 9322
TEST_TIME 87 9345
TEST_TIME 88 9282
TEST_TIME 89 9236
TEST_TIME 90 9231
TEST_TIME 91 9246
TEST_TIME 92 9252
TEST_TIME 93 9221
TEST_TIME 94 9149
TEST_TIME 95 9115
TEST_TIME 96 9118
TEST_TIME 97 9109
TEST_TIME 98 9124
TEST_TIME 99 9090
TEST_TIME 100 9122
TEST_TIME 101 9105
TEST_TIME 102 9119
TEST_TIME 103 9121
TEST_TIME 104 9188
TEST_TIME 105 9153
TEST_TIME 106 9236
TEST_TIME 107 9249
TEST_TIME 108 9246
TEST_TIME 109 9203
TEST_TIME 110 9153
TEST_TIME 111 9138
TEST_TIME 112 9108
TEST_TIME 113 9109
TEST_TIME 114 9076
TEST_TIME 115 9051
TEST_TIME 116 8999
TEST_TIME 117 9013
TEST_TIME 118 8995
TEST_TIME 119 8970
TEST_TIME 120 8908

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2022 10:39:00 to 31-12-2022 10:39:03.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1508441 1508441 0 0.00 0 24607 14 0 0
doc_list_biud 1508439 1508439 0 0.00 0 77579 39 0 0
sp_add_invoice_to_stock 5511 5292 219 3.97 39 202320 24927 0 219
sp_cancel_adding_invoice 5096 5096 0 0.00 6 101963 1192 0 0
sp_cancel_client_order 1942 1942 0 0.00 5 25122 405 0 0
sp_cancel_customer_reserve 497501 497501 0 0.00 1 31799 100 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 4 10587 129 0 0
sp_cancel_pay_to_supplier 471 471 0 0.00 3 16519 171 0 0
sp_cancel_supplier_invoice 5691 5673 18 0.31 2 77595 994 0 18
sp_cancel_supplier_order 397 397 0 0.00 9 1378439 158339 0 0
sp_cancel_write_off 8199 8199 0 0.00 2 8934 185 0 0
sp_client_order 22343 22343 0 0.00 5 27590 465 0 0
sp_customer_reserve 479876 479876 0 0.00 0 171421 243 0 0
sp_fill_shopping_cart 33982 33982 0 0.00 1 12876 78 0 0
sp_fill_shopping_cart_clo_res 479861 479861 0 0.00 0 14406 19 0 0
sp_get_clo_for_invoice 5241 5241 0 0.00 0 25684 250 0 0
sp_kill_cost_storno 14505 14505 0 0.00 0 10971 72 0 0
sp_kill_qstorno_ret_qs2qd 1009271 1009271 0 0.00 0 77575 44 0 0
sp_kill_qty_storno 534813 534813 0 0.00 0 77578 100 0 0
sp_lock_selected_doc 22195 22195 0 0.00 0 12052 7 0 0
sp_make_cost_storno 30226 30226 0 0.00 0 24601 77 0 0
sp_multiply_rows_for_pdistr 30226 30226 0 0.00 0 7001 36 0 0
sp_multiply_rows_for_qdistr 22343 22343 0 0.00 0 11052 181 0 0
sp_pay_from_customer 7838 7838 0 0.00 5 13334 174 0 0
sp_pay_to_supplier 6383 6383 0 0.00 6 24615 206 0 0
sp_payment_common 14221 14221 0 0.00 2 6568 46 0 0
sp_qd_handle_on_cancel_clo 1942 1942 0 0.00 0 9872 309 0 0
sp_qd_handle_on_invoice_upd_sts 10337 10337 0 0.00 0 18300 484 0 0
sp_qd_handle_on_reserve_upd_sts 10764 10764 0 0.00 0 14103 67 0 0
sp_reserve_write_off 10764 10764 0 0.00 8 18792 282 0 0
sp_supplier_invoice 5759 5759 0 0.00 11 103156 2953 0 0
sp_supplier_order 5884 5884 0 0.00 7 24210 1250 0 0
srv_aggregate_perf_data 125 125 0 0.00 2355 155758 14420 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 740 173989 39299 0 0
srv_find_qd_qs_mism 1013916 1013916 0 0.00 0 5209 11 0 0
srv_make_invnt_saldo 495 495 0 0.00 40 180194 3756 0 0
srv_make_money_saldo 925 925 0 0.00 0 29913 392 0 0
srv_recalc_idx_stat 4 4 0 0.00 61873 314244 201928 0 0
t$perf-abend:sp_add_invoice_to_stock 219 219 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5292 5292 0 0.00 1 14521 858 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 6372 469 0 0
t$perf-norm:sp_cancel_client_order 1942 1942 0 0.00 0 2086 25 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 511 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 434 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 471 471 0 0.00 0 328 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 1504 33 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 77638 6650 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 2519 18 0 0
t$perf-norm:sp_client_order 22343 22343 0 0.00 0 9386 34 0 0
t$perf-norm:sp_customer_reserve 1157 1157 0 0.00 0 600 13 0 0
t$perf-norm:sp_pay_from_customer 7838 7838 0 0.00 0 6335 17 0 0
t$perf-norm:sp_pay_to_supplier 6383 6383 0 0.00 0 5841 17 0 0
t$perf-norm:sp_reserve_write_off 10764 10764 0 0.00 0 4408 21 0 0
t$perf-norm:sp_supplier_invoice 5759 5759 0 0.00 0 4821 27 0 0
t$perf-norm:sp_supplier_order 5884 5884 0 0.00 0 5095 32 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 720 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 705 22 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 834 16 0 0
t$perf-norm:srv_make_money_saldo 925 925 0 0.00 0 343 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 191 56 0 0
v_add_invoice_to_stock 10918 10480 438 4.01 0 24327 30 0 438
v_all_customers 41254 41254 0 0.00 0 114 4 0 0
v_all_suppliers 32598 32598 0 0.00 0 86 4 0 0
v_all_wares 44686 44686 0 0.00 0 156 4 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 237 13 0 0
v_cancel_client_order 3884 3884 0 0.00 0 24338 20 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 2912 15 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 10749 18 0 0
v_cancel_payment_to_supplier 942 942 0 0.00 0 15858 35 0 0
v_cancel_supplier_invoice 910 874 36 3.95 0 11435 44 0 36
v_cancel_supplier_order 794 794 0 0.00 0 3100 14 0 0
v_cancel_write_off 2140 2140 0 0.00 0 8267 29 0 0
v_max_id_clo_ord 5884 5884 0 0.00 0 4242 5 0 0
v_max_id_clo_res 1157 1157 0 0.00 0 2150 15 0 0
v_max_id_ord_sup 5755 5755 0 0.00 0 5622 8 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 431 7 0 0
v_max_non_paid_realizn 4009 4009 0 0.00 0 4781 8 0 0
v_min_id_clo_ord 5884 5884 0 0.00 0 2726 7 0 0
v_min_id_clo_res 1157 1157 0 0.00 0 7459 17 0 0
v_min_id_ord_sup 5755 5755 0 0.00 0 7484 14 0 0
v_min_non_paid_invoice 6383 6383 0 0.00 0 5190 16 0 0
v_min_non_paid_realizn 7838 7838 0 0.00 0 6565 19 0 0
v_our_firm 11090 11090 0 0.00 0 69 5 0 0
v_reserve_write_off 21528 21528 0 0.00 0 17962 15 0 0
x_lock_depdocs_on_canc_invoice 10332 10332 0 0.00 0 18239 122 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7331 320 0 0
x_make_qstorno_1000_1200 5884 5884 0 0.00 2 24201 1089 0 0
x_make_qstorno_1200_2000 5759 5759 0 0.00 4 98367 2744 0 0
x_make_qstorno_2100_3300 479876 479876 0 0.00 0 171415 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2022 10:39:04 to 31-12-2022 10:39:08.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 30331 8192000000 9734730000 10033188864 810776784 9.005 100 88 176 0 840074928 8146912 21151232
08:10:02 18145 8192000000 9759135968 10067730432 832855232 9.228 100 87 174 0 865599696 11821600 20922864
08:11:23 18010 8192000000 9798321952 10115743744 864126704 9.541 100 88 176 0 901995696 17397328 20471664
08:12:45 8993 8192000000 9853017344 10183049216 907074864 9.968 100 88 176 0 962504432 36123184 19306384
08:13:58 3704 8192000000 9913269584 10257076224 954160560 10.432 100 80 157 0 1028601984 57755520 16685904
08:15:05 1946 8192000000 9968778112 10319962112 988087808 10.763 100 88 172 0 1087807664 82374128 17345728
08:16:09 1628 8192000000 9970967936 10334584832 1011410320 10.989 100 81 152 0 1090909136 64421200 15077616
08:17:12 1150 8192000000 10010284784 10383507456 1038615584 11.251 100 93 178 1 1129724992 72635712 18473696
08:18:15 1024 8192000000 10026182736 10400088064 1058146848 11.439 100 95 180 1 1151894208 74339296 19408064
08:19:17 1402 8192000000 10041820352 10417971200 1064581728 11.500 100 93 176 2 1161106272 77856064 18668480
08:20:21 933 8192000000 10064050736 10444132352 1069190784 11.544 100 97 193 0 1181968496 93878832 18898880
08:21:25 1348 8192000000 10052365968 10436386816 1076550752 11.615 100 97 184 1 1171719232 76509248 18659232
08:22:29 1147 8192000000 10033066240 10419478528 1084291824 11.688 100 96 185 2 1156183648 52319376 19572448
08:23:31 1081 8192000000 10052695456 10439626752 1089900192 11.742 100 94 179 2 1173722944 65503008 18319744
08:24:33 1164 8192000000 10062892624 10455441408 1091788304 11.760 100 85 161 1 1183019968 75954656 15277008
08:25:36 1153 8192000000 10063990000 10452131840 1098039584 11.819 100 98 183 0 1180863600 64787264 18036752
08:26:38 1127 8192000000 10074435648 10463760384 1103062352 11.867 100 96 184 1 1194888736 72706192 19120192
08:27:40 942 8192000000 10046780592 10434895872 1102704352 11.863 100 95 188 1 1168757824 46955696 19097776
08:28:42 1154 8192000000 10050790128 10434621440 1090989552 11.752 100 91 173 0 1170476688 61704864 17782272
08:29:44 1041 8192000000 10053974688 10444816384 1100324304 11.841 100 86 160 1 1180881744 64574640 15982800
08:30:46 1192 8192000000 10050781648 10436243456 1089206240 11.735 100 95 169 0 1179071536 71752992 18112304
08:31:48 1314 8192000000 10032896352 10410295296 1066586208 11.519 100 96 183 4 1156878032 71748720 18543104
08:32:50 1187 8192000000 10024131664 10406715392 1070355168 11.555 100 96 184 0 1147145152 59186368 17603616
08:33:53 1188 8192000000 9960533344 10332565504 1033240448 11.200 99 94 171 0 1084433952 34554800 16638704
08:34:55 986 8192000000 10010141440 10389577728 1054811296 11.407 100 92 170 1 1131609280 57844960 18953024
08:35:57 1099 8192000000 9955877696 10334457856 1028331648 11.152 100 76 141 3 1080821872 38070688 14419536
08:36:59 5076 8192000000 9998547760 10382880768 1043372208 11.297 99 57 101 1 1114973504 60972432 10628864
08:38:09 1320 8192000000 10022305328 10400333824 1050928256 11.370 100 75 142 1 1145742976 80402192 14412528
08:39:16 17765 8192000000 10018578032 10406293504 1065438448 11.508 100 68 130 1 1144771728 65905968 13427312
08:40:37 7953 8192000000 10041749584 10423123968 1076062992 11.610 100 71 133 1 1165218528 75281376 13874160
08:41:49 10213 8192000000 10075665632 10481405952 1088550752 11.729 100 56 104 0 1205184448 106170496 10463200
08:43:03 2028 8192000000 10100907584 10497662976 1110357616 11.936 100 77 140 2 1216202928 90363040 15482272
08:44:08 985 8192000000 10153406080 10545438720 1124433952 12.069 100 95 187 1 1265324496 121809696 19080848
08:45:11 4755 8192000000 10153381600 10550976512 1136037024 12.178 100 77 147 1 1268629072 117141184 15450864
08:46:23 1155 8192000000 10194850928 10596638720 1146456176 12.276 100 91 170 0 1306303088 140702960 19143952
08:47:27 4193 8192000000 10213072480 10615840768 1146784656 12.279 100 78 152 0 1327349440 163186960 17377824
08:48:38 2552 8192000000 10232568672 10630430720 1158192768 12.386 100 88 167 0 1343381712 166506096 18682848
08:49:45 2747 8192000000 10274474592 10689298432 1154846416 12.355 99 85 158 0 1382058240 208933120 18278704
08:50:50 1750 8192000000 10296860672 10713264128 1176753824 12.560 100 84 160 1 1410556896 214961760 18841312
08:51:55 2010 8192000000 10345620448 10745802752 1176248080 12.555 100 92 184 0 1457798704 260102544 21448080
08:53:03 1717 8192000000 10378214112 10790469632 1186187104 12.648 100 83 163 1 1492942800 287570880 19184816
08:54:09 4012 8192000000 10395446336 10838351872 1187964400 12.664 100 92 182 0 1516265088 306255360 22045328
08:55:17 723 8192000000 10430715440 10841464832 1175764176 12.551 100 92 181 0 1537994512 340531888 21698448
08:56:24 865 8192000000 10440100576 10876186624 1180403616 12.594 100 97 186 0 1546599056 342477136 23718304
08:57:28 1705 8192000000 10437231472 10883129344 1184609824 12.633 100 93 185 0 1540095728 333526688 21959216
08:58:35 901 8192000000 10467053456 10889183232 1196397040 12.743 100 97 193 0 1573653216 355086960 22169216
08:59:39 2200 8192000000 10510148304 10916564992 1190663200 12.690 100 93 184 0 1612982112 399885776 22433136
09:00:45 2075 8192000000 10494274416 10935083008 1176555104 12.558 100 89 172 3 1603541120 405710512 21275504
09:01:48 9463 8192000000 10522669200 10983116800 1182484592 12.613 100 61 116 0 1629474832 431947216 15043024
09:03:02 8930 8192000000 10471568240 10955657216 1177306080 12.565 100 50 99 1 1582301936 392006192 12989664
09:04:15 15570 8192000000 10420651888 10900901888 1158711104 12.391 99 38 76 1 1522856048 353914320 10230624
09:05:35 7245 8192000000 10323891712 10791022592 1166510544 12.464 100 51 93 1 1454255120 276563904 11180672
09:06:48 15746 8192000000 10325240048 10780090368 1146097072 12.273 99 21 42 0 1427201776 275534144 5570560
09:08:06 41056 8192000000 10191802832 10694905856 1128792016 12.110 97 17 34 0 1325644128 192740464 4111648
09:09:57 99882 8192000000 10064253712 10514518016 1022714416 11.098 89 21 36 0 1061974480 35751056 3509008
09:12:38 147701 8192000000 10082104368 10548805632 1113748576 11.968 98 27 54 0 1196528112 77287584 5491952
09:16:07 107823 8192000000 10096496592 10548715520 1115540640 11.985 97 31 60 0 1196967680 75219920 6207120
09:19:32 35455 8192000000 10124053232 10563092480 1134844560 12.167 100 28 56 2 1233265296 91470992 6949744
09:21:08 28089 8192000000 10136109264 10593067008 1124398480 12.069 98 36 71 0 1225787680 94216176 7173024
09:22:37 173989 8192000000 10057426144 10546081792 1079943184 11.647 95 11 23 0 1137085648 55168960 1973504
09:26:42 54767 8192000000 10114231312 10578755584 1144516912 12.258 100 19 34 0 1211617552 62894016 4206624
09:28:38 55557 8192000000 10075807792 10553556992 1116629344 11.995 98 20 39 0 1186617600 65136288 4851968
09:30:35 72351 8192000000 10070724336 10531516416 1118627552 12.014 98 25 50 1 1171367600 47092800 5647248
09:32:55 94162 8192000000 10116642496 10562617344 1117894464 12.007 97 21 40 0 1197853264 75672480 4286320
09:35:29 50242 8192000000 10153646240 10598703104 1137377536 12.191 99 37 70 0 1253449568 108513584 7558448
09:37:23 35104 8192000000 10082195696 10517934080 1130000736 12.121 99 32 60 2 1165262384 29890928 5370720
09:39:01 10401 8192000000 10092661952 10521878528 1118773040 12.015 98 45 90 0 1173803856 45903568 9127248
09:40:14 42559 8192000000 10153344608 10599366656 1141182064 12.227 100 37 74 0 1245031712 96486976 7362672
09:41:57 69941 8192000000 10113254064 10561449984 1122892480 12.054 98 30 56 0 1187684352 58574336 6217536
09:44:25 70611 8192000000 10084785008 10516238336 1109186128 11.925 97 53 104 0 1161661088 40273984 12200976
09:46:37 35764 8192000000 10097815120 10550894592 1141990160 12.234 100 46 90 0 1211652336 60617504 9044672
09:48:13 46624 8192000000 10066930960 10535849984 1138707312 12.203 100 10 20 0 1177404768 36692240 2005216
09:50:01 84060 8192000000 10056371600 10531885056 1135534352 12.174 99 11 22 0 1173393056 35764256 2094448
09:52:34 71711 8192000000 10084807680 10529861632 1069699264 11.549 94 10 20 0 1105309872 33152608 2458000
09:55:26 25135 8192000000 10133815312 10579025920 1136411072 12.182 100 28 55 1 1224738032 82553056 5773904
09:57:04 37571 8192000000 10142286224 10592182272 1136785152 12.185 100 33 66 0 1244287824 99861200 7641472
09:58:46 9398 8192000000 10154353024 10587623424 1118407072 12.012 98 34 64 1 1237392608 111708464 7277072
09:59:59 57116 8192000000 10196958432 10627747840 1113485760 11.965 97 29 56 0 1243844544 123915536 6443248
10:01:56 27340 8192000000 10097011744 10535411712 1096468000 11.804 98 41 80 1 1206384784 100889440 9027344
10:03:24 30101 8192000000 10102326528 10545827840 1095937648 11.799 98 38 76 0 1178924144 75495344 7491152
10:04:59 99312 8192000000 10065567104 10514378752 1096051616 11.800 99 36 70 3 1164950032 60980976 7917440
10:07:38 73681 8192000000 10088297680 10572570624 1120069088 12.028 100 24 48 0 1192201904 67680512 4452304
10:09:53 66976 8192000000 10059262368 10534481920 1122589424 12.051 99 14 26 0 1163029584 37666240 2773920
10:12:03 43162 8192000000 10075739024 10521362432 1135820832 12.176 100 19 36 1 1173172768 33495360 3856576
10:13:49 21538 8192000000 10085937408 10529595392 1117489008 12.003 98 29 58 0 1158208704 34432336 6287360
10:15:10 83448 8192000000 10089339360 10529251328 1084829920 11.693 96 27 52 0 1109499168 19608736 5060512
10:17:44 23515 8192000000 10093519296 10533306368 1117107952 12.000 99 44 89 1 1173599584 46815264 9676368
10:19:10 57550 8192000000 10095305264 10563702784 1100458960 11.842 97 32 60 1 1187669792 80558960 6651872
10:21:10 45348 8192000000 10110904832 10598719488 1135605104 12.174 100 31 62 0 1219221424 76681552 6934768
10:23:14 128330 8192000000 10080456176 10555809792 1121164912 12.038 98 14 28 0 1182022928 58252928 2605088
10:26:28 104511 8192000000 10063957920 10522673152 1094968672 11.790 96 18 36 1 1133557984 34404240 4185072
10:29:13 19655 8192000000 10099160048 10529464320 1140643312 12.222 100 66 131 3 1197968704 43819072 13506320
10:30:34 49434 8192000000 10067788752 10529927168 1134291488 12.162 100 20 40 0 1201239952 62983024 3965440
10:32:23 38659 8192000000 10060980704 10516250624 1116424624 11.993 99 15 32 0 1171099056 50531856 4142576
10:34:02 85358 8192000000 10070754496 10569777152 1103428432 11.870 98 9 18 0 1156077936 50915904 1733600
10:36:28 25436 8192000000 10064298368 10504957952 1105974784 11.894 98 17 34 0 1163522928 53945856 3602288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-12-2022 10:39:10 to 31-12-2022 10:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 219 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2022 10:39:27 to 31-12-2022 10:39:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          227199
MON$OLDEST_ACTIVE               233422
MON$OLDEST_SNAPSHOT             233422
MON$NEXT_TRANSACTION            233422
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2049248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:743: 
:744: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:24 2022
:745:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:746: 
:747: 
:748: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:29 2022
:749:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:750: 
:751: 
:752: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:29 2022
:753:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:754: 
:755: 
:756: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:29 2022
:757:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:758: 
:759: 
:760: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:31 2022
:761:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:762: 
:763: 
:764: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:31 2022
:765:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:766: 
:767: 
:768: mscs-test-161.budzdorov.ru    Sat Dec 31 10:37:31 2022
:769:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:770: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

31.12.22 10:39:31. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-31 10:39:33 No severe PSQL-related problems occured
31.12.22 10:39:35. End of report.