Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.2022 07:40
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 15415740 kB
MemAvailable 30596156 kB
Buffers 0 kB
Cached 16327076 kB
SwapCached 2048 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-03 07:37:22
DTS_END                         2022-10-03 07:37:22.4460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49948
TRN_ID                          212059
ATT_ID                          645
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: 8582.19 26765 1029863
customer order: creation 159.47 541 19136
customer order: refuse 13.85 381 1662
order to supplier: creation 43.10 1499 5172
order to supplier: removal 3.48 166535 417
invoice (draft): creation 42.38 3187 5085
invoice (draft): removal 47.92 1047 5750
invoice accept: apply 39.00 28109 4680
invoice accept: cancel 42.80 1297 5136
customer reserve: creation 3672.77 265 440732
customer reserve: removal 4246.15 108 509538
realization accept: apply 77.72 276 9327
realization accept: cancel 71.90 169 8628
payment from customer: creation 56.03 179 6723
payment from customer: removal 5.25 130 630
payment to supplier: creation 45.64 180 5477
payment to supplier: removal 3.14 131 377
service: total inventory turnovers 3.72 4349 446
service: total monetary turnovers 6.92 425 830
service: aggregate perf. data 0.94 14739 113
service: refresh index statistics 0.03 311749 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-10-2022 07:40:25 to 03-10-2022 07:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8571
TEST_TIME 2 7218
TEST_TIME 3 6773
TEST_TIME 4 6909
TEST_TIME 5 7035
TEST_TIME 6 6579
TEST_TIME 7 6621
TEST_TIME 8 6993
TEST_TIME 9 7044
TEST_TIME 10 7133
TEST_TIME 11 6896
TEST_TIME 12 7093
TEST_TIME 13 7331
TEST_TIME 14 7533
TEST_TIME 15 7478
TEST_TIME 16 7566
TEST_TIME 17 7653
TEST_TIME 18 7611
TEST_TIME 19 7737
TEST_TIME 20 7702
TEST_TIME 21 7922
TEST_TIME 22 7914
TEST_TIME 23 7913
TEST_TIME 24 7883
TEST_TIME 25 8112
TEST_TIME 26 8179
TEST_TIME 27 8833
TEST_TIME 28 9154
TEST_TIME 29 9329
TEST_TIME 30 9665
TEST_TIME 31 10011
TEST_TIME 32 10851
TEST_TIME 33 10945
TEST_TIME 34 10743
TEST_TIME 35 10502
TEST_TIME 36 10320
TEST_TIME 37 10410
TEST_TIME 38 10317
TEST_TIME 39 10205
TEST_TIME 40 10072
TEST_TIME 41 10014
TEST_TIME 42 9869
TEST_TIME 43 9843
TEST_TIME 44 9695
TEST_TIME 45 9703
TEST_TIME 46 9542
TEST_TIME 47 9479
TEST_TIME 48 9468
TEST_TIME 49 9486
TEST_TIME 50 9315
TEST_TIME 51 9292
TEST_TIME 52 9229
TEST_TIME 53 9221
TEST_TIME 54 9250
TEST_TIME 55 9220
TEST_TIME 56 9150
TEST_TIME 57 9010
TEST_TIME 58 8951
TEST_TIME 59 8954
TEST_TIME 60 8877
TEST_TIME 61 8864
TEST_TIME 62 8811
TEST_TIME 63 8860
TEST_TIME 64 8988
TEST_TIME 65 8997
TEST_TIME 66 8922
TEST_TIME 67 8942
TEST_TIME 68 8929
TEST_TIME 69 8926
TEST_TIME 70 8909
TEST_TIME 71 8994
TEST_TIME 72 9019
TEST_TIME 73 8992
TEST_TIME 74 8953
TEST_TIME 75 8915
TEST_TIME 76 8869
TEST_TIME 77 8853
TEST_TIME 78 8818
TEST_TIME 79 8782
TEST_TIME 80 8720
TEST_TIME 81 8755
TEST_TIME 82 8737
TEST_TIME 83 8694
TEST_TIME 84 8680
TEST_TIME 85 8667
TEST_TIME 86 8699
TEST_TIME 87 8656
TEST_TIME 88 8626
TEST_TIME 89 8624
TEST_TIME 90 8661
TEST_TIME 91 8889
TEST_TIME 92 8869
TEST_TIME 93 8916
TEST_TIME 94 8862
TEST_TIME 95 8844
TEST_TIME 96 8837
TEST_TIME 97 8791
TEST_TIME 98 8748
TEST_TIME 99 8713
TEST_TIME 100 8680
TEST_TIME 101 8639
TEST_TIME 102 8580
TEST_TIME 103 8557
TEST_TIME 104 8554
TEST_TIME 105 8522
TEST_TIME 106 8538
TEST_TIME 107 8532
TEST_TIME 108 8511
TEST_TIME 109 8494
TEST_TIME 110 8540
TEST_TIME 111 8545
TEST_TIME 112 8574
TEST_TIME 113 8554
TEST_TIME 114 8575
TEST_TIME 115 8633
TEST_TIME 116 8626
TEST_TIME 117 8631
TEST_TIME 118 8681
TEST_TIME 119 8677
TEST_TIME 120 8605

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2022 07:40:29 to 03-10-2022 07:40:32.

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 1435518 1435518 0 0.00 0 32858 14 0 0
doc_list_biud 1435518 1435518 0 0.00 0 38804 44 0 0
sp_add_invoice_to_stock 4804 4680 124 2.58 46 215774 28109 0 124
sp_cancel_adding_invoice 5136 5136 0 0.00 7 82647 1298 0 0
sp_cancel_client_order 1662 1662 0 0.00 7 5719 381 0 0
sp_cancel_customer_reserve 509538 509538 0 0.00 1 38876 109 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 4 3526 130 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 4112 132 0 0
sp_cancel_supplier_invoice 5757 5750 7 0.12 3 23419 1047 0 7
sp_cancel_supplier_order 417 417 0 0.00 13 1521815 166536 0 0
sp_cancel_write_off 8628 8628 0 0.00 1 8632 170 0 0
sp_client_order 19136 19136 0 0.00 5 38759 542 0 0
sp_customer_reserve 440732 440732 0 0.00 1 80734 265 0 0
sp_fill_shopping_cart 29386 29386 0 0.00 1 10963 87 0 0
sp_fill_shopping_cart_clo_res 440725 440725 0 0.00 0 26946 20 0 0
sp_get_clo_for_invoice 4641 4641 0 0.00 0 52120 408 0 0
sp_kill_cost_storno 14771 14771 0 0.00 0 8041 77 0 0
sp_kill_qstorno_ret_qs2qd 1033871 1033871 0 0.00 0 38351 47 0 0
sp_kill_qty_storno 545099 545099 0 0.00 0 38797 108 0 0
sp_lock_selected_doc 19443 19443 0 0.00 0 5584 7 0 0
sp_make_cost_storno 26168 26168 0 0.00 0 32800 80 0 0
sp_multiply_rows_for_pdistr 26168 26168 0 0.00 0 10548 39 0 0
sp_multiply_rows_for_qdistr 19136 19136 0 0.00 0 13044 214 0 0
sp_pay_from_customer 6723 6723 0 0.00 5 11722 180 0 0
sp_pay_to_supplier 5477 5477 0 0.00 7 10157 180 0 0
sp_payment_common 12200 12200 0 0.00 2 7647 45 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 1 5708 316 0 0
sp_qd_handle_on_invoice_upd_sts 9777 9777 0 0.00 0 29813 544 0 0
sp_qd_handle_on_reserve_upd_sts 9327 9327 0 0.00 0 36906 66 0 0
sp_reserve_write_off 9327 9327 0 0.00 8 36966 277 0 0
sp_supplier_invoice 5085 5085 0 0.00 7 62643 3187 0 0
sp_supplier_order 5172 5172 0 0.00 9 49635 1500 0 0
srv_aggregate_perf_data 113 113 0 0.00 2449 115647 14739 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 663 141062 34497 0 0
srv_find_qd_qs_mism 983712 983712 0 0.00 0 32850 11 0 0
srv_make_invnt_saldo 446 446 0 0.00 54 144785 4349 0 0
srv_make_money_saldo 830 830 0 0.00 1 19371 426 0 0
srv_recalc_idx_stat 4 4 0 0.00 47275 633486 311750 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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 4680 4680 0 0.00 1 11591 948 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 6766 545 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 10322 45 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 3765 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 371 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2145 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 5702 53 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 78191 6543 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 4837 32 0 0
t$perf-norm:sp_client_order 19136 19136 0 0.00 0 6959 41 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 3563 21 0 0
t$perf-norm:sp_pay_from_customer 6723 6723 0 0.00 0 11227 28 0 0
t$perf-norm:sp_pay_to_supplier 5477 5477 0 0.00 0 4436 22 0 0
t$perf-norm:sp_reserve_write_off 9327 9327 0 0.00 0 16367 31 0 0
t$perf-norm:sp_supplier_invoice 5085 5085 0 0.00 0 5324 32 0 0
t$perf-norm:sp_supplier_order 5172 5172 0 0.00 0 8399 31 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 187 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 7082 217 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 564 16 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1150 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 113 39 0 0
v_add_invoice_to_stock 9530 9282 248 2.60 0 8508 25 0 248
v_all_customers 35138 35138 0 0.00 0 82 4 0 0
v_all_suppliers 28852 28852 0 0.00 0 107 4 0 0
v_all_wares 38272 38272 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1911 17 0 0
v_cancel_client_order 3324 3324 0 0.00 0 1304 9 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 2802 12 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 1 395 9 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3894 17 0 0
v_cancel_supplier_invoice 814 800 14 1.71 0 1486 26 0 14
v_cancel_supplier_order 834 834 0 0.00 0 380 10 0 0
v_cancel_write_off 1846 1846 0 0.00 0 3662 24 0 0
v_max_id_clo_ord 5172 5172 0 0.00 0 3479 6 0 0
v_max_id_clo_res 973 973 0 0.00 0 658 10 0 0
v_max_id_ord_sup 5078 5078 0 0.00 0 4805 8 0 0
v_max_non_paid_invoice 1301 1301 0 0.00 0 58 8 0 0
v_max_non_paid_realizn 3477 3477 0 0.00 0 929 7 0 0
v_min_id_clo_ord 5172 5172 0 0.00 0 3755 9 0 0
v_min_id_clo_res 973 973 0 0.00 0 3520 13 0 0
v_min_id_ord_sup 5078 5078 0 0.00 0 4589 8 0 0
v_min_non_paid_invoice 5477 5477 0 0.00 0 7587 15 0 0
v_min_non_paid_realizn 6723 6723 0 0.00 0 7640 17 0 0
v_our_firm 9626 9626 0 0.00 0 58 5 0 0
v_reserve_write_off 18654 18654 0 0.00 0 2428 9 0 0
x_lock_depdocs_on_canc_invoice 10486 10486 0 0.00 0 18242 134 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 4540 356 0 0
x_make_qstorno_1000_1200 5172 5172 0 0.00 2 49612 1329 0 0
x_make_qstorno_1200_2000 5085 5085 0 0.00 3 62293 2950 0 0
x_make_qstorno_2100_3300 440732 440732 0 0.00 0 74966 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-10-2022 07:40:34 to 03-10-2022 07:40:38.

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
05:07:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 6033 8192000000 9702970208 9999159296 791769328 8.813 100 82 164 0 819281376 6891136 20620912
05:09:37 21614 8192000000 9753119184 10062368768 833227296 9.232 100 81 162 0 862377648 9740864 19409488
05:11:09 13568 8192000000 9788057264 10104971264 862200304 9.522 100 84 166 1 898002976 16096064 19706608
05:12:36 7518 8192000000 9851505424 10182721536 915302320 10.050 100 82 158 1 965739760 32868064 17569376
05:13:57 6550 8192000000 9931284176 10275610624 958517024 10.475 100 90 176 0 1036550096 59057936 18975136
05:15:10 1937 8192000000 9968776128 10321629184 992842912 10.809 100 92 174 0 1078268976 68020880 17405184
05:16:15 1336 8192000000 10002620912 10365566976 1016640320 11.040 100 93 179 1 1125529856 90974976 17914560
05:17:19 1333 8192000000 10018906960 10392350720 1037892672 11.244 100 96 182 3 1135237136 79491504 17852960
05:18:22 1044 8192000000 10018276608 10399027200 1053750608 11.397 100 95 185 0 1143953200 70951536 19251056
05:19:25 957 8192000000 10063879696 10445709312 1065170928 11.506 100 98 186 2 1184979968 100793472 19015568
05:20:27 965 8192000000 10034458768 10420531200 1074237104 11.593 100 80 150 2 1153032576 63980416 14815056
05:21:30 976 8192000000 10052438864 10441506816 1082442880 11.671 100 91 172 1 1177673904 76051472 19179552
05:22:31 1645 8192000000 10033832864 10425278464 1084264992 11.688 100 81 151 3 1154546032 54705904 15575136
05:23:34 2285 8192000000 10074681344 10458632192 1087595040 11.720 100 92 172 1 1192486288 87498256 17392992
05:24:38 1145 8192000000 10061322064 10451165184 1092465248 11.766 100 94 187 1 1184950832 72731824 19753760
05:25:41 1006 8192000000 10068955920 10459312128 1100219920 11.840 100 87 168 0 1193147072 77070192 15856960
05:26:42 1142 8192000000 10039886032 10426982400 1078583712 11.634 99 94 172 0 1163940816 67868144 17488960
05:27:44 963 8192000000 10053578192 10447085568 1098474160 11.823 100 93 171 3 1177733328 62077872 17181296
05:28:46 904 8192000000 10047488720 10437496832 1089329648 11.736 100 90 168 0 1169285536 62751920 17203968
05:29:47 942 8192000000 10006701360 10391035904 1072151104 11.573 100 90 170 0 1129497552 39676720 17669728
05:30:49 995 8192000000 10053039296 10439778304 1074645264 11.596 100 96 185 2 1174274976 80625696 19004016
05:31:51 1012 8192000000 10019758944 10402078720 1062568368 11.481 100 90 176 0 1140501216 58977216 18955632
05:32:53 1281 8192000000 10018763216 10398203904 1046158848 11.324 100 90 170 2 1137237152 74203280 16875024
05:34:01 925 8192000000 10013816448 10389471232 1039971232 11.264 99 94 177 2 1135591488 77139536 18480720
05:35:03 1379 8192000000 9984787712 10366427136 1043436368 11.298 100 70 130 3 1104306256 47287104 13582784
05:36:05 1341 8192000000 9995917968 10371297280 1040200352 11.267 99 79 155 0 1112380176 55654096 16525728
05:37:08 1160 8192000000 10004863136 10383745024 1049569264 11.357 100 92 180 0 1128760480 60356096 18835120
05:38:15 22815 8192000000 10002628272 10387689472 1061868704 11.474 100 52 99 0 1116757584 44962640 9926240
05:39:43 30660 8192000000 10023822720 10426937344 1075604960 11.606 99 55 103 1 1146272448 60346688 10320800
05:41:18 1092 8192000000 10086513872 10476285952 1104200624 11.877 100 94 178 2 1202988032 79892960 18894448
05:42:22 2105 8192000000 10117865104 10505306112 1119807760 12.025 100 85 160 1 1235375392 98402544 17165088
05:43:31 4722 8192000000 10128222528 10521231360 1125722272 12.081 100 86 157 4 1253022224 107467280 19832672
05:44:43 1041 8192000000 10193318720 10580213760 1122622544 12.052 99 92 180 2 1299376832 157863440 18890848
05:45:48 2381 8192000000 10177450416 10584014848 1147877680 12.290 100 71 138 1 1293631312 130917616 14836016
05:46:54 1635 8192000000 10238139472 10633146368 1155673088 12.363 100 88 168 3 1352297536 177555680 19068768
05:47:58 2452 8192000000 10225840560 10628763648 1163120304 12.432 100 94 183 0 1340065648 157045088 19900256
05:49:08 3187 8192000000 10259860000 10678620160 1169705248 12.494 100 85 166 0 1370845168 182641888 18498032
05:50:17 1546 8192000000 10286297360 10690240512 1173425456 12.529 100 92 169 2 1396067872 202900000 19742416
05:51:23 3422 8192000000 10286356144 10709803008 1169497248 12.492 100 66 126 1 1406722368 222216464 15008656
05:52:30 996 8192000000 10330753472 10732568576 1173868048 12.533 100 97 194 2 1443047328 247379440 21799840
05:53:34 1022 8192000000 10370987264 10771365888 1183230912 12.620 100 94 186 2 1477634528 272940768 21462848
05:54:38 3367 8192000000 10408945856 10813460480 1193342288 12.714 100 84 162 0 1507689152 294840816 19506048
05:55:46 1091 8192000000 10438368320 10872029184 1207159152 12.843 100 94 181 1 1538319488 310459120 20701216
05:56:50 655 8192000000 10489821840 10900471808 1217721280 12.941 100 95 182 0 1595924688 355845472 22357936
05:57:54 1183 8192000000 10482302896 10926497792 1213972944 12.906 100 95 186 2 1588613616 351670320 22970352
05:58:59 774 8192000000 10535161296 10939117568 1208438512 12.855 100 97 188 0 1638890640 408756752 21695376
06:00:02 1017 8192000000 10558672656 10965782528 1188271504 12.667 100 89 166 0 1661784464 453305520 20207440
06:01:10 4731 8192000000 10597860544 11041476608 1194407680 12.724 100 73 145 0 1701321088 488094128 18819280
06:02:21 2281 8192000000 10613058560 11072409600 1194032176 12.721 100 80 156 1 1717293888 502851888 20409824
06:03:30 6212 8192000000 10536054336 11043065856 1189524480 12.679 100 62 122 1 1652731616 447229328 15977808
06:04:42 15014 8192000000 10474978288 10961281024 1184968256 12.637 100 43 84 0 1592890912 396763056 11159600
06:06:09 17756 8192000000 10451126032 10957627392 1174782432 12.542 100 37 72 0 1570752352 385984032 9985888
06:07:39 13439 8192000000 10375257104 10938007552 1157853296 12.383 99 23 46 0 1506170048 341996192 6320560
06:09:01 17666 8192000000 10182227328 10734477312 1131335328 12.134 100 20 38 1 1319449552 183772768 4341456
06:10:45 24033 8192000000 10091955472 10520461312 1148528032 12.296 100 50 95 3 1199529424 41284528 9716864
06:12:12 78556 8192000000 10138513296 10557997056 1138539344 12.202 99 69 127 1 1223075920 70772736 13763840
06:14:32 33359 8192000000 10089320608 10588061696 1111408000 11.946 97 19 36 0 1194663728 79679376 3576352
06:17:16 64111 8192000000 10109341744 10565726208 1122286928 12.049 98 30 57 0 1185091248 56532880 6271440
06:19:45 56778 8192000000 10104495200 10562658304 1132375008 12.144 99 17 34 0 1195559584 59656704 3527872
06:21:42 72576 8192000000 10090705488 10573647872 1142007472 12.234 100 16 31 1 1231744832 85491136 4246224
06:24:07 65808 8192000000 10125096064 10576044032 1149163616 12.302 100 36 71 0 1205779344 49357504 7258224
06:26:14 68626 8192000000 10044101184 10496516096 1110412128 11.936 98 16 32 0 1149541808 35588432 3541248
06:28:23 40251 8192000000 10102677520 10552762368 1114404400 11.974 98 22 45 0 1191437232 72294256 4738576
06:30:16 28198 8192000000 10112587184 10593095680 1130290080 12.124 99 24 47 0 1211838624 75776176 5772368
06:31:45 39179 8192000000 10104393024 10605002752 1138693136 12.203 100 13 26 0 1229235056 87669120 2872800
06:33:38 92696 8192000000 10053600032 10520350720 1110011792 11.933 98 19 39 0 1140692160 25588064 5092304
06:36:31 71006 8192000000 10058880640 10510446592 1127645840 12.099 100 13 26 0 1185920624 55837072 2437712
06:38:42 53335 8192000000 10105810208 10557980672 1106003760 11.895 97 22 44 0 1164821680 53812800 5005120
06:40:38 73368 8192000000 10069451632 10574413824 1121109408 12.037 99 12 24 0 1218760736 94977312 2674016
06:42:53 30657 8192000000 10076726832 10507374592 1133171488 12.151 100 36 65 2 1160601824 20322896 7107440
06:44:24 38421 8192000000 10106557776 10559434752 1127371776 12.097 99 47 94 1 1216357168 79118192 9867200
06:46:07 29873 8192000000 10089433632 10510856192 1112232992 11.954 98 43 81 0 1165367808 44785584 8349232
06:47:38 8928 8192000000 10155177616 10583973888 1125618016 12.080 99 56 110 1 1239701552 102544400 11539136
06:49:36 66363 8192000000 10122266080 10573574144 1115677520 11.986 98 22 44 0 1171563536 51535808 4350208
06:51:44 141061 8192000000 10073777008 10555523072 1103133776 11.867 98 29 52 1 1185442608 76623792 5685040
06:55:06 70091 8192000000 10077158752 10544553984 1109089680 11.924 98 9 18 0 1165291232 54415872 1785680
06:57:19 41731 8192000000 10072748320 10521231360 1133542032 12.155 100 12 24 0 1180715088 44462576 2710480
06:59:03 39995 8192000000 10069574912 10527547392 1124570752 12.070 99 21 42 1 1183155648 54260240 4324656
07:00:57 33602 8192000000 10134766656 10579419136 1141415792 12.229 100 47 83 0 1235013216 84532400 9065024
07:02:33 31889 8192000000 10126361280 10571935744 1102279520 11.859 96 28 55 0 1224087456 115138992 6668944
07:04:07 25641 8192000000 10154888096 10577281024 1115548224 11.985 99 46 90 0 1243332864 118141824 9642816
07:05:46 27844 8192000000 10188334848 10634027008 1102729920 11.864 98 37 72 0 1260085344 149546256 7809168
07:07:15 44980 8192000000 10186403184 10633854976 1094936560 11.790 98 23 46 0 1271287344 170783152 5567632
07:09:26 23710 8192000000 10107490784 10548432896 1128196176 12.104 100 48 94 0 1178348704 40397392 9755136
07:10:57 67143 8192000000 10116399456 10584158208 1139582016 12.212 100 31 60 0 1225941216 79822432 6536768
07:13:19 68012 8192000000 10081490064 10571980800 1135590400 12.174 99 10 20 0 1194733904 57372288 1771216
07:15:32 29281 8192000000 10080032304 10569396224 1145865120 12.271 100 21 40 0 1182416192 32921456 3629616
07:17:02 41368 8192000000 10054654448 10517368832 1123818592 12.063 98 18 31 1 1150408672 23232352 3357728
07:19:02 72068 8192000000 10076211040 10537840640 1123830144 12.063 98 28 54 1 1166956016 37502352 5623520
07:21:14 78882 8192000000 10096785024 10564894720 1137546672 12.192 99 18 36 0 1197590608 56398560 3645376
07:23:33 100285 8192000000 10089120128 10586812416 1124311280 12.068 98 10 20 0 1173236432 46384720 2540432
07:26:14 60738 8192000000 10115872224 10564734976 1114657088 11.976 97 22 44 0 1172904128 53586912 4660128
07:28:24 36212 8192000000 10097793552 10553896960 1124320192 12.068 98 32 62 1 1191324560 60134240 6870128
07:30:10 30976 8192000000 10092920528 10526527488 1112545568 11.957 98 29 58 0 1160599296 42661904 5391824
07:31:42 38560 8192000000 10162109408 10607636480 1129316016 12.115 99 41 82 0 1246975024 109129696 8529312
07:33:33 13702 8192000000 10099322224 10521657344 1114925840 11.979 98 58 113 0 1179014416 51767728 12320848
07:34:47 57703 8192000000 10082145088 10568773632 1114668992 11.977 98 23 46 0 1216250352 96861424 4719936
07:36:58 73216 8192000000 8908464496 9087000576 60829504 0.737 6 2 4 0 87279632 26139056 311072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-10-2022 07:40:40 to 03-10-2022 07:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 03-10-2022 07:40:57 to 03-10-2022 07:40:59.

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          212601
MON$OLDEST_ACTIVE               212602
MON$OLDEST_SNAPSHOT             212602
MON$NEXT_TRANSACTION            212602
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                       2053344
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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