Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.02.2023 13:37
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 15084492 kB
MemAvailable 30416360 kB
Buffers 0 kB
Cached 16591216 kB
SwapCached 3144 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 2023-02-28 13:37:27
DTS_END                         2023-02-28 13:37:27.8240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41978
TRN_ID                          212084
ATT_ID                          610
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: 9098.53 22758 1091824
customer order: creation 160.98 471 19317
customer order: refuse 13.54 375 1625
order to supplier: creation 43.53 1342 5224
order to supplier: removal 3.28 166642 394
invoice (draft): creation 43.49 3017 5219
invoice (draft): removal 53.06 936 6367
invoice accept: apply 38.94 25021 4673
invoice accept: cancel 48.59 1003 5831
customer reserve: creation 3686.53 235 442384
customer reserve: removal 4716.43 90 565971
realization accept: apply 77.17 262 9260
realization accept: cancel 92.34 149 11081
payment from customer: creation 55.53 166 6663
payment from customer: removal 5.23 110 628
payment to supplier: creation 45.18 182 5421
payment to supplier: removal 3.08 101 370
service: total inventory turnovers 4.35 3334 522
service: total monetary turnovers 6.33 429 760
service: aggregate perf. data 0.92 15154 110
service: refresh index statistics 0.03 236143 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-02-2023 13:38:16 to 28-02-2023 13:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7360
TEST_TIME 2 4652
TEST_TIME 3 5611
TEST_TIME 4 5110
TEST_TIME 5 5348
TEST_TIME 6 5377
TEST_TIME 7 5600
TEST_TIME 8 5769
TEST_TIME 9 6081
TEST_TIME 10 6104
TEST_TIME 11 6420
TEST_TIME 12 6463
TEST_TIME 13 6479
TEST_TIME 14 6578
TEST_TIME 15 6580
TEST_TIME 16 6866
TEST_TIME 17 6903
TEST_TIME 18 7052
TEST_TIME 19 7327
TEST_TIME 20 7206
TEST_TIME 21 7590
TEST_TIME 22 7849
TEST_TIME 23 7852
TEST_TIME 24 8289
TEST_TIME 25 8645
TEST_TIME 26 9540
TEST_TIME 27 9886
TEST_TIME 28 10459
TEST_TIME 29 10806
TEST_TIME 30 11133
TEST_TIME 31 10850
TEST_TIME 32 10825
TEST_TIME 33 10755
TEST_TIME 34 10525
TEST_TIME 35 10491
TEST_TIME 36 10231
TEST_TIME 37 10238
TEST_TIME 38 10224
TEST_TIME 39 10172
TEST_TIME 40 10258
TEST_TIME 41 10230
TEST_TIME 42 10044
TEST_TIME 43 9921
TEST_TIME 44 9894
TEST_TIME 45 9975
TEST_TIME 46 10208
TEST_TIME 47 10164
TEST_TIME 48 10111
TEST_TIME 49 10020
TEST_TIME 50 9855
TEST_TIME 51 9757
TEST_TIME 52 9657
TEST_TIME 53 9713
TEST_TIME 54 9603
TEST_TIME 55 9546
TEST_TIME 56 9522
TEST_TIME 57 9530
TEST_TIME 58 9545
TEST_TIME 59 9482
TEST_TIME 60 9427
TEST_TIME 61 9322
TEST_TIME 62 9294
TEST_TIME 63 9287
TEST_TIME 64 9344
TEST_TIME 65 9268
TEST_TIME 66 9410
TEST_TIME 67 9409
TEST_TIME 68 9486
TEST_TIME 69 9426
TEST_TIME 70 9411
TEST_TIME 71 9417
TEST_TIME 72 9415
TEST_TIME 73 9492
TEST_TIME 74 9521
TEST_TIME 75 9585
TEST_TIME 76 9588
TEST_TIME 77 9478
TEST_TIME 78 9411
TEST_TIME 79 9385
TEST_TIME 80 9332
TEST_TIME 81 9320
TEST_TIME 82 9260
TEST_TIME 83 9194
TEST_TIME 84 9159
TEST_TIME 85 9117
TEST_TIME 86 9062
TEST_TIME 87 9000
TEST_TIME 88 9110
TEST_TIME 89 9080
TEST_TIME 90 9167
TEST_TIME 91 9124
TEST_TIME 92 9122
TEST_TIME 93 9282
TEST_TIME 94 9245
TEST_TIME 95 9320
TEST_TIME 96 9287
TEST_TIME 97 9294
TEST_TIME 98 9301
TEST_TIME 99 9290
TEST_TIME 100 9336
TEST_TIME 101 9305
TEST_TIME 102 9251
TEST_TIME 103 9254
TEST_TIME 104 9198
TEST_TIME 105 9212
TEST_TIME 106 9160
TEST_TIME 107 9179
TEST_TIME 108 9147
TEST_TIME 109 9107
TEST_TIME 110 9087
TEST_TIME 111 9104
TEST_TIME 112 9077
TEST_TIME 113 9076
TEST_TIME 114 9072
TEST_TIME 115 9071
TEST_TIME 116 9077
TEST_TIME 117 9113
TEST_TIME 118 9134
TEST_TIME 119 9176
TEST_TIME 120 9121

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 13:38:20 to 28-02-2023 13:38:24.

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 1500348 1500348 0 0.00 0 23947 11 0 0
doc_list_biud 1500345 1500345 0 0.00 0 55034 40 0 0
sp_add_invoice_to_stock 4743 4673 70 1.47 37 175368 25022 0 70
sp_cancel_adding_invoice 5831 5831 0 0.00 5 67203 1004 0 0
sp_cancel_client_order 1625 1625 0 0.00 6 10333 375 0 0
sp_cancel_customer_reserve 565971 565971 0 0.00 1 55060 91 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 4 7056 110 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 2876 102 0 0
sp_cancel_supplier_invoice 6374 6367 7 0.10 2 25704 936 0 7
sp_cancel_supplier_order 394 394 0 0.00 8 1472305 166643 0 0
sp_cancel_write_off 11081 11081 0 0.00 2 8754 150 0 0
sp_client_order 19317 19317 0 0.00 5 32529 472 0 0
sp_customer_reserve 442384 442384 0 0.00 0 96119 235 0 0
sp_fill_shopping_cart 29745 29745 0 0.00 1 26611 75 0 0
sp_fill_shopping_cart_clo_res 442363 442363 0 0.00 0 11832 19 0 0
sp_get_clo_for_invoice 4638 4638 0 0.00 0 26758 306 0 0
sp_kill_cost_storno 17910 17910 0 0.00 0 8596 70 0 0
sp_kill_qstorno_ret_qs2qd 1149784 1149784 0 0.00 0 55015 41 0 0
sp_kill_qty_storno 605164 605164 0 0.00 0 55030 92 0 0
sp_lock_selected_doc 19293 19293 0 0.00 0 9037 6 0 0
sp_make_cost_storno 25982 25982 0 0.00 0 23941 74 0 0
sp_multiply_rows_for_pdistr 25982 25982 0 0.00 0 17840 39 0 0
sp_multiply_rows_for_qdistr 19317 19317 0 0.00 0 10474 178 0 0
sp_pay_from_customer 6663 6663 0 0.00 5 23954 166 0 0
sp_pay_to_supplier 5421 5421 0 0.00 7 11496 182 0 0
sp_payment_common 12084 12084 0 0.00 2 8301 43 0 0
sp_qd_handle_on_cancel_clo 1625 1625 0 0.00 1 9068 308 0 0
sp_qd_handle_on_invoice_upd_sts 10467 10467 0 0.00 0 21394 461 0 0
sp_qd_handle_on_reserve_upd_sts 9259 9259 0 0.00 0 13050 63 0 0
sp_reserve_write_off 9260 9260 0 0.00 8 22086 263 0 0
sp_supplier_invoice 5219 5219 0 0.00 10 115814 3017 0 0
sp_supplier_order 5224 5224 0 0.00 8 36773 1343 0 0
srv_aggregate_perf_data 110 110 0 0.00 2720 165763 15155 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1030 137690 38759 0 0
srv_find_qd_qs_mism 1046222 1046222 0 0.00 0 22813 8 0 0
srv_make_invnt_saldo 522 522 0 0.00 40 83214 3335 0 0
srv_make_money_saldo 760 760 0 0.00 1 40580 429 0 0
srv_recalc_idx_stat 4 4 0 0.00 76017 412201 236144 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4678 4678 0 0.00 1 11642 762 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 9541 366 0 0
t$perf-norm:sp_cancel_client_order 1625 1625 0 0.00 0 4264 23 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 2601 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 2399 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 393 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1499 28 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 77754 5624 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 575 12 0 0
t$perf-norm:sp_client_order 19319 19319 0 0.00 0 9114 30 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 1082 13 0 0
t$perf-norm:sp_pay_from_customer 6663 6663 0 0.00 0 9776 18 0 0
t$perf-norm:sp_pay_to_supplier 5421 5421 0 0.00 0 5992 18 0 0
t$perf-norm:sp_reserve_write_off 9260 9260 0 0.00 0 10398 21 0 0
t$perf-norm:sp_supplier_invoice 5219 5219 0 0.00 0 8688 26 0 0
t$perf-norm:sp_supplier_order 5224 5224 0 0.00 0 5984 27 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 238 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 3003 53 0 0
t$perf-norm:srv_make_invnt_saldo 522 522 0 0.00 0 1141 15 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 3358 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 19 185 88 0 0
v_add_invoice_to_stock 9402 9262 140 1.48 0 3464 22 0 140
v_all_customers 35856 35856 0 0.00 0 224 3 0 0
v_all_suppliers 29114 29114 0 0.00 0 79 3 0 0
v_all_wares 38634 38634 0 0.00 0 141 3 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1966 15 0 0
v_cancel_client_order 3250 3250 0 0.00 0 1993 8 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 6996 15 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 1988 10 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 180 8 0 0
v_cancel_supplier_invoice 730 716 14 1.91 0 1964 26 0 14
v_cancel_supplier_order 788 788 0 0.00 0 1880 10 0 0
v_cancel_write_off 1930 1930 0 0.00 0 399 20 0 0
v_max_id_clo_ord 5221 5221 0 0.00 0 2157 4 0 0
v_max_id_clo_res 917 917 0 0.00 0 3343 18 0 0
v_max_id_ord_sup 5207 5207 0 0.00 0 8571 10 0 0
v_max_non_paid_invoice 1292 1292 0 0.00 0 3262 10 0 0
v_max_non_paid_realizn 3319 3319 0 0.00 0 1782 8 0 0
v_min_id_clo_ord 5221 5221 0 0.00 0 1015 7 0 0
v_min_id_clo_res 917 917 0 0.00 0 1519 9 0 0
v_min_id_ord_sup 5207 5207 0 0.00 0 5568 13 0 0
v_min_non_paid_invoice 5421 5421 0 0.00 0 8293 23 0 0
v_min_non_paid_realizn 6663 6663 0 0.00 0 8179 19 0 0
v_our_firm 9466 9466 0 0.00 0 224 4 0 0
v_reserve_write_off 18518 18518 0 0.00 0 7861 9 0 0
x_lock_depdocs_on_canc_invoice 11840 11840 0 0.00 0 17953 128 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 5301 349 0 0
x_make_qstorno_1000_1200 5224 5224 0 0.00 3 36740 1209 0 0
x_make_qstorno_1200_2000 5219 5219 0 0.00 4 112960 2790 0 0
x_make_qstorno_2100_3300 442384 442384 0 0.00 0 96094 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 13:38:25 to 28-02-2023 13:38:29.

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
11:07:31 2 8192000000 8798206224 8809402368 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:32 36272 8192000000 9718470336 10016063488 789718128 8.792 100 88 177 0 819268032 7648624 21901280
11:10:13 18679 8192000000 9765363696 10071777280 839826592 9.298 100 89 176 1 872807568 12325104 20655872
11:11:39 4736 8192000000 9792654416 10110009344 864695648 9.547 100 84 168 0 904720144 21030592 18993904
11:12:47 7500 8192000000 9860583808 10190622720 911090944 10.008 100 94 185 0 971040688 39715760 20233984
11:13:57 3865 8192000000 9913392048 10256769024 951278704 10.404 100 84 167 0 1026772176 58190848 17302624
11:15:03 2179 8192000000 9972492448 10322223104 990564272 10.787 100 92 180 0 1079879104 70089856 19224976
11:16:07 1906 8192000000 9965717520 10327633920 1015416368 11.028 100 87 165 0 1089448896 57321840 16710688
11:17:11 868 8192000000 10000244384 10370736128 1035545072 11.222 100 97 190 0 1121127520 66299024 19283424
11:18:13 1080 8192000000 10016787936 10392186880 1062121200 11.477 100 89 171 1 1143177840 63429920 17626720
11:19:15 2946 8192000000 10035859328 10416099328 1067243456 11.526 100 80 146 1 1159318256 77919408 14155392
11:20:19 3757 8192000000 10039310912 10430246912 1074587824 11.596 100 69 132 1 1169576112 82304864 12683424
11:21:23 1000 8192000000 10048935728 10434072576 1083350208 11.679 100 95 178 5 1176443008 74600064 18492736
11:22:25 1073 8192000000 10096643744 10483044352 1086445264 11.709 100 96 186 2 1215646080 111435472 17765344
11:23:27 899 8192000000 10066813424 10453925888 1094910944 11.789 100 96 184 0 1182940656 69110032 18919680
11:24:28 1560 8192000000 10033279216 10434658304 1097621296 11.815 100 58 116 1 1158533056 49782528 11129232
11:25:30 1562 8192000000 10065986656 10457743360 1101119104 11.848 100 84 160 3 1187679584 70043968 16516512
11:26:33 929 8192000000 10053988704 10442412032 1103471152 11.871 100 92 182 3 1172223184 50477888 18274144
11:27:35 1238 8192000000 10036755984 10424569856 1092487936 11.766 100 82 154 2 1160423120 52428320 15506864
11:28:37 1158 8192000000 10044984144 10437812224 1096178432 11.801 100 86 161 1 1167441312 53301472 17961408
11:29:39 1448 8192000000 10034746544 10422722560 1068712368 11.540 100 85 155 0 1157086176 72967920 15405888
11:30:41 1276 8192000000 10039874672 10427166720 1075275328 11.602 100 84 161 3 1157255552 65830304 16149920
11:31:43 892 8192000000 10020291824 10403831808 1061303344 11.469 99 92 163 0 1143011504 63075232 18632928
11:32:45 1034 8192000000 10011475376 10393350144 1055734720 11.416 100 85 171 0 1135907024 61510400 18661904
11:33:48 1114 8192000000 10024892736 10404683776 1054622368 11.405 100 85 166 1 1146576448 75302432 16651648
11:34:50 973 8192000000 10012947568 10394771456 1061776992 11.473 100 91 173 1 1136810928 55858480 19175456
11:35:52 1864 8192000000 10008908512 10388369408 1036177760 11.228 99 74 144 1 1128315808 77643344 14494704
11:36:54 1943 8192000000 9955399584 10331082752 1007770384 10.954 99 71 126 2 1075017984 54213408 13034192
11:38:01 3322 8192000000 10011451696 10385301504 1055187200 11.410 100 97 175 4 1123453664 52483568 15782896
11:39:11 10479 8192000000 10037502464 10415665152 1064962416 11.504 100 80 153 1 1156050848 76195552 14892880
11:40:46 7825 8192000000 10049610704 10441080832 1085705088 11.702 100 57 102 2 1164749872 68180320 10864464
11:41:58 2133 8192000000 10105889104 10499514368 1099871552 11.836 100 84 149 0 1213442688 98579392 14991744
11:43:07 2170 8192000000 10138974784 10532745216 1106901024 11.903 100 82 155 1 1266164816 142287104 16976688
11:44:14 2349 8192000000 10175275744 10566909952 1115348560 11.983 100 89 169 0 1291287824 158037104 17902160
11:45:20 1716 8192000000 10197819216 10597072896 1130969152 12.130 100 79 153 0 1312623200 164562464 17091584
11:46:26 2272 8192000000 10223486176 10619273216 1139505168 12.211 100 82 148 1 1335002368 179768720 15728480
11:47:32 1019 8192000000 10246663312 10644291584 1149239792 12.302 100 95 182 1 1360296048 189029872 22026384
11:48:37 1682 8192000000 10292914896 10700853248 1168926720 12.487 100 86 170 0 1403050368 215729024 18394624
11:49:43 1136 8192000000 10317582672 10715623424 1173442080 12.529 100 92 169 1 1426430144 233923008 19065056
11:50:48 1029 8192000000 10383764240 10796310528 1197282816 12.751 100 96 190 2 1494104336 274243744 22577776
11:51:52 1269 8192000000 10454380736 10856013824 1204381376 12.817 100 95 185 1 1561307696 334966048 21960272
11:52:55 1700 8192000000 10465884688 10893701120 1208263248 12.853 100 96 184 0 1570122960 340334512 21525200
11:54:00 1214 8192000000 10533666032 10940792832 1208924080 12.859 100 92 183 0 1638761376 408577200 21260096
11:55:04 2276 8192000000 10551838816 10970775552 1212950624 12.896 100 81 157 3 1660051232 427545680 19554928
11:56:10 1255 8192000000 10570485104 11005423616 1214652224 12.912 100 74 139 0 1679972080 448434288 16885568
11:57:16 1199 8192000000 10583730608 11018141696 1204674048 12.820 100 76 149 0 1689662480 467157872 17830560
11:58:22 1568 8192000000 10590793392 11026362368 1203863248 12.812 100 76 150 2 1701187088 478352688 18971152
11:59:26 9432 8192000000 10575256768 11026018304 1172617568 12.521 100 47 94 0 1676816656 491445632 12753456
12:00:38 13086 8192000000 10572154480 11027374080 1153125232 12.339 100 48 96 0 1701942912 535732432 13085248
12:02:00 13244 8192000000 10508139936 10983137280 1148499376 12.295 100 31 61 0 1612001056 455117504 8384176
12:03:51 35035 8192000000 10300673632 10774814720 1129429200 12.116 99 28 56 0 1421061056 284812880 6818976
12:05:27 43706 8192000000 10134426912 10671783936 1119927712 12.026 99 16 32 0 1325614592 201680144 4006736
12:07:12 61611 8192000000 10081389696 10538033152 1118064880 12.009 98 14 26 0 1144208080 23359024 2784176
12:09:16 2792 8192000000 10116518944 10538827776 1146293296 12.275 100 65 117 0 1210574624 51506128 12775200
12:10:21 30827 8192000000 10094356704 10559115264 1113098976 11.962 98 21 42 0 1189885584 72203152 4583456
12:12:55 48348 8192000000 10126327632 10549915648 1094771056 11.788 97 37 67 0 1199719296 98017968 6930272
12:14:44 137690 8192000000 10122094816 10560757760 1110874192 11.941 98 20 40 0 1215934656 100761056 4299408
12:18:09 110608 8192000000 10136997488 10568876032 1118028672 12.008 98 22 43 0 1199238944 76663968 4546304
12:21:02 38695 8192000000 10229129408 10677010432 1149953456 12.309 100 26 51 0 1305240016 149637248 5649312
12:22:45 79116 8192000000 10093173024 10621489152 1129802336 12.119 99 4 8 0 1230928720 99905456 1220928
12:25:07 64497 8192000000 10079200464 10521341952 1116823120 11.997 97 19 34 2 1150530688 30123888 3583680
12:27:19 94883 8192000000 10134241232 10584756224 1144694768 12.260 100 48 90 1 1228672016 73368224 10609024
12:30:16 68745 8192000000 10066691472 10529587200 1109852400 11.931 98 19 32 0 1149587328 36170352 3564576
12:32:25 65612 8192000000 10133936768 10570018816 1125890016 12.083 98 40 78 1 1218929520 84204976 8834528
12:34:38 85763 8192000000 10082696368 10575986688 1125656144 12.080 99 12 24 0 1205704464 77273056 2775264
12:37:05 80404 8192000000 10043331040 10507558912 1127225616 12.095 99 9 18 0 1151421568 22696432 1499520
12:39:47 42976 8192000000 10078582896 10548719616 1124828160 12.073 98 7 12 0 1180893968 54901424 1164384
12:41:31 42818 8192000000 10124461696 10570051584 1155233024 12.359 100 18 36 0 1228043008 69253680 3556304
12:43:17 46518 8192000000 10107601360 10587406336 1122172560 12.048 98 22 42 1 1202803984 75655632 4975792
12:45:09 67427 8192000000 10070344304 10516475904 1136788384 12.185 99 28 57 0 1175547216 32797728 5961104
12:47:17 30958 8192000000 10091137872 10531581952 1093707696 11.778 96 33 66 0 1150577872 49868000 7002176
12:48:53 43825 8192000000 10152609440 10606100480 1135781392 12.176 100 37 74 0 1245927776 102353088 7793296
12:50:48 29996 8192000000 10101127360 10583412736 1121275024 12.039 99 28 56 0 1221059712 93303936 6480752
12:52:25 40839 8192000000 10109455632 10580938752 1112229600 11.954 98 29 58 0 1188429408 70601024 5598784
12:54:08 105094 8192000000 10054272528 10489888768 1059678272 11.453 93 37 67 2 1114321056 47395584 7247200
12:56:56 7804 8192000000 10045344080 10470797312 1113117424 11.962 100 29 57 1 1156790592 37469216 6203952
12:58:04 80924 8192000000 10066295472 10536960000 1119561712 12.023 99 21 42 0 1190835616 66778496 4495408
13:00:29 37745 8192000000 10108027216 10548658176 1128431936 12.107 100 25 49 0 1218608048 85034736 5141376
13:02:07 78140 8192000000 10126098160 10586603520 1113933168 11.970 98 18 37 0 1227285888 109408112 3944608
13:04:30 80604 8192000000 10109539072 10566103040 1075826240 11.608 95 21 43 0 1216734800 136129296 4779264
13:06:51 40295 8192000000 10122065936 10578087936 1088586496 11.729 97 23 44 0 1187423456 93740416 5096544
13:08:34 57151 8192000000 10148561552 10592993280 1119260656 12.020 99 20 40 0 1249927312 126149776 4516880
13:10:39 35964 8192000000 10065677184 10509303808 1085777424 11.702 97 20 40 0 1131375504 41678576 3919504
13:12:20 46476 8192000000 10061837904 10526244864 1080828528 11.655 97 40 79 0 1148757760 59517904 8411328
13:14:15 15946 8192000000 10106890192 10532777984 1123868672 12.064 100 46 92 0 1197456816 64632400 8955744
13:15:32 58012 8192000000 10145328368 10608943104 1118107008 12.009 98 21 42 0 1212507840 90131440 4269392
13:17:33 24686 8192000000 10076577312 10503286784 1131306048 12.134 100 34 66 0 1171086352 33177600 6602704
13:18:58 102111 8192000000 10087898096 10567360512 1140051472 12.216 100 34 65 1 1224504464 77093696 7359296
13:21:42 4104 8192000000 10082859152 10511921152 1134063856 12.160 100 57 100 1 1187516016 43801952 9650208
13:22:46 59500 8192000000 10111449424 10583605248 1141419472 12.229 100 14 28 0 1221946288 77378960 3147856
13:24:46 68011 8192000000 10081450832 10545586176 1111926992 11.951 97 19 38 0 1167426960 51429088 4070880
13:27:07 83239 8192000000 10076334000 10588639232 1115395120 11.983 98 14 28 0 1174366928 56022192 2949616
13:29:38 33996 8192000000 10100494336 10572419072 1132555184 12.145 99 32 64 0 1196360080 57399120 6405776
13:31:15 41057 8192000000 10125242928 10577915904 1142130688 12.236 99 22 44 0 1222952768 76142016 4680064
13:32:56 27008 8192000000 10169085856 10607661056 1135680352 12.175 99 38 76 0 1227322256 83469232 8172672
13:34:43 52202 8192000000 10082878096 10533257216 1131321840 12.134 99 24 48 0 1208342320 71787072 5233408
13:36:36 57019 8192000000 9483390384 9860972544 586899616 6.685 52 15 28 0 636357200 46710544 2747040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-02-2023 13:38:31 to 28-02-2023 13:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-02-2023 13:38:48 to 28-02-2023 13:38:50.

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          212083
MON$OLDEST_ACTIVE               214068
MON$OLDEST_SNAPSHOT             214068
MON$NEXT_TRANSACTION            214068
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                       2056672
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
--- end of diff output ---

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

28.02.23 13:38:51. 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
2023-02-28 13:38:54 No severe PSQL-related problems occured
28.02.23 13:38:55. End of report.