Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 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 15080356 kB
MemAvailable 30407624 kB
Buffers 0 kB
Cached 16595172 kB
SwapCached 3032 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-03-11 10:37:28
DTS_END                         2023-03-11 10:37:28.9100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39556
TRN_ID                          208859
ATT_ID                          562
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: 8592.15 24912 1031058
customer order: creation 158.28 538 18993
customer order: refuse 13.31 475 1597
order to supplier: creation 42.04 1456 5045
order to supplier: removal 3.31 188688 397
invoice (draft): creation 42.29 3415 5075
invoice (draft): removal 47.77 1141 5732
invoice accept: apply 39.14 28353 4697
invoice accept: cancel 43.43 1335 5212
customer reserve: creation 3684.60 265 442152
customer reserve: removal 4243.93 114 509271
realization accept: apply 76.88 307 9225
realization accept: cancel 77.98 193 9358
payment from customer: creation 53.80 194 6456
payment from customer: removal 5.22 120 626
payment to supplier: creation 45.08 215 5410
payment to supplier: removal 3.20 131 384
service: total inventory turnovers 3.88 3976 465
service: total monetary turnovers 7.12 360 854
service: aggregate perf. data 0.88 16556 105
service: refresh index statistics 0.03 250412 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 10:38:31 to 11-03-2023 10:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8069
TEST_TIME 2 5100
TEST_TIME 3 5954
TEST_TIME 4 5364
TEST_TIME 5 5810
TEST_TIME 6 5677
TEST_TIME 7 6325
TEST_TIME 8 6333
TEST_TIME 9 5997
TEST_TIME 10 6165
TEST_TIME 11 6426
TEST_TIME 12 6464
TEST_TIME 13 6404
TEST_TIME 14 6506
TEST_TIME 15 6561
TEST_TIME 16 6466
TEST_TIME 17 6562
TEST_TIME 18 6636
TEST_TIME 19 6589
TEST_TIME 20 6693
TEST_TIME 21 6886
TEST_TIME 22 7017
TEST_TIME 23 7218
TEST_TIME 24 7375
TEST_TIME 25 7483
TEST_TIME 26 8014
TEST_TIME 27 8294
TEST_TIME 28 8631
TEST_TIME 29 9017
TEST_TIME 30 9068
TEST_TIME 31 9756
TEST_TIME 32 9810
TEST_TIME 33 10307
TEST_TIME 34 10730
TEST_TIME 35 10789
TEST_TIME 36 10707
TEST_TIME 37 10631
TEST_TIME 38 10613
TEST_TIME 39 10445
TEST_TIME 40 10320
TEST_TIME 41 10208
TEST_TIME 42 10049
TEST_TIME 43 10088
TEST_TIME 44 9946
TEST_TIME 45 9891
TEST_TIME 46 9761
TEST_TIME 47 9662
TEST_TIME 48 9541
TEST_TIME 49 9540
TEST_TIME 50 9464
TEST_TIME 51 9322
TEST_TIME 52 9280
TEST_TIME 53 9250
TEST_TIME 54 9213
TEST_TIME 55 9182
TEST_TIME 56 9162
TEST_TIME 57 9073
TEST_TIME 58 8958
TEST_TIME 59 8870
TEST_TIME 60 8952
TEST_TIME 61 8982
TEST_TIME 62 8903
TEST_TIME 63 8833
TEST_TIME 64 8813
TEST_TIME 65 8815
TEST_TIME 66 8785
TEST_TIME 67 8762
TEST_TIME 68 8778
TEST_TIME 69 8829
TEST_TIME 70 8843
TEST_TIME 71 8853
TEST_TIME 72 8861
TEST_TIME 73 8886
TEST_TIME 74 8915
TEST_TIME 75 8883
TEST_TIME 76 8913
TEST_TIME 77 9010
TEST_TIME 78 8976
TEST_TIME 79 8871
TEST_TIME 80 8819
TEST_TIME 81 8791
TEST_TIME 82 8747
TEST_TIME 83 8759
TEST_TIME 84 8706
TEST_TIME 85 8685
TEST_TIME 86 8616
TEST_TIME 87 8562
TEST_TIME 88 8559
TEST_TIME 89 8605
TEST_TIME 90 8653
TEST_TIME 91 8663
TEST_TIME 92 8691
TEST_TIME 93 8672
TEST_TIME 94 8692
TEST_TIME 95 8742
TEST_TIME 96 8719
TEST_TIME 97 8769
TEST_TIME 98 8804
TEST_TIME 99 8793
TEST_TIME 100 8826
TEST_TIME 101 8811
TEST_TIME 102 8850
TEST_TIME 103 8792
TEST_TIME 104 8743
TEST_TIME 105 8711
TEST_TIME 106 8701
TEST_TIME 107 8677
TEST_TIME 108 8646
TEST_TIME 109 8611
TEST_TIME 110 8561
TEST_TIME 111 8547
TEST_TIME 112 8545
TEST_TIME 113 8550
TEST_TIME 114 8570
TEST_TIME 115 8563
TEST_TIME 116 8529
TEST_TIME 117 8556
TEST_TIME 118 8610
TEST_TIME 119 8636
TEST_TIME 120 8616

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-03-2023 10:38:35 to 11-03-2023 10:38:38.

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 1437813 1437813 0 0.00 0 19708 14 0 0
doc_list_biud 1437806 1437806 0 0.00 0 34466 46 0 0
sp_add_invoice_to_stock 4816 4697 119 2.47 37 213241 28353 0 119
sp_cancel_adding_invoice 5212 5212 0 0.00 6 84283 1336 0 0
sp_cancel_client_order 1597 1597 0 0.00 5 10513 476 0 0
sp_cancel_customer_reserve 509271 509271 0 0.00 1 34483 114 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 8989 120 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 3896 131 0 0
sp_cancel_supplier_invoice 5735 5732 3 0.05 2 18067 1141 0 3
sp_cancel_supplier_order 397 397 0 0.00 19 1411679 188689 0 0
sp_cancel_write_off 9358 9358 0 0.00 2 15676 193 0 0
sp_client_order 18993 18993 0 0.00 5 40748 538 0 0
sp_customer_reserve 442152 442152 0 0.00 1 68319 266 0 0
sp_fill_shopping_cart 29109 29109 0 0.00 1 20037 91 0 0
sp_fill_shopping_cart_clo_res 442127 442127 0 0.00 0 22732 24 0 0
sp_get_clo_for_invoice 4657 4657 0 0.00 0 41974 459 0 0
sp_kill_cost_storno 15580 15580 0 0.00 0 6832 82 0 0
sp_kill_qstorno_ret_qs2qd 1034029 1034029 0 0.00 0 34441 49 0 0
sp_kill_qty_storno 545446 545446 0 0.00 0 34464 114 0 0
sp_lock_selected_doc 19191 19191 0 0.00 0 9706 10 0 0
sp_make_cost_storno 25745 25745 0 0.00 0 14472 87 0 0
sp_multiply_rows_for_pdistr 25744 25744 0 0.00 0 9779 41 0 0
sp_multiply_rows_for_qdistr 18993 18993 0 0.00 0 13271 204 0 0
sp_pay_from_customer 6456 6456 0 0.00 5 17107 194 0 0
sp_pay_to_supplier 5410 5410 0 0.00 6 14441 216 0 0
sp_payment_common 11864 11864 0 0.00 2 6946 49 0 0
sp_qd_handle_on_cancel_clo 1597 1597 0 0.00 1 10490 388 0 0
sp_qd_handle_on_invoice_upd_sts 9866 9866 0 0.00 0 23523 579 0 0
sp_qd_handle_on_reserve_upd_sts 9225 9225 0 0.00 0 10135 74 0 0
sp_reserve_write_off 9225 9225 0 0.00 8 15581 307 0 0
sp_supplier_invoice 5075 5075 0 0.00 8 69895 3416 0 0
sp_supplier_order 5045 5045 0 0.00 7 77734 1457 0 0
srv_aggregate_perf_data 105 105 0 0.00 2293 204624 16557 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 758 127801 36158 0 0
srv_find_qd_qs_mism 985199 985199 0 0.00 0 19458 10 0 0
srv_make_invnt_saldo 465 465 0 0.00 56 73332 3976 0 0
srv_make_money_saldo 854 854 0 0.00 1 9918 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 39525 414712 250413 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4697 4697 0 0.00 1 12280 928 0 0
t$perf-norm:sp_cancel_adding_invoice 347 347 0 0.00 1 6202 561 0 0
t$perf-norm:sp_cancel_client_order 1597 1597 0 0.00 0 8285 29 0 0
t$perf-norm:sp_cancel_customer_reserve 618 618 0 0.00 0 5532 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 2241 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 538 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 16651 84 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 96655 7447 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 759 19 0 0
t$perf-norm:sp_client_order 18993 18993 0 0.00 0 8540 38 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 9338 21 0 0
t$perf-norm:sp_pay_from_customer 6456 6456 0 0.00 0 13126 21 0 0
t$perf-norm:sp_pay_to_supplier 5410 5410 0 0.00 0 5341 22 0 0
t$perf-norm:sp_reserve_write_off 9225 9225 0 0.00 0 13233 24 0 0
t$perf-norm:sp_supplier_invoice 5075 5075 0 0.00 0 15224 37 0 0
t$perf-norm:sp_supplier_order 5045 5045 0 0.00 0 1896 31 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 208 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 224 9 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 8336 36 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 6650 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 197 68 0 0
v_add_invoice_to_stock 9544 9306 238 2.49 0 8911 33 0 238
v_all_customers 34736 34736 0 0.00 0 122 4 0 0
v_all_suppliers 28248 28248 0 0.00 0 79 4 0 0
v_all_wares 37986 37986 0 0.00 0 110 4 0 0
v_cancel_adding_invoice 694 694 0 0.00 0 5886 29 0 0
v_cancel_client_order 3194 3194 0 0.00 0 6008 17 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 8958 18 0 0
v_cancel_customer_reserve 1236 1236 0 0.00 0 11075 22 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 1291 12 0 0
v_cancel_supplier_invoice 794 788 6 0.75 0 3570 26 0 6
v_cancel_supplier_order 794 794 0 0.00 0 2019 14 0 0
v_cancel_write_off 1900 1900 0 0.00 0 11108 34 0 0
v_max_id_clo_ord 5045 5045 0 0.00 0 11142 7 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 2335 22 0 0
v_max_id_ord_sup 5071 5071 0 0.00 0 2162 6 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 2104 10 0 0
v_max_non_paid_realizn 3309 3309 0 0.00 0 3273 8 0 0
v_min_id_clo_ord 5045 5045 0 0.00 0 2732 9 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 3957 15 0 0
v_min_id_ord_sup 5071 5071 0 0.00 0 6518 12 0 0
v_min_non_paid_invoice 5410 5410 0 0.00 0 5165 16 0 0
v_min_non_paid_realizn 6453 6453 0 0.00 0 6250 22 0 0
v_our_firm 9538 9538 0 0.00 0 54 5 0 0
v_reserve_write_off 18450 18450 0 0.00 0 11090 13 0 0
x_lock_depdocs_on_canc_invoice 10550 10550 0 0.00 0 21617 145 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 6452 413 0 0
x_make_qstorno_1000_1200 5045 5045 0 0.00 3 77694 1282 0 0
x_make_qstorno_1200_2000 5075 5075 0 0.00 3 64735 3150 0 0
x_make_qstorno_2100_3300 442151 442151 0 0.00 0 68305 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 10:38:39 to 11-03-2023 10:38:43.

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:33 851 8192000000 8938239888 8997130240 67176272 0.813 12 11 21 0 70244960 496880 2571808
08:08:35 31111 8192000000 9737064160 10039447552 814775008 9.046 100 86 172 0 843776496 8210656 20790832
08:10:11 22050 8192000000 9780640480 10094436352 857171280 9.472 100 90 179 1 890554288 12435344 20947664
08:11:35 20290 8192000000 9818241376 10141872128 888121920 9.780 100 87 174 1 925295520 17752192 19421408
08:13:03 10371 8192000000 9876490080 10209251328 920262512 10.099 100 87 174 0 987641680 48410288 18968880
08:14:17 7045 8192000000 9918872960 10263613440 952607312 10.417 100 88 175 1 1031751728 60855952 18288464
08:15:28 3033 8192000000 9961048352 10311258112 983547232 10.719 100 93 179 2 1081381296 79519712 18314352
08:16:35 2094 8192000000 9978989520 10338394112 1006648256 10.943 100 94 175 0 1102865520 77991328 18225936
08:17:39 1740 8192000000 10002404688 10374959104 1035261984 11.219 100 87 163 0 1124695024 73466048 15966992
08:18:43 1472 8192000000 10040694224 10416009216 1057576016 11.433 100 97 180 2 1159045568 84001168 17468384
08:19:45 1355 8192000000 10041508368 10419179520 1063072752 11.486 100 93 178 0 1157601808 76283600 18245456
08:20:47 1043 8192000000 10032353728 10405552128 1070886096 11.561 100 97 184 2 1153128032 63864992 18376944
08:21:49 1976 8192000000 10056877184 10442702848 1072995248 11.581 100 89 170 0 1172779648 82188352 17596048
08:22:53 1545 8192000000 10029880800 10418634752 1078697648 11.635 100 82 142 2 1162068976 69696752 13674576
08:23:55 1326 8192000000 10035008864 10425425920 1081141792 11.658 100 83 148 2 1161711408 64833248 15736368
08:24:57 1227 8192000000 10071686112 10462826496 1086553200 11.710 100 97 189 1 1196031744 91160256 18318288
08:26:00 1192 8192000000 10065435760 10454519808 1094515040 11.786 100 93 176 2 1186039024 73479616 18044368
08:27:03 1102 8192000000 10040316848 10428715008 1101549568 11.852 100 90 165 1 1160748288 42336400 16862320
08:28:05 2142 8192000000 10039348816 10436300800 1102455520 11.861 100 66 120 0 1159842784 46312656 11074608
08:29:08 659 8192000000 10066366752 10460299264 1096200464 11.802 100 84 164 0 1189902752 77036000 16666288
08:30:11 1142 8192000000 10062434096 10454183936 1100315664 11.841 100 88 169 5 1180609872 63224144 17070064
08:31:14 996 8192000000 10038631440 10424868864 1079384400 11.642 100 97 184 4 1161129472 63036640 18708432
08:32:17 1100 8192000000 10030692864 10409623552 1062076304 11.476 100 98 192 4 1151997264 70841152 19079808
08:33:19 1112 8192000000 10003807072 10377625600 1047124944 11.333 100 94 176 2 1124214160 58361280 18727936
08:34:20 5714 8192000000 10022103120 10414313472 1062617168 11.482 100 60 111 2 1146976352 72555632 11803552
08:35:27 1164 8192000000 10001429056 10371817472 1025850528 11.128 100 94 176 0 1125561280 80422016 19288736
08:36:30 4057 8192000000 10002628544 10390704128 1029520688 11.164 99 60 117 1 1128250832 87015744 11714400
08:37:36 1082 8192000000 10006635952 10380439552 1045801584 11.320 100 88 153 4 1121292432 61119280 14371568
08:38:46 35864 8192000000 10013407024 10405421056 1064447440 11.499 100 58 113 0 1135142576 59345104 11350032
08:40:36 18250 8192000000 10075742848 10468806656 1090258048 11.745 100 58 112 0 1200524896 98941328 11325520
08:42:00 1061 8192000000 10104428448 10494734336 1102525328 11.862 100 94 172 3 1226405552 103833872 20046352
08:43:03 1243 8192000000 10146091424 10532564992 1109837184 11.931 100 82 153 0 1259101280 133783664 15480432
08:44:08 4523 8192000000 10177725872 10572906496 1127847472 12.101 100 83 156 5 1295579712 151906672 15825568
08:45:16 2980 8192000000 10224275296 10627186688 1140147104 12.217 100 73 144 0 1341232832 185738944 15346784
08:46:23 1419 8192000000 10224454192 10618466304 1147726000 12.288 100 85 150 2 1337626784 173464816 16435968
08:47:32 2721 8192000000 10269087056 10672189440 1162843360 12.430 100 91 170 2 1388241712 205831296 19567056
08:48:37 1572 8192000000 10326566080 10720964608 1169741248 12.494 100 93 177 1 1437679088 248610336 19327504
08:49:42 1308 8192000000 10382533776 10781630464 1183194400 12.620 100 94 181 1 1492361584 287802208 21364976
08:50:45 2036 8192000000 10426253856 10825625600 1187014768 12.656 100 95 176 1 1531811456 324762224 20034464
08:51:51 1411 8192000000 10448321936 10857119744 1193583664 12.717 100 96 181 2 1557690112 343477008 20629440
08:52:55 1045 8192000000 10495890016 10901000192 1203182544 12.806 100 93 179 0 1602207696 379268736 19756416
08:54:02 1310 8192000000 10555230480 10974564352 1216180608 12.926 100 92 181 1 1662575760 425585056 20810096
08:55:07 1400 8192000000 10571767584 10982400000 1212126672 12.889 100 94 174 3 1675118752 441944944 21047136
08:56:13 1094 8192000000 10643446080 11055570944 1217237696 12.936 100 100 197 0 1750142880 508163216 24741968
08:57:20 1018 8192000000 10640889280 11088404480 1210459936 12.873 100 97 192 0 1746653504 512685872 23507696
08:58:24 2295 8192000000 10666356544 11098382336 1214712496 12.913 100 93 182 1 1774046704 537165008 22169200
08:59:30 1403 8192000000 10705769376 11140562944 1211283152 12.881 100 95 188 0 1809725200 574691904 23750144
09:00:36 2298 8192000000 10690423312 11158523904 1216694384 12.931 100 81 158 1 1798414224 562018720 19701120
09:01:41 5227 8192000000 10680503968 11159408640 1209982560 12.869 100 76 142 3 1786362448 558356592 18023296
09:02:56 753 8192000000 10700846672 11164475392 1189977552 12.683 100 92 182 1 1803695696 590255456 23462688
09:04:00 6373 8192000000 10643326736 11141296128 1192227856 12.704 100 51 102 0 1744686288 538548896 13909536
09:05:19 8578 8192000000 10556132896 11049013248 1175706416 12.550 99 44 88 1 1657885648 470468160 11711072
09:06:52 35193 8192000000 10513899168 10959208448 1157198688 12.377 99 35 69 1 1596456864 430634672 8623504
09:08:48 25064 8192000000 10338242240 10818265088 1143252576 12.246 100 31 51 1 1476811984 327306992 6252416
09:10:15 34139 8192000000 10256731344 10725265408 1142336432 12.238 100 23 45 0 1354352240 206646384 5369424
09:11:55 35427 8192000000 10102585008 10586976256 1086191008 11.706 95 14 28 0 1204562176 115229264 3141904
09:13:33 53830 8192000000 10119694080 10573377536 1139772880 12.213 100 56 105 2 1230553472 78814048 11966544
09:15:30 55833 8192000000 10117451088 10590027776 1129856784 12.120 99 14 28 0 1209384016 76646016 2881216
09:17:55 42037 8192000000 10116423872 10572337152 1137504640 12.192 100 51 87 2 1213476272 67519040 8452592
09:19:39 81837 8192000000 10102407408 10560950272 1130954064 12.130 100 31 51 0 1227489664 88911136 7624464
09:22:08 75244 8192000000 10074197408 10518986752 1137661344 12.194 100 42 69 1 1209833280 65495408 6676528
09:24:23 39684 8192000000 10090690624 10526928896 1131772096 12.138 100 39 73 0 1191666528 51969264 7925168
09:26:10 43599 8192000000 10070612128 10501570560 1122343408 12.049 99 34 64 1 1158031440 28024880 7663152
09:27:55 18135 8192000000 10070141152 10515828736 1117541776 12.004 99 28 56 0 1172424448 48912528 5970144
09:29:26 25204 8192000000 10081939360 10510209024 1108254880 11.916 99 49 93 4 1169917104 51515600 10146624
09:31:11 41665 8192000000 10082519856 10543771648 1110668464 11.939 99 17 34 0 1173639904 59009760 3961680
09:33:02 74504 8192000000 10082595776 10549256192 1129723584 12.119 100 12 24 1 1192153504 59002992 3426928
09:35:17 93437 8192000000 10125634400 10591019008 1118705680 12.015 99 21 42 1 1235435184 111397360 5332144
09:38:11 30661 8192000000 10071695408 10553868288 1133764720 12.157 100 42 76 3 1175928480 33677072 8486688
09:39:49 63678 8192000000 10051269120 10506821632 1126727936 12.091 99 16 32 0 1167828000 37832528 3267536
09:41:53 80665 8192000000 10075716272 10540900352 1101353344 11.850 96 21 42 0 1144723296 39237264 4132688
09:44:22 54787 8192000000 10119335328 10563158016 1146785392 12.279 100 31 62 0 1217034528 63254976 6994160
09:46:38 53059 8192000000 10078349472 10534277120 1068333632 11.536 94 28 56 0 1127431648 53160528 5937488
09:48:32 79133 8192000000 10108639536 10571161600 1098059232 11.819 97 24 48 0 1178547152 74789488 5698432
09:51:06 66244 8192000000 10056940928 10499493888 1095356592 11.794 97 17 34 0 1127841440 28848784 3636064
09:53:15 20946 8192000000 10138089696 10572697600 1118441504 12.012 99 42 84 0 1227989440 101085632 8462304
09:54:44 127801 8192000000 10043757936 10493431808 1117065984 11.999 99 18 35 0 1172406656 51725936 3614736
09:58:06 55302 8192000000 10084045024 10522701824 1120623392 12.033 99 61 116 1 1187040288 53841904 12574992
10:00:08 43033 8192000000 10057617568 10521337856 1110465664 11.937 100 27 54 0 1189197088 73027536 5703888
10:01:51 119047 8192000000 10103427168 10549465088 1100151472 11.839 98 16 30 0 1177216976 72896160 4169344
10:04:56 49691 8192000000 10136036800 10601291776 1112018720 11.952 99 15 28 0 1220286128 104800720 3466688
10:06:58 32046 8192000000 10045946496 10498535424 1092625664 11.768 97 25 48 1 1137020080 39609120 4785296
10:08:43 55638 8192000000 10066323472 10529746944 1091011216 11.752 97 33 64 0 1175669712 77937328 6721168
10:10:39 53278 8192000000 10131962512 10589831168 1122571408 12.051 99 40 76 1 1213662064 82927856 8162800
10:12:34 71667 8192000000 10115458848 10545659904 1099568880 11.834 97 26 51 0 1183106256 78387536 5149840
10:14:49 69163 8192000000 10075362960 10548711424 1127515568 12.098 100 25 50 0 1211915232 79461280 4938384
10:16:59 26080 8192000000 10142310128 10595045376 1145520768 12.267 100 49 97 0 1221074640 66215632 9338240
10:18:27 13207 8192000000 10112056992 10536357888 1116345232 11.992 98 50 98 0 1168457440 42634864 9477344
10:19:43 101020 8192000000 10052029488 10504093696 1127233776 12.095 99 25 50 1 1195730448 63174896 5321776
10:22:30 27534 8192000000 10100978656 10550968320 1135238384 12.171 99 42 81 1 1190193248 46706288 8248576
10:24:00 58549 8192000000 10059255696 10528690176 1115032128 11.980 98 12 24 1 1158499808 40749664 2718016
10:25:58 59418 8192000000 10051312592 10505969664 1098162992 11.820 97 7 14 0 1137004976 37393856 1448128
10:27:58 62046 8192000000 10097892784 10549002240 1099707792 11.835 97 18 36 0 1155274400 51954848 3611760
10:30:38 29670 8192000000 10139841728 10582155264 1137726128 12.194 99 22 44 0 1237164144 94376432 5061584
10:32:08 44050 8192000000 10164620384 10633023488 1157196944 12.377 100 26 52 0 1252907168 89870848 5839376
10:34:47 78706 8192000000 10172407760 10656346112 1148197616 12.293 100 19 38 1 1351635104 198545904 4891584
10:37:17 21555 8192000000 9224425424 9559322624 359208128 4.200 33 7 12 1 380103552 19543888 1351536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-03-2023 10:38:45 to 11-03-2023 10:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2023 10:39:03 to 11-03-2023 10:39:05.

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          209129
MON$OLDEST_ACTIVE               209130
MON$OLDEST_SNAPSHOT             209130
MON$NEXT_TRANSACTION            209130
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                       2057760
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             753
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Mar 11 10:37:59 2023
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

11.03.23 10:39:06. 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-03-11 10:39:08 No severe PSQL-related problems occured
11.03.23 10:39:10. End of report.