Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2022 07:45
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 14759236 kB
MemAvailable 30519980 kB
Buffers 0 kB
Cached 16966596 kB
SwapCached 2140 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-17 07:43:53
DTS_END                         2022-10-17 07:43:53.9100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39804
TRN_ID                          224729
ATT_ID                          640
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: 8537.64 26106 1024517
customer order: creation 174.16 468 20899
customer order: refuse 15.48 444 1857
order to supplier: creation 45.93 1378 5512
order to supplier: removal 3.21 162024 385
invoice (draft): creation 47.19 3630 5663
invoice (draft): removal 44.80 1051 5376
invoice accept: apply 42.79 25369 5135
invoice accept: cancel 39.88 1507 4786
customer reserve: creation 3885.77 241 466292
customer reserve: removal 3947.93 102 473751
realization accept: apply 84.47 297 10136
realization accept: cancel 73.27 191 8792
payment from customer: creation 60.48 172 7258
payment from customer: removal 5.99 140 719
payment to supplier: creation 50.61 213 6073
payment to supplier: removal 3.34 134 401
service: total inventory turnovers 3.42 4883 410
service: total monetary turnovers 7.94 389 953
service: aggregate perf. data 0.96 14756 115
service: refresh index statistics 0.03 304737 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 07:45:29 to 17-10-2022 07:45:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5598
TEST_TIME 2 6269
TEST_TIME 3 6152
TEST_TIME 4 5897
TEST_TIME 5 6683
TEST_TIME 6 6631
TEST_TIME 7 6621
TEST_TIME 8 6595
TEST_TIME 9 7207
TEST_TIME 10 7161
TEST_TIME 11 7313
TEST_TIME 12 7383
TEST_TIME 13 7396
TEST_TIME 14 7451
TEST_TIME 15 7468
TEST_TIME 16 7663
TEST_TIME 17 7851
TEST_TIME 18 7781
TEST_TIME 19 7717
TEST_TIME 20 7748
TEST_TIME 21 8044
TEST_TIME 22 8278
TEST_TIME 23 8588
TEST_TIME 24 8736
TEST_TIME 25 8906
TEST_TIME 26 8767
TEST_TIME 27 9011
TEST_TIME 28 8954
TEST_TIME 29 9129
TEST_TIME 30 9224
TEST_TIME 31 9597
TEST_TIME 32 9746
TEST_TIME 33 10043
TEST_TIME 34 10385
TEST_TIME 35 10433
TEST_TIME 36 10570
TEST_TIME 37 10536
TEST_TIME 38 10681
TEST_TIME 39 10553
TEST_TIME 40 10685
TEST_TIME 41 10505
TEST_TIME 42 10597
TEST_TIME 43 10656
TEST_TIME 44 10604
TEST_TIME 45 10526
TEST_TIME 46 10420
TEST_TIME 47 10265
TEST_TIME 48 10135
TEST_TIME 49 10011
TEST_TIME 50 9876
TEST_TIME 51 9737
TEST_TIME 52 9795
TEST_TIME 53 9767
TEST_TIME 54 9666
TEST_TIME 55 9575
TEST_TIME 56 9503
TEST_TIME 57 9486
TEST_TIME 58 9397
TEST_TIME 59 9298
TEST_TIME 60 9209
TEST_TIME 61 9171
TEST_TIME 62 9109
TEST_TIME 63 9185
TEST_TIME 64 9118
TEST_TIME 65 9063
TEST_TIME 66 8968
TEST_TIME 67 8859
TEST_TIME 68 8829
TEST_TIME 69 8861
TEST_TIME 70 8850
TEST_TIME 71 8815
TEST_TIME 72 8773
TEST_TIME 73 8784
TEST_TIME 74 8758
TEST_TIME 75 8740
TEST_TIME 76 8761
TEST_TIME 77 8765
TEST_TIME 78 8797
TEST_TIME 79 8782
TEST_TIME 80 8797
TEST_TIME 81 8798
TEST_TIME 82 8773
TEST_TIME 83 8826
TEST_TIME 84 8862
TEST_TIME 85 8899
TEST_TIME 86 8861
TEST_TIME 87 8858
TEST_TIME 88 8811
TEST_TIME 89 8750
TEST_TIME 90 8778
TEST_TIME 91 8712
TEST_TIME 92 8680
TEST_TIME 93 8699
TEST_TIME 94 8668
TEST_TIME 95 8603
TEST_TIME 96 8568
TEST_TIME 97 8532
TEST_TIME 98 8535
TEST_TIME 99 8532
TEST_TIME 100 8584
TEST_TIME 101 8604
TEST_TIME 102 8590
TEST_TIME 103 8627
TEST_TIME 104 8597
TEST_TIME 105 8647
TEST_TIME 106 8715
TEST_TIME 107 8676
TEST_TIME 108 8679
TEST_TIME 109 8775
TEST_TIME 110 8728
TEST_TIME 111 8737
TEST_TIME 112 8766
TEST_TIME 113 8709
TEST_TIME 114 8709
TEST_TIME 115 8653
TEST_TIME 116 8628
TEST_TIME 117 8590
TEST_TIME 118 8543
TEST_TIME 119 8576
TEST_TIME 120 8570

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2022 07:45:33 to 17-10-2022 07:45:36.

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 1454214 1454214 0 0.00 0 18225 14 0 0
doc_list_biud 1454205 1454205 0 0.00 0 38548 40 0 0
sp_add_invoice_to_stock 5269 5135 134 2.54 41 245573 25369 0 134
sp_cancel_adding_invoice 4786 4786 0 0.00 6 89474 1507 0 0
sp_cancel_client_order 1857 1857 0 0.00 6 9510 445 0 0
sp_cancel_customer_reserve 473751 473751 0 0.00 1 25627 103 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 4085 140 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 1934 134 0 0
sp_cancel_supplier_invoice 5385 5376 9 0.16 3 32866 1051 0 9
sp_cancel_supplier_order 385 385 0 0.00 23 1566521 162025 0 0
sp_cancel_write_off 8792 8792 0 0.00 2 19192 191 0 0
sp_client_order 20899 20899 0 0.00 6 27328 468 0 0
sp_customer_reserve 466292 466292 0 0.00 0 146650 242 0 0
sp_fill_shopping_cart 32060 32060 0 0.00 1 25555 97 0 0
sp_fill_shopping_cart_clo_res 466260 466260 0 0.00 0 17737 20 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 45756 344 0 0
sp_kill_cost_storno 14698 14698 0 0.00 0 12105 79 0 0
sp_kill_qstorno_ret_qs2qd 962055 962055 0 0.00 0 32854 44 0 0
sp_kill_qty_storno 510165 510165 0 0.00 0 38540 105 0 0
sp_lock_selected_doc 21179 21179 0 0.00 0 10434 9 0 0
sp_make_cost_storno 28549 28549 0 0.00 0 18215 79 0 0
sp_multiply_rows_for_pdistr 28549 28549 0 0.00 0 10616 40 0 0
sp_multiply_rows_for_qdistr 20899 20899 0 0.00 0 17746 172 0 0
sp_pay_from_customer 7258 7258 0 0.00 6 9789 172 0 0
sp_pay_to_supplier 6073 6073 0 0.00 7 11055 213 0 0
sp_payment_common 13331 13331 0 0.00 2 6876 47 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 1 9283 360 0 0
sp_qd_handle_on_invoice_upd_sts 9868 9868 0 0.00 0 38530 630 0 0
sp_qd_handle_on_reserve_upd_sts 10136 10136 0 0.00 0 16610 77 0 0
sp_reserve_write_off 10136 10136 0 0.00 8 16626 298 0 0
sp_supplier_invoice 5663 5663 0 0.00 10 120516 3631 0 0
sp_supplier_order 5512 5512 0 0.00 8 38154 1378 0 0
srv_aggregate_perf_data 115 115 0 0.00 2649 204879 14757 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 692 157504 35647 0 0
srv_find_qd_qs_mism 974973 974973 0 0.00 0 13923 10 0 0
srv_make_invnt_saldo 410 410 0 0.00 55 56210 4884 0 0
srv_make_money_saldo 953 953 0 0.00 1 20040 390 0 0
srv_recalc_idx_stat 4 4 0 0.00 177404 424983 304738 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5140 5140 0 0.00 1 14881 1077 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 13743 544 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 8047 30 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3600 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 835 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 811 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1419 46 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 103098 7777 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 2769 25 0 0
t$perf-norm:sp_client_order 20899 20899 0 0.00 0 8895 36 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 6655 22 0 0
t$perf-norm:sp_pay_from_customer 7258 7258 0 0.00 0 4690 21 0 0
t$perf-norm:sp_pay_to_supplier 6073 6073 0 0.00 0 8888 25 0 0
t$perf-norm:sp_reserve_write_off 10136 10136 0 0.00 0 8637 28 0 0
t$perf-norm:sp_supplier_invoice 5664 5664 0 0.00 0 5470 36 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 5288 34 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 565 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 615 18 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 792 16 0 0
t$perf-norm:srv_make_money_saldo 953 953 0 0.00 0 4434 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 183 53 0 0
v_add_invoice_to_stock 10428 10160 268 2.57 0 4907 26 0 268
v_all_customers 38166 38166 0 0.00 0 95 4 0 0
v_all_suppliers 31082 31082 0 0.00 0 85 5 0 0
v_all_wares 41790 41790 0 0.00 0 133 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1402 17 0 0
v_cancel_client_order 3714 3714 0 0.00 0 4229 13 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 4073 18 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 1 1396 11 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1929 16 0 0
v_cancel_supplier_invoice 846 828 18 2.12 0 444 26 0 18
v_cancel_supplier_order 770 770 0 0.00 0 1709 13 0 0
v_cancel_write_off 2142 2142 0 0.00 0 1374 25 0 0
v_max_id_clo_ord 5508 5508 0 0.00 0 841 6 0 0
v_max_id_clo_res 955 955 0 0.00 0 2328 13 0 0
v_max_id_ord_sup 5654 5654 0 0.00 0 5737 8 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 144 8 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 190 8 0 0
v_min_id_clo_ord 5508 5508 0 0.00 0 14066 15 0 0
v_min_id_clo_res 955 955 0 0.00 0 5922 18 0 0
v_min_id_ord_sup 5654 5654 0 0.00 0 6750 10 0 0
v_min_non_paid_invoice 6073 6073 0 0.00 0 5065 16 0 0
v_min_non_paid_realizn 7258 7258 0 0.00 0 6866 17 0 0
v_our_firm 10368 10368 0 0.00 0 72 5 0 0
v_reserve_write_off 20270 20270 0 0.00 0 4232 11 0 0
x_lock_depdocs_on_canc_invoice 9748 9748 0 0.00 0 15461 150 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 11514 421 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 2 38128 1176 0 0
x_make_qstorno_1200_2000 5663 5663 0 0.00 4 119892 3361 0 0
x_make_qstorno_2100_3300 466291 466291 0 0.00 0 146358 208 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-10-2022 07:45:37 to 17-10-2022 07:45:42.

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:13:57 2 8192000000 8799449392 8810975232 4086992 0.049 2 1 2 0 4186384 63616 35776
05:14:58 21309 8192000000 9706061616 9996128256 781877216 8.712 100 95 190 0 813330992 7977760 23476016
05:16:29 16988 8192000000 9744329376 10047721472 825943984 9.158 100 87 174 0 858128160 10883024 21301152
05:17:52 14761 8192000000 9793178992 10108575744 855227696 9.452 100 84 168 0 890289968 15519408 19542864
05:19:11 9262 8192000000 9842926064 10171777024 899305200 9.891 100 82 162 0 955515072 38037984 18171888
05:20:22 7374 8192000000 9908649504 10248462336 939074544 10.284 100 79 156 0 1019165792 63674768 16416480
05:21:37 3656 8192000000 9935025648 10281893888 981695664 10.701 100 83 161 0 1054394032 56330144 16368224
05:22:44 1909 8192000000 9955024704 10313330688 1006159072 10.938 100 85 166 1 1077640512 54356048 17125392
05:23:49 1076 8192000000 10004116720 10369482752 1033483984 11.202 100 93 180 0 1120928784 70171088 17273712
05:24:52 1402 8192000000 10045479744 10416328704 1047253664 11.334 100 97 194 2 1158935456 91774416 19907376
05:25:55 1021 8192000000 10036361232 10409873408 1052475440 11.384 100 96 185 2 1154054128 82516304 19062384
05:26:57 1087 8192000000 10054415456 10434048000 1056052800 11.419 100 95 184 1 1173876736 99377664 18446272
05:28:00 1133 8192000000 10049238336 10428338176 1069224160 11.545 100 85 163 1 1166819040 81475392 16119488
05:29:02 1055 8192000000 10046358288 10432004096 1078543728 11.634 100 94 185 1 1169192144 72663552 17984864
05:30:04 1013 8192000000 10043789424 10427392000 1081744240 11.664 100 89 171 0 1161586512 61122800 18719472
05:31:06 1122 8192000000 10052285200 10440454144 1088383232 11.727 100 97 177 1 1175610064 67567424 19659408
05:32:09 1309 8192000000 10068593520 10456395776 1094681792 11.787 100 98 190 1 1192111024 77813296 19615936
05:33:12 1252 8192000000 10071489824 10457489408 1093394224 11.775 100 95 187 2 1194769472 82793360 18581888
05:34:14 1061 8192000000 10049077920 10436399104 1097833280 11.817 100 94 183 0 1170180464 53767456 18579728
05:35:16 16016 8192000000 10038440720 10440065024 1093068016 11.772 100 60 114 2 1173439376 68779600 11591760
05:36:35 1500 8192000000 10056367040 10455957504 1102620496 11.862 100 66 125 2 1180290288 63932016 13737776
05:37:38 2762 8192000000 10029039920 10417131520 1077877168 11.627 100 70 139 2 1141434896 49773808 13783920
05:38:41 982 8192000000 10034513408 10411278336 1076098384 11.610 99 97 183 0 1156324816 61081680 19144752
05:39:43 3133 8192000000 10022273904 10411892736 1075281216 11.602 100 69 136 0 1147221072 58518128 13421728
05:40:48 992 8192000000 10000850656 10378235904 1049544656 11.356 100 85 161 2 1121143456 55413232 16185568
05:41:50 1088 8192000000 9980780064 10363564032 1049684560 11.358 100 73 140 3 1105193984 40031392 15478032
05:42:52 1037 8192000000 9964100928 10338226176 1040831104 11.273 100 83 158 3 1086241936 28713232 16697600
05:43:54 1169 8192000000 10008513184 10389909504 1045306064 11.316 100 81 155 1 1131964608 70292160 16366384
05:45:04 1183 8192000000 10013903728 10395652096 1056621088 11.424 100 78 152 0 1135506752 62119808 16765856
05:46:09 1022 8192000000 10056394160 10439917568 1079434848 11.642 100 89 166 1 1172670256 76025424 17209984
05:47:15 11116 8192000000 10055767712 10448056320 1092399856 11.765 100 73 141 1 1176076048 68389728 15286464
05:48:30 2584 8192000000 10105841536 10500751360 1100359952 11.841 100 70 140 1 1221657328 106401248 14896128
05:49:36 4418 8192000000 10108042704 10509754368 1113917360 11.969 100 78 152 0 1225539056 94857728 16763968
05:50:44 1517 8192000000 10160969168 10548637696 1123214960 12.057 100 83 155 1 1273622048 134351824 16055264
05:51:48 1475 8192000000 10164653408 10566479872 1130045104 12.122 100 96 180 2 1277471168 127630624 19795440
05:52:53 2163 8192000000 10179296416 10584870912 1143311664 12.247 100 85 164 0 1298540464 136377120 18851680
05:53:58 3525 8192000000 10200022672 10604179456 1161990832 12.422 100 66 130 0 1320154048 144049584 14113632
05:55:06 2781 8192000000 10241976384 10655842304 1171925936 12.515 100 85 167 0 1351841312 161063952 18851424
05:56:15 897 8192000000 10278045600 10680283136 1181508992 12.604 100 91 170 3 1387519840 186861488 19149360
05:57:20 2630 8192000000 10307131120 10717675520 1185302240 12.640 100 87 169 0 1414665056 210351792 19011024
05:58:27 946 8192000000 10300205552 10720468992 1177141568 12.564 100 90 168 1 1409555888 213852256 18562064
05:59:31 1178 8192000000 10362340784 10788139008 1188709984 12.671 100 94 177 1 1464895504 255495488 20690032
06:00:37 1824 8192000000 10350262096 10757246976 1182331664 12.612 100 94 182 1 1457423088 253953216 21138208
06:01:44 1501 8192000000 10397828752 10798231552 1175518848 12.548 100 91 173 1 1503787536 309415360 18853328
06:02:49 1244 8192000000 10421554448 10836242432 1174839488 12.542 100 93 174 1 1530556512 335628624 20088400
06:03:54 684 8192000000 10464137248 10893418496 1188131088 12.666 100 100 196 1 1568922832 356995440 23796304
06:04:57 913 8192000000 10462836736 10895388672 1192796672 12.709 100 97 189 0 1567159264 352143856 22218736
06:06:00 1471 8192000000 10466900640 10899922944 1176042528 12.553 100 98 192 1 1572482912 374321024 22119360
06:07:03 4828 8192000000 10421537984 10871148544 1159208320 12.396 100 69 125 2 1529428400 355031360 15188720
06:08:13 10710 8192000000 10410627936 10849480704 1159485968 12.398 100 48 96 2 1525502608 353746688 12269952
06:09:30 7814 8192000000 10427793408 10861088768 1147412592 12.285 100 43 86 1 1526002336 367908016 10681728
06:10:49 2754 8192000000 10436745152 10887745536 1155318832 12.359 100 55 110 0 1536348448 368132608 12897008
06:11:58 17583 8192000000 10425679344 10870276096 1141149056 12.226 99 37 72 0 1532097008 381486752 9461200
06:13:27 14845 8192000000 10420377728 10865741824 1136660064 12.184 99 36 71 0 1513140560 367339872 9140624
06:15:17 19109 8192000000 10311652384 10756907008 1121613008 12.042 99 24 49 0 1420225792 292334848 6277936
06:16:38 3366 8192000000 10230003216 10761822208 1138693568 12.203 100 68 136 0 1333879840 179441904 15744368
06:17:43 7224 8192000000 10208318960 10649210880 1138869440 12.205 100 45 84 0 1310091952 161148816 10073696
06:18:53 1333 8192000000 10221034000 10654990336 1144624848 12.259 100 76 152 0 1324286576 162398240 17263488
06:19:58 7197 8192000000 10191255024 10615062528 1123930016 12.064 99 38 74 2 1298821584 164452256 10439312
06:21:09 10658 8192000000 10211266320 10693292032 1144955360 12.262 100 29 56 0 1325486640 172957120 7574160
06:22:22 34877 8192000000 10260909040 10693005312 1148924992 12.299 100 42 80 0 1353338464 195457888 8955584
06:24:22 53097 8192000000 10221922432 10663641088 1134226144 12.161 99 39 70 0 1314459024 171939216 8293664
06:26:18 75494 8192000000 10067832416 10559533056 1124148368 12.066 99 11 19 0 1205624416 79621584 1854464
06:28:47 56429 8192000000 10098883728 10557669376 1147375056 12.285 100 14 28 0 1193184368 43026288 2783024
06:30:44 65820 8192000000 10104744416 10543370240 1145034704 12.263 100 23 43 0 1203790144 54711424 4044016
06:33:09 80616 8192000000 10128926832 10573492224 1139160624 12.208 99 16 32 1 1213376560 71452464 2763472
06:35:30 95557 8192000000 10068111984 10510651392 1111099424 11.943 97 18 32 1 1145838720 31193552 3545744
06:38:07 138350 8192000000 10075415168 10536185856 1116226560 11.991 98 44 88 0 1157411904 31598448 9586896
06:41:31 156125 8192000000 10075255904 10501332992 1051813536 11.378 92 55 111 1 1108655632 45691232 11150864
06:45:10 39462 8192000000 10094198752 10535841792 1109326176 11.926 98 28 56 0 1180868080 65028528 6513376
06:46:51 9578 8192000000 10051992752 10512998400 1119539600 12.023 99 37 70 1 1154897120 28326656 7030864
06:48:02 66134 8192000000 10073310960 10528964608 1109070576 11.924 99 15 31 0 1168568720 56101584 3396560
06:50:08 64053 8192000000 10102866672 10562351104 1121817952 12.044 100 10 18 0 1218836288 94860144 2158192
06:52:44 27959 8192000000 10051987744 10528845824 1133069760 12.150 100 35 69 1 1167695440 26902560 7723120
06:54:20 74117 8192000000 10042020272 10491060224 1095647904 11.796 96 12 19 2 1124992784 26801520 2543360
06:56:35 36771 8192000000 10072048704 10508521472 1116958800 11.998 98 26 47 1 1155024320 33340304 4725216
06:58:13 39611 8192000000 10106678832 10550714368 1139355408 12.209 99 25 48 0 1201196992 56794848 5046736
06:59:57 70706 8192000000 10069836080 10500911104 1031603712 11.184 91 25 46 0 1112885920 75706032 5576176
07:02:12 77826 8192000000 10074116672 10523852800 1057467344 11.432 95 28 55 1 1102323952 39638416 5218192
07:04:36 51886 8192000000 10089015120 10542743552 1113107520 11.962 99 25 48 0 1184726832 66546544 5072768
07:06:29 97156 8192000000 10077305120 10586853376 1116848736 11.997 99 13 26 0 1186906368 67439248 2618384
07:09:10 23304 8192000000 10091274864 10529865728 1110034896 11.933 98 41 81 1 1163466128 45673360 7757872
07:10:33 100162 8192000000 10044544560 10501554176 1103240960 11.868 99 25 48 0 1150322912 42061376 5020576
07:13:26 74708 8192000000 10029796192 10486571008 1116230480 11.991 100 15 30 0 1168328192 48462848 3634864
07:15:41 56476 8192000000 10022620624 10485104640 1063368208 11.489 95 16 32 0 1113260112 46785136 3106768
07:17:41 59276 8192000000 10062782096 10498023424 1106039392 11.895 99 17 33 0 1150991824 41513744 3438688
07:19:56 61477 8192000000 10084746752 10554793984 1106105168 11.896 99 14 21 0 1172304688 62380768 3818752
07:21:58 25870 8192000000 10130012320 10575962112 1134363824 12.162 100 34 65 0 1221198032 80254032 6580176
07:23:30 18116 8192000000 10102555760 10570211328 1131675552 12.137 99 33 63 0 1197221088 58109408 7436128
07:24:52 44482 8192000000 10079372224 10537377792 1116547648 11.994 98 25 50 0 1170503792 47963600 5992544
07:26:53 36362 8192000000 10124826960 10567983104 1133096544 12.151 100 32 60 0 1195997856 56462112 6439200
07:28:56 44570 8192000000 10103748272 10580426752 1122781728 12.053 99 16 32 0 1212491648 86475488 3234432
07:30:48 79741 8192000000 10172342800 10641485824 1142288128 12.237 99 31 60 0 1224319152 75810096 6220928
07:33:09 28654 8192000000 10078516096 10514976768 1122515408 12.051 98 37 72 2 1154043904 23489584 8038912
07:34:45 13037 8192000000 10119059136 10551476224 1139477040 12.211 100 47 94 0 1204518544 55796976 9244528
07:35:58 157502 8192000000 10064557648 10525003776 1109800400 11.931 97 27 55 0 1151094320 36181424 5112496
07:39:36 60761 8192000000 10067551760 10518409216 1091138192 11.753 96 10 20 0 1154684784 61428832 2117760
07:41:37 80809 8192000000 10104248240 10594734080 1110835024 11.940 98 17 34 0 1160703664 45968576 3900064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-10-2022 07:45:43 to 17-10-2022 07:46:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 07:46:01 to 17-10-2022 07:46:03.

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          222947
MON$OLDEST_ACTIVE               225345
MON$OLDEST_SNAPSHOT             225345
MON$NEXT_TRANSACTION            225345
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                       2122432
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Oct 17 07:43:56 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Oct 17 07:43:58 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Oct 17 07:43:58 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Mon Oct 17 07:43:58 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Mon Oct 17 07:44:00 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Mon Oct 17 07:44:01 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Mon Oct 17 07:44:01 2022
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:33: 
:34: 
:35: mscs-test-161.budzdorov.ru    Mon Oct 17 07:44:04 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
--- end of diff output ---

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

17.10.22 07:46:04. 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-17 07:46:07 No severe PSQL-related problems occured
17.10.22 07:46:08. End of report.