Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.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 15163352 kB
MemAvailable 30618764 kB
Buffers 0 kB
Cached 16570740 kB
SwapCached 2132 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-09-26 13:37:20
DTS_END                         2022-09-26 13:37:20.8180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59318
TRN_ID                          226936
ATT_ID                          690
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: 9022.05 24068 1082646
customer order: creation 180.65 450 21678
customer order: refuse 15.38 422 1846
order to supplier: creation 48.42 1307 5810
order to supplier: removal 3.65 166185 438
invoice (draft): creation 47.63 2912 5716
invoice (draft): removal 49.05 1087 5886
invoice accept: apply 43.67 27525 5240
invoice accept: cancel 44.40 1362 5328
customer reserve: creation 3974.98 269 476997
customer reserve: removal 4317.18 111 518061
realization accept: apply 87.51 281 10501
realization accept: cancel 73.51 186 8821
payment from customer: creation 63.36 178 7603
payment from customer: removal 5.67 127 680
payment to supplier: creation 50.47 196 6056
payment to supplier: removal 3.38 103 405
service: total inventory turnovers 4.30 3987 516
service: total monetary turnovers 7.78 394 933
service: aggregate perf. data 1.05 15584 126
service: refresh index statistics 0.04 258697 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2022 13:39:00 to 26-09-2022 13:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7603
TEST_TIME 2 5088
TEST_TIME 3 5616
TEST_TIME 4 6490
TEST_TIME 5 6658
TEST_TIME 6 6274
TEST_TIME 7 6135
TEST_TIME 8 5960
TEST_TIME 9 6069
TEST_TIME 10 6308
TEST_TIME 11 6559
TEST_TIME 12 6491
TEST_TIME 13 6543
TEST_TIME 14 6758
TEST_TIME 15 6963
TEST_TIME 16 6911
TEST_TIME 17 7041
TEST_TIME 18 7154
TEST_TIME 19 7254
TEST_TIME 20 7318
TEST_TIME 21 7436
TEST_TIME 22 7638
TEST_TIME 23 7740
TEST_TIME 24 7884
TEST_TIME 25 7984
TEST_TIME 26 8207
TEST_TIME 27 8602
TEST_TIME 28 8720
TEST_TIME 29 8649
TEST_TIME 30 9196
TEST_TIME 31 9701
TEST_TIME 32 10078
TEST_TIME 33 10146
TEST_TIME 34 10197
TEST_TIME 35 10726
TEST_TIME 36 10699
TEST_TIME 37 10770
TEST_TIME 38 11044
TEST_TIME 39 10931
TEST_TIME 40 10767
TEST_TIME 41 10710
TEST_TIME 42 10743
TEST_TIME 43 10631
TEST_TIME 44 10476
TEST_TIME 45 10364
TEST_TIME 46 10318
TEST_TIME 47 10206
TEST_TIME 48 10216
TEST_TIME 49 10048
TEST_TIME 50 9914
TEST_TIME 51 9976
TEST_TIME 52 9945
TEST_TIME 53 9845
TEST_TIME 54 9834
TEST_TIME 55 9803
TEST_TIME 56 9744
TEST_TIME 57 9693
TEST_TIME 58 9698
TEST_TIME 59 9647
TEST_TIME 60 9707
TEST_TIME 61 9953
TEST_TIME 62 9996
TEST_TIME 63 9985
TEST_TIME 64 9916
TEST_TIME 65 9865
TEST_TIME 66 9917
TEST_TIME 67 9918
TEST_TIME 68 9895
TEST_TIME 69 9828
TEST_TIME 70 9907
TEST_TIME 71 9915
TEST_TIME 72 9872
TEST_TIME 73 9867
TEST_TIME 74 9756
TEST_TIME 75 9723
TEST_TIME 76 9691
TEST_TIME 77 9613
TEST_TIME 78 9564
TEST_TIME 79 9558
TEST_TIME 80 9523
TEST_TIME 81 9487
TEST_TIME 82 9423
TEST_TIME 83 9331
TEST_TIME 84 9415
TEST_TIME 85 9435
TEST_TIME 86 9410
TEST_TIME 87 9437
TEST_TIME 88 9453
TEST_TIME 89 9403
TEST_TIME 90 9394
TEST_TIME 91 9419
TEST_TIME 92 9439
TEST_TIME 93 9500
TEST_TIME 94 9439
TEST_TIME 95 9469
TEST_TIME 96 9435
TEST_TIME 97 9373
TEST_TIME 98 9365
TEST_TIME 99 9304
TEST_TIME 100 9280
TEST_TIME 101 9233
TEST_TIME 102 9225
TEST_TIME 103 9200
TEST_TIME 104 9169
TEST_TIME 105 9148
TEST_TIME 106 9127
TEST_TIME 107 9122
TEST_TIME 108 9112
TEST_TIME 109 9092
TEST_TIME 110 9096
TEST_TIME 111 9114
TEST_TIME 112 9255
TEST_TIME 113 9222
TEST_TIME 114 9206
TEST_TIME 115 9232
TEST_TIME 116 9185
TEST_TIME 117 9163
TEST_TIME 118 9129
TEST_TIME 119 9103
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2022 13:39:04 to 26-09-2022 13:39:07.

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 1521526 1521526 0 0.00 0 20937 15 0 0
doc_list_biud 1521518 1521518 0 0.00 0 26634 44 0 0
sp_add_invoice_to_stock 5326 5240 86 1.61 43 253347 27525 0 86
sp_cancel_adding_invoice 5328 5328 0 0.00 5 82836 1363 0 0
sp_cancel_client_order 1846 1846 0 0.00 6 9024 423 0 0
sp_cancel_customer_reserve 518061 518061 0 0.00 1 26387 111 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 3548 128 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 1571 104 0 0
sp_cancel_supplier_invoice 5895 5886 9 0.15 2 12262 1088 0 9
sp_cancel_supplier_order 438 438 0 0.00 16 1638949 166186 0 0
sp_cancel_write_off 8821 8821 0 0.00 2 11123 187 0 0
sp_client_order 21678 21678 0 0.00 5 12388 450 0 0
sp_customer_reserve 476997 476997 0 0.00 1 146983 269 0 0
sp_fill_shopping_cart 33191 33191 0 0.00 2 13485 81 0 0
sp_fill_shopping_cart_clo_res 476973 476973 0 0.00 0 15693 18 0 0
sp_get_clo_for_invoice 5206 5206 0 0.00 0 20611 242 0 0
sp_kill_cost_storno 15234 15234 0 0.00 0 7976 80 0 0
sp_kill_qstorno_ret_qs2qd 1051267 1051267 0 0.00 0 26632 48 0 0
sp_kill_qty_storno 556085 556085 0 0.00 0 26634 110 0 0
sp_lock_selected_doc 21686 21686 0 0.00 0 6624 7 0 0
sp_make_cost_storno 29365 29365 0 0.00 0 20669 80 0 0
sp_multiply_rows_for_pdistr 29363 29363 0 0.00 0 10681 39 0 0
sp_multiply_rows_for_qdistr 21676 21676 0 0.00 0 12350 174 0 0
sp_pay_from_customer 7603 7603 0 0.00 5 12786 178 0 0
sp_pay_to_supplier 6056 6056 0 0.00 7 10872 196 0 0
sp_payment_common 13658 13658 0 0.00 2 3985 44 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 1 9015 349 0 0
sp_qd_handle_on_invoice_upd_sts 10532 10532 0 0.00 0 9932 534 0 0
sp_qd_handle_on_reserve_upd_sts 10501 10501 0 0.00 0 9750 65 0 0
sp_reserve_write_off 10501 10501 0 0.00 8 10813 282 0 0
sp_supplier_invoice 5716 5716 0 0.00 10 81342 2912 0 0
sp_supplier_order 5810 5810 0 0.00 9 23757 1308 0 0
srv_aggregate_perf_data 126 126 0 0.00 2235 181334 15585 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 721 134931 34052 0 0
srv_find_qd_qs_mism 1031443 1031443 0 0.00 0 10306 12 0 0
srv_make_invnt_saldo 516 516 0 0.00 67 93966 3987 0 0
srv_make_money_saldo 933 933 0 0.00 1 7598 395 0 0
srv_recalc_idx_stat 5 5 0 0.00 92464 325670 258697 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 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 5245 5245 0 0.00 1 14297 943 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 2 5955 473 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 9963 34 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 1390 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 445 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 2495 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1192 25 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 90675 7391 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 1958 24 0 0
t$perf-norm:sp_client_order 21678 21678 0 0.00 0 12530 38 0 0
t$perf-norm:sp_customer_reserve 1124 1124 0 0.00 0 1373 14 0 0
t$perf-norm:sp_pay_from_customer 7603 7603 0 0.00 0 7056 20 0 0
t$perf-norm:sp_pay_to_supplier 6056 6056 0 0.00 0 11503 28 0 0
t$perf-norm:sp_reserve_write_off 10501 10501 0 0.00 0 12575 24 0 0
t$perf-norm:sp_supplier_invoice 5716 5716 0 0.00 0 3590 30 0 0
t$perf-norm:sp_supplier_order 5810 5810 0 0.00 0 13688 37 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 487 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 533 13 0 0
t$perf-norm:srv_make_invnt_saldo 516 516 0 0.00 0 2142 21 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 443 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 146 54 0 0
v_add_invoice_to_stock 10568 10396 172 1.62 0 3949 25 0 172
v_all_customers 39424 39424 0 0.00 0 63 4 0 0
v_all_suppliers 32060 32060 0 0.00 0 74 4 0 0
v_all_wares 43344 43344 0 0.00 0 124 4 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 1730 18 0 0
v_cancel_client_order 3692 3692 0 0.00 0 2013 10 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 621 11 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 1131 11 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1481 14 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 12071 37 0 18
v_cancel_supplier_order 876 876 0 0.00 0 1728 13 0 0
v_cancel_write_off 2070 2070 0 0.00 0 1832 25 0 0
v_max_id_clo_ord 5806 5806 0 0.00 0 2528 6 0 0
v_max_id_clo_res 1124 1124 0 0.00 0 1636 14 0 0
v_max_id_ord_sup 5709 5709 0 0.00 0 4317 7 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 88 8 0 0
v_max_non_paid_realizn 4116 4116 0 0.00 0 422 7 0 0
v_min_id_clo_ord 5806 5806 0 0.00 0 668 7 0 0
v_min_id_clo_res 1124 1124 0 0.00 0 239 7 0 0
v_min_id_ord_sup 5709 5709 0 0.00 0 2779 7 0 0
v_min_non_paid_invoice 6054 6054 0 0.00 0 3971 15 0 0
v_min_non_paid_realizn 7603 7603 0 0.00 0 2856 16 0 0
v_our_firm 10894 10894 0 0.00 0 73 5 0 0
v_reserve_write_off 21000 21000 0 0.00 0 3598 10 0 0
x_lock_depdocs_on_canc_invoice 10796 10796 0 0.00 0 20109 137 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 2925 305 0 0
x_make_qstorno_1000_1200 5810 5810 0 0.00 2 23743 1134 0 0
x_make_qstorno_1200_2000 5716 5716 0 0.00 5 81092 2706 0 0
x_make_qstorno_2100_3300 476997 476997 0 0.00 0 146965 238 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 13:39:08 to 26-09-2022 13:39:12.

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:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:25 41516 8192000000 9738666960 10039046144 813676032 9.035 100 93 186 0 844444784 7803600 22965152
11:10:09 17224 8192000000 9761662720 10070499328 837313568 9.273 100 88 176 0 871602448 12944320 21344560
11:11:33 13533 8192000000 9801763952 10121097216 868085120 9.581 100 83 165 0 909680240 22766160 18828960
11:12:51 8510 8192000000 9856349856 10187862016 908709680 9.985 100 83 162 0 964833904 37840592 18283632
11:14:02 3319 8192000000 9905406080 10248110080 948323408 10.375 100 90 173 0 1016867744 50175424 18368912
11:15:09 2077 8192000000 9967541328 10320822272 982045312 10.704 100 88 175 1 1081753744 81786400 17922032
11:16:13 2071 8192000000 9988817328 10347335680 1014353056 11.017 100 83 155 0 1102494912 72502096 15639760
11:17:18 4525 8192000000 10007458864 10372071424 1031688464 11.185 100 85 166 1 1123387392 75590128 16108800
11:18:25 884 8192000000 10027853248 10398253056 1049505984 11.356 100 94 179 2 1143517824 76126432 17885408
11:19:27 1255 8192000000 10033886512 10407120896 1067600688 11.529 100 92 180 0 1154730080 68543696 18585696
11:20:32 1963 8192000000 10036665840 10414981120 1073350752 11.584 100 79 144 2 1153452848 65284960 14817136
11:21:34 1312 8192000000 10061146192 10446442496 1083756048 11.683 100 96 186 3 1184505312 82511184 18238080
11:22:38 1060 8192000000 10059190176 10445058048 1085686656 11.702 100 98 190 0 1178543920 73751744 19105520
11:23:41 1175 8192000000 10042638544 10429505536 1091509760 11.757 100 94 176 4 1164843552 54666304 18667488
11:24:43 1197 8192000000 10046533376 10443956224 1091488064 11.757 100 80 149 1 1168771600 62649008 14634528
11:25:45 1027 8192000000 10063196128 10450845696 1096453232 11.804 100 91 176 3 1184753872 70583776 17716864
11:26:47 1125 8192000000 10068190976 10458247168 1101548672 11.852 100 98 190 1 1190269792 70077120 18644000
11:27:49 1054 8192000000 10088447728 10480660480 1102590336 11.862 100 95 186 1 1211398368 90101328 18706704
11:28:51 1125 8192000000 10073994640 10466832384 1106861264 11.903 100 96 187 1 1200337504 74767168 18709072
11:29:53 1134 8192000000 10024546304 10411315200 1081351264 11.660 100 91 176 2 1143932512 45003408 17577840
11:30:56 1119 8192000000 10053932560 10439872512 1083242016 11.678 100 94 178 1 1174618704 73608832 17767856
11:31:58 1089 8192000000 10045012976 10432892928 1084683104 11.692 100 92 173 1 1168124176 65495168 17945904
11:33:00 1154 8192000000 10016496192 10396786688 1059941040 11.456 100 97 178 1 1138580720 60420272 18219408
11:34:02 1185 8192000000 10017677056 10390097920 1045127344 11.314 100 96 189 0 1140329584 76605328 18596912
11:35:05 1235 8192000000 10001394368 10375995392 1040554448 11.270 100 94 173 2 1124585376 64813504 19217424
11:36:07 2322 8192000000 9980021264 10358992896 1040540128 11.270 100 71 135 1 1100670288 46966208 13163952
11:37:10 6920 8192000000 9971188960 10348470272 1034496752 11.212 99 67 114 1 1090837600 45342352 10998496
11:38:27 2307 8192000000 10031805472 10418688000 1067739360 11.530 100 73 129 1 1153397216 73218816 12439040
11:39:35 18815 8192000000 10040291968 10425708544 1075767776 11.607 100 61 115 4 1152021280 64944080 11309424
11:40:59 1546 8192000000 10063606736 10446524416 1092301888 11.765 99 89 175 0 1185344240 75096256 17946096
11:42:04 4161 8192000000 10119008720 10504232960 1115803264 11.987 100 90 164 3 1231043408 99453856 15786288
11:43:13 11712 8192000000 10138675456 10542751744 1124790336 12.072 100 59 112 0 1250129872 113664288 11675248
11:44:29 7774 8192000000 10149660624 10552766464 1146193536 12.274 100 60 109 1 1265766656 106477488 13095632
11:45:41 2820 8192000000 10241804528 10638188544 1154376672 12.351 100 78 152 1 1343795056 171766224 17652160
11:46:47 1500 8192000000 10260178624 10660507648 1161755872 12.420 100 86 168 3 1373283872 193010080 18517920
11:47:52 1091 8192000000 10261104592 10670796800 1171538160 12.511 100 85 167 1 1375161376 184985104 18638112
11:48:56 1674 8192000000 10298764064 10702999552 1173784144 12.532 100 79 151 0 1416220432 225584144 16852144
11:50:00 1872 8192000000 10348285856 10750472192 1176874368 12.561 100 93 175 0 1455131792 258414368 19843056
11:51:06 1066 8192000000 10373857120 10778959872 1186213312 12.648 100 96 188 1 1484827968 277674048 20940608
11:52:12 998 8192000000 10380560304 10799980544 1193019104 12.711 100 96 182 1 1490767040 276489168 21258768
11:53:16 1261 8192000000 10399447376 10803957760 1177027840 12.562 100 93 176 1 1515563920 317024576 21511504
11:54:25 1021 8192000000 10440993648 10860367872 1195797056 12.737 100 98 192 2 1551860400 334399888 21663456
11:55:28 753 8192000000 10463823472 10868867072 1190707328 12.690 100 99 191 0 1573478960 359729504 23042128
11:56:31 832 8192000000 10486326496 10903670784 1184577248 12.633 100 98 185 1 1594106640 387254768 22274624
11:57:36 2149 8192000000 10532627456 10952351744 1177368480 12.566 100 92 178 1 1642088096 442456880 22262736
11:58:41 709 8192000000 10546037776 10974453760 1187953216 12.664 100 97 194 0 1650230912 439933632 22344064
11:59:46 2067 8192000000 10571116160 10993029120 1196584736 12.745 100 98 194 1 1675593696 456132400 22876560
12:00:51 853 8192000000 10597085920 11030507520 1200781040 12.784 100 94 180 1 1703382288 479535968 23065280
12:01:55 2382 8192000000 10613277344 11036909568 1188287312 12.667 100 66 131 0 1713522304 508669376 16565616
12:03:03 1144 8192000000 10619531456 11059965952 1197786944 12.756 100 90 170 2 1723217184 506393520 19036720
12:04:07 6544 8192000000 10540117168 11039948800 1170367264 12.500 100 57 113 0 1647759568 463389776 14002528
12:05:15 1789 8192000000 10551545168 10990387200 1169999792 12.497 100 69 138 0 1654884240 468405952 16478496
12:06:22 9396 8192000000 10487997632 10977308672 1161745776 12.420 100 37 72 0 1616746912 445105264 9895872
12:07:36 3205 8192000000 10433706368 10939887616 1168297440 12.481 100 71 139 1 1532685872 347530240 16858192
12:08:42 24790 8192000000 10324301952 10803806208 1172631648 12.521 100 31 62 0 1462405920 281945936 7828336
12:10:08 47382 8192000000 10310366464 10797785088 1164660656 12.447 100 24 48 0 1413322224 242779216 5882352
12:11:57 20452 8192000000 10164133104 10689961984 1141615648 12.231 99 23 43 0 1296973152 150213664 5143840
12:13:24 69331 8192000000 10176929584 10621231104 1153519264 12.343 99 19 38 0 1289145344 131448128 4177952
12:15:37 98986 8192000000 10087272928 10540318720 1130294800 12.124 98 12 24 0 1180763200 48287904 2180496
12:18:19 98956 8192000000 10074046864 10538229760 1093801696 11.779 95 14 28 0 1162691616 66010528 2879392
12:21:09 89294 8192000000 10067404576 10527207424 1132199984 12.142 99 18 34 1 1158930176 22375168 4355024
12:23:45 43332 8192000000 10070558864 10498174976 1115519408 11.985 97 58 99 1 1147987904 22083840 10384656
12:25:31 134929 8192000000 10121566272 10547662848 1145115328 12.264 99 86 150 2 1213473600 52772464 15585808
12:28:47 2320 8192000000 10110460288 10530635776 1126036288 12.084 98 76 136 1 1199559120 58481120 15041712
12:29:54 5984 8192000000 10148274912 10575003648 1156876624 12.374 100 61 102 0 1246468400 78366000 11225776
12:31:01 10508 8192000000 10245458096 10673922048 1163863136 12.439 100 63 125 0 1335957408 158384336 13709936
12:32:13 73458 8192000000 10231057648 10702274560 1151202944 12.321 98 28 56 0 1318223632 160368064 6652624
12:34:27 120963 8192000000 10250975616 10711711744 1106977408 11.904 95 19 38 0 1319151424 207111968 5062048
12:37:34 39158 8192000000 10058628672 10553835520 1107866624 11.912 98 21 42 0 1194536672 81399744 5270304
12:39:17 58605 8192000000 10051833904 10527166464 1095612736 11.796 97 26 50 0 1165727024 65398064 4716224
12:41:16 37769 8192000000 10062942416 10493517824 1076985504 11.619 96 38 68 1 1106464240 21763776 7714960
12:43:03 83195 8192000000 10077670976 10562564096 1111904896 11.950 98 28 54 1 1191300816 73758144 5637776
12:45:54 51303 8192000000 10123080512 10554486784 1123663968 12.062 98 27 52 0 1188084160 59346176 5074016
12:47:49 47500 8192000000 10108968800 10549178368 1130411552 12.125 99 32 64 0 1189513920 52592688 6509680
12:49:36 12032 8192000000 10107350720 10537435136 1134567200 12.164 99 52 101 0 1184408496 39665104 10176192
12:50:52 74379 8192000000 10071639472 10534113280 1131477888 12.135 99 22 45 1 1204856848 69065728 4313232
12:53:30 66516 8192000000 10100711920 10561531904 1132415424 12.144 99 46 92 1 1185898048 44465680 9016944
12:55:51 22267 8192000000 10117456000 10559545344 1137075568 12.188 99 49 95 0 1203665760 56937376 9652816
12:57:14 28424 8192000000 10087520576 10538655744 1144366240 12.257 100 27 54 2 1198289184 48342832 5580112
12:58:45 81121 8192000000 10146266928 10600697856 1142713328 12.241 99 20 41 0 1225920208 78733472 4473408
13:01:07 79134 8192000000 10106605568 10609725440 1100635072 11.844 96 15 30 0 1162172160 57968432 3568656
13:03:27 46485 8192000000 10094461248 10553974784 1106198464 11.896 97 17 33 0 1155620800 46318832 3103504
13:05:14 68935 8192000000 10077956864 10527068160 1116487328 11.994 99 14 29 0 1173729392 54319264 2922800
13:07:27 42088 8192000000 10131678048 10583330816 1119660000 12.024 99 35 68 0 1200076448 73566160 6850288
13:09:17 84168 8192000000 10081091840 10570018816 1090099040 11.744 97 18 34 0 1156865600 63026736 3739824
13:11:41 18831 8192000000 10090676912 10513653760 1097357056 11.813 98 45 90 0 1164875264 58595824 8922384
13:13:01 7480 8192000000 10107815104 10533822464 1133103312 12.151 100 76 144 1 1194711536 46252960 15355264
13:14:13 92727 8192000000 10078500160 10557181952 1140539984 12.221 100 36 70 1 1210283344 62583600 7159760
13:17:44 79467 8192000000 10082842784 10559320064 1119257056 12.020 97 12 24 0 1174281888 52106032 2918800
13:20:11 63676 8192000000 10075890800 10554105856 1119575632 12.023 98 17 33 1 1169625472 46475888 3573952
13:22:20 29229 8192000000 10106185552 10551119872 1128394064 12.106 99 25 50 0 1189436448 55507328 5535056
13:23:52 29880 8192000000 10105084976 10542563328 1121544400 12.042 98 25 50 0 1188941984 62075968 5321616
13:25:26 40850 8192000000 10165294096 10608205824 1145973776 12.272 100 35 70 0 1253642592 99941536 7727280
13:27:11 27595 8192000000 10207493872 10651607040 1149783312 12.307 100 49 96 0 1298632016 138605696 10243008
13:28:49 70720 8192000000 10068641680 10602393600 1122959424 12.055 99 16 32 0 1254655680 128166576 3529680
13:31:04 24804 8192000000 10118555024 10549141504 1123641136 12.061 99 58 113 0 1194570752 59849200 11080416
13:32:37 38375 8192000000 10112377424 10570809344 1120143344 12.028 100 54 108 1 1203145408 71606736 11395328
13:34:30 63825 8192000000 10073786832 10559188992 1114608480 11.976 99 12 24 0 1161476448 44017152 2850816
13:37:20 8831 8192000000 8934607856 9144238080 104241168 1.256 10 1 2 0 107468256 3191680 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-09-2022 13:39:14 to 26-09-2022 13:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 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 26-09-2022 13:39:31 to 26-09-2022 13:39:33.

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          227281
MON$OLDEST_ACTIVE               227282
MON$OLDEST_SNAPSHOT             227282
MON$NEXT_TRANSACTION            227282
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                       2084000
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2849 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2849 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2849 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 ---
:148: 
:149: mscs-test-161.budzdorov.ru    Mon Sep 26 13:37:34 2022
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
--- end of diff output ---

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

26.09.22 13:39:34. 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-09-26 13:39:37 No severe PSQL-related problems occured
26.09.22 13:39:38. End of report.