Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.03.2023 22:41
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 15333100 kB
MemAvailable 30450768 kB
Buffers 0 kB
Cached 16402496 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-03-13 22:38:45
DTS_END                         2023-03-13 22:38:45.1520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35450
TRN_ID                          252050
ATT_ID                          715
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: 9325.92 17344 1119110
customer order: creation 191.00 345 22920
customer order: refuse 16.66 336 1999
order to supplier: creation 48.82 1230 5858
order to supplier: removal 3.55 85201 426
invoice (draft): creation 50.51 3260 6061
invoice (draft): removal 51.14 743 6137
invoice accept: apply 44.75 17535 5370
invoice accept: cancel 45.98 811 5518
customer reserve: creation 4031.25 179 483750
customer reserve: removal 4539.62 53 544754
realization accept: apply 90.98 169 10917
realization accept: cancel 71.14 111 8537
payment from customer: creation 64.47 113 7736
payment from customer: removal 6.37 86 764
payment to supplier: creation 52.98 123 6357
payment to supplier: removal 3.88 76 465
service: total inventory turnovers 5.13 985 615
service: total monetary turnovers 6.93 24 832
service: aggregate perf. data 0.75 26685 90
service: refresh index statistics 0.03 208820 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-03-2023 22:41:24 to 13-03-2023 22:41:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5093
TEST_TIME 2 3534
TEST_TIME 3 4527
TEST_TIME 4 5097
TEST_TIME 5 5041
TEST_TIME 6 5097
TEST_TIME 7 5388
TEST_TIME 8 5830
TEST_TIME 9 6517
TEST_TIME 10 6808
TEST_TIME 11 7020
TEST_TIME 12 7175
TEST_TIME 13 7087
TEST_TIME 14 7482
TEST_TIME 15 7877
TEST_TIME 16 8212
TEST_TIME 17 8414
TEST_TIME 18 8356
TEST_TIME 19 8979
TEST_TIME 20 9051
TEST_TIME 21 9115
TEST_TIME 22 9071
TEST_TIME 23 9298
TEST_TIME 24 9466
TEST_TIME 25 9681
TEST_TIME 26 9925
TEST_TIME 27 9851
TEST_TIME 28 10428
TEST_TIME 29 10419
TEST_TIME 30 10495
TEST_TIME 31 10941
TEST_TIME 32 10864
TEST_TIME 33 10549
TEST_TIME 34 10526
TEST_TIME 35 10551
TEST_TIME 36 10402
TEST_TIME 37 10273
TEST_TIME 38 10151
TEST_TIME 39 10069
TEST_TIME 40 10097
TEST_TIME 41 10301
TEST_TIME 42 10178
TEST_TIME 43 10045
TEST_TIME 44 9990
TEST_TIME 45 9991
TEST_TIME 46 9841
TEST_TIME 47 9800
TEST_TIME 48 9859
TEST_TIME 49 9726
TEST_TIME 50 9650
TEST_TIME 51 9664
TEST_TIME 52 9710
TEST_TIME 53 9563
TEST_TIME 54 9569
TEST_TIME 55 9492
TEST_TIME 56 9616
TEST_TIME 57 9629
TEST_TIME 58 9572
TEST_TIME 59 9625
TEST_TIME 60 9586
TEST_TIME 61 9693
TEST_TIME 62 9712
TEST_TIME 63 9768
TEST_TIME 64 9691
TEST_TIME 65 9769
TEST_TIME 66 9767
TEST_TIME 67 9850
TEST_TIME 68 9844
TEST_TIME 69 9795
TEST_TIME 70 9714
TEST_TIME 71 9640
TEST_TIME 72 9669
TEST_TIME 73 9637
TEST_TIME 74 9595
TEST_TIME 75 9573
TEST_TIME 76 9513
TEST_TIME 77 9462
TEST_TIME 78 9444
TEST_TIME 79 9500
TEST_TIME 80 9542
TEST_TIME 81 9502
TEST_TIME 82 9566
TEST_TIME 83 9529
TEST_TIME 84 9550
TEST_TIME 85 9544
TEST_TIME 86 9545
TEST_TIME 87 9592
TEST_TIME 88 9576
TEST_TIME 89 9594
TEST_TIME 90 9650
TEST_TIME 91 9607
TEST_TIME 92 9695
TEST_TIME 93 9638
TEST_TIME 94 9562
TEST_TIME 95 9513
TEST_TIME 96 9545
TEST_TIME 97 9515
TEST_TIME 98 9522
TEST_TIME 99 9477
TEST_TIME 100 9466
TEST_TIME 101 9440
TEST_TIME 102 9374
TEST_TIME 103 9364
TEST_TIME 104 9331
TEST_TIME 105 9348
TEST_TIME 106 9329
TEST_TIME 107 9315
TEST_TIME 108 9339
TEST_TIME 109 9361
TEST_TIME 110 9359
TEST_TIME 111 9398
TEST_TIME 112 9359
TEST_TIME 113 9361
TEST_TIME 114 9346
TEST_TIME 115 9433
TEST_TIME 116 9460
TEST_TIME 117 9429
TEST_TIME 118 9388
TEST_TIME 119 9328
TEST_TIME 120 9332

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-03-2023 22:41:28 to 13-03-2023 22:41:31.

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 1568542 1568542 0 0.00 0 19492 7 0 0
doc_list_biud 1568538 1568538 0 0.00 0 27602 24 0 0
sp_add_invoice_to_stock 5501 5370 131 2.38 36 192483 17536 0 131
sp_cancel_adding_invoice 5518 5518 0 0.00 5 60919 811 0 0
sp_cancel_client_order 1999 1999 0 0.00 4 11788 337 0 0
sp_cancel_customer_reserve 544754 544754 0 0.00 0 25551 54 0 0
sp_cancel_pay_from_customer 764 764 0 0.00 3 8191 87 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 3 3862 76 0 0
sp_cancel_supplier_invoice 6155 6137 18 0.29 1 27615 743 0 18
sp_cancel_supplier_order 426 426 0 0.00 6 967385 85201 0 0
sp_cancel_write_off 8537 8537 0 0.00 1 11856 111 0 0
sp_client_order 22920 22920 0 0.00 3 23744 345 0 0
sp_customer_reserve 483750 483750 0 0.00 0 99753 180 0 0
sp_fill_shopping_cart 34827 34827 0 0.00 1 13281 33 0 0
sp_fill_shopping_cart_clo_res 483733 483733 0 0.00 0 10321 12 0 0
sp_get_clo_for_invoice 5351 5351 0 0.00 0 24187 258 0 0
sp_kill_cost_storno 15284 15284 0 0.00 0 11039 57 0 0
sp_kill_qstorno_ret_qs2qd 1104608 1104608 0 0.00 0 27598 29 0 0
sp_kill_qty_storno 583639 583639 0 0.00 0 27600 63 0 0
sp_lock_selected_doc 22697 22697 0 0.00 0 10012 6 0 0
sp_make_cost_storno 30361 30361 0 0.00 0 15076 53 0 0
sp_multiply_rows_for_pdistr 30361 30361 0 0.00 0 6817 24 0 0
sp_multiply_rows_for_qdistr 22920 22920 0 0.00 0 21025 141 0 0
sp_pay_from_customer 7736 7736 0 0.00 4 20940 114 0 0
sp_pay_to_supplier 6357 6357 0 0.00 4 15081 123 0 0
sp_payment_common 14093 14093 0 0.00 1 20867 34 0 0
sp_qd_handle_on_cancel_clo 1999 1999 0 0.00 0 11783 308 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 25735 292 0 0
sp_qd_handle_on_reserve_upd_sts 10917 10917 0 0.00 0 18073 54 0 0
sp_reserve_write_off 10917 10917 0 0.00 6 18101 170 0 0
sp_supplier_invoice 6061 6061 0 0.00 5 75993 3261 0 0
sp_supplier_order 5858 5858 0 0.00 4 27733 1231 0 0
srv_aggregate_perf_data 90 90 0 0.00 2677 468143 26685 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1028 121696 44889 0 0
srv_find_qd_qs_mism 1068440 1068440 0 0.00 0 19491 6 0 0
srv_make_invnt_saldo 615 615 0 0.00 32 26634 986 0 0
srv_make_money_saldo 832 832 0 0.00 1 386 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 96123 368325 208820 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 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 5370 5370 0 0.00 0 7202 148 0 0
t$perf-norm:sp_cancel_adding_invoice 471 471 0 0.00 0 7264 97 0 0
t$perf-norm:sp_cancel_client_order 1999 1999 0 0.00 0 1397 11 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1369 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 764 764 0 0.00 0 3045 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 2101 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 588 11 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 25670 1536 0 0
t$perf-norm:sp_cancel_write_off 1120 1120 0 0.00 0 819 3 0 0
t$perf-norm:sp_client_order 22920 22920 0 0.00 0 7618 14 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 7420 15 0 0
t$perf-norm:sp_pay_from_customer 7736 7736 0 0.00 0 3290 6 0 0
t$perf-norm:sp_pay_to_supplier 6357 6357 0 0.00 0 9712 9 0 0
t$perf-norm:sp_reserve_write_off 10917 10917 0 0.00 0 9043 9 0 0
t$perf-norm:sp_supplier_invoice 6061 6061 0 0.00 0 6873 15 0 0
t$perf-norm:sp_supplier_order 5858 5858 0 0.00 0 4157 12 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 265 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 1250 19 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 936 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10962 10700 262 2.39 0 6453 18 0 262
v_all_customers 41376 41376 0 0.00 0 20 1 0 0
v_all_suppliers 32794 32794 0 0.00 0 158 1 0 0
v_all_wares 45838 45838 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 942 942 0 0.00 0 1241 7 0 0
v_cancel_client_order 3998 3998 0 0.00 0 1361 3 0 0
v_cancel_customer_prepayment 1528 1528 0 0.00 0 1983 4 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 1498 6 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 2180 7 0 0
v_cancel_supplier_invoice 918 882 36 3.92 0 1304 18 0 36
v_cancel_supplier_order 852 852 0 0.00 0 1633 7 0 0
v_cancel_write_off 2240 2240 0 0.00 0 2551 16 0 0
v_max_id_clo_ord 5858 5858 0 0.00 0 84 1 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 2136 8 0 0
v_max_id_ord_sup 6050 6050 0 0.00 0 4130 4 0 0
v_max_non_paid_invoice 1868 1868 0 0.00 0 3058 4 0 0
v_max_non_paid_realizn 4343 4343 0 0.00 0 6033 8 0 0
v_min_id_clo_ord 5858 5858 0 0.00 0 3081 7 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 5480 13 0 0
v_min_id_ord_sup 6050 6050 0 0.00 0 6306 9 0 0
v_min_non_paid_invoice 6357 6357 0 0.00 0 7818 21 0 0
v_min_non_paid_realizn 7736 7736 0 0.00 0 20684 26 0 0
v_our_firm 11248 11248 0 0.00 0 16 1 0 0
v_reserve_write_off 21834 21834 0 0.00 0 6253 6 0 0
x_lock_depdocs_on_canc_invoice 11214 11214 0 0.00 0 26448 103 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7746 296 0 0
x_make_qstorno_1000_1200 5858 5858 0 0.00 1 27724 1163 0 0
x_make_qstorno_1200_2000 6061 6061 0 0.00 1 75971 3141 0 0
x_make_qstorno_2100_3300 483750 483750 0 0.00 0 99750 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-03-2023 22:41:32 to 13-03-2023 22:41:36.

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
20:08:49 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:51 35226 8192000000 9892621168 10203729920 975761440 10.643 100 90 180 0 1005349712 5438352 24149920
20:11:31 22141 8192000000 9955098592 10278375424 1033700768 11.204 100 83 166 0 1062849312 8002048 21146496
20:12:58 12550 8192000000 9991134688 10324529152 1076675184 11.616 100 85 170 0 1107810976 10249520 20886272
20:14:16 8133 8192000000 10044388128 10391359488 1132205600 12.142 100 86 170 0 1163267824 10988320 20073904
20:15:27 5741 8192000000 10079102672 10436243456 1165309040 12.453 100 77 154 0 1195244496 12705968 17229488
20:16:35 3911 8192000000 10115067408 10484428800 1206587600 12.837 100 78 155 0 1236448640 12891344 16969696
20:17:43 2251 8192000000 10150165760 10524434432 1238657376 13.134 100 87 172 1 1271048528 13193536 19197616
20:18:48 3109 8192000000 10179328080 10560114688 1268411904 13.407 100 74 148 2 1297067040 12720320 15934816
20:19:53 1145 8192000000 10209370288 10592538624 1291895120 13.621 100 87 175 0 1323947664 12803328 19249216
20:20:56 3814 8192000000 10193258256 10597064704 1302963088 13.722 100 38 76 0 1319933488 8431696 8538704
20:22:02 5838 8192000000 10195580592 10611007488 1315246688 13.834 100 25 50 0 1328491040 7489440 5754912
20:23:14 52618 8192000000 10218631232 10635276288 1322177232 13.896 100 49 95 0 1339436992 5554384 11705376
20:25:13 4195 8192000000 10231797504 10645897216 1329824624 13.966 100 31 62 0 1344452704 7651648 6976432
20:26:17 7915 8192000000 10226806576 10648985600 1334362656 14.007 100 24 46 0 1346510240 7266192 4881392
20:27:25 10043 8192000000 10225118560 10645995520 1324959488 13.922 99 18 36 0 1333723792 4760368 4003936
20:28:36 26593 8192000000 10210770176 10615873536 1271438944 13.435 96 34 68 1 1284736528 5554160 7743424
20:30:03 5445 8192000000 10174642592 10577334272 1257692080 13.309 97 36 72 0 1274230560 8218240 8320240
20:31:09 3216 8192000000 10179944080 10577371136 1279841536 13.512 100 36 72 2 1295817984 7016448 8960000
20:32:13 59897 8192000000 10121234496 10515042304 1148001024 12.291 91 37 75 0 1161381232 4930688 8449520
20:34:14 4921 8192000000 10164530240 10566508544 1269789040 13.420 100 36 72 0 1284505200 6880544 7835616
20:35:20 9165 8192000000 10173120608 10583855104 1287059488 13.577 100 20 40 1 1297066320 5215136 4791696
20:36:33 11260 8192000000 10188948720 10600054784 1298085232 13.678 100 20 41 0 1308223600 5486080 4652288
20:37:45 32164 8192000000 10210093984 10628632576 1314077328 13.823 100 15 30 0 1321793040 4555712 3160000
20:39:20 14159 8192000000 10254412880 10661212160 1342101072 14.076 100 50 100 0 1360940336 7228480 11610784
20:40:39 30319 8192000000 10267571712 10684641280 1359615040 14.234 100 45 88 0 1380741040 10867520 10258480
20:42:16 13815 8192000000 10286653840 10705063936 1372385168 14.348 100 48 95 2 1397986560 14553872 11047520
20:43:33 13538 8192000000 10302400736 10722725888 1384300816 14.455 100 47 93 1 1411609792 15984240 11324736
20:45:08 4850 8192000000 10314236816 10738233344 1398187168 14.579 100 43 82 0 1424393312 16599632 9606512
20:46:15 15942 8192000000 10322684592 10750455808 1401996160 14.613 100 52 105 1 1435596272 21171040 12429072
20:47:34 1425 8192000000 10343082224 10761633792 1407956288 14.666 100 69 133 0 1447209440 22589856 16663296
20:48:39 24843 8192000000 10323138384 10758725632 1396322432 14.562 99 38 74 0 1427881584 23060896 8498256
20:50:13 9069 8192000000 10334073888 10758057984 1392216512 14.526 99 59 116 1 1433239504 27114608 13908384
20:51:26 4337 8192000000 10334942816 10754965504 1395347424 14.554 100 69 138 1 1441054400 29057008 16649968
20:52:38 2904 8192000000 10313401088 10726653952 1374172592 14.364 100 67 130 0 1416922864 27422864 15327408
20:53:44 4249 8192000000 10302808896 10721832960 1371270256 14.338 100 64 126 1 1416940976 30558272 15112448
20:54:52 7776 8192000000 10281506336 10696183808 1311055232 13.796 96 37 74 0 1350599216 29890080 9653904
20:56:07 2167 8192000000 10341125424 10754392064 1390663632 14.512 100 75 150 0 1443709456 35493008 17552816
20:57:20 1028 8192000000 10339736784 10747994112 1380618240 14.422 100 80 159 0 1441628544 41697408 19312896
20:58:28 6427 8192000000 10316475632 10737217536 1371190592 14.338 100 46 90 0 1418247824 35459008 11598224
20:59:40 7711 8192000000 10307224672 10735058944 1364591872 14.279 99 36 69 0 1404509792 31226384 8691536
21:00:55 18434 8192000000 10302589872 10729422848 1359065152 14.229 99 37 74 0 1405720288 36683856 9971280
21:02:23 21162 8192000000 10283523136 10708807680 1342559488 14.080 99 30 59 0 1384790304 34401728 7829088
21:03:52 17108 8192000000 10284442224 10711293952 1360045952 14.238 100 28 56 0 1395623232 27993888 7583392
21:05:11 43657 8192000000 10281513136 10714574848 1334059568 14.004 98 16 32 0 1365337328 26735568 4542192
21:06:57 33849 8192000000 10305261728 10721054720 1371128768 14.337 100 29 58 1 1398120560 19704496 7287296
21:08:37 38019 8192000000 10283962160 10718236672 1360334240 14.240 100 14 28 0 1383592592 19514848 3743504
21:10:23 94361 8192000000 10286986144 10718437376 1342981360 14.084 98 21 41 0 1356055648 8558288 4516000
21:13:01 60732 8192000000 10299966608 10731339776 1362990512 14.264 99 27 52 0 1376132352 7634160 5507680
21:15:06 117442 8192000000 10306636656 10753814528 1392697088 14.530 100 30 58 0 1409405296 9638976 7069232
21:18:12 38741 8192000000 10317169424 10759663616 1387812928 14.486 99 25 50 0 1410888896 16647504 6428464
21:20:07 62652 8192000000 10292436080 10748248064 1393906000 14.541 100 15 30 0 1407792496 10409824 3476672
21:22:10 37477 8192000000 10321937584 10758922240 1400507920 14.600 100 32 65 0 1418131328 10843632 6779776
21:23:49 95413 8192000000 10309052080 10765152256 1405799728 14.647 100 22 44 0 1423391840 12624528 4967584
21:27:15 36295 8192000000 10298873184 10750894080 1378021440 14.399 99 32 64 0 1396751264 10432752 8297072
21:28:54 32860 8192000000 10282495632 10740146176 1365379328 14.286 98 18 36 0 1381114448 11645632 4089488
21:30:31 77147 8192000000 10272118432 10731417600 1366131472 14.292 99 9 18 0 1377967264 9819936 2015856
21:33:09 48782 8192000000 10275772256 10735689728 1336758144 14.028 96 15 28 0 1347291216 7746912 2786160
21:35:08 53059 8192000000 10259227008 10709499904 1337003344 14.030 98 24 47 1 1352154368 9256832 5894192
21:37:02 44621 8192000000 10256195840 10705928192 1310746144 13.793 96 21 42 0 1329211872 13824448 4641280
21:39:00 47690 8192000000 10261226768 10718396416 1336781728 14.028 98 27 54 0 1355321024 12768016 5771280
21:40:59 70531 8192000000 10271927488 10705915904 1294703376 13.647 95 25 50 0 1312099712 12003680 5392656
21:43:10 39405 8192000000 10261971808 10712162304 1319925008 13.876 97 22 42 2 1335398864 10860352 4613504
21:44:52 66187 8192000000 10241201216 10694938624 1287492352 13.581 95 14 28 0 1301945776 11290624 3162800
21:47:04 41162 8192000000 10276703472 10711486464 1344766224 14.100 99 27 53 0 1357816592 7371392 5678976
21:48:45 58029 8192000000 10280770624 10710253568 1356158720 14.203 100 53 106 1 1379713264 9896224 13658320
21:50:47 50851 8192000000 10254907312 10699264000 1332516144 13.990 99 32 64 0 1354664544 15017984 7130416
21:52:51 56312 8192000000 10263308688 10714161152 1363997760 14.273 100 27 53 1 1381926976 10954944 6974272
21:54:48 50285 8192000000 10263455216 10714329088 1356279536 14.204 99 15 30 0 1372723824 12764800 3679488
21:57:44 89531 8192000000 10274327200 10733359104 1363627552 14.270 99 9 18 0 1377192848 11325056 2240240
22:00:31 72283 8192000000 10274180384 10718867456 1331202784 13.978 97 14 28 0 1346246208 11488864 3554560
22:02:46 38036 8192000000 10263759488 10712449024 1339497568 14.053 98 28 57 0 1355677376 10175648 6004160
22:04:26 74323 8192000000 10273648272 10727133184 1362070016 14.256 99 26 52 0 1381296064 13574912 5651136
22:06:41 38945 8192000000 10289336832 10732437504 1367863664 14.308 100 26 52 0 1383587824 10063296 5660864
22:08:48 58288 8192000000 10290208640 10733826048 1366792928 14.298 99 27 54 0 1384787456 12555632 5438896
22:10:50 121696 8192000000 10284338704 10732265472 1320657696 13.883 95 22 44 1 1335821680 10378752 4785232
22:14:01 65623 8192000000 10279080288 10749067264 1369615456 14.324 99 20 40 1 1390208096 15892272 4700368
22:16:06 73863 8192000000 10288554176 10757423104 1375271728 14.374 99 15 30 1 1395927536 16526272 4129536
22:18:20 77168 8192000000 10271265232 10734870528 1356988688 14.210 98 11 20 0 1371049392 11775312 2285392
22:20:38 72387 8192000000 10265946032 10727309312 1362014016 14.255 98 21 42 1 1377680432 11094288 4572128
22:22:51 76809 8192000000 10258685680 10728554496 1300944928 13.704 94 8 17 0 1311400160 8347344 2107888
22:25:08 66368 8192000000 10267053968 10713272320 1319017136 13.868 96 20 40 1 1333684608 9760272 4907200
22:27:19 63683 8192000000 10248149104 10702913536 1298833808 13.685 96 26 52 0 1313378592 8143312 6401472
22:29:23 59003 8192000000 10240091616 10679599104 1299530000 13.691 96 32 63 0 1316539648 8857360 8152288
22:31:22 36292 8192000000 10251033280 10689388544 1306706160 13.756 97 34 68 0 1323623680 9826000 7091520
22:33:03 22769 8192000000 10272090368 10717143040 1344501696 14.098 99 32 64 0 1371637488 20131808 7003984
22:34:33 119607 8192000000 10263658672 10722758656 1328020176 13.949 98 7 14 0 1350751040 21186112 1544752
22:37:33 29354 8192000000 10264550752 10709606400 1344665952 14.099 99 25 50 0 1363692576 13885312 5141312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-03-2023 22:41:38 to 13-03-2023 22:41:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 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 13-03-2023 22:41:54 to 13-03-2023 22:41:56.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          251098
MON$OLDEST_ACTIVE               252186
MON$OLDEST_SNAPSHOT             252186
MON$NEXT_TRANSACTION            252186
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-03 16:41:55.3470
MON$PAGES                       2025696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:180: 
:181: mscs-test-161.budzdorov.ru    Mon Mar 13 22:39:03 2023
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:183: 
:184: 
:185: mscs-test-161.budzdorov.ru    Mon Mar 13 22:39:10 2023
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:187: 
:188: 
:189: mscs-test-161.budzdorov.ru    Mon Mar 13 22:39:17 2023
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:191: 
--- end of diff output ---

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

13.03.23 22:41:58. There are 3 logs in /var/tmp/logs-oltp30 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-03-13 22:42:00 No severe PSQL-related problems occured
13.03.23 22:42:01. End of report.