Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2022 07:48
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 14590936 kB
MemAvailable 30082428 kB
Buffers 0 kB
Cached 17083848 kB
SwapCached 3412 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-12-10 07:47:19
DTS_END                         2022-12-10 07:47:19.2010
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/55806
TRN_ID                          219354
ATT_ID                          651
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: 8661.94 24782 1039433
customer order: creation 168.73 497 20248
customer order: refuse 13.79 474 1655
order to supplier: creation 46.05 1505 5526
order to supplier: removal 3.13 179323 375
invoice (draft): creation 46.18 3360 5542
invoice (draft): removal 48.82 1119 5858
invoice accept: apply 40.65 26017 4878
invoice accept: cancel 43.68 1248 5241
customer reserve: creation 3703.36 249 444403
customer reserve: removal 4250.98 100 510117
realization accept: apply 81.97 327 9836
realization accept: cancel 86.88 179 10426
payment from customer: creation 58.63 213 7035
payment from customer: removal 5.46 142 655
payment to supplier: creation 48.11 207 5773
payment to supplier: removal 3.22 126 386
service: total inventory turnovers 3.71 4396 445
service: total monetary turnovers 7.75 434 930
service: aggregate perf. data 0.83 17536 99
service: refresh index statistics 0.04 258184 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5855
TEST_TIME 2 5057
TEST_TIME 3 5213
TEST_TIME 4 5107
TEST_TIME 5 5482
TEST_TIME 6 5434
TEST_TIME 7 5780
TEST_TIME 8 5976
TEST_TIME 9 6460
TEST_TIME 10 6658
TEST_TIME 11 6766
TEST_TIME 12 6736
TEST_TIME 13 6930
TEST_TIME 14 6898
TEST_TIME 15 7034
TEST_TIME 16 7130
TEST_TIME 17 7185
TEST_TIME 18 7260
TEST_TIME 19 7371
TEST_TIME 20 7366
TEST_TIME 21 7412
TEST_TIME 22 7632
TEST_TIME 23 7707
TEST_TIME 24 7817
TEST_TIME 25 8002
TEST_TIME 26 8137
TEST_TIME 27 8334
TEST_TIME 28 8275
TEST_TIME 29 8307
TEST_TIME 30 8266
TEST_TIME 31 8237
TEST_TIME 32 8420
TEST_TIME 33 8566
TEST_TIME 34 8861
TEST_TIME 35 9645
TEST_TIME 36 9741
TEST_TIME 37 10206
TEST_TIME 38 10241
TEST_TIME 39 9982
TEST_TIME 40 10251
TEST_TIME 41 10290
TEST_TIME 42 10281
TEST_TIME 43 10190
TEST_TIME 44 10060
TEST_TIME 45 9945
TEST_TIME 46 9837
TEST_TIME 47 9764
TEST_TIME 48 9759
TEST_TIME 49 9648
TEST_TIME 50 9677
TEST_TIME 51 9582
TEST_TIME 52 9560
TEST_TIME 53 9528
TEST_TIME 54 9637
TEST_TIME 55 9581
TEST_TIME 56 9553
TEST_TIME 57 9455
TEST_TIME 58 9346
TEST_TIME 59 9254
TEST_TIME 60 9275
TEST_TIME 61 9180
TEST_TIME 62 9141
TEST_TIME 63 9133
TEST_TIME 64 9141
TEST_TIME 65 9091
TEST_TIME 66 9048
TEST_TIME 67 9041
TEST_TIME 68 8969
TEST_TIME 69 8888
TEST_TIME 70 9027
TEST_TIME 71 8967
TEST_TIME 72 8935
TEST_TIME 73 8895
TEST_TIME 74 8959
TEST_TIME 75 9000
TEST_TIME 76 9010
TEST_TIME 77 9046
TEST_TIME 78 9028
TEST_TIME 79 9010
TEST_TIME 80 9080
TEST_TIME 81 9194
TEST_TIME 82 9164
TEST_TIME 83 9156
TEST_TIME 84 9099
TEST_TIME 85 9025
TEST_TIME 86 9021
TEST_TIME 87 8970
TEST_TIME 88 8942
TEST_TIME 89 8924
TEST_TIME 90 8882
TEST_TIME 91 8834
TEST_TIME 92 8805
TEST_TIME 93 8811
TEST_TIME 94 8883
TEST_TIME 95 8870
TEST_TIME 96 8847
TEST_TIME 97 8828
TEST_TIME 98 8838
TEST_TIME 99 8820
TEST_TIME 100 8798
TEST_TIME 101 8861
TEST_TIME 102 8973
TEST_TIME 103 9032
TEST_TIME 104 8997
TEST_TIME 105 8984
TEST_TIME 106 9032
TEST_TIME 107 8957
TEST_TIME 108 8924
TEST_TIME 109 8868
TEST_TIME 110 8835
TEST_TIME 111 8779
TEST_TIME 112 8753
TEST_TIME 113 8755
TEST_TIME 114 8789
TEST_TIME 115 8753
TEST_TIME 116 8754
TEST_TIME 117 8747
TEST_TIME 118 8762
TEST_TIME 119 8740
TEST_TIME 120 8679

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2022 07:48:33 to 10-12-2022 07:48: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 1450687 1450687 0 0.00 0 32747 14 0 0
doc_list_biud 1450682 1450682 0 0.00 0 64126 42 0 0
sp_add_invoice_to_stock 4999 4878 121 2.42 68 191186 26018 0 121
sp_cancel_adding_invoice 5241 5241 0 0.00 6 67426 1248 0 0
sp_cancel_client_order 1655 1655 0 0.00 7 10721 475 0 0
sp_cancel_customer_reserve 510117 510117 0 0.00 1 33108 101 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 5307 143 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 2952 126 0 0
sp_cancel_supplier_invoice 5871 5858 13 0.22 2 64295 1119 0 13
sp_cancel_supplier_order 375 375 0 0.00 7 1348540 179323 0 0
sp_cancel_write_off 10426 10426 0 0.00 2 8216 180 0 0
sp_client_order 20248 20248 0 0.00 6 38055 498 0 0
sp_customer_reserve 444403 444403 0 0.00 0 128771 250 0 0
sp_fill_shopping_cart 31315 31315 0 0.00 1 13310 90 0 0
sp_fill_shopping_cart_clo_res 444383 444383 0 0.00 0 15766 20 0 0
sp_get_clo_for_invoice 4849 4849 0 0.00 0 28325 352 0 0
sp_kill_cost_storno 16708 16708 0 0.00 0 7335 80 0 0
sp_kill_qstorno_ret_qs2qd 1036893 1036893 0 0.00 0 64113 44 0 0
sp_kill_qty_storno 548357 548357 0 0.00 0 64123 103 0 0
sp_lock_selected_doc 20237 20237 0 0.00 0 7357 7 0 0
sp_make_cost_storno 27493 27493 0 0.00 0 30435 96 0 0
sp_multiply_rows_for_pdistr 27493 27493 0 0.00 0 29840 48 0 0
sp_multiply_rows_for_qdistr 20248 20248 0 0.00 0 16174 180 0 0
sp_pay_from_customer 7035 7035 0 0.00 6 29891 213 0 0
sp_pay_to_supplier 5773 5773 0 0.00 6 12264 208 0 0
sp_payment_common 12808 12808 0 0.00 2 10808 52 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 10713 383 0 0
sp_qd_handle_on_invoice_upd_sts 10090 10090 0 0.00 0 48671 572 0 0
sp_qd_handle_on_reserve_upd_sts 9836 9836 0 0.00 0 21975 74 0 0
sp_reserve_write_off 9836 9836 0 0.00 8 30579 327 0 0
sp_supplier_invoice 5542 5542 0 0.00 8 117775 3361 0 0
sp_supplier_order 5526 5526 0 0.00 7 64535 1505 0 0
srv_aggregate_perf_data 99 99 0 0.00 2543 239744 17537 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 822 147719 34452 0 0
srv_find_qd_qs_mism 992601 992601 0 0.00 0 32743 10 0 0
srv_make_invnt_saldo 445 445 0 0.00 55 160435 4397 0 0
srv_make_money_saldo 930 930 0 0.00 1 30811 435 0 0
srv_recalc_idx_stat 5 5 0 0.00 74810 489901 258185 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4879 4879 0 0.00 0 11553 977 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 5870 387 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 1270 30 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 1691 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 2352 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 2926 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 857 32 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 96958 7844 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1060 19 0 0
t$perf-norm:sp_client_order 20248 20248 0 0.00 0 5482 37 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 364 12 0 0
t$perf-norm:sp_pay_from_customer 7035 7035 0 0.00 0 5610 18 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 6187 21 0 0
t$perf-norm:sp_reserve_write_off 9836 9836 0 0.00 0 2920 23 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 4363 35 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 3902 35 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 427 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 821 15 0 0
t$perf-norm:srv_make_invnt_saldo 445 445 0 0.00 0 931 23 0 0
t$perf-norm:srv_make_money_saldo 930 930 0 0.00 0 3872 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 7 253 82 0 0
v_add_invoice_to_stock 9934 9692 242 2.43 0 8927 26 0 242
v_all_customers 36978 36978 0 0.00 0 118 4 0 0
v_all_suppliers 30440 30440 0 0.00 0 132 5 0 0
v_all_wares 40496 40496 0 0.00 0 166 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1905 21 0 0
v_cancel_client_order 3310 3310 0 0.00 0 6099 14 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 5274 18 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 3406 14 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 291 10 0 0
v_cancel_supplier_invoice 844 818 26 3.08 0 3596 32 0 26
v_cancel_supplier_order 750 750 0 0.00 0 2261 16 0 0
v_cancel_write_off 1984 1984 0 0.00 0 5022 27 0 0
v_max_id_clo_ord 5526 5526 0 0.00 0 849 5 0 0
v_max_id_clo_res 979 979 0 0.00 0 1584 14 0 0
v_max_id_ord_sup 5541 5541 0 0.00 0 7604 8 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 785 8 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 5489 9 0 0
v_min_id_clo_ord 5526 5526 0 0.00 0 3398 11 0 0
v_min_id_clo_res 979 979 0 0.00 0 4403 17 0 0
v_min_id_ord_sup 5541 5541 0 0.00 0 6712 11 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 10802 23 0 0
v_min_non_paid_realizn 7035 7035 0 0.00 0 8674 22 0 0
v_our_firm 10294 10294 0 0.00 0 58 5 0 0
v_reserve_write_off 19672 19672 0 0.00 0 26242 14 0 0
x_lock_depdocs_on_canc_invoice 10690 10690 0 0.00 0 12304 150 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 2440 395 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 2 64339 1319 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 3 107895 3111 0 0
x_make_qstorno_2100_3300 444403 444403 0 0.00 0 128770 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2022 07:48:37 to 10-12-2022 07:48:41.

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:17:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:18:23 15267 8192000000 9721052992 10019131392 804067584 8.937 100 88 176 0 832641680 7157264 21416832
05:19:41 23288 8192000000 9765251008 10072207360 838765856 9.287 100 87 174 0 871015936 11807824 20442256
05:21:13 16443 8192000000 9803125872 10121031680 871456816 9.615 100 85 170 0 909747248 18861984 19428448
05:22:37 7358 8192000000 9877383328 10213875712 919817504 10.094 100 72 144 0 977930080 43061216 15051360
05:23:51 2860 8192000000 9926830000 10270273536 962640000 10.515 100 88 175 0 1042666736 61749424 18277312
05:24:57 1591 8192000000 9977803680 10331729920 1000407568 10.882 100 92 177 1 1096438336 77923536 18107232
05:26:01 931 8192000000 10002667456 10366545920 1027276672 11.142 100 95 183 2 1121283520 75849872 18156976
05:27:04 1199 8192000000 10011166032 10384183296 1048374352 11.345 100 89 172 0 1131159728 65105152 17680224
05:28:07 1048 8192000000 10042235248 10421018624 1063502112 11.490 100 91 176 1 1157574160 76127424 17944624
05:29:11 1364 8192000000 10027985376 10405609472 1067792208 11.531 100 91 176 3 1153666928 68132416 17742304
05:30:13 1103 8192000000 10049484992 10437607424 1079208944 11.640 100 88 167 0 1169897072 72914576 17773552
05:31:15 1296 8192000000 10025261632 10414559232 1087480592 11.719 100 84 163 2 1146070400 41439328 17150480
05:32:17 1208 8192000000 10052736432 10447122432 1092903840 11.770 100 78 153 1 1182520048 74424672 15191536
05:33:20 1454 8192000000 10074110048 10461188096 1102926864 11.865 100 95 184 0 1195236432 73829168 18480400
05:34:22 1116 8192000000 10092722864 10481999872 1102296848 11.859 100 96 180 1 1216223184 94284144 19642192
05:35:24 1037 8192000000 10053041472 10442526720 1108046768 11.914 100 90 168 0 1176322016 50722160 17553088
05:36:26 988 8192000000 10080315888 10470952960 1104993232 11.885 100 91 168 2 1201451744 79357840 17100672
05:37:28 4621 8192000000 10051074384 10450219008 1103880592 11.874 100 63 119 0 1186492448 69544176 13067680
05:38:34 942 8192000000 10068735120 10457374720 1100486304 11.842 100 82 159 1 1184734896 68541664 15706928
05:39:37 4478 8192000000 10020843328 10410283008 1059980720 11.456 97 73 134 0 1132491424 60455040 12055664
05:40:42 1084 8192000000 10014111056 10394021888 1062999488 11.485 99 91 175 3 1136063952 55936288 17128176
05:41:44 1013 8192000000 10024916512 10407411712 1063152928 11.487 100 95 177 3 1152582912 70751728 18678256
05:42:46 1198 8192000000 9991642016 10366558208 1040171232 11.266 99 92 177 3 1114466336 56225904 18069200
05:43:49 1196 8192000000 10016458768 10393083904 1051206496 11.372 100 95 180 1 1137853696 68668496 17978704
05:44:50 1443 8192000000 10012262928 10388336640 1040077936 11.265 100 85 162 3 1134533952 77984736 16471280
05:45:53 9667 8192000000 9968837360 10352640000 1043789344 11.301 100 53 103 1 1100040432 45996640 10254448
05:47:05 1775 8192000000 9976927536 10350592000 1020393776 11.076 97 78 150 1 1092423952 56467776 15562400
05:48:22 1208 8192000000 10004333152 10380939264 1063898640 11.494 99 90 170 3 1117022896 35988192 17136064
05:49:32 1344 8192000000 10053815168 10435088384 1091429360 11.756 100 93 184 4 1178882784 67197200 20256224
05:50:38 1729 8192000000 10080470816 10463502336 1097089008 11.810 100 97 175 0 1204943824 88127232 19727584
05:51:45 6766 8192000000 10117709168 10514001920 1106457728 11.899 100 73 142 2 1236976688 116658528 13860432
05:53:04 2867 8192000000 10123813264 10514391040 1118163792 12.010 100 75 139 1 1242881168 110244000 14473376
05:54:11 1786 8192000000 10155544848 10553716736 1130900176 12.130 100 79 155 0 1266894368 119928240 16065952
05:55:19 2811 8192000000 10175258448 10584756224 1139449824 12.210 100 92 180 1 1287029984 128194272 19385888
05:56:32 814 8192000000 10169327520 10589048832 1148774928 12.298 100 95 170 3 1283319600 114868064 19676608
05:57:37 19878 8192000000 10205639568 10620850176 1151396752 12.323 100 66 129 1 1312176832 147047728 13732352
05:59:01 964 8192000000 10221366656 10622443520 1168529840 12.483 100 85 167 1 1337520608 150987008 18003760
06:00:05 1171 8192000000 10244622592 10663747584 1167877568 12.477 100 80 153 0 1356395632 170757408 17760656
06:01:11 1007 8192000000 10263524464 10668748800 1167003120 12.469 100 80 150 1 1375069904 191499200 16567584
06:02:23 2534 8192000000 10289860704 10688077824 1169723712 12.494 100 92 178 3 1395887216 206062016 20101488
06:03:30 1080 8192000000 10318118032 10716393472 1178931088 12.580 100 96 180 1 1423805968 224782896 20091984
06:04:41 1509 8192000000 10358531728 10766172160 1185647472 12.643 100 89 163 0 1469892288 265686720 18558096
06:05:45 880 8192000000 10389373584 10798706688 1197020416 12.749 100 95 189 1 1495682832 276114240 22548176
06:06:50 970 8192000000 10443124816 10851467264 1200250928 12.779 100 92 179 0 1553117984 331477840 21389216
06:07:57 1036 8192000000 10459307536 10869669888 1194197632 12.722 100 95 186 0 1565719136 349334864 22186640
06:09:01 848 8192000000 10476244048 10914680832 1190540992 12.688 100 96 182 3 1582229792 370281664 21407136
06:10:05 1439 8192000000 10487124736 10905731072 1181762400 12.607 100 86 164 1 1591998944 390344032 19892512
06:11:14 2057 8192000000 10503309520 10924306432 1177058768 12.563 100 84 154 1 1619698256 424024112 18615376
06:12:19 9442 8192000000 10459340016 10935689216 1165183392 12.452 99 52 104 0 1565250224 387114400 12952432
06:13:43 1368 8192000000 10454833568 10901295104 1167366256 12.472 100 79 156 0 1556449968 369972944 19110768
06:14:46 10563 8192000000 10450124688 10876387328 1174499104 12.539 100 47 92 1 1552579040 366929280 11150656
06:16:11 8854 8192000000 10500967328 10931183616 1170192528 12.499 100 42 79 1 1600704416 420374144 10137744
06:17:24 13999 8192000000 10493368048 10955374592 1149604784 12.306 99 30 60 0 1588989152 430994736 8389632
06:18:45 7185 8192000000 10495042912 10967011328 1150403904 12.313 100 41 82 1 1593716560 432760448 10552208
06:19:54 1975 8192000000 10474549296 10941689856 1159757664 12.401 100 85 168 3 1573376640 394519232 19099744
06:21:01 4294 8192000000 10405933680 10941677568 1163432880 12.435 100 67 124 0 1529754288 351871456 14449952
06:22:18 20249 8192000000 10306906912 10770259968 1155271296 12.359 100 31 60 0 1418786272 255922224 7592752
06:23:42 41263 8192000000 10233022608 10762534912 1147581392 12.287 99 21 42 0 1378953424 225816672 5555360
06:25:36 80377 8192000000 10192938000 10674597888 1119805264 12.025 98 19 34 0 1306901408 182818240 4277904
06:28:00 50244 8192000000 10122642192 10616537088 1128007728 12.103 99 17 34 0 1245139968 113298752 3833488
06:30:03 75021 8192000000 10083068080 10513596416 1126501904 12.088 99 27 51 0 1167284656 35363936 5418816
06:32:20 66328 8192000000 10099323888 10538098688 1113136672 11.962 97 32 63 0 1167979776 48785488 6057616
06:34:40 92320 8192000000 10084687088 10550026240 1094061952 11.781 96 20 36 2 1130190032 32334528 3793552
06:37:33 41314 8192000000 10080400032 10499547136 1108281504 11.916 98 36 68 3 1160626240 45521712 6823024
06:39:17 63092 8192000000 10101901232 10555637760 1107771488 11.911 98 20 40 0 1159477616 47739840 3966288
06:41:51 134009 8192000000 10070525488 10553626624 1117216000 12.001 98 18 34 1 1173345680 51587232 4542448
06:45:07 83498 8192000000 10107645440 10545352704 1125501008 12.079 99 37 66 1 1179722704 47373312 6848384
06:47:39 53906 8192000000 10071852032 10530750464 1122929360 12.055 99 16 30 1 1162132976 35795440 3408176
06:49:36 25118 8192000000 10089464496 10523348992 1111959472 11.951 98 27 52 0 1169693920 52344448 5390000
06:51:02 53890 8192000000 10108439984 10602893312 1132150400 12.142 100 18 36 0 1210103824 73695376 4258048
06:53:00 147695 8192000000 10083652496 10539429888 1070328848 11.555 94 22 44 0 1141107120 66364240 4414032
06:56:34 62407 8192000000 10081404432 10617606144 1132396896 12.144 100 21 36 1 1236151920 99085728 4669296
06:58:41 11055 8192000000 10083759984 10520227840 1136039760 12.178 100 69 129 2 1176619840 26371680 14208400
06:59:56 13494 8192000000 10128074192 10565689344 1134856592 12.167 99 43 80 0 1204844048 61800960 8186496
07:01:15 58977 8192000000 10069259792 10562924544 1108125472 11.915 97 21 42 0 1185384000 72606480 4652048
07:03:18 15721 8192000000 10085273648 10515718144 1103798944 11.874 99 40 80 0 1172366032 60208368 8358720
07:04:35 38930 8192000000 10100140800 10530054144 1093497552 11.776 97 27 52 0 1167411488 68149408 5764528
07:06:19 61907 8192000000 10160328400 10608115712 1109279520 11.926 98 31 60 0 1240045136 124283760 6481856
07:09:19 36947 8192000000 10085156352 10505490432 1115032512 11.980 99 41 82 1 1159442848 36187584 8222752
07:11:01 117945 8192000000 10076277072 10525216768 1113007280 11.961 99 28 56 0 1186248080 67425248 5815552
07:14:06 72395 8192000000 10066382720 10518646784 1121462272 12.041 100 15 30 1 1176169936 51593280 3114384
07:16:29 62825 8192000000 10080027904 10527510528 1119584672 12.023 99 18 34 1 1178004736 54563088 3856976
07:18:32 65857 8192000000 10078676880 10542702592 1128747088 12.110 100 10 20 0 1186954448 56214368 1992992
07:20:41 57204 8192000000 10101045808 10596679680 1146542848 12.277 100 27 52 1 1209952496 57914352 5495296
07:22:39 41788 8192000000 10069587392 10512867328 1110297248 11.935 97 32 64 0 1154703504 37013232 7393024
07:24:21 20077 8192000000 10092421696 10524098560 1119849616 12.026 99 42 84 0 1181129472 52691120 8588736
07:25:43 22553 8192000000 10146826144 10574614528 1128543424 12.108 99 48 93 0 1222565904 84177664 9844816
07:27:09 38696 8192000000 10178405680 10610360320 1135753264 12.176 99 55 107 1 1270611760 122679568 12178928
07:28:52 54511 8192000000 10128356592 10669096960 1140164016 12.217 100 24 48 0 1292022288 146189392 5668880
07:31:27 14413 8192000000 10106222816 10536611840 1134001616 12.159 99 53 98 1 1181231456 36790096 10439744
07:32:44 39446 8192000000 10091728176 10581086208 1133138864 12.151 100 36 72 0 1190695344 50579280 6977200
07:34:25 72388 8192000000 10061253712 10500657152 1136093888 12.179 100 25 50 0 1163546752 22533440 4919424
07:36:40 63486 8192000000 10092844592 10560937984 1120444160 12.031 99 10 20 0 1186935760 64166256 2325344
07:38:43 84684 8192000000 10108306512 10599256064 1131718656 12.138 99 19 38 1 1238128432 102375840 4033936
07:41:27 62665 8192000000 10054586400 10523279360 1096627184 11.806 96 10 20 0 1132991904 34213136 2151584
07:43:30 34231 8192000000 10072783808 10535886848 1104905600 11.884 98 32 59 0 1158460288 46706816 6847872
07:45:04 59635 8192000000 10063283408 10507689984 1070592800 11.558 95 31 63 0 1138355056 61155872 6606384
07:47:09 20806 8192000000 9172323232 9478709248 300855504 3.542 27 11 22 0 365589360 62388960 2344896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2022 07:49:01 to 10-12-2022 07:49: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          221229
MON$OLDEST_ACTIVE               221230
MON$OLDEST_SNAPSHOT             221230
MON$NEXT_TRANSACTION            221230
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                       2089248
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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    Sat Dec 10 07:47:32 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Dec 10 07:47:33 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

10.12.22 07:49: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-12-10 07:49:06 No severe PSQL-related problems occured
10.12.22 07:49:08. End of report.