Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.05.2022 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16122156 kB
MemAvailable 31436316 kB
Buffers 0 kB
Cached 15550788 kB
SwapCached 2620 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.2
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-05-12 13:37:19
DTS_END                         2022-05-12 13:37:19.4850
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/38572
TRN_ID                          209192
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: 8529.22 33096 1023506
customer order: creation 154.78 488 18574
customer order: refuse 13.96 450 1675
order to supplier: creation 42.46 1537 5095
order to supplier: removal 3.33 187350 400
invoice (draft): creation 43.40 3676 5208
invoice (draft): removal 49.04 1210 5885
invoice accept: apply 38.53 27755 4623
invoice accept: cancel 43.68 1467 5241
customer reserve: creation 3611.78 260 433414
customer reserve: removal 4252.80 113 510336
realization accept: apply 77.02 293 9242
realization accept: cancel 78.68 189 9442
payment from customer: creation 54.86 198 6583
payment from customer: removal 5.42 159 650
payment to supplier: creation 44.66 221 5359
payment to supplier: removal 3.13 145 375
service: total inventory turnovers 3.57 4832 428
service: total monetary turnovers 7.30 423 876
service: aggregate perf. data 0.80 16030 96
service: refresh index statistics 0.03 415115 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-05-2022 13:38:27 to 12-05-2022 13:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7165
TEST_TIME 2 5082
TEST_TIME 3 5713
TEST_TIME 4 5913
TEST_TIME 5 5862
TEST_TIME 6 6284
TEST_TIME 7 6322
TEST_TIME 8 6272
TEST_TIME 9 6283
TEST_TIME 10 6523
TEST_TIME 11 6613
TEST_TIME 12 6630
TEST_TIME 13 6843
TEST_TIME 14 6772
TEST_TIME 15 7000
TEST_TIME 16 6970
TEST_TIME 17 6976
TEST_TIME 18 6900
TEST_TIME 19 6963
TEST_TIME 20 6911
TEST_TIME 21 7082
TEST_TIME 22 7252
TEST_TIME 23 7224
TEST_TIME 24 7270
TEST_TIME 25 7407
TEST_TIME 26 7617
TEST_TIME 27 7692
TEST_TIME 28 8083
TEST_TIME 29 8245
TEST_TIME 30 8496
TEST_TIME 31 8569
TEST_TIME 32 9270
TEST_TIME 33 9554
TEST_TIME 34 9937
TEST_TIME 35 10109
TEST_TIME 36 10265
TEST_TIME 37 10359
TEST_TIME 38 10388
TEST_TIME 39 10371
TEST_TIME 40 10297
TEST_TIME 41 10356
TEST_TIME 42 10133
TEST_TIME 43 9928
TEST_TIME 44 9936
TEST_TIME 45 10010
TEST_TIME 46 9874
TEST_TIME 47 9757
TEST_TIME 48 9881
TEST_TIME 49 9899
TEST_TIME 50 9799
TEST_TIME 51 9735
TEST_TIME 52 9618
TEST_TIME 53 9542
TEST_TIME 54 9495
TEST_TIME 55 9400
TEST_TIME 56 9370
TEST_TIME 57 9285
TEST_TIME 58 9217
TEST_TIME 59 9101
TEST_TIME 60 9077
TEST_TIME 61 9115
TEST_TIME 62 9087
TEST_TIME 63 9012
TEST_TIME 64 8992
TEST_TIME 65 8911
TEST_TIME 66 8821
TEST_TIME 67 8769
TEST_TIME 68 8745
TEST_TIME 69 8683
TEST_TIME 70 8651
TEST_TIME 71 8689
TEST_TIME 72 8767
TEST_TIME 73 8809
TEST_TIME 74 8800
TEST_TIME 75 8771
TEST_TIME 76 8895
TEST_TIME 77 8919
TEST_TIME 78 8951
TEST_TIME 79 8902
TEST_TIME 80 9039
TEST_TIME 81 9048
TEST_TIME 82 9033
TEST_TIME 83 8969
TEST_TIME 84 8925
TEST_TIME 85 8951
TEST_TIME 86 8935
TEST_TIME 87 8877
TEST_TIME 88 8847
TEST_TIME 89 8799
TEST_TIME 90 8761
TEST_TIME 91 8719
TEST_TIME 92 8676
TEST_TIME 93 8697
TEST_TIME 94 8676
TEST_TIME 95 8749
TEST_TIME 96 8719
TEST_TIME 97 8730
TEST_TIME 98 8724
TEST_TIME 99 8735
TEST_TIME 100 8726
TEST_TIME 101 8820
TEST_TIME 102 8834
TEST_TIME 103 8839
TEST_TIME 104 8822
TEST_TIME 105 8779
TEST_TIME 106 8729
TEST_TIME 107 8688
TEST_TIME 108 8685
TEST_TIME 109 8652
TEST_TIME 110 8620
TEST_TIME 111 8584
TEST_TIME 112 8583
TEST_TIME 113 8591
TEST_TIME 114 8582
TEST_TIME 115 8592
TEST_TIME 116 8611
TEST_TIME 117 8602
TEST_TIME 118 8597
TEST_TIME 119 8603
TEST_TIME 120 8557

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-05-2022 13:38:31 to 12-05-2022 13:38:34.

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 1421719 1421719 0 0.00 0 29788 14 0 0
doc_list_biud 1421711 1421711 0 0.00 0 88560 48 0 0
sp_add_invoice_to_stock 4733 4623 110 2.32 73 191562 27755 0 110
sp_cancel_adding_invoice 5241 5241 0 0.00 6 101414 1468 0 0
sp_cancel_client_order 1675 1675 0 0.00 6 10418 451 0 0
sp_cancel_customer_reserve 510336 510336 0 0.00 1 31462 113 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 4 7450 160 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 8005 146 0 0
sp_cancel_supplier_invoice 5893 5885 8 0.13 2 55536 1210 0 8
sp_cancel_supplier_order 400 400 0 0.00 12 1543519 187350 0 0
sp_cancel_write_off 9442 9442 0 0.00 2 13772 189 0 0
sp_client_order 18574 18574 0 0.00 6 25133 488 0 0
sp_customer_reserve 433414 433414 0 0.00 1 44025 260 0 0
sp_fill_shopping_cart 28860 28860 0 0.00 1 21385 89 0 0
sp_fill_shopping_cart_clo_res 433390 433390 0 0.00 0 16578 20 0 0
sp_get_clo_for_invoice 4588 4588 0 0.00 0 20817 342 0 0
sp_kill_cost_storno 15708 15708 0 0.00 0 13754 89 0 0
sp_kill_qstorno_ret_qs2qd 1036399 1036399 0 0.00 0 55098 50 0 0
sp_kill_qty_storno 546804 546804 0 0.00 0 88559 115 0 0
sp_lock_selected_doc 19338 19338 0 0.00 0 9023 7 0 0
sp_make_cost_storno 25770 25770 0 0.00 0 29773 89 0 0
sp_multiply_rows_for_pdistr 25768 25768 0 0.00 0 10872 40 0 0
sp_multiply_rows_for_qdistr 18573 18573 0 0.00 0 10150 177 0 0
sp_pay_from_customer 6583 6583 0 0.00 5 12443 199 0 0
sp_pay_to_supplier 5359 5359 0 0.00 6 12675 221 0 0
sp_payment_common 11942 11942 0 0.00 2 12632 50 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 1 10411 377 0 0
sp_qd_handle_on_invoice_upd_sts 9826 9826 0 0.00 0 88558 630 0 0
sp_qd_handle_on_reserve_upd_sts 9240 9240 0 0.00 0 23589 68 0 0
sp_reserve_write_off 9242 9242 0 0.00 8 23658 293 0 0
sp_supplier_invoice 5208 5208 0 0.00 11 125954 3677 0 0
sp_supplier_order 5095 5095 0 0.00 8 42359 1538 0 0
srv_aggregate_perf_data 96 96 0 0.00 2575 89749 16030 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 711 102482 34603 0 0
srv_find_qd_qs_mism 977789 977789 0 0.00 0 22600 11 0 0
srv_make_invnt_saldo 428 428 0 0.00 32 101539 4833 0 0
srv_make_money_saldo 876 876 0 0.00 1 16492 423 0 0
srv_recalc_idx_stat 4 4 0 0.00 176261 619519 415115 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4632 4632 0 0.00 1 10859 985 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 2 8771 485 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 2569 36 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 701 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 1586 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 4416 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1245 33 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 106571 6926 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 5419 27 0 0
t$perf-norm:sp_client_order 18574 18574 0 0.00 0 6968 39 0 0
t$perf-norm:sp_customer_reserve 874 874 0 0.00 0 2292 24 0 0
t$perf-norm:sp_pay_from_customer 6583 6583 0 0.00 0 5593 19 0 0
t$perf-norm:sp_pay_to_supplier 5359 5359 0 0.00 0 11369 25 0 0
t$perf-norm:sp_reserve_write_off 9242 9242 0 0.00 0 9535 25 0 0
t$perf-norm:sp_supplier_invoice 5208 5208 0 0.00 0 6850 35 0 0
t$perf-norm:sp_supplier_order 5096 5096 0 0.00 0 8817 38 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 573 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 252 8 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 867 22 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 607 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 195 80 0 0
v_add_invoice_to_stock 9388 9168 220 2.34 0 8220 29 0 220
v_all_customers 34220 34220 0 0.00 0 131 4 0 0
v_all_suppliers 28448 28448 0 0.00 0 84 4 0 0
v_all_wares 37140 37140 0 0.00 0 101 4 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 5478 27 0 0
v_cancel_client_order 3350 3350 0 0.00 0 7671 14 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 6810 23 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 3043 13 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 544 12 0 0
v_cancel_supplier_invoice 852 836 16 1.87 0 8213 35 0 16
v_cancel_supplier_order 800 800 0 0.00 0 7645 25 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2714 28 0 0
v_max_id_clo_ord 5090 5090 0 0.00 0 397 5 0 0
v_max_id_clo_res 874 874 0 0.00 0 1053 9 0 0
v_max_id_ord_sup 5197 5197 0 0.00 0 6881 10 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 60 8 0 0
v_max_non_paid_realizn 3375 3375 0 0.00 0 5971 10 0 0
v_min_id_clo_ord 5090 5090 0 0.00 0 1710 9 0 0
v_min_id_clo_res 874 874 0 0.00 0 1580 12 0 0
v_min_id_ord_sup 5197 5197 0 0.00 0 5086 11 0 0
v_min_non_paid_invoice 5359 5359 0 0.00 0 12624 24 0 0
v_min_non_paid_realizn 6583 6583 0 0.00 0 11878 18 0 0
v_our_firm 9336 9336 0 0.00 0 57 5 0 0
v_reserve_write_off 18480 18480 0 0.00 0 8263 15 0 0
x_lock_depdocs_on_canc_invoice 10708 10708 0 0.00 0 19408 138 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 15935 435 0 0
x_make_qstorno_1000_1200 5095 5095 0 0.00 2 42233 1354 0 0
x_make_qstorno_1200_2000 5208 5208 0 0.00 4 117937 3430 0 0
x_make_qstorno_2100_3300 433412 433412 0 0.00 0 32423 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-05-2022 13:38:36 to 12-05-2022 13:38:40.

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
11:07:23 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 34921 8192000000 9728164880 10024386560 787605984 8.771 100 91 182 0 822915456 13081984 22227488
11:10:02 19263 8192000000 9762601008 10070122496 831632800 9.216 100 91 182 0 870765296 16635472 22497024
11:11:30 19315 8192000000 9793275872 10109431808 852712160 9.427 100 92 184 0 903332656 28652592 21967904
11:12:59 11713 8192000000 9864444608 10201194496 912766192 10.025 100 75 150 0 977088528 47599312 16723024
11:14:14 3446 8192000000 9916538352 10261794816 959785696 10.487 100 87 170 2 1032714176 54892640 18035840
11:15:24 3212 8192000000 9942765568 10294902784 985405312 10.737 100 94 185 0 1064763424 60077312 19280800
11:16:30 1224 8192000000 10002267248 10361556992 1010316464 10.978 100 92 168 1 1119558912 92104688 17137760
11:17:33 1155 8192000000 10002241296 10366156800 1027288528 11.142 100 97 190 1 1119861168 73370928 19201712
11:18:36 1261 8192000000 10013940896 10382266368 1046488144 11.327 100 98 187 2 1133733424 67816592 19428688
11:19:39 1363 8192000000 10031505232 10408091648 1064466752 11.499 100 98 190 1 1153828672 70084240 19277680
11:20:42 2535 8192000000 10024509216 10405396480 1070561152 11.557 100 89 172 3 1149195520 60663440 17970928
11:21:47 1014 8192000000 10080085632 10459787264 1078623248 11.634 100 98 190 1 1196397968 99046848 18727872
11:22:49 1402 8192000000 10061800832 10446225408 1081043776 11.657 100 99 184 5 1182627424 82019424 19564224
11:23:51 1429 8192000000 10042772928 10432757760 1088328976 11.727 100 90 176 0 1169373312 62910000 18134336
11:24:54 1124 8192000000 10063329680 10453340160 1093179392 11.773 100 97 183 1 1187439920 75878688 18381840
11:25:56 1340 8192000000 10053073648 10439819264 1101827312 11.855 100 98 190 2 1179327168 56959872 20539984
11:26:59 6924 8192000000 10025798992 10415349760 1084544320 11.691 99 69 126 2 1153844208 58042784 11257104
11:28:06 1064 8192000000 10052593104 10441834496 1105263712 11.888 100 93 173 2 1172316176 48113856 18938608
11:29:08 3784 8192000000 10029544304 10419605504 1085304736 11.698 100 64 118 4 1150560960 53184272 12071952
11:30:12 2692 8192000000 10031730800 10425925632 1080131056 11.649 99 70 133 0 1162735824 67417024 15187744
11:31:16 862 8192000000 10045210352 10432438272 1087859072 11.722 100 86 169 1 1165029984 58846352 18324560
11:32:17 1041 8192000000 9981243984 10358095872 1040954288 11.274 98 89 166 3 1104074416 45967024 17153104
11:33:20 1368 8192000000 10027521392 10411790336 1054300704 11.402 100 83 162 0 1152998064 81765408 16931952
11:34:23 996 8192000000 10007552912 10380468224 1046801536 11.330 100 92 178 0 1127002272 63069904 17130832
11:35:25 4312 8192000000 9985980560 10367930368 1027438752 11.144 98 68 126 2 1096219248 55164736 13615760
11:36:30 3036 8192000000 9998026800 10371719168 1050474960 11.365 100 80 149 4 1117844400 52066032 15303408
11:37:35 13160 8192000000 9979478432 10359590912 1039029456 11.255 100 62 118 0 1103428736 52845888 11553392
11:38:55 3117 8192000000 10020945872 10400911360 1057242896 11.430 100 75 144 0 1133685680 62157568 14285216
11:40:08 2959 8192000000 10024262096 10416668672 1064391808 11.498 100 57 106 0 1152879920 77448480 11039632
11:41:15 2679 8192000000 10026275232 10408194048 1076122864 11.611 100 79 146 0 1148598656 57873776 14602016
11:42:25 5689 8192000000 10071243024 10456776704 1089420064 11.737 100 76 148 0 1196543984 91548400 15575520
11:43:36 727 8192000000 10078705440 10464972800 1097071248 11.810 100 78 148 0 1192802496 80458368 15272880
11:44:41 9865 8192000000 10136120176 10532630528 1106137168 11.896 100 65 130 1 1244755408 124395232 14223008
11:45:59 10066 8192000000 10173337072 10573709312 1119195488 12.019 100 70 140 0 1288624576 154489648 14939440
11:47:17 1430 8192000000 10211323136 10608386048 1137728880 12.194 100 93 177 2 1328634016 170075568 20829568
11:48:22 1153 8192000000 10257763952 10651176960 1152758400 12.335 100 95 179 1 1368160384 195838032 19563952
11:49:26 1383 8192000000 10298049072 10699259904 1163220096 12.433 100 89 173 0 1414294752 231851776 19222880
11:50:31 1915 8192000000 10292065728 10691874816 1168355168 12.481 100 89 174 0 1404828768 217195456 19278144
11:51:37 1142 8192000000 10337655296 10737692672 1171023264 12.506 100 95 179 1 1451010976 258771328 21216384
11:52:43 1069 8192000000 10363949616 10768011264 1188543168 12.670 100 88 174 0 1471929344 263685648 19700528
11:53:49 1382 8192000000 10422198784 10825711616 1191666864 12.699 100 93 185 2 1527893376 316067312 20159200
11:54:54 859 8192000000 10445938512 10847490048 1195229392 12.732 100 94 188 0 1551255680 332650816 23375472
11:56:01 704 8192000000 10503217280 10910986240 1205278752 12.825 100 94 184 1 1606925600 380111488 21535360
11:57:04 759 8192000000 10528563184 10950459392 1203073520 12.805 100 95 187 0 1635177904 409946624 22157760
11:58:10 838 8192000000 10554399616 10981982208 1192645648 12.708 100 99 189 2 1656542288 441887376 22009264
11:59:17 2369 8192000000 10585165072 11015696384 1199916400 12.776 100 86 168 0 1686440752 465322208 21202144
12:00:23 790 8192000000 10621571152 11036975104 1198206016 12.760 100 96 184 0 1729995360 509233184 22556160
12:01:29 3203 8192000000 10621644976 11057143808 1197983488 12.758 100 69 134 0 1729139152 514190880 16964784
12:02:39 8311 8192000000 10607625312 11080830976 1188206496 12.667 100 59 118 0 1711375408 507004224 16164688
12:03:52 5073 8192000000 10641062080 11097063424 1186006320 12.646 100 62 119 1 1746728192 545648656 15073216
12:05:09 1876 8192000000 10621441696 11081392128 1178883216 12.580 100 74 144 0 1722237632 525267888 18086528
12:06:17 12529 8192000000 10546265008 11014053888 1165920752 12.459 99 38 76 0 1667316064 490293520 11101792
12:07:34 16373 8192000000 10552012672 10995896320 1169292448 12.490 100 38 75 2 1655063120 475749232 10021440
12:08:52 21739 8192000000 10491879504 10993664000 1149936224 12.309 100 36 72 1 1621244544 461692416 9615904
12:10:39 29667 8192000000 10302645328 10770956288 1149647952 12.306 100 36 72 1 1398442144 239388800 9405392
12:12:12 23948 8192000000 10170915600 10643079168 1140831776 12.223 99 34 68 1 1291335248 142613616 7889856
12:13:39 5208 8192000000 10166663696 10626285568 1148552304 12.296 100 51 101 0 1272709792 112481888 11675600
12:14:48 20800 8192000000 10186252224 10628263936 1136187680 12.180 99 29 57 0 1265223776 122450400 6585696
12:16:56 74680 8192000000 10236724544 10671652864 1144816288 12.261 99 28 54 2 1298199888 146521728 6861872
12:19:15 85750 8192000000 10226892432 10664513536 1152105232 12.329 100 48 91 2 1320570864 157701296 10764336
12:21:43 40147 8192000000 10191017536 10686103552 1142383680 12.238 99 41 79 0 1316532528 165094560 9054288
12:23:29 66139 8192000000 10179149456 10633949184 1128434592 12.107 98 30 57 0 1269190960 134875152 5881216
12:25:36 39183 8192000000 10084141888 10594684928 1147578960 12.287 100 10 20 0 1183984496 34418208 1987328
12:27:53 72186 8192000000 10089639952 10553434112 1135132048 12.170 99 26 51 5 1174986672 34436432 5418192
12:30:05 90812 8192000000 10093687600 10566479872 1117084752 11.999 97 13 26 0 1182995120 62884448 3025920
12:32:46 84561 8192000000 10045588112 10484932608 1123063008 12.056 99 39 78 0 1158770128 27912576 7794544
12:35:13 45216 8192000000 10086081680 10548649984 1140406320 12.219 100 16 32 0 1195918240 52061520 3450400
12:37:00 29443 8192000000 10119033024 10573635584 1136020352 12.178 99 33 66 0 1212575840 69721760 6833728
12:38:41 24934 8192000000 10077418096 10531758080 1141066224 12.226 100 27 52 1 1177284960 30286864 5931872
12:40:06 59390 8192000000 10080362016 10546139136 1138554048 12.202 99 28 56 0 1182651056 38358096 5738912
12:42:54 91955 8192000000 10082912944 10550579200 1129486160 12.117 99 13 26 0 1159005440 26800608 2718672
12:45:36 53022 8192000000 10087795424 10571657216 1123410048 12.059 98 9 18 0 1156053536 30567424 2076064
12:47:34 66763 8192000000 10147803104 10632491008 1131895216 12.139 98 24 48 1 1234708160 97384656 5428288
12:49:41 45519 8192000000 10086992224 10568196096 1132178208 12.142 99 29 56 1 1238553968 99885344 6490416
12:51:28 50691 8192000000 10167550480 10607988736 1144299904 12.256 99 29 58 0 1236632352 86295824 6036624
12:53:19 59836 8192000000 10136888576 10582867968 1109504464 11.928 97 15 30 1 1202566000 90209072 2852464
12:55:31 46663 8192000000 10148677104 10575761408 1095647040 11.796 96 27 51 1 1182051552 81075008 5329504
12:57:27 22347 8192000000 10113619696 10539909120 1117006304 11.999 99 59 115 0 1200164160 70716112 12441744
12:59:08 59689 8192000000 10078193472 10497523712 1072138640 11.572 96 41 82 1 1119440512 39353552 7948320
13:01:08 102481 8192000000 10101680592 10556297216 1120354640 12.030 100 33 66 1 1231741552 104550992 6835920
13:03:57 29409 8192000000 10052808208 10567962624 1118933056 12.017 100 8 16 0 1164846800 44355280 1558464
13:05:26 62871 8192000000 10063168432 10521268224 1103435360 11.870 98 14 29 0 1158191568 51458224 3297984
13:07:29 76483 8192000000 10106005232 10579120128 1121128560 12.038 99 17 34 2 1216232560 91404432 3699568
13:09:50 65897 8192000000 10089662864 10565398528 1093958240 11.780 97 7 14 1 1154885904 59382176 1545488
13:12:03 62848 8192000000 10073005408 10539290624 1115618208 11.986 99 24 48 1 1166454768 45753616 5082944
13:14:11 48476 8192000000 10100054752 10540982272 1115884400 11.988 98 25 50 1 1174342976 52720352 5738224
13:16:00 34022 8192000000 10142428752 10590318592 1122011888 12.046 99 36 72 0 1225945120 96103504 7829728
13:17:38 68468 8192000000 10137662752 10628014080 1124495200 12.069 99 23 47 2 1241297376 112091984 4710192
13:19:50 8223 8192000000 10072825728 10491797504 1109842928 11.931 98 75 145 0 1157413728 32336864 15233936
13:20:59 86610 8192000000 10090725824 10541441024 1094870864 11.789 97 37 73 0 1172780688 70458512 7451312
13:23:28 64777 8192000000 10081464112 10527944704 1121757552 12.044 99 30 60 2 1165950160 37786112 6406496
13:25:37 69286 8192000000 10074693776 10548228096 1123086416 12.056 99 10 20 1 1195569680 70179728 2303536
13:27:47 65662 8192000000 10101992784 10562564096 1134510096 12.164 99 7 14 1 1198456640 62385968 1560576
13:29:59 99284 8192000000 10085310608 10549825536 1112888800 11.960 97 17 34 2 1195076944 78640288 3547856
13:32:46 16829 8192000000 10088546512 10530017280 1116916192 11.998 98 29 58 4 1171741568 49165488 5659888
13:34:04 18001 8192000000 10079128912 10523799552 1137672880 12.194 100 44 86 2 1188414304 41811056 8930368
13:35:22 55205 8192000000 10127671472 10556592128 1117883744 12.007 97 28 56 2 1171467024 47229040 6354240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-05-2022 13:38:41 to 12-05-2022 13:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-05-2022 13:38:58 to 12-05-2022 13:39:00.

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          208770
MON$OLDEST_ACTIVE               211258
MON$OLDEST_SNAPSHOT             211258
MON$NEXT_TRANSACTION            211258
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                       2082400
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             755
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.05.22 13:39:01. 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-05-12 13:39:04 No severe PSQL-related problems occured
12.05.22 13:39:05. End of report.